{"level":"warn","ts":"2026-08-06T00:59:00.968309Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-06T00:59:00.968639Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.162:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.162:2380","--initial-cluster=instance=https://199.19.213.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.162:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-06T00:59:00.968776Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-06T00:59:00.968799Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.162:2380"]} {"level":"info","ts":"2026-08-06T00:59:00.96884Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-06T00:59:00.969733Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.162:2379"]} {"level":"info","ts":"2026-08-06T00:59:00.96985Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.162:2380"],"listen-peer-urls":["https://199.19.213.162:2380"],"advertise-client-urls":["https://199.19.213.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.162:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-06T00:59:00.974204Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.094066ms"} {"level":"info","ts":"2026-08-06T00:59:00.982362Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8c0a14645b8502c5","cluster-id":"49fe27222e1c6db4"} {"level":"info","ts":"2026-08-06T00:59:00.982504Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 switched to configuration voters=()"} {"level":"info","ts":"2026-08-06T00:59:00.982538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 became follower at term 0"} {"level":"info","ts":"2026-08-06T00:59:00.982554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8c0a14645b8502c5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-06T00:59:00.982564Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 became follower at term 1"} {"level":"info","ts":"2026-08-06T00:59:00.982612Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 switched to configuration voters=(10090900336341746373)"} {"level":"warn","ts":"2026-08-06T00:59:00.990599Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-06T00:59:00.992557Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-06T00:59:00.99387Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-06T00:59:00.996854Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8c0a14645b8502c5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-06T00:59:00.99716Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8c0a14645b8502c5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-06T00:59:00.997189Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-06T00:59:01.001361Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-06T00:59:01.00139Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-06T00:59:01.002444Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 switched to configuration voters=(10090900336341746373)"} {"level":"info","ts":"2026-08-06T00:59:01.002862Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49fe27222e1c6db4","local-member-id":"8c0a14645b8502c5","added-peer-id":"8c0a14645b8502c5","added-peer-peer-urls":["https://199.19.213.162:2380"]} {"level":"info","ts":"2026-08-06T00:59:01.005413Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-06T00:59:01.005745Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8c0a14645b8502c5","initial-advertise-peer-urls":["https://199.19.213.162:2380"],"listen-peer-urls":["https://199.19.213.162:2380"],"advertise-client-urls":["https://199.19.213.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-06T00:59:01.005793Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-06T00:59:01.006148Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.162:2380"} {"level":"info","ts":"2026-08-06T00:59:01.006259Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.162:2380"} {"level":"info","ts":"2026-08-06T00:59:01.783097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 is starting a new election at term 1"} {"level":"info","ts":"2026-08-06T00:59:01.783294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-06T00:59:01.783324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 received MsgPreVoteResp from 8c0a14645b8502c5 at term 1"} {"level":"info","ts":"2026-08-06T00:59:01.783349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 became candidate at term 2"} {"level":"info","ts":"2026-08-06T00:59:01.783362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 received MsgVoteResp from 8c0a14645b8502c5 at term 2"} {"level":"info","ts":"2026-08-06T00:59:01.783387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 became leader at term 2"} {"level":"info","ts":"2026-08-06T00:59:01.783404Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8c0a14645b8502c5 elected leader 8c0a14645b8502c5 at term 2"} {"level":"info","ts":"2026-08-06T00:59:01.784442Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-06T00:59:01.785449Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8c0a14645b8502c5","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.162:2379]}","request-path":"/0/members/8c0a14645b8502c5/attributes","cluster-id":"49fe27222e1c6db4","publish-timeout":"7s"} {"level":"info","ts":"2026-08-06T00:59:01.785588Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49fe27222e1c6db4","local-member-id":"8c0a14645b8502c5","cluster-version":"3.5"} {"level":"info","ts":"2026-08-06T00:59:01.785705Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-06T00:59:01.785728Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-06T00:59:01.785748Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-06T00:59:01.785772Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-06T00:59:01.785876Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-06T00:59:01.785961Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-06T00:59:01.78912Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-06T00:59:01.789842Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.162:2379"} {"level":"info","ts":"2026-08-06T00:59:05.184261Z","caller":"traceutil/trace.go:171","msg":"trace[1721926985] transaction","detail":"{read_only:false; response_revision:218; number_of_response:1; }","duration":"159.084838ms","start":"2026-08-06T00:59:05.025144Z","end":"2026-08-06T00:59:05.184229Z","steps":["trace[1721926985] 'process raft request' (duration: 131.845464ms)","trace[1721926985] 'compare' (duration: 27.090016ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T00:59:05.458469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.682541ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-06T00:59:05.458676Z","caller":"traceutil/trace.go:171","msg":"trace[497670947] transaction","detail":"{read_only:false; response_revision:223; number_of_response:1; }","duration":"208.578634ms","start":"2026-08-06T00:59:05.250077Z","end":"2026-08-06T00:59:05.458656Z","steps":["trace[497670947] 'process raft request' (duration: 87.202184ms)","trace[497670947] 'compare' (duration: 120.520862ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T00:59:06.039488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.994372ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-06T00:59:06.039561Z","caller":"traceutil/trace.go:171","msg":"trace[1504218293] linearizableReadLoop","detail":"{readStateIndex:230; appliedIndex:229; }","duration":"128.140011ms","start":"2026-08-06T00:59:05.911409Z","end":"2026-08-06T00:59:06.039549Z","steps":["trace[1504218293] 'read index received' (duration: 35.082µs)","trace[1504218293] 'applied index is now lower than readState.Index' (duration: 128.104109ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T00:59:06.039635Z","caller":"traceutil/trace.go:171","msg":"trace[575875251] transaction","detail":"{read_only:false; response_revision:224; number_of_response:1; }","duration":"573.16236ms","start":"2026-08-06T00:59:05.466446Z","end":"2026-08-06T00:59:06.039608Z","steps":["trace[575875251] 'process raft request' (duration: 365.997328ms)","trace[575875251] 'compare' (duration: 206.834133ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T00:59:06.039691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.274699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T00:59:06.039712Z","caller":"traceutil/trace.go:171","msg":"trace[1985656325] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:224; }","duration":"128.331392ms","start":"2026-08-06T00:59:05.911372Z","end":"2026-08-06T00:59:06.039704Z","steps":["trace[1985656325] 'agreement among raft nodes before linearized reading' (duration: 128.241847ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:59:06.03971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.075847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T00:59:06.039775Z","caller":"traceutil/trace.go:171","msg":"trace[2014020230] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:224; }","duration":"110.170813ms","start":"2026-08-06T00:59:05.929586Z","end":"2026-08-06T00:59:06.039757Z","steps":["trace[2014020230] 'agreement among raft nodes before linearized reading' (duration: 110.074177ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:59:06.039728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:59:05.46642Z","time spent":"573.263625ms","remote":"127.0.0.1:41990","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-08-06T00:59:06.544873Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":199741493864036361,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T00:59:07.457964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.194598ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-06T00:59:07.458181Z","caller":"traceutil/trace.go:171","msg":"trace[597061147] linearizableReadLoop","detail":"{readStateIndex:232; appliedIndex:230; }","duration":"1.414102181s","start":"2026-08-06T00:59:06.044056Z","end":"2026-08-06T00:59:07.458159Z","steps":["trace[597061147] 'read index received' (duration: 749.693661ms)","trace[597061147] 'applied index is now lower than readState.Index' (duration: 664.40735ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T00:59:07.458406Z","caller":"traceutil/trace.go:171","msg":"trace[355865176] transaction","detail":"{read_only:false; response_revision:225; number_of_response:1; }","duration":"1.415096557s","start":"2026-08-06T00:59:06.043294Z","end":"2026-08-06T00:59:07.45839Z","steps":["trace[355865176] 'process raft request' (duration: 750.376769ms)","trace[355865176] 'compare' (duration: 663.814028ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T00:59:07.458476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:59:06.043274Z","time spent":"1.41516798s","remote":"127.0.0.1:41938","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-08-06T00:59:07.458728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.251449287s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T00:59:07.45882Z","caller":"traceutil/trace.go:171","msg":"trace[1284155885] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:226; }","duration":"1.251585724s","start":"2026-08-06T00:59:06.20721Z","end":"2026-08-06T00:59:07.458796Z","steps":["trace[1284155885] 'agreement among raft nodes before linearized reading' (duration: 1.251376723s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:59:07.458862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:59:06.207192Z","time spent":"1.251662058s","remote":"127.0.0.1:41630","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-08-06T00:59:07.458908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.414835801s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-08-06T00:59:07.458965Z","caller":"traceutil/trace.go:171","msg":"trace[343665622] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:226; }","duration":"1.414918986s","start":"2026-08-06T00:59:06.044028Z","end":"2026-08-06T00:59:07.458947Z","steps":["trace[343665622] 'agreement among raft nodes before linearized reading' (duration: 1.4148076s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:59:07.459011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:59:06.044012Z","time spent":"1.41498634s","remote":"127.0.0.1:41754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-08-06T00:59:07.459114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.681119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T00:59:07.459146Z","caller":"traceutil/trace.go:171","msg":"trace[1959388857] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:226; }","duration":"308.740433ms","start":"2026-08-06T00:59:07.150396Z","end":"2026-08-06T00:59:07.459136Z","steps":["trace[1959388857] 'agreement among raft nodes before linearized reading' (duration: 308.69332ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:59:07.459172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:59:07.15038Z","time spent":"308.786146ms","remote":"127.0.0.1:41938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-06T00:59:07.459206Z","caller":"traceutil/trace.go:171","msg":"trace[1783028043] transaction","detail":"{read_only:false; response_revision:226; number_of_response:1; }","duration":"1.415096146s","start":"2026-08-06T00:59:06.044094Z","end":"2026-08-06T00:59:07.45919Z","steps":["trace[1783028043] 'process raft request' (duration: 1.413988845s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:59:07.459308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.997827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-06T00:59:07.459289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:59:06.044077Z","time spent":"1.41517282s","remote":"127.0.0.1:41938","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-08-06T00:59:07.459341Z","caller":"traceutil/trace.go:171","msg":"trace[1120279195] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:226; }","duration":"333.05917ms","start":"2026-08-06T00:59:07.126269Z","end":"2026-08-06T00:59:07.459328Z","steps":["trace[1120279195] 'agreement among raft nodes before linearized reading' (duration: 333.016417ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:59:07.459368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:59:07.126251Z","time spent":"333.110392ms","remote":"127.0.0.1:41796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"warn","ts":"2026-08-06T00:59:07.964614Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":199741493864036366,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T00:59:08.465792Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":199741493864036366,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T00:59:08.572306Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.110145889s","expected-duration":"1s"} {"level":"info","ts":"2026-08-06T00:59:08.572903Z","caller":"traceutil/trace.go:171","msg":"trace[1338692875] transaction","detail":"{read_only:false; response_revision:227; number_of_response:1; }","duration":"1.110820876s","start":"2026-08-06T00:59:07.462056Z","end":"2026-08-06T00:59:08.572877Z","steps":["trace[1338692875] 'process raft request' (duration: 1.110664467s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:59:08.573062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:59:07.462039Z","time spent":"1.110957293s","remote":"127.0.0.1:41850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-06T00:59:08.58456Z","caller":"traceutil/trace.go:171","msg":"trace[1819209992] transaction","detail":"{read_only:false; response_revision:229; number_of_response:1; }","duration":"1.12024159s","start":"2026-08-06T00:59:07.464306Z","end":"2026-08-06T00:59:08.584547Z","steps":["trace[1819209992] 'process raft request' (duration: 1.120023148s)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:59:08.584604Z","caller":"traceutil/trace.go:171","msg":"trace[3201120] transaction","detail":"{read_only:false; response_revision:228; number_of_response:1; }","duration":"1.121701241s","start":"2026-08-06T00:59:07.462794Z","end":"2026-08-06T00:59:08.584495Z","steps":["trace[3201120] 'process raft request' (duration: 1.121348691s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:59:08.584632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:59:07.464287Z","time spent":"1.120318794s","remote":"127.0.0.1:41938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-06T00:59:08.584773Z","caller":"traceutil/trace.go:171","msg":"trace[2049609892] transaction","detail":"{read_only:false; response_revision:230; number_of_response:1; }","duration":"1.120390648s","start":"2026-08-06T00:59:07.464364Z","end":"2026-08-06T00:59:08.584755Z","steps":["trace[2049609892] 'process raft request' (duration: 1.120028988s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:59:08.584791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:59:07.462773Z","time spent":"1.121905772s","remote":"127.0.0.1:41990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":646,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-06T00:59:08.58482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:59:07.464344Z","time spent":"1.120453631s","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":753,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-06T00:59:08.584545Z","caller":"traceutil/trace.go:171","msg":"trace[1331345666] linearizableReadLoop","detail":"{readStateIndex:237; appliedIndex:232; }","duration":"1.120663393s","start":"2026-08-06T00:59:07.46383Z","end":"2026-08-06T00:59:08.584494Z","steps":["trace[1331345666] 'read index received' (duration: 1.108882339s)","trace[1331345666] 'applied index is now lower than readState.Index' (duration: 11.779794ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T00:59:08.585079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.121216263s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-06T00:59:08.585109Z","caller":"traceutil/trace.go:171","msg":"trace[2084233021] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:231; }","duration":"1.121252355s","start":"2026-08-06T00:59:07.463849Z","end":"2026-08-06T00:59:08.585101Z","steps":["trace[2084233021] 'agreement among raft nodes before linearized reading' (duration: 1.121151579s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:59:08.585129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:59:07.463766Z","time spent":"1.121358941s","remote":"127.0.0.1:41938","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-08-06T00:59:08.585277Z","caller":"traceutil/trace.go:171","msg":"trace[1345646626] transaction","detail":"{read_only:false; response_revision:231; number_of_response:1; }","duration":"563.948033ms","start":"2026-08-06T00:59:08.021317Z","end":"2026-08-06T00:59:08.585265Z","steps":["trace[1345646626] 'process raft request' (duration: 563.126438ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:59:08.585337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:59:08.021298Z","time spent":"564.016537ms","remote":"127.0.0.1:41850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-06T00:59:08.58539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"922.094732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T00:59:08.585419Z","caller":"traceutil/trace.go:171","msg":"trace[412084277] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:231; }","duration":"922.146495ms","start":"2026-08-06T00:59:07.663265Z","end":"2026-08-06T00:59:08.585412Z","steps":["trace[412084277] 'agreement among raft nodes before linearized reading' (duration: 922.110083ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:59:08.585449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:59:07.663252Z","time spent":"922.193028ms","remote":"127.0.0.1:41938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-06T00:59:08.585501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.121661658s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T00:59:08.585538Z","caller":"traceutil/trace.go:171","msg":"trace[2120184942] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:231; }","duration":"1.121725431s","start":"2026-08-06T00:59:07.463802Z","end":"2026-08-06T00:59:08.585528Z","steps":["trace[2120184942] 'agreement among raft nodes before linearized reading' (duration: 1.121650607s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:59:08.585562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:59:07.463795Z","time spent":"1.121761663s","remote":"127.0.0.1:41630","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-08-06T00:59:08.585566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.121394652s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-06T00:59:08.585803Z","caller":"traceutil/trace.go:171","msg":"trace[851635131] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:231; }","duration":"1.121625776s","start":"2026-08-06T00:59:07.464166Z","end":"2026-08-06T00:59:08.585792Z","steps":["trace[851635131] 'agreement among raft nodes before linearized reading' (duration: 1.121359471s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:59:08.58602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:59:07.464146Z","time spent":"1.121861559s","remote":"127.0.0.1:41938","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-08-06T00:59:10.351269Z","caller":"traceutil/trace.go:171","msg":"trace[787951543] linearizableReadLoop","detail":"{readStateIndex:278; appliedIndex:277; }","duration":"149.576167ms","start":"2026-08-06T00:59:10.201671Z","end":"2026-08-06T00:59:10.351247Z","steps":["trace[787951543] 'read index received' (duration: 149.390817ms)","trace[787951543] 'applied index is now lower than readState.Index' (duration: 184.41µs)"],"step_count":2} {"level":"warn","ts":"2026-08-06T00:59:10.351405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.712014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-08-06T00:59:10.351433Z","caller":"traceutil/trace.go:171","msg":"trace[749298460] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:271; }","duration":"149.784738ms","start":"2026-08-06T00:59:10.20164Z","end":"2026-08-06T00:59:10.351425Z","steps":["trace[749298460] 'agreement among raft nodes before linearized reading' (duration: 149.717315ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:59:10.351605Z","caller":"traceutil/trace.go:171","msg":"trace[859926344] transaction","detail":"{read_only:false; response_revision:271; number_of_response:1; }","duration":"200.244479ms","start":"2026-08-06T00:59:10.151323Z","end":"2026-08-06T00:59:10.351567Z","steps":["trace[859926344] 'process raft request' (duration: 199.796055ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:00:18.024599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.222509ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T01:00:18.024779Z","caller":"traceutil/trace.go:171","msg":"trace[264681729] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"143.187483ms","start":"2026-08-06T01:00:17.881577Z","end":"2026-08-06T01:00:18.024765Z","steps":["trace[264681729] 'process raft request' (duration: 15.621474ms)","trace[264681729] 'compare' (duration: 127.067074ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:00:18.024938Z","caller":"traceutil/trace.go:171","msg":"trace[403570582] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"141.334509ms","start":"2026-08-06T01:00:17.883598Z","end":"2026-08-06T01:00:18.024932Z","steps":["trace[403570582] 'process raft request' (duration: 141.299058ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:00:18.025038Z","caller":"traceutil/trace.go:171","msg":"trace[2016331403] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"142.156153ms","start":"2026-08-06T01:00:17.882877Z","end":"2026-08-06T01:00:18.025033Z","steps":["trace[2016331403] 'process raft request' (duration: 141.845074ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:00:22.883085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.925048ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-06T01:00:22.883259Z","caller":"traceutil/trace.go:171","msg":"trace[951345529] linearizableReadLoop","detail":"{readStateIndex:750; appliedIndex:748; }","duration":"167.149628ms","start":"2026-08-06T01:00:22.716092Z","end":"2026-08-06T01:00:22.883242Z","steps":["trace[951345529] 'read index received' (duration: 27.553595ms)","trace[951345529] 'applied index is now lower than readState.Index' (duration: 139.595123ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:00:22.883341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.25101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:00:22.883368Z","caller":"traceutil/trace.go:171","msg":"trace[1182695514] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:714; }","duration":"167.317642ms","start":"2026-08-06T01:00:22.716039Z","end":"2026-08-06T01:00:22.883357Z","steps":["trace[1182695514] 'agreement among raft nodes before linearized reading' (duration: 167.24745ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:00:22.883338Z","caller":"traceutil/trace.go:171","msg":"trace[257606350] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"314.05459ms","start":"2026-08-06T01:00:22.569249Z","end":"2026-08-06T01:00:22.883304Z","steps":["trace[257606350] 'process raft request' (duration: 114.839165ms)","trace[257606350] 'compare' (duration: 198.807174ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:00:22.883489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:00:22.569232Z","time spent":"314.209905ms","remote":"127.0.0.1:41994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1044,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-06T01:00:22.883549Z","caller":"traceutil/trace.go:171","msg":"trace[1496056200] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"307.467341ms","start":"2026-08-06T01:00:22.576071Z","end":"2026-08-06T01:00:22.883539Z","steps":["trace[1496056200] 'process raft request' (duration: 307.112101ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:00:22.88361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:00:22.576054Z","time spent":"307.520052ms","remote":"127.0.0.1:41938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:00:24.093592Z","caller":"traceutil/trace.go:171","msg":"trace[862947408] linearizableReadLoop","detail":"{readStateIndex:761; appliedIndex:760; }","duration":"164.441975ms","start":"2026-08-06T01:00:23.929121Z","end":"2026-08-06T01:00:24.093563Z","steps":["trace[862947408] 'read index received' (duration: 113.178651ms)","trace[862947408] 'applied index is now lower than readState.Index' (duration: 51.262154ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:00:24.093875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.714092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:00:24.09398Z","caller":"traceutil/trace.go:171","msg":"trace[2055091267] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:725; }","duration":"164.881876ms","start":"2026-08-06T01:00:23.929079Z","end":"2026-08-06T01:00:24.09396Z","steps":["trace[2055091267] 'agreement among raft nodes before linearized reading' (duration: 164.63309ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:00:24.094023Z","caller":"traceutil/trace.go:171","msg":"trace[1686342038] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"184.098209ms","start":"2026-08-06T01:00:23.909881Z","end":"2026-08-06T01:00:24.09398Z","steps":["trace[1686342038] 'process raft request' (duration: 132.490885ms)","trace[1686342038] 'compare' (duration: 50.943546ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:00:25.216264Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":199741493864037442,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T01:00:25.700079Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.256510117s","expected-duration":"1s"} {"level":"info","ts":"2026-08-06T01:00:25.701293Z","caller":"traceutil/trace.go:171","msg":"trace[1026580882] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"1.257778711s","start":"2026-08-06T01:00:24.443479Z","end":"2026-08-06T01:00:25.701258Z","steps":["trace[1026580882] 'process raft request' (duration: 1.257480063s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:00:25.701487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:00:24.443458Z","time spent":"1.257921515s","remote":"127.0.0.1:41938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:00:25.711796Z","caller":"traceutil/trace.go:171","msg":"trace[2031069167] linearizableReadLoop","detail":"{readStateIndex:765; appliedIndex:763; }","duration":"996.194955ms","start":"2026-08-06T01:00:24.715548Z","end":"2026-08-06T01:00:25.711742Z","steps":["trace[2031069167] 'read index received' (duration: 985.048807ms)","trace[2031069167] 'applied index is now lower than readState.Index' (duration: 11.145148ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:00:25.711856Z","caller":"traceutil/trace.go:171","msg":"trace[541794413] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"1.168712013s","start":"2026-08-06T01:00:24.543096Z","end":"2026-08-06T01:00:25.711808Z","steps":["trace[541794413] 'process raft request' (duration: 1.168095287s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:00:25.711945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"996.3832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-06T01:00:25.711998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.114127ms","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":"warn","ts":"2026-08-06T01:00:25.712022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:00:24.543057Z","time spent":"1.168879398s","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:00:25.712054Z","caller":"traceutil/trace.go:171","msg":"trace[169201960] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:728; }","duration":"788.199909ms","start":"2026-08-06T01:00:24.923841Z","end":"2026-08-06T01:00:25.712041Z","steps":["trace[169201960] 'agreement among raft nodes before linearized reading' (duration: 788.084286ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:00:25.712099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:00:24.923786Z","time spent":"788.302462ms","remote":"127.0.0.1:41904","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":"warn","ts":"2026-08-06T01:00:25.712114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.586469ms","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-08-06T01:00:25.712136Z","caller":"traceutil/trace.go:171","msg":"trace[455864389] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:728; }","duration":"708.60726ms","start":"2026-08-06T01:00:25.003522Z","end":"2026-08-06T01:00:25.712129Z","steps":["trace[455864389] 'agreement among raft nodes before linearized reading' (duration: 708.513727ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:00:25.712165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:00:25.003446Z","time spent":"708.714673ms","remote":"127.0.0.1:41938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-06T01:00:25.712009Z","caller":"traceutil/trace.go:171","msg":"trace[1423585348] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:728; }","duration":"996.448602ms","start":"2026-08-06T01:00:24.715542Z","end":"2026-08-06T01:00:25.711991Z","steps":["trace[1423585348] 'agreement among raft nodes before linearized reading' (duration: 996.338039ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:00:25.712361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:00:24.715485Z","time spent":"996.867714ms","remote":"127.0.0.1:41638","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-08-06T01:00:25.712373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.36233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:00:25.712408Z","caller":"traceutil/trace.go:171","msg":"trace[1187295562] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:728; }","duration":"746.429621ms","start":"2026-08-06T01:00:24.965968Z","end":"2026-08-06T01:00:25.712398Z","steps":["trace[1187295562] 'agreement among raft nodes before linearized reading' (duration: 746.365019ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:00:25.712433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:00:24.96595Z","time spent":"746.477403ms","remote":"127.0.0.1:41982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-08-06T01:00:25.712472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.960623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-06T01:00:25.712502Z","caller":"traceutil/trace.go:171","msg":"trace[1981697013] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:728; }","duration":"708.007774ms","start":"2026-08-06T01:00:25.004487Z","end":"2026-08-06T01:00:25.712495Z","steps":["trace[1981697013] 'agreement among raft nodes before linearized reading' (duration: 707.936402ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:00:25.712524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:00:25.004467Z","time spent":"708.052255ms","remote":"127.0.0.1:41938","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-08-06T01:00:25.71263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.454393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-06T01:00:25.712673Z","caller":"traceutil/trace.go:171","msg":"trace[1969701085] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:728; }","duration":"709.536255ms","start":"2026-08-06T01:00:25.003129Z","end":"2026-08-06T01:00:25.712665Z","steps":["trace[1969701085] 'agreement among raft nodes before linearized reading' (duration: 709.434122ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:00:25.712692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:00:25.00311Z","time spent":"709.577667ms","remote":"127.0.0.1:41938","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-08-06T01:00:25.712786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.324526ms","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-08-06T01:00:25.712865Z","caller":"traceutil/trace.go:171","msg":"trace[542651499] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:728; }","duration":"710.528592ms","start":"2026-08-06T01:00:25.002315Z","end":"2026-08-06T01:00:25.712843Z","steps":["trace[542651499] 'agreement among raft nodes before linearized reading' (duration: 710.174042ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:00:25.712906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:00:25.002285Z","time spent":"710.613104ms","remote":"127.0.0.1:41938","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-08-06T01:00:40.23312Z","caller":"traceutil/trace.go:171","msg":"trace[1199070897] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"113.415669ms","start":"2026-08-06T01:00:40.11966Z","end":"2026-08-06T01:00:40.233076Z","steps":["trace[1199070897] 'process raft request' (duration: 113.011129ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:00:41.148576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.753546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-08-06T01:00:41.148669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.988503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:00:41.148819Z","caller":"traceutil/trace.go:171","msg":"trace[395057741] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:892; }","duration":"431.258289ms","start":"2026-08-06T01:00:40.717537Z","end":"2026-08-06T01:00:41.148795Z","steps":["trace[395057741] 'range keys from in-memory index tree' (duration: 430.845739ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:00:41.148895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:00:40.717501Z","time spent":"431.382972ms","remote":"127.0.0.1:41630","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-08-06T01:00:41.148673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.195526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:00:41.149049Z","caller":"traceutil/trace.go:171","msg":"trace[1066830990] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:892; }","duration":"144.617766ms","start":"2026-08-06T01:00:41.004411Z","end":"2026-08-06T01:00:41.149029Z","steps":["trace[1066830990] 'count revisions from in-memory index tree' (duration: 144.082373ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:00:41.148725Z","caller":"traceutil/trace.go:171","msg":"trace[1569253213] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:892; }","duration":"112.952671ms","start":"2026-08-06T01:00:41.035746Z","end":"2026-08-06T01:00:41.148699Z","steps":["trace[1569253213] 'range keys from in-memory index tree' (duration: 112.52055ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:00:41.371552Z","caller":"traceutil/trace.go:171","msg":"trace[1361873944] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"217.525969ms","start":"2026-08-06T01:00:41.153999Z","end":"2026-08-06T01:00:41.371525Z","steps":["trace[1361873944] 'process raft request' (duration: 217.331394ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:00:41.398689Z","caller":"traceutil/trace.go:171","msg":"trace[281070309] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"119.969101ms","start":"2026-08-06T01:00:41.278656Z","end":"2026-08-06T01:00:41.398626Z","steps":["trace[281070309] 'process raft request' (duration: 119.821937ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:00:43.154542Z","caller":"traceutil/trace.go:171","msg":"trace[1504011059] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"104.648481ms","start":"2026-08-06T01:00:43.049855Z","end":"2026-08-06T01:00:43.154503Z","steps":["trace[1504011059] 'process raft request' (duration: 104.335853ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:00:53.788873Z","caller":"traceutil/trace.go:171","msg":"trace[1301974492] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"118.57956ms","start":"2026-08-06T01:00:53.670251Z","end":"2026-08-06T01:00:53.78883Z","steps":["trace[1301974492] 'process raft request' (duration: 118.369845ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:01:12.706765Z","caller":"traceutil/trace.go:171","msg":"trace[365072817] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"161.356758ms","start":"2026-08-06T01:01:12.545378Z","end":"2026-08-06T01:01:12.706735Z","steps":["trace[365072817] 'process raft request' (duration: 160.93884ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:01:13.857606Z","caller":"traceutil/trace.go:171","msg":"trace[1741027734] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"305.477953ms","start":"2026-08-06T01:01:13.5521Z","end":"2026-08-06T01:01:13.857578Z","steps":["trace[1741027734] 'process raft request' (duration: 301.398589ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:01:13.857779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:01:13.552072Z","time spent":"305.615536ms","remote":"127.0.0.1:41938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:01:13.864373Z","caller":"traceutil/trace.go:171","msg":"trace[411568617] linearizableReadLoop","detail":"{readStateIndex:1348; appliedIndex:1346; }","duration":"229.123794ms","start":"2026-08-06T01:01:13.635212Z","end":"2026-08-06T01:01:13.864336Z","steps":["trace[411568617] 'read index received' (duration: 218.300942ms)","trace[411568617] 'applied index is now lower than readState.Index' (duration: 10.821172ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:01:13.864442Z","caller":"traceutil/trace.go:171","msg":"trace[1742631091] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"289.012354ms","start":"2026-08-06T01:01:13.575388Z","end":"2026-08-06T01:01:13.8644Z","steps":["trace[1742631091] 'process raft request' (duration: 288.741679ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:01:13.864573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.172711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:01:13.864619Z","caller":"traceutil/trace.go:171","msg":"trace[767710709] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1301; }","duration":"147.281364ms","start":"2026-08-06T01:01:13.717324Z","end":"2026-08-06T01:01:13.864605Z","steps":["trace[767710709] 'agreement among raft nodes before linearized reading' (duration: 147.182632ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:01:13.864674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.440771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-06T01:01:13.864732Z","caller":"traceutil/trace.go:171","msg":"trace[898573558] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1301; }","duration":"229.577344ms","start":"2026-08-06T01:01:13.635142Z","end":"2026-08-06T01:01:13.864719Z","steps":["trace[898573558] 'agreement among raft nodes before linearized reading' (duration: 229.347439ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:04.53397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.174517ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-06T01:02:04.534137Z","caller":"traceutil/trace.go:171","msg":"trace[1876354688] linearizableReadLoop","detail":"{readStateIndex:2058; appliedIndex:2057; }","duration":"220.596224ms","start":"2026-08-06T01:02:04.31352Z","end":"2026-08-06T01:02:04.534116Z","steps":["trace[1876354688] 'read index received' (duration: 87.277164ms)","trace[1876354688] 'applied index is now lower than readState.Index' (duration: 133.31767ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:02:04.534374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.129526ms","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-08-06T01:02:04.534398Z","caller":"traceutil/trace.go:171","msg":"trace[1532744387] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1972; }","duration":"117.200408ms","start":"2026-08-06T01:02:04.41719Z","end":"2026-08-06T01:02:04.53439Z","steps":["trace[1532744387] 'agreement among raft nodes before linearized reading' (duration: 117.058815ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:04.53446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.89495ms","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-08-06T01:02:04.534528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.388247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-06T01:02:04.534582Z","caller":"traceutil/trace.go:171","msg":"trace[570128319] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1972; }","duration":"221.113713ms","start":"2026-08-06T01:02:04.313439Z","end":"2026-08-06T01:02:04.534552Z","steps":["trace[570128319] 'agreement among raft nodes before linearized reading' (duration: 220.754507ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:02:04.534585Z","caller":"traceutil/trace.go:171","msg":"trace[831212965] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1972; }","duration":"107.448138ms","start":"2026-08-06T01:02:04.427121Z","end":"2026-08-06T01:02:04.534569Z","steps":["trace[831212965] 'agreement among raft nodes before linearized reading' (duration: 107.284105ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:04.534561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.737412ms","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-08-06T01:02:04.534685Z","caller":"traceutil/trace.go:171","msg":"trace[754528083] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1972; }","duration":"107.926235ms","start":"2026-08-06T01:02:04.42674Z","end":"2026-08-06T01:02:04.534666Z","steps":["trace[754528083] 'agreement among raft nodes before linearized reading' (duration: 107.60672ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:04.534893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.087258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-06T01:02:04.535026Z","caller":"traceutil/trace.go:171","msg":"trace[2073975307] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1972; }","duration":"108.331083ms","start":"2026-08-06T01:02:04.426665Z","end":"2026-08-06T01:02:04.534996Z","steps":["trace[2073975307] 'agreement among raft nodes before linearized reading' (duration: 107.879235ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:05.168773Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":199741493864039942,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T01:02:05.390824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.286052ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T01:02:05.391173Z","caller":"traceutil/trace.go:171","msg":"trace[2028586403] transaction","detail":"{read_only:false; response_revision:1975; number_of_response:1; }","duration":"851.213146ms","start":"2026-08-06T01:02:04.539938Z","end":"2026-08-06T01:02:05.391151Z","steps":["trace[2028586403] 'process raft request' (duration: 851.008832ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:05.391287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:02:04.539898Z","time spent":"851.326577ms","remote":"127.0.0.1:41938","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-08-06T01:02:05.391247Z","caller":"traceutil/trace.go:171","msg":"trace[1485745909] transaction","detail":"{read_only:false; response_revision:1976; number_of_response:1; }","duration":"851.249616ms","start":"2026-08-06T01:02:04.539943Z","end":"2026-08-06T01:02:05.391193Z","steps":["trace[1485745909] 'process raft request' (duration: 851.059022ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:02:05.391345Z","caller":"traceutil/trace.go:171","msg":"trace[2141416008] transaction","detail":"{read_only:false; response_revision:1974; number_of_response:1; }","duration":"851.381418ms","start":"2026-08-06T01:02:04.539952Z","end":"2026-08-06T01:02:05.391333Z","steps":["trace[2141416008] 'process raft request' (duration: 670.489555ms)","trace[2141416008] 'compare' (duration: 180.14753ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:02:05.391295Z","caller":"traceutil/trace.go:171","msg":"trace[1936564810] transaction","detail":"{read_only:false; response_revision:1977; number_of_response:1; }","duration":"850.89313ms","start":"2026-08-06T01:02:04.540384Z","end":"2026-08-06T01:02:05.391277Z","steps":["trace[1936564810] 'process raft request' (duration: 850.701037ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:05.391425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:02:04.539934Z","time spent":"851.45086ms","remote":"127.0.0.1:41938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T01:02:05.391448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:02:04.539912Z","time spent":"851.428599ms","remote":"127.0.0.1:41938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T01:02:05.391686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:02:04.540373Z","time spent":"851.096043ms","remote":"127.0.0.1:41938","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-08-06T01:02:05.475243Z","caller":"traceutil/trace.go:171","msg":"trace[1966049013] linearizableReadLoop","detail":"{readStateIndex:2064; appliedIndex:2059; }","duration":"807.410727ms","start":"2026-08-06T01:02:04.667804Z","end":"2026-08-06T01:02:05.475214Z","steps":["trace[1966049013] 'read index received' (duration: 542.706113ms)","trace[1966049013] 'applied index is now lower than readState.Index' (duration: 264.703304ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:02:05.475399Z","caller":"traceutil/trace.go:171","msg":"trace[839122443] transaction","detail":"{read_only:false; response_revision:1978; number_of_response:1; }","duration":"548.756668ms","start":"2026-08-06T01:02:04.92663Z","end":"2026-08-06T01:02:05.475387Z","steps":["trace[839122443] 'process raft request' (duration: 548.409092ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:05.475682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:02:04.926593Z","time spent":"548.83862ms","remote":"127.0.0.1:41938","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-08-06T01:02:05.475982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.123339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f26f59f2-ac50-47c1-8e99-3eed47068309\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-06T01:02:05.476219Z","caller":"traceutil/trace.go:171","msg":"trace[945069001] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f26f59f2-ac50-47c1-8e99-3eed47068309; range_end:; response_count:1; response_revision:1978; }","duration":"808.419685ms","start":"2026-08-06T01:02:04.667732Z","end":"2026-08-06T01:02:05.476151Z","steps":["trace[945069001] 'agreement among raft nodes before linearized reading' (duration: 807.747103ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:05.476422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:02:04.667704Z","time spent":"808.69565ms","remote":"127.0.0.1:41850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f26f59f2-ac50-47c1-8e99-3eed47068309\" "} {"level":"warn","ts":"2026-08-06T01:02:06.33305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.644901ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T01:02:06.333182Z","caller":"traceutil/trace.go:171","msg":"trace[1664755466] linearizableReadLoop","detail":"{readStateIndex:2065; appliedIndex:2064; }","duration":"857.82626ms","start":"2026-08-06T01:02:05.475337Z","end":"2026-08-06T01:02:06.333164Z","steps":["trace[1664755466] 'read index received' (duration: 103.066827ms)","trace[1664755466] 'applied index is now lower than readState.Index' (duration: 754.757993ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:02:06.333278Z","caller":"traceutil/trace.go:171","msg":"trace[311103452] transaction","detail":"{read_only:false; response_revision:1979; number_of_response:1; }","duration":"1.112678602s","start":"2026-08-06T01:02:05.220588Z","end":"2026-08-06T01:02:06.333266Z","steps":["trace[311103452] 'process raft request' (duration: 357.715835ms)","trace[311103452] 'compare' (duration: 754.478489ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:02:06.333346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:02:05.220558Z","time spent":"1.112755093s","remote":"127.0.0.1:41938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T01:02:06.333607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.23071702s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T01:02:06.333651Z","caller":"traceutil/trace.go:171","msg":"trace[76262796] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1979; }","duration":"1.230802171s","start":"2026-08-06T01:02:05.102833Z","end":"2026-08-06T01:02:06.333636Z","steps":["trace[76262796] 'agreement among raft nodes before linearized reading' (duration: 1.23072872s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:06.333684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:02:05.10281Z","time spent":"1.230866572s","remote":"127.0.0.1:42134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":5,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-08-06T01:02:06.333939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.363391544s","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-08-06T01:02:06.333993Z","caller":"traceutil/trace.go:171","msg":"trace[207074298] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1979; }","duration":"1.363442444s","start":"2026-08-06T01:02:04.970536Z","end":"2026-08-06T01:02:06.333978Z","steps":["trace[207074298] 'agreement among raft nodes before linearized reading' (duration: 1.363289382s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:06.334038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:02:04.970453Z","time spent":"1.363570257s","remote":"127.0.0.1:41938","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":"warn","ts":"2026-08-06T01:02:06.334329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.429933496s","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-08-06T01:02:06.334369Z","caller":"traceutil/trace.go:171","msg":"trace[1873897797] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1979; }","duration":"1.430015357s","start":"2026-08-06T01:02:04.904343Z","end":"2026-08-06T01:02:06.334359Z","steps":["trace[1873897797] 'agreement among raft nodes before linearized reading' (duration: 1.429904255s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:06.334396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:02:04.904319Z","time spent":"1.430071079s","remote":"127.0.0.1:44060","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-08-06T01:02:06.335169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.641513452s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f26f59f2-ac50-47c1-8e99-3eed47068309\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-08-06T01:02:06.335349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.618336307s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:02:06.335451Z","caller":"traceutil/trace.go:171","msg":"trace[645340063] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1979; }","duration":"1.618572252s","start":"2026-08-06T01:02:04.716859Z","end":"2026-08-06T01:02:06.335431Z","steps":["trace[645340063] 'agreement among raft nodes before linearized reading' (duration: 1.618316057s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:06.335167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.665064074s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-06T01:02:06.335569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:02:04.716846Z","time spent":"1.618664493s","remote":"127.0.0.1:41630","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-08-06T01:02:06.335561Z","caller":"traceutil/trace.go:171","msg":"trace[1611228853] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1979; }","duration":"1.666361227s","start":"2026-08-06T01:02:04.669173Z","end":"2026-08-06T01:02:06.335534Z","steps":["trace[1611228853] 'agreement among raft nodes before linearized reading' (duration: 1.665050774s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:06.335717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:02:04.669148Z","time spent":"1.666536749s","remote":"127.0.0.1:34558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"info","ts":"2026-08-06T01:02:06.335844Z","caller":"traceutil/trace.go:171","msg":"trace[630711845] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f26f59f2-ac50-47c1-8e99-3eed47068309; range_end:; response_count:1; response_revision:1979; }","duration":"1.641735866s","start":"2026-08-06T01:02:04.693561Z","end":"2026-08-06T01:02:06.335297Z","steps":["trace[630711845] 'agreement among raft nodes before linearized reading' (duration: 1.64134718s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:06.335948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:02:04.693533Z","time spent":"1.642398777s","remote":"127.0.0.1:41850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f26f59f2-ac50-47c1-8e99-3eed47068309\" "} {"level":"warn","ts":"2026-08-06T01:02:06.67529Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.096883983s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-06T01:02:06.771197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.88472ms","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-08-06T01:02:06.771366Z","caller":"traceutil/trace.go:171","msg":"trace[1254852231] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1979; }","duration":"287.075303ms","start":"2026-08-06T01:02:06.484262Z","end":"2026-08-06T01:02:06.771337Z","steps":["trace[1254852231] 'range keys from in-memory index tree' (duration: 286.86672ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:02:06.771632Z","caller":"traceutil/trace.go:171","msg":"trace[2030300313] transaction","detail":"{read_only:false; response_revision:1980; number_of_response:1; }","duration":"1.285950056s","start":"2026-08-06T01:02:05.485656Z","end":"2026-08-06T01:02:06.771606Z","steps":["trace[2030300313] 'process raft request' (duration: 1.189977587s)","trace[2030300313] 'compare' (duration: 95.410309ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:02:06.772103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:02:05.485625Z","time spent":"1.286119068s","remote":"127.0.0.1:41850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3729,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:02:06.815006Z","caller":"traceutil/trace.go:171","msg":"trace[516553559] linearizableReadLoop","detail":"{readStateIndex:2068; appliedIndex:2065; }","duration":"481.766399ms","start":"2026-08-06T01:02:06.333215Z","end":"2026-08-06T01:02:06.814982Z","steps":["trace[516553559] 'read index received' (duration: 342.433956ms)","trace[516553559] 'applied index is now lower than readState.Index' (duration: 139.331273ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:02:06.815105Z","caller":"traceutil/trace.go:171","msg":"trace[1932658138] transaction","detail":"{read_only:false; response_revision:1982; number_of_response:1; }","duration":"744.023741ms","start":"2026-08-06T01:02:06.071046Z","end":"2026-08-06T01:02:06.81507Z","steps":["trace[1932658138] 'process raft request' (duration: 743.855678ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:02:06.815229Z","caller":"traceutil/trace.go:171","msg":"trace[243084085] transaction","detail":"{read_only:false; response_revision:1981; number_of_response:1; }","duration":"758.667115ms","start":"2026-08-06T01:02:06.056542Z","end":"2026-08-06T01:02:06.815209Z","steps":["trace[243084085] 'process raft request' (duration: 758.223167ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:06.815295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.557846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:02:06.815338Z","caller":"traceutil/trace.go:171","msg":"trace[2117671150] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1983; }","duration":"477.682678ms","start":"2026-08-06T01:02:06.337646Z","end":"2026-08-06T01:02:06.815328Z","steps":["trace[2117671150] 'agreement among raft nodes before linearized reading' (duration: 477.598297ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:06.815365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:02:06.337612Z","time spent":"477.743699ms","remote":"127.0.0.1:41638","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-08-06T01:02:06.81536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:02:06.071012Z","time spent":"744.175713ms","remote":"127.0.0.1:41938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T01:02:06.815377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:02:06.056512Z","time spent":"758.778017ms","remote":"127.0.0.1:41938","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-08-06T01:02:06.815594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.867102ms","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-08-06T01:02:06.81563Z","caller":"traceutil/trace.go:171","msg":"trace[201948901] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1983; }","duration":"197.943482ms","start":"2026-08-06T01:02:06.617676Z","end":"2026-08-06T01:02:06.81562Z","steps":["trace[201948901] 'agreement among raft nodes before linearized reading' (duration: 197.79833ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:06.815669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.32909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-08-06T01:02:06.815142Z","caller":"traceutil/trace.go:171","msg":"trace[285044169] transaction","detail":"{read_only:false; response_revision:1983; number_of_response:1; }","duration":"470.813799ms","start":"2026-08-06T01:02:06.344318Z","end":"2026-08-06T01:02:06.815131Z","steps":["trace[285044169] 'process raft request' (duration: 470.624096ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:06.815668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.824887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-06T01:02:06.815757Z","caller":"traceutil/trace.go:171","msg":"trace[2042764709] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1983; }","duration":"651.989979ms","start":"2026-08-06T01:02:06.163745Z","end":"2026-08-06T01:02:06.815735Z","steps":["trace[2042764709] 'agreement among raft nodes before linearized reading' (duration: 651.640673ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:06.815783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.38941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-06T01:02:06.815801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:02:06.163717Z","time spent":"652.073451ms","remote":"127.0.0.1:41938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-06T01:02:06.815693Z","caller":"traceutil/trace.go:171","msg":"trace[1397808439] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1983; }","duration":"451.39401ms","start":"2026-08-06T01:02:06.36429Z","end":"2026-08-06T01:02:06.815684Z","steps":["trace[1397808439] 'agreement among raft nodes before linearized reading' (duration: 451.319349ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:06.815843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:02:06.364273Z","time spent":"451.562914ms","remote":"127.0.0.1:41938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-08-06T01:02:06.815988Z","caller":"traceutil/trace.go:171","msg":"trace[61026194] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1983; }","duration":"575.037842ms","start":"2026-08-06T01:02:06.240765Z","end":"2026-08-06T01:02:06.815803Z","steps":["trace[61026194] 'agreement among raft nodes before linearized reading' (duration: 574.39846ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:06.816002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:02:06.344295Z","time spent":"471.45094ms","remote":"127.0.0.1:41938","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-08-06T01:02:06.816022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:02:06.240737Z","time spent":"575.275085ms","remote":"127.0.0.1:34606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"info","ts":"2026-08-06T01:02:15.008384Z","caller":"traceutil/trace.go:171","msg":"trace[537702164] linearizableReadLoop","detail":"{readStateIndex:2152; appliedIndex:2151; }","duration":"143.910272ms","start":"2026-08-06T01:02:14.864442Z","end":"2026-08-06T01:02:15.008352Z","steps":["trace[537702164] 'read index received' (duration: 127.941239ms)","trace[537702164] 'applied index is now lower than readState.Index' (duration: 15.967973ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:02:15.008586Z","caller":"traceutil/trace.go:171","msg":"trace[1080118834] transaction","detail":"{read_only:false; response_revision:2063; number_of_response:1; }","duration":"160.19038ms","start":"2026-08-06T01:02:14.84835Z","end":"2026-08-06T01:02:15.00854Z","steps":["trace[1080118834] 'process raft request' (duration: 144.018304ms)","trace[1080118834] 'compare' (duration: 15.81588ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:02:15.008767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.307539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-06T01:02:15.008814Z","caller":"traceutil/trace.go:171","msg":"trace[762772758] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2063; }","duration":"144.417181ms","start":"2026-08-06T01:02:14.864383Z","end":"2026-08-06T01:02:15.0088Z","steps":["trace[762772758] 'agreement among raft nodes before linearized reading' (duration: 144.146866ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:15.008887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.418166ms","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-08-06T01:02:15.009002Z","caller":"traceutil/trace.go:171","msg":"trace[1880344302] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2063; }","duration":"132.592589ms","start":"2026-08-06T01:02:14.876387Z","end":"2026-08-06T01:02:15.00898Z","steps":["trace[1880344302] 'agreement among raft nodes before linearized reading' (duration: 132.334314ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:02:21.047624Z","caller":"traceutil/trace.go:171","msg":"trace[1261508220] linearizableReadLoop","detail":"{readStateIndex:2199; appliedIndex:2198; }","duration":"132.530031ms","start":"2026-08-06T01:02:20.915069Z","end":"2026-08-06T01:02:21.047599Z","steps":["trace[1261508220] 'read index received' (duration: 132.375088ms)","trace[1261508220] 'applied index is now lower than readState.Index' (duration: 153.723µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:02:21.047728Z","caller":"traceutil/trace.go:171","msg":"trace[1307084625] transaction","detail":"{read_only:false; response_revision:2108; number_of_response:1; }","duration":"169.593997ms","start":"2026-08-06T01:02:20.878097Z","end":"2026-08-06T01:02:21.047691Z","steps":["trace[1307084625] 'process raft request' (duration: 169.303703ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:21.047994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.693284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:02:21.048061Z","caller":"traceutil/trace.go:171","msg":"trace[1294849243] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:2108; }","duration":"133.036069ms","start":"2026-08-06T01:02:20.915008Z","end":"2026-08-06T01:02:21.048044Z","steps":["trace[1294849243] 'agreement among raft nodes before linearized reading' (duration: 132.717414ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:02:39.785081Z","caller":"traceutil/trace.go:171","msg":"trace[896094040] linearizableReadLoop","detail":"{readStateIndex:2316; appliedIndex:2314; }","duration":"166.177597ms","start":"2026-08-06T01:02:39.618879Z","end":"2026-08-06T01:02:39.785057Z","steps":["trace[896094040] 'read index received' (duration: 53.132052ms)","trace[896094040] 'applied index is now lower than readState.Index' (duration: 113.044325ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:02:39.78523Z","caller":"traceutil/trace.go:171","msg":"trace[1039359603] transaction","detail":"{read_only:false; response_revision:2221; number_of_response:1; }","duration":"167.074462ms","start":"2026-08-06T01:02:39.618146Z","end":"2026-08-06T01:02:39.78522Z","steps":["trace[1039359603] 'process raft request' (duration: 158.857447ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:39.78548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.579124ms","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-08-06T01:02:39.785658Z","caller":"traceutil/trace.go:171","msg":"trace[1437302461] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2221; }","duration":"166.668605ms","start":"2026-08-06T01:02:39.618872Z","end":"2026-08-06T01:02:39.78554Z","steps":["trace[1437302461] 'agreement among raft nodes before linearized reading' (duration: 166.37124ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:39.825124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.739733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-06T01:02:39.825216Z","caller":"traceutil/trace.go:171","msg":"trace[676908092] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2222; }","duration":"203.887616ms","start":"2026-08-06T01:02:39.621313Z","end":"2026-08-06T01:02:39.8252Z","steps":["trace[676908092] 'agreement among raft nodes before linearized reading' (duration: 203.558311ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:39.825187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.753718ms","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-08-06T01:02:39.825436Z","caller":"traceutil/trace.go:171","msg":"trace[625379609] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2222; }","duration":"200.038352ms","start":"2026-08-06T01:02:39.625368Z","end":"2026-08-06T01:02:39.825407Z","steps":["trace[625379609] 'agreement among raft nodes before linearized reading' (duration: 199.584355ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:02:41.254328Z","caller":"traceutil/trace.go:171","msg":"trace[1852543523] transaction","detail":"{read_only:false; response_revision:2226; number_of_response:1; }","duration":"130.472605ms","start":"2026-08-06T01:02:41.123518Z","end":"2026-08-06T01:02:41.25399Z","steps":["trace[1852543523] 'process raft request' (duration: 130.282202ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:02:41.320278Z","caller":"traceutil/trace.go:171","msg":"trace[2114492457] linearizableReadLoop","detail":"{readStateIndex:2322; appliedIndex:2320; }","duration":"137.832536ms","start":"2026-08-06T01:02:41.182417Z","end":"2026-08-06T01:02:41.320249Z","steps":["trace[2114492457] 'read index received' (duration: 71.248596ms)","trace[2114492457] 'applied index is now lower than readState.Index' (duration: 66.58305ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:02:41.320588Z","caller":"traceutil/trace.go:171","msg":"trace[848039729] transaction","detail":"{read_only:false; response_revision:2227; number_of_response:1; }","duration":"194.097598ms","start":"2026-08-06T01:02:41.126441Z","end":"2026-08-06T01:02:41.320539Z","steps":["trace[848039729] 'process raft request' (duration: 148.125855ms)","trace[848039729] 'compare' (duration: 45.566456ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:02:41.320894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.482127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-06T01:02:41.320966Z","caller":"traceutil/trace.go:171","msg":"trace[2130647193] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2227; }","duration":"138.61646ms","start":"2026-08-06T01:02:41.182333Z","end":"2026-08-06T01:02:41.32095Z","steps":["trace[2130647193] 'agreement among raft nodes before linearized reading' (duration: 138.379446ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:41.320914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.172575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-06T01:02:41.321009Z","caller":"traceutil/trace.go:171","msg":"trace[470018786] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2227; }","duration":"137.324438ms","start":"2026-08-06T01:02:41.183673Z","end":"2026-08-06T01:02:41.320998Z","steps":["trace[470018786] 'agreement among raft nodes before linearized reading' (duration: 137.036594ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:41.690337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.828834ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T01:02:41.690458Z","caller":"traceutil/trace.go:171","msg":"trace[1228690690] linearizableReadLoop","detail":"{readStateIndex:2324; appliedIndex:2323; }","duration":"285.103027ms","start":"2026-08-06T01:02:41.405338Z","end":"2026-08-06T01:02:41.690441Z","steps":["trace[1228690690] 'read index received' (duration: 50.038769ms)","trace[1228690690] 'applied index is now lower than readState.Index' (duration: 235.062628ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:02:41.690544Z","caller":"traceutil/trace.go:171","msg":"trace[1138258836] transaction","detail":"{read_only:false; response_revision:2229; number_of_response:1; }","duration":"365.174188ms","start":"2026-08-06T01:02:41.325275Z","end":"2026-08-06T01:02:41.690449Z","steps":["trace[1138258836] 'process raft request' (duration: 130.134201ms)","trace[1138258836] 'compare' (duration: 234.58441ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:02:41.691225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:02:41.325247Z","time spent":"365.872069ms","remote":"127.0.0.1:41938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T01:02:41.690663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.28938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:02:41.691394Z","caller":"traceutil/trace.go:171","msg":"trace[1249621919] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:2229; }","duration":"286.104643ms","start":"2026-08-06T01:02:41.405263Z","end":"2026-08-06T01:02:41.691367Z","steps":["trace[1249621919] 'agreement among raft nodes before linearized reading' (duration: 285.235299ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:41.691539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.18489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:02:41.691673Z","caller":"traceutil/trace.go:171","msg":"trace[491864198] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:2229; }","duration":"257.639238ms","start":"2026-08-06T01:02:41.434014Z","end":"2026-08-06T01:02:41.691654Z","steps":["trace[491864198] 'agreement among raft nodes before linearized reading' (duration: 257.176291ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:41.691577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.660754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T01:02:41.691893Z","caller":"traceutil/trace.go:171","msg":"trace[1608438081] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:2229; }","duration":"178.99712ms","start":"2026-08-06T01:02:41.51286Z","end":"2026-08-06T01:02:41.691857Z","steps":["trace[1608438081] 'agreement among raft nodes before linearized reading' (duration: 178.493742ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:02:41.859812Z","caller":"traceutil/trace.go:171","msg":"trace[97719212] transaction","detail":"{read_only:false; response_revision:2230; number_of_response:1; }","duration":"162.935277ms","start":"2026-08-06T01:02:41.696839Z","end":"2026-08-06T01:02:41.859774Z","steps":["trace[97719212] 'process raft request' (duration: 112.911099ms)","trace[97719212] 'compare' (duration: 49.669273ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:02:41.917007Z","caller":"traceutil/trace.go:171","msg":"trace[1120899002] transaction","detail":"{read_only:false; response_revision:2231; number_of_response:1; }","duration":"125.84073ms","start":"2026-08-06T01:02:41.791144Z","end":"2026-08-06T01:02:41.916985Z","steps":["trace[1120899002] 'process raft request' (duration: 125.690308ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:42.267434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.196348ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T01:02:42.267926Z","caller":"traceutil/trace.go:171","msg":"trace[1647262613] transaction","detail":"{read_only:false; response_revision:2233; number_of_response:1; }","duration":"345.100284ms","start":"2026-08-06T01:02:41.922803Z","end":"2026-08-06T01:02:42.267903Z","steps":["trace[1647262613] 'process raft request' (duration: 344.81347ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:42.268075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:02:41.922761Z","time spent":"345.215965ms","remote":"127.0.0.1:41938","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-08-06T01:02:42.26823Z","caller":"traceutil/trace.go:171","msg":"trace[110013475] transaction","detail":"{read_only:false; response_revision:2234; number_of_response:1; }","duration":"344.677127ms","start":"2026-08-06T01:02:41.923524Z","end":"2026-08-06T01:02:42.268201Z","steps":["trace[110013475] 'process raft request' (duration: 344.327531ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:42.268405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:02:41.923498Z","time spent":"344.812219ms","remote":"127.0.0.1:41938","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-08-06T01:02:42.268639Z","caller":"traceutil/trace.go:171","msg":"trace[1577471423] transaction","detail":"{read_only:false; response_revision:2232; number_of_response:1; }","duration":"345.816206ms","start":"2026-08-06T01:02:41.922736Z","end":"2026-08-06T01:02:42.268552Z","steps":["trace[1577471423] 'process raft request' (duration: 41.414738ms)","trace[1577471423] 'compare' (duration: 302.930174ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:02:42.269221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:02:41.922694Z","time spent":"346.435015ms","remote":"127.0.0.1:41938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T01:02:42.818237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.250535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:02:42.818348Z","caller":"traceutil/trace.go:171","msg":"trace[1675570254] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2234; }","duration":"104.422257ms","start":"2026-08-06T01:02:42.713901Z","end":"2026-08-06T01:02:42.818324Z","steps":["trace[1675570254] 'range keys from in-memory index tree' (duration: 104.083951ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:42.818729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.050166ms","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":"warn","ts":"2026-08-06T01:02:42.818806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.52468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:02:42.818865Z","caller":"traceutil/trace.go:171","msg":"trace[68354175] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2234; }","duration":"119.32775ms","start":"2026-08-06T01:02:42.699507Z","end":"2026-08-06T01:02:42.818835Z","steps":["trace[68354175] 'range keys from in-memory index tree' (duration: 118.520217ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:02:42.818936Z","caller":"traceutil/trace.go:171","msg":"trace[776303183] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2234; }","duration":"183.749774ms","start":"2026-08-06T01:02:42.635156Z","end":"2026-08-06T01:02:42.818906Z","steps":["trace[776303183] 'range keys from in-memory index tree' (duration: 183.398818ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:43.858412Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":199741493864040557,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T01:02:44.359395Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":199741493864040557,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T01:02:44.502397Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.241812525s","expected-duration":"1s"} {"level":"info","ts":"2026-08-06T01:02:44.502867Z","caller":"traceutil/trace.go:171","msg":"trace[440681219] transaction","detail":"{read_only:false; response_revision:2236; number_of_response:1; }","duration":"1.242400704s","start":"2026-08-06T01:02:43.260436Z","end":"2026-08-06T01:02:44.502837Z","steps":["trace[440681219] 'process raft request' (duration: 1.242190711s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:44.503055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:02:43.26041Z","time spent":"1.242540976s","remote":"127.0.0.1:41938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T01:02:45.04348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.999799ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T01:02:45.043849Z","caller":"traceutil/trace.go:171","msg":"trace[391708439] transaction","detail":"{read_only:false; response_revision:2237; number_of_response:1; }","duration":"1.716647485s","start":"2026-08-06T01:02:43.32718Z","end":"2026-08-06T01:02:45.043827Z","steps":["trace[391708439] 'process raft request' (duration: 1.34523285s)","trace[391708439] 'compare' (duration: 370.636123ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:02:45.044195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:02:43.327139Z","time spent":"1.717019692s","remote":"127.0.0.1:41938","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-08-06T01:02:45.063546Z","caller":"traceutil/trace.go:171","msg":"trace[246012763] transaction","detail":"{read_only:false; response_revision:2238; number_of_response:1; }","duration":"1.138564727s","start":"2026-08-06T01:02:43.924961Z","end":"2026-08-06T01:02:45.063526Z","steps":["trace[246012763] 'process raft request' (duration: 1.138318093s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:45.063741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.705832028s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-08-06T01:02:45.063663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:02:43.924928Z","time spent":"1.138666788s","remote":"127.0.0.1:41938","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-08-06T01:02:45.063496Z","caller":"traceutil/trace.go:171","msg":"trace[940213369] linearizableReadLoop","detail":"{readStateIndex:2334; appliedIndex:2330; }","duration":"1.705552994s","start":"2026-08-06T01:02:43.357916Z","end":"2026-08-06T01:02:45.063469Z","steps":["trace[940213369] 'read index received' (duration: 1.144770201s)","trace[940213369] 'applied index is now lower than readState.Index' (duration: 560.781503ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:02:45.063836Z","caller":"traceutil/trace.go:171","msg":"trace[802872127] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2238; }","duration":"1.705977501s","start":"2026-08-06T01:02:43.357835Z","end":"2026-08-06T01:02:45.063812Z","steps":["trace[802872127] 'agreement among raft nodes before linearized reading' (duration: 1.705750937s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:45.064137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.346353999s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:02:45.064174Z","caller":"traceutil/trace.go:171","msg":"trace[1408524316] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2238; }","duration":"1.34643315s","start":"2026-08-06T01:02:43.717731Z","end":"2026-08-06T01:02:45.064164Z","steps":["trace[1408524316] 'agreement among raft nodes before linearized reading' (duration: 1.34637622s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:45.064204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:02:43.717706Z","time spent":"1.346491571s","remote":"127.0.0.1:41630","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-08-06T01:02:45.064218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.238228154s","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":"warn","ts":"2026-08-06T01:02:45.064216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.199348109s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-06T01:02:45.064262Z","caller":"traceutil/trace.go:171","msg":"trace[1662641493] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2238; }","duration":"1.238302575s","start":"2026-08-06T01:02:43.825941Z","end":"2026-08-06T01:02:45.064243Z","steps":["trace[1662641493] 'agreement among raft nodes before linearized reading' (duration: 1.238172623s)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:02:45.06427Z","caller":"traceutil/trace.go:171","msg":"trace[1889035973] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2238; }","duration":"1.199478021s","start":"2026-08-06T01:02:43.864775Z","end":"2026-08-06T01:02:45.064253Z","steps":["trace[1889035973] 'agreement among raft nodes before linearized reading' (duration: 1.199330629s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:45.064295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:02:43.825917Z","time spent":"1.238369046s","remote":"127.0.0.1:44060","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-08-06T01:02:45.06432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:02:43.864748Z","time spent":"1.199559453s","remote":"127.0.0.1:41938","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-08-06T01:02:45.06415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.508183462s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T01:02:45.064465Z","caller":"traceutil/trace.go:171","msg":"trace[1939842110] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2238; }","duration":"1.508553738s","start":"2026-08-06T01:02:43.555892Z","end":"2026-08-06T01:02:45.064446Z","steps":["trace[1939842110] 'agreement among raft nodes before linearized reading' (duration: 1.508183432s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:45.06462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:02:43.555871Z","time spent":"1.5086816s","remote":"127.0.0.1:42100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-08-06T01:02:45.064744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.021094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-06T01:02:45.064999Z","caller":"traceutil/trace.go:171","msg":"trace[1340498397] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2238; }","duration":"792.315169ms","start":"2026-08-06T01:02:44.272667Z","end":"2026-08-06T01:02:45.064982Z","steps":["trace[1340498397] 'agreement among raft nodes before linearized reading' (duration: 791.880872ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:45.065054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:02:44.272622Z","time spent":"792.415991ms","remote":"127.0.0.1:41938","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-08-06T01:02:45.065265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:02:43.357806Z","time spent":"1.707439585s","remote":"127.0.0.1:41938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-06T01:02:45.065575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.753796ms","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-08-06T01:02:45.064499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.367465704s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-06T01:02:45.065679Z","caller":"traceutil/trace.go:171","msg":"trace[360320420] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2238; }","duration":"792.965539ms","start":"2026-08-06T01:02:44.272692Z","end":"2026-08-06T01:02:45.065658Z","steps":["trace[360320420] 'agreement among raft nodes before linearized reading' (duration: 791.83547ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:45.064759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.062652ms","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-08-06T01:02:45.065747Z","caller":"traceutil/trace.go:171","msg":"trace[1505290304] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2238; }","duration":"791.096599ms","start":"2026-08-06T01:02:44.274637Z","end":"2026-08-06T01:02:45.065734Z","steps":["trace[1505290304] 'agreement among raft nodes before linearized reading' (duration: 790.052812ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:02:45.065737Z","caller":"traceutil/trace.go:171","msg":"trace[1859593621] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2238; }","duration":"1.368748915s","start":"2026-08-06T01:02:43.696971Z","end":"2026-08-06T01:02:45.06572Z","steps":["trace[1859593621] 'agreement among raft nodes before linearized reading' (duration: 1.367458614s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:45.065795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:02:44.274614Z","time spent":"791.16588ms","remote":"127.0.0.1:41938","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-08-06T01:02:45.065778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:02:44.27268Z","time spent":"793.080471ms","remote":"127.0.0.1:41938","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-08-06T01:02:45.065809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:02:43.696931Z","time spent":"1.368855327s","remote":"127.0.0.1:41938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-06T01:02:45.704792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.253367ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T01:02:45.704924Z","caller":"traceutil/trace.go:171","msg":"trace[153522456] transaction","detail":"{read_only:false; response_revision:2239; number_of_response:1; }","duration":"634.563285ms","start":"2026-08-06T01:02:45.070339Z","end":"2026-08-06T01:02:45.704902Z","steps":["trace[153522456] 'process raft request' (duration: 395.087604ms)","trace[153522456] 'compare' (duration: 238.758489ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:02:45.704983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:02:45.070306Z","time spent":"634.650736ms","remote":"127.0.0.1:41938","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-08-06T01:02:45.707248Z","caller":"traceutil/trace.go:171","msg":"trace[728895754] transaction","detail":"{read_only:false; response_revision:2240; number_of_response:1; }","duration":"636.510806ms","start":"2026-08-06T01:02:45.070703Z","end":"2026-08-06T01:02:45.707214Z","steps":["trace[728895754] 'process raft request' (duration: 636.283702ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:02:45.707529Z","caller":"traceutil/trace.go:171","msg":"trace[1956506753] linearizableReadLoop","detail":"{readStateIndex:2340; appliedIndex:2334; }","duration":"632.355899ms","start":"2026-08-06T01:02:45.075152Z","end":"2026-08-06T01:02:45.707508Z","steps":["trace[1956506753] 'read index received' (duration: 390.450389ms)","trace[1956506753] 'applied index is now lower than readState.Index' (duration: 241.9043ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:02:45.707556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:02:45.070692Z","time spent":"636.640698ms","remote":"127.0.0.1:41938","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-08-06T01:02:45.707651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.500911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:02:45.707694Z","caller":"traceutil/trace.go:171","msg":"trace[234547688] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2244; }","duration":"632.588043ms","start":"2026-08-06T01:02:45.075089Z","end":"2026-08-06T01:02:45.707677Z","steps":["trace[234547688] 'agreement among raft nodes before linearized reading' (duration: 632.496051ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:45.707725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:02:45.075067Z","time spent":"632.651313ms","remote":"127.0.0.1:41630","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-08-06T01:02:45.70791Z","caller":"traceutil/trace.go:171","msg":"trace[1411440083] transaction","detail":"{read_only:false; response_revision:2243; number_of_response:1; }","duration":"635.819355ms","start":"2026-08-06T01:02:45.072079Z","end":"2026-08-06T01:02:45.707898Z","steps":["trace[1411440083] 'process raft request' (duration: 635.332567ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:45.707987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:02:45.072067Z","time spent":"635.874756ms","remote":"127.0.0.1:41938","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-08-06T01:02:45.708078Z","caller":"traceutil/trace.go:171","msg":"trace[427939978] transaction","detail":"{read_only:false; response_revision:2244; number_of_response:1; }","duration":"635.391718ms","start":"2026-08-06T01:02:45.072674Z","end":"2026-08-06T01:02:45.708065Z","steps":["trace[427939978] 'process raft request' (duration: 634.794828ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:02:45.707848Z","caller":"traceutil/trace.go:171","msg":"trace[468284379] transaction","detail":"{read_only:false; response_revision:2242; number_of_response:1; }","duration":"636.083959ms","start":"2026-08-06T01:02:45.071743Z","end":"2026-08-06T01:02:45.707827Z","steps":["trace[468284379] 'process raft request' (duration: 635.586301ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:45.708318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:02:45.071738Z","time spent":"636.522296ms","remote":"127.0.0.1:41938","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-08-06T01:02:45.708134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:02:45.072664Z","time spent":"635.438758ms","remote":"127.0.0.1:41938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:02:45.70835Z","caller":"traceutil/trace.go:171","msg":"trace[500652536] transaction","detail":"{read_only:false; response_revision:2241; number_of_response:1; }","duration":"636.639039ms","start":"2026-08-06T01:02:45.07166Z","end":"2026-08-06T01:02:45.708299Z","steps":["trace[500652536] 'process raft request' (duration: 635.5061ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:45.708639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:02:45.071634Z","time spent":"636.829461ms","remote":"127.0.0.1:41938","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-08-06T01:04:30.454201Z","caller":"traceutil/trace.go:171","msg":"trace[546622546] transaction","detail":"{read_only:false; response_revision:2893; number_of_response:1; }","duration":"164.785097ms","start":"2026-08-06T01:04:30.289384Z","end":"2026-08-06T01:04:30.454169Z","steps":["trace[546622546] 'process raft request' (duration: 164.5687ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:04:30.456112Z","caller":"traceutil/trace.go:171","msg":"trace[685992678] linearizableReadLoop","detail":"{readStateIndex:3013; appliedIndex:3011; }","duration":"165.539072ms","start":"2026-08-06T01:04:30.290536Z","end":"2026-08-06T01:04:30.456075Z","steps":["trace[685992678] 'read index received' (duration: 163.425382ms)","trace[685992678] 'applied index is now lower than readState.Index' (duration: 2.11222ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:04:30.456268Z","caller":"traceutil/trace.go:171","msg":"trace[555192969] transaction","detail":"{read_only:false; response_revision:2894; number_of_response:1; }","duration":"166.240485ms","start":"2026-08-06T01:04:30.289967Z","end":"2026-08-06T01:04:30.456207Z","steps":["trace[555192969] 'process raft request' (duration: 165.926545ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:04:30.456397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.817171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-06T01:04:30.456435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.264029ms","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-08-06T01:04:30.456482Z","caller":"traceutil/trace.go:171","msg":"trace[147229523] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2894; }","duration":"165.929654ms","start":"2026-08-06T01:04:30.290516Z","end":"2026-08-06T01:04:30.456446Z","steps":["trace[147229523] 'agreement among raft nodes before linearized reading' (duration: 165.686686ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:04:30.456525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.584636ms","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-08-06T01:04:30.456565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.626008ms","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-08-06T01:04:30.45659Z","caller":"traceutil/trace.go:171","msg":"trace[1843676193] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2894; }","duration":"163.651649ms","start":"2026-08-06T01:04:30.292932Z","end":"2026-08-06T01:04:30.456584Z","steps":["trace[1843676193] 'agreement among raft nodes before linearized reading' (duration: 163.589607ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:04:30.456486Z","caller":"traceutil/trace.go:171","msg":"trace[1812750336] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2894; }","duration":"158.334292ms","start":"2026-08-06T01:04:30.298135Z","end":"2026-08-06T01:04:30.45647Z","steps":["trace[1812750336] 'agreement among raft nodes before linearized reading' (duration: 158.197038ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:04:30.456602Z","caller":"traceutil/trace.go:171","msg":"trace[1348675927] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2894; }","duration":"163.698621ms","start":"2026-08-06T01:04:30.29288Z","end":"2026-08-06T01:04:30.456579Z","steps":["trace[1348675927] 'agreement among raft nodes before linearized reading' (duration: 163.428312ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:05:07.880047Z","caller":"traceutil/trace.go:171","msg":"trace[96091263] transaction","detail":"{read_only:false; response_revision:3099; number_of_response:1; }","duration":"191.141657ms","start":"2026-08-06T01:05:07.688853Z","end":"2026-08-06T01:05:07.879995Z","steps":["trace[96091263] 'process raft request' (duration: 190.953182ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:05:07.880621Z","caller":"traceutil/trace.go:171","msg":"trace[955942730] linearizableReadLoop","detail":"{readStateIndex:3226; appliedIndex:3224; }","duration":"163.286446ms","start":"2026-08-06T01:05:07.717304Z","end":"2026-08-06T01:05:07.880591Z","steps":["trace[955942730] 'read index received' (duration: 162.484042ms)","trace[955942730] 'applied index is now lower than readState.Index' (duration: 800.804µs)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:05:07.881154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.836272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:05:07.881213Z","caller":"traceutil/trace.go:171","msg":"trace[548527449] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3100; }","duration":"163.953575ms","start":"2026-08-06T01:05:07.717237Z","end":"2026-08-06T01:05:07.88119Z","steps":["trace[548527449] 'agreement among raft nodes before linearized reading' (duration: 163.77501ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:05:07.881374Z","caller":"traceutil/trace.go:171","msg":"trace[993380947] transaction","detail":"{read_only:false; response_revision:3100; number_of_response:1; }","duration":"190.424466ms","start":"2026-08-06T01:05:07.690939Z","end":"2026-08-06T01:05:07.881363Z","steps":["trace[993380947] 'process raft request' (duration: 189.452738ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:05:07.881531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.884033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:05:07.881566Z","caller":"traceutil/trace.go:171","msg":"trace[1994521448] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:3100; }","duration":"163.941385ms","start":"2026-08-06T01:05:07.717615Z","end":"2026-08-06T01:05:07.881556Z","steps":["trace[1994521448] 'agreement among raft nodes before linearized reading' (duration: 163.883613ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:05:07.881601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.947355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:05:07.881798Z","caller":"traceutil/trace.go:171","msg":"trace[1121199883] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:3100; }","duration":"164.191262ms","start":"2026-08-06T01:05:07.717539Z","end":"2026-08-06T01:05:07.88173Z","steps":["trace[1121199883] 'agreement among raft nodes before linearized reading' (duration: 163.840302ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:06:28.388921Z","caller":"traceutil/trace.go:171","msg":"trace[1982494765] transaction","detail":"{read_only:false; response_revision:3521; number_of_response:1; }","duration":"170.808406ms","start":"2026-08-06T01:06:28.218078Z","end":"2026-08-06T01:06:28.388886Z","steps":["trace[1982494765] 'process raft request' (duration: 170.15107ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:54.260761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.391816ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-06T01:06:54.260918Z","caller":"traceutil/trace.go:171","msg":"trace[229653304] linearizableReadLoop","detail":"{readStateIndex:3801; appliedIndex:3800; }","duration":"138.590392ms","start":"2026-08-06T01:06:54.122302Z","end":"2026-08-06T01:06:54.260893Z","steps":["trace[229653304] 'read index received' (duration: 43.321µs)","trace[229653304] 'applied index is now lower than readState.Index' (duration: 138.545191ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:06:54.261121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.799667ms","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-08-06T01:06:54.26116Z","caller":"traceutil/trace.go:171","msg":"trace[1168400021] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3652; }","duration":"138.883079ms","start":"2026-08-06T01:06:54.122264Z","end":"2026-08-06T01:06:54.261147Z","steps":["trace[1168400021] 'agreement among raft nodes before linearized reading' (duration: 138.692415ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:07:03.445863Z","caller":"traceutil/trace.go:171","msg":"trace[813508406] transaction","detail":"{read_only:false; response_revision:3694; number_of_response:1; }","duration":"147.054851ms","start":"2026-08-06T01:07:03.298774Z","end":"2026-08-06T01:07:03.445829Z","steps":["trace[813508406] 'process raft request' (duration: 146.834086ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:07:03.525316Z","caller":"traceutil/trace.go:171","msg":"trace[353276994] linearizableReadLoop","detail":"{readStateIndex:3845; appliedIndex:3843; }","duration":"108.818196ms","start":"2026-08-06T01:07:03.416468Z","end":"2026-08-06T01:07:03.525286Z","steps":["trace[353276994] 'read index received' (duration: 29.147196ms)","trace[353276994] 'applied index is now lower than readState.Index' (duration: 79.66989ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:07:03.525997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.504772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-06T01:07:03.526077Z","caller":"traceutil/trace.go:171","msg":"trace[547699397] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3695; }","duration":"109.637436ms","start":"2026-08-06T01:07:03.416414Z","end":"2026-08-06T01:07:03.526051Z","steps":["trace[547699397] 'agreement among raft nodes before linearized reading' (duration: 109.484062ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:07:03.527131Z","caller":"traceutil/trace.go:171","msg":"trace[1801787505] transaction","detail":"{read_only:false; response_revision:3695; number_of_response:1; }","duration":"179.908331ms","start":"2026-08-06T01:07:03.347206Z","end":"2026-08-06T01:07:03.527114Z","steps":["trace[1801787505] 'process raft request' (duration: 127.943424ms)","trace[1801787505] 'compare' (duration: 49.989292ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:07:04.78561Z","caller":"traceutil/trace.go:171","msg":"trace[1659638097] transaction","detail":"{read_only:false; response_revision:3704; number_of_response:1; }","duration":"102.56299ms","start":"2026-08-06T01:07:04.683016Z","end":"2026-08-06T01:07:04.785579Z","steps":["trace[1659638097] 'process raft request' (duration: 62.592178ms)","trace[1659638097] 'compare' (duration: 39.240385ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:07:05.676041Z","caller":"traceutil/trace.go:171","msg":"trace[951002963] linearizableReadLoop","detail":"{readStateIndex:3856; appliedIndex:3855; }","duration":"104.175423ms","start":"2026-08-06T01:07:05.571841Z","end":"2026-08-06T01:07:05.676016Z","steps":["trace[951002963] 'read index received' (duration: 104.04929ms)","trace[951002963] 'applied index is now lower than readState.Index' (duration: 124.793µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:07:05.676083Z","caller":"traceutil/trace.go:171","msg":"trace[567244688] transaction","detail":"{read_only:false; response_revision:3705; number_of_response:1; }","duration":"105.921543ms","start":"2026-08-06T01:07:05.570127Z","end":"2026-08-06T01:07:05.676049Z","steps":["trace[567244688] 'process raft request' (duration: 105.707468ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:07:05.676326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.453929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-06T01:07:05.67637Z","caller":"traceutil/trace.go:171","msg":"trace[2141995754] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3705; }","duration":"104.545012ms","start":"2026-08-06T01:07:05.571815Z","end":"2026-08-06T01:07:05.67636Z","steps":["trace[2141995754] 'agreement among raft nodes before linearized reading' (duration: 104.321937ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:07:05.679655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.54046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-06T01:07:05.679725Z","caller":"traceutil/trace.go:171","msg":"trace[840600757] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3707; }","duration":"107.672043ms","start":"2026-08-06T01:07:05.572033Z","end":"2026-08-06T01:07:05.679705Z","steps":["trace[840600757] 'agreement among raft nodes before linearized reading' (duration: 107.453498ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:07:05.679978Z","caller":"traceutil/trace.go:171","msg":"trace[211011146] transaction","detail":"{read_only:false; response_revision:3706; number_of_response:1; }","duration":"108.032631ms","start":"2026-08-06T01:07:05.571932Z","end":"2026-08-06T01:07:05.679965Z","steps":["trace[211011146] 'process raft request' (duration: 107.420907ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:07:05.851286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.372099ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T01:07:05.851841Z","caller":"traceutil/trace.go:171","msg":"trace[997581227] transaction","detail":"{read_only:false; response_revision:3708; number_of_response:1; }","duration":"169.957873ms","start":"2026-08-06T01:07:05.681824Z","end":"2026-08-06T01:07:05.851782Z","steps":["trace[997581227] 'process raft request' (duration: 42.939059ms)","trace[997581227] 'compare' (duration: 126.245596ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:07:05.852154Z","caller":"traceutil/trace.go:171","msg":"trace[1497937281] linearizableReadLoop","detail":"{readStateIndex:3860; appliedIndex:3858; }","duration":"166.013433ms","start":"2026-08-06T01:07:05.686118Z","end":"2026-08-06T01:07:05.852131Z","steps":["trace[1497937281] 'read index received' (duration: 38.775854ms)","trace[1497937281] 'applied index is now lower than readState.Index' (duration: 127.235989ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:07:05.852179Z","caller":"traceutil/trace.go:171","msg":"trace[1331767042] transaction","detail":"{read_only:false; response_revision:3709; number_of_response:1; }","duration":"167.532927ms","start":"2026-08-06T01:07:05.684607Z","end":"2026-08-06T01:07:05.85214Z","steps":["trace[1331767042] 'process raft request' (duration: 166.930543ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:07:05.852241Z","caller":"traceutil/trace.go:171","msg":"trace[961168766] transaction","detail":"{read_only:false; response_revision:3710; number_of_response:1; }","duration":"165.713816ms","start":"2026-08-06T01:07:05.686466Z","end":"2026-08-06T01:07:05.85218Z","steps":["trace[961168766] 'process raft request' (duration: 165.242335ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:07:05.8526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.472483ms","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-08-06T01:07:05.853793Z","caller":"traceutil/trace.go:171","msg":"trace[1400518085] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3710; }","duration":"167.715461ms","start":"2026-08-06T01:07:05.686056Z","end":"2026-08-06T01:07:05.853771Z","steps":["trace[1400518085] 'agreement among raft nodes before linearized reading' (duration: 166.219597ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:07:06.003383Z","caller":"traceutil/trace.go:171","msg":"trace[1112603405] transaction","detail":"{read_only:false; response_revision:3711; number_of_response:1; }","duration":"145.636948ms","start":"2026-08-06T01:07:05.857711Z","end":"2026-08-06T01:07:06.003347Z","steps":["trace[1112603405] 'process raft request' (duration: 111.830108ms)","trace[1112603405] 'compare' (duration: 33.595355ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:07:21.855142Z","caller":"traceutil/trace.go:171","msg":"trace[253986245] transaction","detail":"{read_only:false; response_revision:3874; number_of_response:1; }","duration":"107.643956ms","start":"2026-08-06T01:07:21.747453Z","end":"2026-08-06T01:07:21.855097Z","steps":["trace[253986245] 'process raft request' (duration: 106.866419ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:07:22.12332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.842025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-08-06T01:07:22.12343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.978727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-06T01:07:22.123512Z","caller":"traceutil/trace.go:171","msg":"trace[707103640] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3876; }","duration":"142.07274ms","start":"2026-08-06T01:07:21.981403Z","end":"2026-08-06T01:07:22.123475Z","steps":["trace[707103640] 'range keys from in-memory index tree' (duration: 141.65063ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:07:22.123534Z","caller":"traceutil/trace.go:171","msg":"trace[1724020646] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3876; }","duration":"142.12686ms","start":"2026-08-06T01:07:21.98138Z","end":"2026-08-06T01:07:22.123507Z","steps":["trace[1724020646] 'range keys from in-memory index tree' (duration: 141.717082ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:07:22.124731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.904708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-06T01:07:22.124781Z","caller":"traceutil/trace.go:171","msg":"trace[502683671] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3876; }","duration":"142.969729ms","start":"2026-08-06T01:07:21.9818Z","end":"2026-08-06T01:07:22.12477Z","steps":["trace[502683671] 'range keys from in-memory index tree' (duration: 142.728694ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:07:22.280366Z","caller":"traceutil/trace.go:171","msg":"trace[1716252741] linearizableReadLoop","detail":"{readStateIndex:4038; appliedIndex:4033; }","duration":"129.237843ms","start":"2026-08-06T01:07:22.151097Z","end":"2026-08-06T01:07:22.280335Z","steps":["trace[1716252741] 'read index received' (duration: 2.503536ms)","trace[1716252741] 'applied index is now lower than readState.Index' (duration: 126.733237ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:07:22.280494Z","caller":"traceutil/trace.go:171","msg":"trace[520534218] transaction","detail":"{read_only:false; response_revision:3879; number_of_response:1; }","duration":"152.01202ms","start":"2026-08-06T01:07:22.128469Z","end":"2026-08-06T01:07:22.280481Z","steps":["trace[520534218] 'process raft request' (duration: 151.699343ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:07:22.280662Z","caller":"traceutil/trace.go:171","msg":"trace[1859622798] transaction","detail":"{read_only:false; response_revision:3880; number_of_response:1; }","duration":"152.100852ms","start":"2026-08-06T01:07:22.128527Z","end":"2026-08-06T01:07:22.280627Z","steps":["trace[1859622798] 'process raft request' (duration: 151.725473ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:07:22.280702Z","caller":"traceutil/trace.go:171","msg":"trace[1195275052] transaction","detail":"{read_only:false; response_revision:3878; number_of_response:1; }","duration":"152.347857ms","start":"2026-08-06T01:07:22.128294Z","end":"2026-08-06T01:07:22.280642Z","steps":["trace[1195275052] 'process raft request' (duration: 151.189431ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:07:22.280811Z","caller":"traceutil/trace.go:171","msg":"trace[833063945] transaction","detail":"{read_only:false; response_revision:3881; number_of_response:1; }","duration":"149.669977ms","start":"2026-08-06T01:07:22.131074Z","end":"2026-08-06T01:07:22.280744Z","steps":["trace[833063945] 'process raft request' (duration: 149.219727ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:07:22.281224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.080992ms","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-08-06T01:07:22.281292Z","caller":"traceutil/trace.go:171","msg":"trace[814972523] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3881; }","duration":"130.186794ms","start":"2026-08-06T01:07:22.15109Z","end":"2026-08-06T01:07:22.281277Z","steps":["trace[814972523] 'agreement among raft nodes before linearized reading' (duration: 129.357126ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:07:43.074372Z","caller":"traceutil/trace.go:171","msg":"trace[2068539436] transaction","detail":"{read_only:false; response_revision:3999; number_of_response:1; }","duration":"135.214099ms","start":"2026-08-06T01:07:42.939124Z","end":"2026-08-06T01:07:43.074338Z","steps":["trace[2068539436] 'process raft request' (duration: 134.922622ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:07:58.569386Z","caller":"traceutil/trace.go:171","msg":"trace[282211463] linearizableReadLoop","detail":"{readStateIndex:4244; appliedIndex:4243; }","duration":"116.326386ms","start":"2026-08-06T01:07:58.453026Z","end":"2026-08-06T01:07:58.569352Z","steps":["trace[282211463] 'read index received' (duration: 72.471723ms)","trace[282211463] 'applied index is now lower than readState.Index' (duration: 43.853693ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:07:58.569814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.742925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:07:58.569951Z","caller":"traceutil/trace.go:171","msg":"trace[2019258892] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:4080; }","duration":"116.875658ms","start":"2026-08-06T01:07:58.453017Z","end":"2026-08-06T01:07:58.569893Z","steps":["trace[2019258892] 'agreement among raft nodes before linearized reading' (duration: 116.562561ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:07:58.569804Z","caller":"traceutil/trace.go:171","msg":"trace[939275405] transaction","detail":"{read_only:false; response_revision:4080; number_of_response:1; }","duration":"249.542771ms","start":"2026-08-06T01:07:58.320219Z","end":"2026-08-06T01:07:58.569761Z","steps":["trace[939275405] 'process raft request' (duration: 205.3201ms)","trace[939275405] 'compare' (duration: 43.352932ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:07:58.860818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.242754ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T01:07:58.861121Z","caller":"traceutil/trace.go:171","msg":"trace[590449773] transaction","detail":"{read_only:false; response_revision:4082; number_of_response:1; }","duration":"284.944725ms","start":"2026-08-06T01:07:58.576137Z","end":"2026-08-06T01:07:58.861082Z","steps":["trace[590449773] 'process raft request' (duration: 152.265531ms)","trace[590449773] 'compare' (duration: 131.948658ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:07:58.861372Z","caller":"traceutil/trace.go:171","msg":"trace[1597008326] transaction","detail":"{read_only:false; response_revision:4083; number_of_response:1; }","duration":"283.861262ms","start":"2026-08-06T01:07:58.577474Z","end":"2026-08-06T01:07:58.861335Z","steps":["trace[1597008326] 'process raft request' (duration: 283.472994ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:07:58.861771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.363226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.162\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-06T01:07:58.861562Z","caller":"traceutil/trace.go:171","msg":"trace[1430281210] linearizableReadLoop","detail":"{readStateIndex:4247; appliedIndex:4245; }","duration":"123.137331ms","start":"2026-08-06T01:07:58.738401Z","end":"2026-08-06T01:07:58.861539Z","steps":["trace[1430281210] 'read index received' (duration: 69.991µs)","trace[1430281210] 'applied index is now lower than readState.Index' (duration: 123.06455ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:07:58.86182Z","caller":"traceutil/trace.go:171","msg":"trace[1466046115] range","detail":"{range_begin:/registry/masterleases/199.19.213.162; range_end:; response_count:1; response_revision:4084; }","duration":"123.483908ms","start":"2026-08-06T01:07:58.738324Z","end":"2026-08-06T01:07:58.861808Z","steps":["trace[1466046115] 'agreement among raft nodes before linearized reading' (duration: 123.302264ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:07:58.862077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.762065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T01:07:58.862194Z","caller":"traceutil/trace.go:171","msg":"trace[1138870045] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:4084; }","duration":"100.957529ms","start":"2026-08-06T01:07:58.761212Z","end":"2026-08-06T01:07:58.86217Z","steps":["trace[1138870045] 'agreement among raft nodes before linearized reading' (duration: 100.734584ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:08:21.500939Z","caller":"traceutil/trace.go:171","msg":"trace[1122280029] transaction","detail":"{read_only:false; response_revision:4235; number_of_response:1; }","duration":"205.124768ms","start":"2026-08-06T01:08:21.295772Z","end":"2026-08-06T01:08:21.500896Z","steps":["trace[1122280029] 'process raft request' (duration: 204.907063ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:08:22.158101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.92625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/alertmanagers.monitoring.coreos.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:08:22.158261Z","caller":"traceutil/trace.go:171","msg":"trace[646731460] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/alertmanagers.monitoring.coreos.com; range_end:; response_count:0; response_revision:4237; }","duration":"154.144545ms","start":"2026-08-06T01:08:22.004087Z","end":"2026-08-06T01:08:22.158232Z","steps":["trace[646731460] 'range keys from in-memory index tree' (duration: 153.800307ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:08:22.159973Z","caller":"traceutil/trace.go:171","msg":"trace[2001555477] transaction","detail":"{read_only:false; response_revision:4238; number_of_response:1; }","duration":"142.306968ms","start":"2026-08-06T01:08:22.017643Z","end":"2026-08-06T01:08:22.15995Z","steps":["trace[2001555477] 'process raft request' (duration: 142.009532ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:08:22.583245Z","caller":"traceutil/trace.go:171","msg":"trace[1458870812] transaction","detail":"{read_only:false; response_revision:4244; number_of_response:1; }","duration":"223.21286ms","start":"2026-08-06T01:08:22.359987Z","end":"2026-08-06T01:08:22.5832Z","steps":["trace[1458870812] 'process raft request' (duration: 223.027386ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:08:22.583512Z","caller":"traceutil/trace.go:171","msg":"trace[825057337] transaction","detail":"{read_only:false; response_revision:4246; number_of_response:1; }","duration":"197.480515ms","start":"2026-08-06T01:08:22.386021Z","end":"2026-08-06T01:08:22.583502Z","steps":["trace[825057337] 'process raft request' (duration: 197.105648ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:08:22.583295Z","caller":"traceutil/trace.go:171","msg":"trace[909392982] transaction","detail":"{read_only:false; response_revision:4243; number_of_response:1; }","duration":"223.325692ms","start":"2026-08-06T01:08:22.359923Z","end":"2026-08-06T01:08:22.583249Z","steps":["trace[909392982] 'process raft request' (duration: 192.880529ms)","trace[909392982] 'compare' (duration: 30.033795ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:08:22.583754Z","caller":"traceutil/trace.go:171","msg":"trace[579426392] transaction","detail":"{read_only:false; response_revision:4245; number_of_response:1; }","duration":"220.433932ms","start":"2026-08-06T01:08:22.362838Z","end":"2026-08-06T01:08:22.583272Z","steps":["trace[579426392] 'process raft request' (duration: 220.238128ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:08:22.584085Z","caller":"traceutil/trace.go:171","msg":"trace[1925431209] linearizableReadLoop","detail":"{readStateIndex:4420; appliedIndex:4416; }","duration":"192.291797ms","start":"2026-08-06T01:08:22.391775Z","end":"2026-08-06T01:08:22.584067Z","steps":["trace[1925431209] 'read index received' (duration: 161.16931ms)","trace[1925431209] 'applied index is now lower than readState.Index' (duration: 31.121467ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:08:22.584223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.4164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/podmonitors.monitoring.coreos.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:08:22.584228Z","caller":"traceutil/trace.go:171","msg":"trace[2143929210] transaction","detail":"{read_only:false; response_revision:4247; number_of_response:1; }","duration":"141.963321ms","start":"2026-08-06T01:08:22.442228Z","end":"2026-08-06T01:08:22.584191Z","steps":["trace[2143929210] 'process raft request' (duration: 141.335388ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:08:22.584272Z","caller":"traceutil/trace.go:171","msg":"trace[1828345233] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/podmonitors.monitoring.coreos.com; range_end:; response_count:0; response_revision:4247; }","duration":"192.516182ms","start":"2026-08-06T01:08:22.391739Z","end":"2026-08-06T01:08:22.584255Z","steps":["trace[1828345233] 'agreement among raft nodes before linearized reading' (duration: 192.39427ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:08:23.115111Z","caller":"traceutil/trace.go:171","msg":"trace[1569556746] linearizableReadLoop","detail":"{readStateIndex:4434; appliedIndex:4432; }","duration":"100.864715ms","start":"2026-08-06T01:08:23.014217Z","end":"2026-08-06T01:08:23.115082Z","steps":["trace[1569556746] 'read index received' (duration: 90.564771ms)","trace[1569556746] 'applied index is now lower than readState.Index' (duration: 10.298544ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:08:23.115279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.020968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/prometheuses.monitoring.coreos.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:08:23.115328Z","caller":"traceutil/trace.go:171","msg":"trace[2055554614] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/prometheuses.monitoring.coreos.com; range_end:; response_count:0; response_revision:4260; }","duration":"101.13349ms","start":"2026-08-06T01:08:23.01418Z","end":"2026-08-06T01:08:23.115314Z","steps":["trace[2055554614] 'agreement among raft nodes before linearized reading' (duration: 101.018347ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:08:23.115394Z","caller":"traceutil/trace.go:171","msg":"trace[1084670721] transaction","detail":"{read_only:false; response_revision:4259; number_of_response:1; }","duration":"108.893611ms","start":"2026-08-06T01:08:23.006437Z","end":"2026-08-06T01:08:23.11533Z","steps":["trace[1084670721] 'process raft request' (duration: 98.177899ms)","trace[1084670721] 'compare' (duration: 10.226692ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:08:23.115641Z","caller":"traceutil/trace.go:171","msg":"trace[1337907697] transaction","detail":"{read_only:false; response_revision:4260; number_of_response:1; }","duration":"105.171204ms","start":"2026-08-06T01:08:23.010402Z","end":"2026-08-06T01:08:23.115573Z","steps":["trace[1337907697] 'process raft request' (duration: 104.634723ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:08:40.361625Z","caller":"traceutil/trace.go:171","msg":"trace[191028744] transaction","detail":"{read_only:false; response_revision:4373; number_of_response:1; }","duration":"104.769144ms","start":"2026-08-06T01:08:40.256821Z","end":"2026-08-06T01:08:40.36159Z","steps":["trace[191028744] 'process raft request' (duration: 104.550739ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:08:42.417298Z","caller":"traceutil/trace.go:171","msg":"trace[1605094782] transaction","detail":"{read_only:false; response_revision:4385; number_of_response:1; }","duration":"127.035366ms","start":"2026-08-06T01:08:42.290236Z","end":"2026-08-06T01:08:42.417271Z","steps":["trace[1605094782] 'process raft request' (duration: 126.887353ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:09:01.816048Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2647} {"level":"info","ts":"2026-08-06T01:09:01.908287Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2647,"took":"90.941993ms","hash":4016817033,"current-db-size-bytes":40845312,"current-db-size":"41 MB","current-db-size-in-use-bytes":16433152,"current-db-size-in-use":"16 MB"} {"level":"info","ts":"2026-08-06T01:09:01.908409Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4016817033,"revision":2647,"compact-revision":-1} {"level":"info","ts":"2026-08-06T01:09:18.70655Z","caller":"traceutil/trace.go:171","msg":"trace[1351145668] transaction","detail":"{read_only:false; response_revision:4934; number_of_response:1; }","duration":"136.718435ms","start":"2026-08-06T01:09:18.569782Z","end":"2026-08-06T01:09:18.7065Z","steps":["trace[1351145668] 'process raft request' (duration: 133.816937ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:23.14253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.455214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5e9894fb-ce12-49d5-9e9d-b1e9e26a8b72\" ","response":"range_response_count:1 size:3774"} {"level":"info","ts":"2026-08-06T01:09:23.142668Z","caller":"traceutil/trace.go:171","msg":"trace[1600982668] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5e9894fb-ce12-49d5-9e9d-b1e9e26a8b72; range_end:; response_count:1; response_revision:5045; }","duration":"289.629477ms","start":"2026-08-06T01:09:22.853012Z","end":"2026-08-06T01:09:23.142642Z","steps":["trace[1600982668] 'range keys from in-memory index tree' (duration: 289.28411ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:09:23.180447Z","caller":"traceutil/trace.go:171","msg":"trace[571111759] linearizableReadLoop","detail":"{readStateIndex:5252; appliedIndex:5251; }","duration":"274.670399ms","start":"2026-08-06T01:09:22.905741Z","end":"2026-08-06T01:09:23.180411Z","steps":["trace[571111759] 'read index received' (duration: 274.437074ms)","trace[571111759] 'applied index is now lower than readState.Index' (duration: 229.465µs)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:09:23.181955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.192879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207418"} {"level":"info","ts":"2026-08-06T01:09:23.182033Z","caller":"traceutil/trace.go:171","msg":"trace[1144334666] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5046; }","duration":"276.344292ms","start":"2026-08-06T01:09:22.90567Z","end":"2026-08-06T01:09:23.182014Z","steps":["trace[1144334666] 'agreement among raft nodes before linearized reading' (duration: 274.909843ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:23.18264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.0456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5e9894fb-ce12-49d5-9e9d-b1e9e26a8b72\" ","response":"range_response_count:1 size:3774"} {"level":"info","ts":"2026-08-06T01:09:23.182762Z","caller":"traceutil/trace.go:171","msg":"trace[2118657180] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5e9894fb-ce12-49d5-9e9d-b1e9e26a8b72; range_end:; response_count:1; response_revision:5046; }","duration":"245.236354ms","start":"2026-08-06T01:09:22.9375Z","end":"2026-08-06T01:09:23.182736Z","steps":["trace[2118657180] 'agreement among raft nodes before linearized reading' (duration: 244.932518ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:23.18345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.521077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-06T01:09:23.183539Z","caller":"traceutil/trace.go:171","msg":"trace[1520940920] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5046; }","duration":"183.642619ms","start":"2026-08-06T01:09:22.999882Z","end":"2026-08-06T01:09:23.183524Z","steps":["trace[1520940920] 'agreement among raft nodes before linearized reading' (duration: 183.401294ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:09:23.183776Z","caller":"traceutil/trace.go:171","msg":"trace[114926332] transaction","detail":"{read_only:false; response_revision:5046; number_of_response:1; }","duration":"290.776109ms","start":"2026-08-06T01:09:22.892984Z","end":"2026-08-06T01:09:23.18376Z","steps":["trace[114926332] 'process raft request' (duration: 287.290089ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:09:23.31937Z","caller":"traceutil/trace.go:171","msg":"trace[1940384324] transaction","detail":"{read_only:false; response_revision:5048; number_of_response:1; }","duration":"118.23129ms","start":"2026-08-06T01:09:23.201092Z","end":"2026-08-06T01:09:23.319324Z","steps":["trace[1940384324] 'process raft request' (duration: 94.776274ms)","trace[1940384324] 'compare' (duration: 22.864884ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:09:23.323646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.825434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-08-06T01:09:23.323728Z","caller":"traceutil/trace.go:171","msg":"trace[279829468] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5049; }","duration":"101.943916ms","start":"2026-08-06T01:09:23.221765Z","end":"2026-08-06T01:09:23.323709Z","steps":["trace[279829468] 'agreement among raft nodes before linearized reading' (duration: 101.690911ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:09:25.089203Z","caller":"traceutil/trace.go:171","msg":"trace[1272045233] transaction","detail":"{read_only:false; response_revision:5071; number_of_response:1; }","duration":"126.535682ms","start":"2026-08-06T01:09:24.962638Z","end":"2026-08-06T01:09:25.089174Z","steps":["trace[1272045233] 'process raft request' (duration: 126.313568ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:09:26.419264Z","caller":"traceutil/trace.go:171","msg":"trace[540009277] linearizableReadLoop","detail":"{readStateIndex:5290; appliedIndex:5290; }","duration":"215.691998ms","start":"2026-08-06T01:09:26.203548Z","end":"2026-08-06T01:09:26.41924Z","steps":["trace[540009277] 'read index received' (duration: 215.684098ms)","trace[540009277] 'applied index is now lower than readState.Index' (duration: 6.08µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:09:26.419388Z","caller":"traceutil/trace.go:171","msg":"trace[676563264] transaction","detail":"{read_only:false; response_revision:5083; number_of_response:1; }","duration":"249.378137ms","start":"2026-08-06T01:09:26.169996Z","end":"2026-08-06T01:09:26.419374Z","steps":["trace[676563264] 'process raft request' (duration: 248.984179ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:26.419475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.888852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-28ca8186-00e6-43e5-9d7a-9d5afa11ba7a\" ","response":"range_response_count:1 size:4063"} {"level":"info","ts":"2026-08-06T01:09:26.419507Z","caller":"traceutil/trace.go:171","msg":"trace[281368638] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-28ca8186-00e6-43e5-9d7a-9d5afa11ba7a; range_end:; response_count:1; response_revision:5083; }","duration":"215.982435ms","start":"2026-08-06T01:09:26.203516Z","end":"2026-08-06T01:09:26.419499Z","steps":["trace[281368638] 'agreement among raft nodes before linearized reading' (duration: 215.808441ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:09:26.454846Z","caller":"traceutil/trace.go:171","msg":"trace[1400086814] transaction","detail":"{read_only:false; response_revision:5084; number_of_response:1; }","duration":"210.884043ms","start":"2026-08-06T01:09:26.243935Z","end":"2026-08-06T01:09:26.454819Z","steps":["trace[1400086814] 'process raft request' (duration: 210.690469ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:09:26.546637Z","caller":"traceutil/trace.go:171","msg":"trace[1729418411] linearizableReadLoop","detail":"{readStateIndex:5292; appliedIndex:5290; }","duration":"120.141743ms","start":"2026-08-06T01:09:26.426466Z","end":"2026-08-06T01:09:26.546608Z","steps":["trace[1729418411] 'read index received' (duration: 28.156858ms)","trace[1729418411] 'applied index is now lower than readState.Index' (duration: 91.983745ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:09:26.546841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.348147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T01:09:26.546881Z","caller":"traceutil/trace.go:171","msg":"trace[999271538] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:5085; }","duration":"120.427569ms","start":"2026-08-06T01:09:26.426442Z","end":"2026-08-06T01:09:26.546869Z","steps":["trace[999271538] 'agreement among raft nodes before linearized reading' (duration: 120.268176ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:09:26.547099Z","caller":"traceutil/trace.go:171","msg":"trace[890147622] transaction","detail":"{read_only:false; number_of_response:1; response_revision:5085; }","duration":"123.250105ms","start":"2026-08-06T01:09:26.423838Z","end":"2026-08-06T01:09:26.547088Z","steps":["trace[890147622] 'process raft request' (duration: 74.799433ms)","trace[890147622] 'compare' (duration: 47.811739ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:09:28.792685Z","caller":"traceutil/trace.go:171","msg":"trace[1030856969] transaction","detail":"{read_only:false; response_revision:5156; number_of_response:1; }","duration":"377.660293ms","start":"2026-08-06T01:09:28.41496Z","end":"2026-08-06T01:09:28.792621Z","steps":["trace[1030856969] 'process raft request' (duration: 377.416328ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:28.792905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:09:28.414929Z","time spent":"377.858106ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7567,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:09:28.872797Z","caller":"traceutil/trace.go:171","msg":"trace[1951836644] transaction","detail":"{read_only:false; response_revision:5157; number_of_response:1; }","duration":"123.959326ms","start":"2026-08-06T01:09:28.748801Z","end":"2026-08-06T01:09:28.87276Z","steps":["trace[1951836644] 'process raft request' (duration: 58.448628ms)","trace[1951836644] 'compare' (duration: 65.278363ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:09:28.873045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.703515ms","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-08-06T01:09:28.873144Z","caller":"traceutil/trace.go:171","msg":"trace[2131134696] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5157; }","duration":"104.871738ms","start":"2026-08-06T01:09:28.768251Z","end":"2026-08-06T01:09:28.873122Z","steps":["trace[2131134696] 'agreement among raft nodes before linearized reading' (duration: 104.543322ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:28.873124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.20246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.162\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-06T01:09:28.873247Z","caller":"traceutil/trace.go:171","msg":"trace[1507728810] range","detail":"{range_begin:/registry/masterleases/199.19.213.162; range_end:; response_count:1; response_revision:5157; }","duration":"130.401094ms","start":"2026-08-06T01:09:28.742816Z","end":"2026-08-06T01:09:28.873217Z","steps":["trace[1507728810] 'agreement among raft nodes before linearized reading' (duration: 130.023256ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:09:28.995661Z","caller":"traceutil/trace.go:171","msg":"trace[1269679511] transaction","detail":"{read_only:false; response_revision:5159; number_of_response:1; }","duration":"102.4634ms","start":"2026-08-06T01:09:28.893166Z","end":"2026-08-06T01:09:28.99563Z","steps":["trace[1269679511] 'process raft request' (duration: 86.929683ms)","trace[1269679511] 'compare' (duration: 15.368944ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:09:33.929336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.562975ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T01:09:33.929477Z","caller":"traceutil/trace.go:171","msg":"trace[1865109491] linearizableReadLoop","detail":"{readStateIndex:5462; appliedIndex:5461; }","duration":"217.933316ms","start":"2026-08-06T01:09:33.711508Z","end":"2026-08-06T01:09:33.929442Z","steps":["trace[1865109491] 'read index received' (duration: 59.441µs)","trace[1865109491] 'applied index is now lower than readState.Index' (duration: 217.872455ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:09:33.929731Z","caller":"traceutil/trace.go:171","msg":"trace[217667883] transaction","detail":"{read_only:false; response_revision:5251; number_of_response:1; }","duration":"327.783786ms","start":"2026-08-06T01:09:33.601931Z","end":"2026-08-06T01:09:33.929715Z","steps":["trace[217667883] 'compare' (duration: 319.444621ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:33.930146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:09:33.601906Z","time spent":"328.142574ms","remote":"127.0.0.1:41938","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-08-06T01:09:33.930122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.525438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:09:33.930633Z","caller":"traceutil/trace.go:171","msg":"trace[2027804223] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:5251; }","duration":"219.053138ms","start":"2026-08-06T01:09:33.7115Z","end":"2026-08-06T01:09:33.930553Z","steps":["trace[2027804223] 'agreement among raft nodes before linearized reading' (duration: 218.445416ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:34.625676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.836711ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-06T01:09:34.62586Z","caller":"traceutil/trace.go:171","msg":"trace[1997993924] linearizableReadLoop","detail":"{readStateIndex:5463; appliedIndex:5462; }","duration":"696.331572ms","start":"2026-08-06T01:09:33.929511Z","end":"2026-08-06T01:09:34.625843Z","steps":["trace[1997993924] 'read index received' (duration: 37.258736ms)","trace[1997993924] 'applied index is now lower than readState.Index' (duration: 659.071506ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:09:34.62633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.853228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:09:34.626358Z","caller":"traceutil/trace.go:171","msg":"trace[1229889173] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:5251; }","duration":"712.977441ms","start":"2026-08-06T01:09:33.913372Z","end":"2026-08-06T01:09:34.626349Z","steps":["trace[1229889173] 'agreement among raft nodes before linearized reading' (duration: 712.90766ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:34.62638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:09:33.913355Z","time spent":"713.018381ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-06T01:09:34.626563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.162698ms","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-08-06T01:09:34.626586Z","caller":"traceutil/trace.go:171","msg":"trace[1005271478] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5251; }","duration":"905.197308ms","start":"2026-08-06T01:09:33.721379Z","end":"2026-08-06T01:09:34.626577Z","steps":["trace[1005271478] 'agreement among raft nodes before linearized reading' (duration: 905.121057ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:34.626603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:09:33.721372Z","time spent":"905.226729ms","remote":"127.0.0.1:41938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-06T01:09:34.626707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"907.648917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:09:34.626721Z","caller":"traceutil/trace.go:171","msg":"trace[1093975644] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5251; }","duration":"907.689178ms","start":"2026-08-06T01:09:33.719027Z","end":"2026-08-06T01:09:34.626716Z","steps":["trace[1093975644] 'agreement among raft nodes before linearized reading' (duration: 907.659918ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:34.626733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:09:33.719015Z","time spent":"907.715549ms","remote":"127.0.0.1:41630","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-08-06T01:09:34.626853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"912.067735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-78bd7720-9cc8-41ce-a0e2-1880b0ec778e\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-08-06T01:09:34.626873Z","caller":"traceutil/trace.go:171","msg":"trace[1777133575] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-78bd7720-9cc8-41ce-a0e2-1880b0ec778e; range_end:; response_count:1; response_revision:5251; }","duration":"912.086635ms","start":"2026-08-06T01:09:33.71478Z","end":"2026-08-06T01:09:34.626867Z","steps":["trace[1777133575] 'agreement among raft nodes before linearized reading' (duration: 912.028444ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:34.626888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:09:33.714735Z","time spent":"912.150427ms","remote":"127.0.0.1:41850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-78bd7720-9cc8-41ce-a0e2-1880b0ec778e\" "} {"level":"info","ts":"2026-08-06T01:09:34.627136Z","caller":"traceutil/trace.go:171","msg":"trace[82022149] transaction","detail":"{read_only:false; response_revision:5252; number_of_response:1; }","duration":"588.494721ms","start":"2026-08-06T01:09:34.038635Z","end":"2026-08-06T01:09:34.62713Z","steps":["trace[82022149] 'process raft request' (duration: 587.134855ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:34.627201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:09:34.038613Z","time spent":"588.544133ms","remote":"127.0.0.1:41938","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-08-06T01:09:34.669103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.885139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:09:34.669263Z","caller":"traceutil/trace.go:171","msg":"trace[1811854905] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:5253; }","duration":"629.085893ms","start":"2026-08-06T01:09:34.04016Z","end":"2026-08-06T01:09:34.669246Z","steps":["trace[1811854905] 'agreement among raft nodes before linearized reading' (duration: 628.891009ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:34.669296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:09:34.040137Z","time spent":"629.150114ms","remote":"127.0.0.1:42056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"info","ts":"2026-08-06T01:09:34.669105Z","caller":"traceutil/trace.go:171","msg":"trace[1983602630] transaction","detail":"{read_only:false; response_revision:5253; number_of_response:1; }","duration":"508.015592ms","start":"2026-08-06T01:09:34.160988Z","end":"2026-08-06T01:09:34.669003Z","steps":["trace[1983602630] 'process raft request' (duration: 495.613307ms)","trace[1983602630] 'compare' (duration: 12.234732ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:09:34.669156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.433542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-78bd7720-9cc8-41ce-a0e2-1880b0ec778e\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-08-06T01:09:34.669589Z","caller":"traceutil/trace.go:171","msg":"trace[2055268106] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-78bd7720-9cc8-41ce-a0e2-1880b0ec778e; range_end:; response_count:1; response_revision:5253; }","duration":"504.87991ms","start":"2026-08-06T01:09:34.164689Z","end":"2026-08-06T01:09:34.669569Z","steps":["trace[2055268106] 'agreement among raft nodes before linearized reading' (duration: 504.307969ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:34.669633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:09:34.164638Z","time spent":"504.984042ms","remote":"127.0.0.1:41850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-78bd7720-9cc8-41ce-a0e2-1880b0ec778e\" "} {"level":"warn","ts":"2026-08-06T01:09:34.669621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:09:34.160965Z","time spent":"508.554212ms","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":784,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-06T01:09:35.32697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.870206ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T01:09:35.327192Z","caller":"traceutil/trace.go:171","msg":"trace[1119062079] linearizableReadLoop","detail":"{readStateIndex:5475; appliedIndex:5473; }","duration":"351.259393ms","start":"2026-08-06T01:09:34.975911Z","end":"2026-08-06T01:09:35.327171Z","steps":["trace[1119062079] 'read index received' (duration: 332.115235ms)","trace[1119062079] 'applied index is now lower than readState.Index' (duration: 19.142888ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:09:35.327542Z","caller":"traceutil/trace.go:171","msg":"trace[914454614] transaction","detail":"{read_only:false; response_revision:5263; number_of_response:1; }","duration":"434.507406ms","start":"2026-08-06T01:09:34.893015Z","end":"2026-08-06T01:09:35.327523Z","steps":["trace[914454614] 'process raft request' (duration: 434.082588ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:35.32765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:09:34.893006Z","time spent":"434.591448ms","remote":"127.0.0.1:41938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T01:09:35.327736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.807274ms","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-08-06T01:09:35.327772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.602682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-5bbc54859-wdwg2.18c9120cf9a8c0a9\" ","response":"range_response_count:1 size:895"} {"level":"info","ts":"2026-08-06T01:09:35.327939Z","caller":"traceutil/trace.go:171","msg":"trace[1559257591] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-5bbc54859-wdwg2.18c9120cf9a8c0a9; range_end:; response_count:1; response_revision:5263; }","duration":"150.817017ms","start":"2026-08-06T01:09:35.177095Z","end":"2026-08-06T01:09:35.327912Z","steps":["trace[1559257591] 'agreement among raft nodes before linearized reading' (duration: 150.48316ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:09:35.327905Z","caller":"traceutil/trace.go:171","msg":"trace[630008333] transaction","detail":"{read_only:false; response_revision:5262; number_of_response:1; }","duration":"434.957335ms","start":"2026-08-06T01:09:34.892303Z","end":"2026-08-06T01:09:35.32726Z","steps":["trace[630008333] 'process raft request' (duration: 11.693721ms)","trace[630008333] 'compare' (duration: 422.713713ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:09:35.327821Z","caller":"traceutil/trace.go:171","msg":"trace[328680615] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5263; }","duration":"351.953797ms","start":"2026-08-06T01:09:34.975854Z","end":"2026-08-06T01:09:35.327807Z","steps":["trace[328680615] 'agreement among raft nodes before linearized reading' (duration: 351.704932ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:35.328067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:09:34.975831Z","time spent":"352.224383ms","remote":"127.0.0.1:41938","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-08-06T01:09:35.328104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:09:34.892288Z","time spent":"435.71271ms","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":736,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:09:35.62103Z","caller":"traceutil/trace.go:171","msg":"trace[1746477558] transaction","detail":"{read_only:false; response_revision:5264; number_of_response:1; }","duration":"289.580425ms","start":"2026-08-06T01:09:35.331425Z","end":"2026-08-06T01:09:35.621006Z","steps":["trace[1746477558] 'process raft request' (duration: 289.436143ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:09:35.694539Z","caller":"traceutil/trace.go:171","msg":"trace[62531844] linearizableReadLoop","detail":"{readStateIndex:5479; appliedIndex:5475; }","duration":"212.876341ms","start":"2026-08-06T01:09:35.481637Z","end":"2026-08-06T01:09:35.694514Z","steps":["trace[62531844] 'read index received' (duration: 139.265228ms)","trace[62531844] 'applied index is now lower than readState.Index' (duration: 73.610123ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:09:35.694609Z","caller":"traceutil/trace.go:171","msg":"trace[1213829548] transaction","detail":"{read_only:false; response_revision:5267; number_of_response:1; }","duration":"328.840501ms","start":"2026-08-06T01:09:35.365744Z","end":"2026-08-06T01:09:35.694585Z","steps":["trace[1213829548] 'process raft request' (duration: 328.731828ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:09:35.6946Z","caller":"traceutil/trace.go:171","msg":"trace[1031583424] transaction","detail":"{read_only:false; response_revision:5266; number_of_response:1; }","duration":"360.996745ms","start":"2026-08-06T01:09:35.333561Z","end":"2026-08-06T01:09:35.694558Z","steps":["trace[1031583424] 'process raft request' (duration: 360.879273ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:09:35.694727Z","caller":"traceutil/trace.go:171","msg":"trace[595714826] transaction","detail":"{read_only:false; response_revision:5265; number_of_response:1; }","duration":"361.438963ms","start":"2026-08-06T01:09:35.333242Z","end":"2026-08-06T01:09:35.694681Z","steps":["trace[595714826] 'process raft request' (duration: 361.067806ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:35.694792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:09:35.333554Z","time spent":"361.132628ms","remote":"127.0.0.1:41938","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-08-06T01:09:35.694765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:09:35.365713Z","time spent":"328.948152ms","remote":"127.0.0.1:41938","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-08-06T01:09:35.694931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:09:35.33321Z","time spent":"361.624278ms","remote":"127.0.0.1:41938","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-08-06T01:09:35.695299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.750841ms","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-08-06T01:09:35.695394Z","caller":"traceutil/trace.go:171","msg":"trace[277263440] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5267; }","duration":"206.892233ms","start":"2026-08-06T01:09:35.488481Z","end":"2026-08-06T01:09:35.695373Z","steps":["trace[277263440] 'agreement among raft nodes before linearized reading' (duration: 206.260851ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:35.695343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.719758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-06T01:09:35.695509Z","caller":"traceutil/trace.go:171","msg":"trace[949933332] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5267; }","duration":"213.926333ms","start":"2026-08-06T01:09:35.481565Z","end":"2026-08-06T01:09:35.695492Z","steps":["trace[949933332] 'agreement among raft nodes before linearized reading' (duration: 213.091106ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:09:35.820532Z","caller":"traceutil/trace.go:171","msg":"trace[218334940] transaction","detail":"{read_only:false; response_revision:5268; number_of_response:1; }","duration":"120.11242ms","start":"2026-08-06T01:09:35.700382Z","end":"2026-08-06T01:09:35.820494Z","steps":["trace[218334940] 'process raft request' (duration: 51.15485ms)","trace[218334940] 'compare' (duration: 68.801047ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:09:35.859872Z","caller":"traceutil/trace.go:171","msg":"trace[2032253457] linearizableReadLoop","detail":"{readStateIndex:5481; appliedIndex:5479; }","duration":"144.101124ms","start":"2026-08-06T01:09:35.715739Z","end":"2026-08-06T01:09:35.85984Z","steps":["trace[2032253457] 'read index received' (duration: 35.813417ms)","trace[2032253457] 'applied index is now lower than readState.Index' (duration: 108.286367ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:09:35.860025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.258387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:09:35.86007Z","caller":"traceutil/trace.go:171","msg":"trace[634664813] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5269; }","duration":"144.36682ms","start":"2026-08-06T01:09:35.715691Z","end":"2026-08-06T01:09:35.860058Z","steps":["trace[634664813] 'agreement among raft nodes before linearized reading' (duration: 144.265038ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:09:35.860229Z","caller":"traceutil/trace.go:171","msg":"trace[1023142878] transaction","detail":"{read_only:false; response_revision:5269; number_of_response:1; }","duration":"159.708622ms","start":"2026-08-06T01:09:35.700483Z","end":"2026-08-06T01:09:35.860191Z","steps":["trace[1023142878] 'process raft request' (duration: 159.143751ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:37.344125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.593426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"warn","ts":"2026-08-06T01:09:37.344162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.505478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12975"} {"level":"info","ts":"2026-08-06T01:09:37.344224Z","caller":"traceutil/trace.go:171","msg":"trace[1494328165] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5279; }","duration":"126.737159ms","start":"2026-08-06T01:09:37.217465Z","end":"2026-08-06T01:09:37.344202Z","steps":["trace[1494328165] 'range keys from in-memory index tree' (duration: 126.429883ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:09:37.344232Z","caller":"traceutil/trace.go:171","msg":"trace[1466595239] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5279; }","duration":"149.614149ms","start":"2026-08-06T01:09:37.194597Z","end":"2026-08-06T01:09:37.344211Z","steps":["trace[1466595239] 'range keys from in-memory index tree' (duration: 149.353975ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:09:38.872876Z","caller":"traceutil/trace.go:171","msg":"trace[1631652799] linearizableReadLoop","detail":"{readStateIndex:5522; appliedIndex:5521; }","duration":"130.600413ms","start":"2026-08-06T01:09:38.742245Z","end":"2026-08-06T01:09:38.872845Z","steps":["trace[1631652799] 'read index received' (duration: 128.177146ms)","trace[1631652799] 'applied index is now lower than readState.Index' (duration: 2.421527ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:09:38.873011Z","caller":"traceutil/trace.go:171","msg":"trace[674983397] transaction","detail":"{read_only:false; response_revision:5310; number_of_response:1; }","duration":"154.805741ms","start":"2026-08-06T01:09:38.71816Z","end":"2026-08-06T01:09:38.872966Z","steps":["trace[674983397] 'process raft request' (duration: 152.323652ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:38.873141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.871269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.162\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-06T01:09:38.873178Z","caller":"traceutil/trace.go:171","msg":"trace[796048445] range","detail":"{range_begin:/registry/masterleases/199.19.213.162; range_end:; response_count:1; response_revision:5310; }","duration":"130.972391ms","start":"2026-08-06T01:09:38.742194Z","end":"2026-08-06T01:09:38.873166Z","steps":["trace[796048445] 'agreement among raft nodes before linearized reading' (duration: 130.767817ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:09:40.716884Z","caller":"traceutil/trace.go:171","msg":"trace[1166158631] transaction","detail":"{read_only:false; response_revision:5338; number_of_response:1; }","duration":"229.369135ms","start":"2026-08-06T01:09:40.487496Z","end":"2026-08-06T01:09:40.716865Z","steps":["trace[1166158631] 'process raft request' (duration: 229.237963ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:09:45.468569Z","caller":"traceutil/trace.go:171","msg":"trace[2066679278] linearizableReadLoop","detail":"{readStateIndex:5616; appliedIndex:5615; }","duration":"114.640201ms","start":"2026-08-06T01:09:45.353894Z","end":"2026-08-06T01:09:45.468534Z","steps":["trace[2066679278] 'read index received' (duration: 114.56623ms)","trace[2066679278] 'applied index is now lower than readState.Index' (duration: 72.191µs)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:09:45.468955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.041569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-08-06T01:09:45.469045Z","caller":"traceutil/trace.go:171","msg":"trace[1532177639] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5399; }","duration":"115.196092ms","start":"2026-08-06T01:09:45.35383Z","end":"2026-08-06T01:09:45.469026Z","steps":["trace[1532177639] 'agreement among raft nodes before linearized reading' (duration: 114.917936ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:09:45.469107Z","caller":"traceutil/trace.go:171","msg":"trace[308224012] transaction","detail":"{read_only:false; response_revision:5399; number_of_response:1; }","duration":"174.451575ms","start":"2026-08-06T01:09:45.294638Z","end":"2026-08-06T01:09:45.46909Z","steps":["trace[308224012] 'process raft request' (duration: 173.70888ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:45.778602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.305041ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T01:09:45.778725Z","caller":"traceutil/trace.go:171","msg":"trace[1575185095] linearizableReadLoop","detail":"{readStateIndex:5617; appliedIndex:5616; }","duration":"308.622009ms","start":"2026-08-06T01:09:45.470085Z","end":"2026-08-06T01:09:45.778707Z","steps":["trace[1575185095] 'read index received' (duration: 105.201436ms)","trace[1575185095] 'applied index is now lower than readState.Index' (duration: 203.419033ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:09:45.77878Z","caller":"traceutil/trace.go:171","msg":"trace[2123700236] transaction","detail":"{read_only:false; response_revision:5400; number_of_response:1; }","duration":"401.37466ms","start":"2026-08-06T01:09:45.377378Z","end":"2026-08-06T01:09:45.778753Z","steps":["trace[2123700236] 'process raft request' (duration: 197.824274ms)","trace[2123700236] 'compare' (duration: 202.863372ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:09:45.778896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:09:45.37736Z","time spent":"401.486582ms","remote":"127.0.0.1:41938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T01:09:45.778883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.771702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T01:09:45.77896Z","caller":"traceutil/trace.go:171","msg":"trace[1805350667] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:5400; }","duration":"308.893523ms","start":"2026-08-06T01:09:45.47005Z","end":"2026-08-06T01:09:45.778944Z","steps":["trace[1805350667] 'agreement among raft nodes before linearized reading' (duration: 308.72107ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:45.779011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:09:45.470033Z","time spent":"308.966686ms","remote":"127.0.0.1:41850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":32,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-08-06T01:09:45.782949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.594167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-5e9894fb-ce12-49d5-9e9d-b1e9e26a8b72\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-08-06T01:09:45.78299Z","caller":"traceutil/trace.go:171","msg":"trace[1361738999] range","detail":"{range_begin:/registry/persistentvolumes/pvc-5e9894fb-ce12-49d5-9e9d-b1e9e26a8b72; range_end:; response_count:1; response_revision:5401; }","duration":"309.668639ms","start":"2026-08-06T01:09:45.473311Z","end":"2026-08-06T01:09:45.78298Z","steps":["trace[1361738999] 'agreement among raft nodes before linearized reading' (duration: 309.554877ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:45.783013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:09:45.473295Z","time spent":"309.711629ms","remote":"127.0.0.1:41804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1473,"request content":"key:\"/registry/persistentvolumes/pvc-5e9894fb-ce12-49d5-9e9d-b1e9e26a8b72\" "} {"level":"warn","ts":"2026-08-06T01:09:45.78325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.109208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:13878"} {"level":"info","ts":"2026-08-06T01:09:45.783334Z","caller":"traceutil/trace.go:171","msg":"trace[335214003] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5401; }","duration":"309.193429ms","start":"2026-08-06T01:09:45.474116Z","end":"2026-08-06T01:09:45.783309Z","steps":["trace[335214003] 'agreement among raft nodes before linearized reading' (duration: 309.042376ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:45.7834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:09:45.474077Z","time spent":"309.306782ms","remote":"127.0.0.1:41850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":1,"response size":13901,"request content":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-08-06T01:09:50.304039Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":199741493864048514,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-06T01:09:50.577968Z","caller":"traceutil/trace.go:171","msg":"trace[469842463] transaction","detail":"{read_only:false; response_revision:5453; number_of_response:1; }","duration":"784.705311ms","start":"2026-08-06T01:09:49.793232Z","end":"2026-08-06T01:09:50.577938Z","steps":["trace[469842463] 'process raft request' (duration: 784.492647ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:50.578244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:09:49.793204Z","time spent":"784.951676ms","remote":"127.0.0.1:41938","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-08-06T01:09:50.611291Z","caller":"traceutil/trace.go:171","msg":"trace[1129176259] linearizableReadLoop","detail":"{readStateIndex:5672; appliedIndex:5671; }","duration":"807.294744ms","start":"2026-08-06T01:09:49.803966Z","end":"2026-08-06T01:09:50.611261Z","steps":["trace[1129176259] 'read index received' (duration: 774.377929ms)","trace[1129176259] 'applied index is now lower than readState.Index' (duration: 32.915425ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:09:50.611514Z","caller":"traceutil/trace.go:171","msg":"trace[727300947] transaction","detail":"{read_only:false; response_revision:5454; number_of_response:1; }","duration":"817.33086ms","start":"2026-08-06T01:09:49.794159Z","end":"2026-08-06T01:09:50.61149Z","steps":["trace[727300947] 'process raft request' (duration: 816.927092ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:50.611651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:09:49.794141Z","time spent":"817.410991ms","remote":"127.0.0.1:41938","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-08-06T01:09:50.612009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.61726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-06T01:09:50.612068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.770975ms","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-08-06T01:09:50.612106Z","caller":"traceutil/trace.go:171","msg":"trace[1966118986] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5454; }","duration":"679.851706ms","start":"2026-08-06T01:09:49.932244Z","end":"2026-08-06T01:09:50.612095Z","steps":["trace[1966118986] 'agreement among raft nodes before linearized reading' (duration: 679.713283ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:50.612146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:09:49.932217Z","time spent":"679.922858ms","remote":"127.0.0.1:41938","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-08-06T01:09:50.612289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.331294ms","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-08-06T01:09:50.612321Z","caller":"traceutil/trace.go:171","msg":"trace[119074699] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5454; }","duration":"808.394755ms","start":"2026-08-06T01:09:49.803918Z","end":"2026-08-06T01:09:50.612313Z","steps":["trace[119074699] 'agreement among raft nodes before linearized reading' (duration: 808.310254ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:50.612346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:09:49.803897Z","time spent":"808.442636ms","remote":"127.0.0.1:41938","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-08-06T01:09:50.612449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.446163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3243"} {"level":"info","ts":"2026-08-06T01:09:50.612526Z","caller":"traceutil/trace.go:171","msg":"trace[2035166678] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5454; }","duration":"715.526895ms","start":"2026-08-06T01:09:49.896979Z","end":"2026-08-06T01:09:50.612506Z","steps":["trace[2035166678] 'agreement among raft nodes before linearized reading' (duration: 715.328091ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:50.612548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.326924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-06T01:09:50.612581Z","caller":"traceutil/trace.go:171","msg":"trace[646715925] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5454; }","duration":"539.403335ms","start":"2026-08-06T01:09:50.073169Z","end":"2026-08-06T01:09:50.612572Z","steps":["trace[646715925] 'agreement among raft nodes before linearized reading' (duration: 539.310533ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:50.612593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:09:49.896927Z","time spent":"715.649827ms","remote":"127.0.0.1:41326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3266,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-08-06T01:09:50.612618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:09:50.073145Z","time spent":"539.464516ms","remote":"127.0.0.1:41938","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-08-06T01:09:50.612764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.521459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-06T01:09:50.612797Z","caller":"traceutil/trace.go:171","msg":"trace[1087503262] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5454; }","duration":"732.553679ms","start":"2026-08-06T01:09:49.880235Z","end":"2026-08-06T01:09:50.612789Z","steps":["trace[1087503262] 'agreement among raft nodes before linearized reading' (duration: 732.468218ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:50.612824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:09:49.880171Z","time spent":"732.646991ms","remote":"127.0.0.1:41938","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":"info","ts":"2026-08-06T01:09:50.6121Z","caller":"traceutil/trace.go:171","msg":"trace[1639495564] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5454; }","duration":"807.731232ms","start":"2026-08-06T01:09:49.804348Z","end":"2026-08-06T01:09:50.612079Z","steps":["trace[1639495564] 'agreement among raft nodes before linearized reading' (duration: 807.312313ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:50.613143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:09:49.804341Z","time spent":"808.790653ms","remote":"127.0.0.1:41938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-06T01:09:50.776625Z","caller":"traceutil/trace.go:171","msg":"trace[223689212] transaction","detail":"{read_only:false; response_revision:5455; number_of_response:1; }","duration":"157.689459ms","start":"2026-08-06T01:09:50.618885Z","end":"2026-08-06T01:09:50.776574Z","steps":["trace[223689212] 'process raft request' (duration: 151.833844ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:09:50.81821Z","caller":"traceutil/trace.go:171","msg":"trace[1919227907] transaction","detail":"{read_only:false; response_revision:5456; number_of_response:1; }","duration":"199.292303ms","start":"2026-08-06T01:09:50.61888Z","end":"2026-08-06T01:09:50.818172Z","steps":["trace[1919227907] 'process raft request' (duration: 199.052359ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:09:50.818265Z","caller":"traceutil/trace.go:171","msg":"trace[1404791641] linearizableReadLoop","detail":"{readStateIndex:5676; appliedIndex:5672; }","duration":"198.311465ms","start":"2026-08-06T01:09:50.619926Z","end":"2026-08-06T01:09:50.818237Z","steps":["trace[1404791641] 'read index received' (duration: 150.785484ms)","trace[1404791641] 'applied index is now lower than readState.Index' (duration: 47.525091ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:09:50.818335Z","caller":"traceutil/trace.go:171","msg":"trace[367696696] transaction","detail":"{read_only:false; response_revision:5459; number_of_response:1; }","duration":"198.09271ms","start":"2026-08-06T01:09:50.620194Z","end":"2026-08-06T01:09:50.818286Z","steps":["trace[367696696] 'process raft request' (duration: 197.943957ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:09:50.818501Z","caller":"traceutil/trace.go:171","msg":"trace[731245190] transaction","detail":"{read_only:false; response_revision:5458; number_of_response:1; }","duration":"199.296874ms","start":"2026-08-06T01:09:50.619194Z","end":"2026-08-06T01:09:50.818491Z","steps":["trace[731245190] 'process raft request' (duration: 198.916576ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:50.818498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.08511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:09:50.818532Z","caller":"traceutil/trace.go:171","msg":"trace[1338925680] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5461; }","duration":"101.205292ms","start":"2026-08-06T01:09:50.717318Z","end":"2026-08-06T01:09:50.818523Z","steps":["trace[1338925680] 'agreement among raft nodes before linearized reading' (duration: 101.10745ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:50.818822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.877526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-keystone-default-user\" ","response":"range_response_count:1 size:1162"} {"level":"info","ts":"2026-08-06T01:09:50.818892Z","caller":"traceutil/trace.go:171","msg":"trace[1419393121] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-keystone-default-user; range_end:; response_count:1; response_revision:5461; }","duration":"198.972797ms","start":"2026-08-06T01:09:50.619902Z","end":"2026-08-06T01:09:50.818875Z","steps":["trace[1419393121] 'agreement among raft nodes before linearized reading' (duration: 198.826804ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:09:50.819218Z","caller":"traceutil/trace.go:171","msg":"trace[730389420] transaction","detail":"{read_only:false; response_revision:5457; number_of_response:1; }","duration":"200.259312ms","start":"2026-08-06T01:09:50.618919Z","end":"2026-08-06T01:09:50.819179Z","steps":["trace[730389420] 'process raft request' (duration: 199.1555ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:50.998194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.823447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:1451"} {"level":"info","ts":"2026-08-06T01:09:50.998259Z","caller":"traceutil/trace.go:171","msg":"trace[966500705] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5467; }","duration":"119.936189ms","start":"2026-08-06T01:09:50.878313Z","end":"2026-08-06T01:09:50.998249Z","steps":["trace[966500705] 'agreement among raft nodes before linearized reading' (duration: 119.724425ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:09:50.99837Z","caller":"traceutil/trace.go:171","msg":"trace[1314204684] transaction","detail":"{read_only:false; response_revision:5466; number_of_response:1; }","duration":"121.00623ms","start":"2026-08-06T01:09:50.877324Z","end":"2026-08-06T01:09:50.998331Z","steps":["trace[1314204684] 'process raft request' (duration: 120.438519ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:09:50.998555Z","caller":"traceutil/trace.go:171","msg":"trace[1826784575] transaction","detail":"{read_only:false; response_revision:5467; number_of_response:1; }","duration":"120.572422ms","start":"2026-08-06T01:09:50.877819Z","end":"2026-08-06T01:09:50.998392Z","steps":["trace[1826784575] 'process raft request' (duration: 120.149294ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:09:50.997883Z","caller":"traceutil/trace.go:171","msg":"trace[1002922580] transaction","detail":"{read_only:false; response_revision:5465; number_of_response:1; }","duration":"120.618802ms","start":"2026-08-06T01:09:50.87722Z","end":"2026-08-06T01:09:50.997838Z","steps":["trace[1002922580] 'process raft request' (duration: 120.385587ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:13:08.155199Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":199741493864052011,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-06T01:13:08.236323Z","caller":"traceutil/trace.go:171","msg":"trace[1253789304] linearizableReadLoop","detail":"{readStateIndex:7068; appliedIndex:7067; }","duration":"581.408986ms","start":"2026-08-06T01:13:07.654896Z","end":"2026-08-06T01:13:08.236305Z","steps":["trace[1253789304] 'read index received' (duration: 581.242609ms)","trace[1253789304] 'applied index is now lower than readState.Index' (duration: 165.717µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:13:08.236446Z","caller":"traceutil/trace.go:171","msg":"trace[2069301204] transaction","detail":"{read_only:false; response_revision:6805; number_of_response:1; }","duration":"663.927123ms","start":"2026-08-06T01:13:07.572463Z","end":"2026-08-06T01:13:08.23639Z","steps":["trace[2069301204] 'process raft request' (duration: 663.719535ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:13:08.236481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.560123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T01:13:08.236507Z","caller":"traceutil/trace.go:171","msg":"trace[1148043507] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:6805; }","duration":"581.677887ms","start":"2026-08-06T01:13:07.654822Z","end":"2026-08-06T01:13:08.2365Z","steps":["trace[1148043507] 'agreement among raft nodes before linearized reading' (duration: 581.599424ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:13:08.236538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:13:07.654801Z","time spent":"581.7297ms","remote":"127.0.0.1:41754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":12,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-08-06T01:13:08.236562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:13:07.572435Z","time spent":"664.065639ms","remote":"127.0.0.1:41938","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-08-06T01:13:08.236618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.677828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-06T01:13:08.236701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.35884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-08-06T01:13:08.236723Z","caller":"traceutil/trace.go:171","msg":"trace[1718159681] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:6805; }","duration":"425.423083ms","start":"2026-08-06T01:13:07.811294Z","end":"2026-08-06T01:13:08.236717Z","steps":["trace[1718159681] 'agreement among raft nodes before linearized reading' (duration: 425.340959ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:13:08.236739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:13:07.811273Z","time spent":"425.463554ms","remote":"127.0.0.1:41938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-08-06T01:13:08.236757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.286712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-06T01:13:08.236713Z","caller":"traceutil/trace.go:171","msg":"trace[1296342888] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6805; }","duration":"519.806673ms","start":"2026-08-06T01:13:07.716896Z","end":"2026-08-06T01:13:08.236703Z","steps":["trace[1296342888] 'agreement among raft nodes before linearized reading' (duration: 519.586583ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:13:08.236874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.85666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"warn","ts":"2026-08-06T01:13:08.236843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.024693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T01:13:08.236892Z","caller":"traceutil/trace.go:171","msg":"trace[1822867440] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:6805; }","duration":"437.927743ms","start":"2026-08-06T01:13:07.798959Z","end":"2026-08-06T01:13:08.236886Z","steps":["trace[1822867440] 'agreement among raft nodes before linearized reading' (duration: 437.888341ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:13:08.236915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:13:07.798944Z","time spent":"437.968115ms","remote":"127.0.0.1:41938","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":"warn","ts":"2026-08-06T01:13:08.236919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.223742ms","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-08-06T01:13:08.236922Z","caller":"traceutil/trace.go:171","msg":"trace[1700144137] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:6805; }","duration":"226.158429ms","start":"2026-08-06T01:13:08.010745Z","end":"2026-08-06T01:13:08.236903Z","steps":["trace[1700144137] 'agreement among raft nodes before linearized reading' (duration: 225.95213ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:13:08.236943Z","caller":"traceutil/trace.go:171","msg":"trace[882690782] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6805; }","duration":"242.291225ms","start":"2026-08-06T01:13:07.994643Z","end":"2026-08-06T01:13:08.236934Z","steps":["trace[882690782] 'agreement among raft nodes before linearized reading' (duration: 242.204951ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:13:08.236983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.209154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-08-06T01:13:08.236962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.086804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-06T01:13:08.237013Z","caller":"traceutil/trace.go:171","msg":"trace[2132740221] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6805; }","duration":"490.266606ms","start":"2026-08-06T01:13:07.74674Z","end":"2026-08-06T01:13:08.237007Z","steps":["trace[2132740221] 'agreement among raft nodes before linearized reading' (duration: 490.170172ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:13:08.237035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:13:07.746729Z","time spent":"490.299297ms","remote":"127.0.0.1:41938","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":"info","ts":"2026-08-06T01:13:08.237053Z","caller":"traceutil/trace.go:171","msg":"trace[131998126] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6805; }","duration":"238.244531ms","start":"2026-08-06T01:13:07.998777Z","end":"2026-08-06T01:13:08.237022Z","steps":["trace[131998126] 'agreement among raft nodes before linearized reading' (duration: 237.813392ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:13:08.237111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.83508ms","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-08-06T01:13:08.237981Z","caller":"traceutil/trace.go:171","msg":"trace[1543430648] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6805; }","duration":"269.075393ms","start":"2026-08-06T01:13:07.968884Z","end":"2026-08-06T01:13:08.237959Z","steps":["trace[1543430648] 'agreement among raft nodes before linearized reading' (duration: 267.671723ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:13:08.23683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.002985ms","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-08-06T01:13:08.238082Z","caller":"traceutil/trace.go:171","msg":"trace[91086010] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6805; }","duration":"383.2686ms","start":"2026-08-06T01:13:07.854802Z","end":"2026-08-06T01:13:08.238071Z","steps":["trace[91086010] 'agreement among raft nodes before linearized reading' (duration: 381.992215ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:13:08.238114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:13:07.854791Z","time spent":"383.313911ms","remote":"127.0.0.1:41938","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":"info","ts":"2026-08-06T01:13:08.236828Z","caller":"traceutil/trace.go:171","msg":"trace[770689189] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6805; }","duration":"235.413558ms","start":"2026-08-06T01:13:08.00139Z","end":"2026-08-06T01:13:08.236803Z","steps":["trace[770689189] 'agreement among raft nodes before linearized reading' (duration: 235.181418ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:13:08.237187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:13:07.71688Z","time spent":"519.994591ms","remote":"127.0.0.1:41630","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-08-06T01:13:08.945113Z","caller":"traceutil/trace.go:171","msg":"trace[151172725] transaction","detail":"{read_only:false; response_revision:6806; number_of_response:1; }","duration":"704.330191ms","start":"2026-08-06T01:13:08.240759Z","end":"2026-08-06T01:13:08.945089Z","steps":["trace[151172725] 'process raft request' (duration: 700.751737ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:13:08.94526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:13:08.240738Z","time spent":"704.452077ms","remote":"127.0.0.1:41938","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-08-06T01:13:08.945814Z","caller":"traceutil/trace.go:171","msg":"trace[1516702519] transaction","detail":"{read_only:false; response_revision:6807; number_of_response:1; }","duration":"704.921877ms","start":"2026-08-06T01:13:08.240873Z","end":"2026-08-06T01:13:08.945795Z","steps":["trace[1516702519] 'process raft request' (duration: 704.816442ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:13:08.945922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:13:08.24086Z","time spent":"705.002861ms","remote":"127.0.0.1:41938","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-08-06T01:13:08.946033Z","caller":"traceutil/trace.go:171","msg":"trace[918770606] transaction","detail":"{read_only:false; response_revision:6808; number_of_response:1; }","duration":"704.917377ms","start":"2026-08-06T01:13:08.241091Z","end":"2026-08-06T01:13:08.946008Z","steps":["trace[918770606] 'process raft request' (duration: 704.672676ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:13:08.946137Z","caller":"traceutil/trace.go:171","msg":"trace[944222602] transaction","detail":"{read_only:false; response_revision:6809; number_of_response:1; }","duration":"705.012191ms","start":"2026-08-06T01:13:08.241113Z","end":"2026-08-06T01:13:08.946125Z","steps":["trace[944222602] 'process raft request' (duration: 704.808402ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:13:08.946187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:13:08.241099Z","time spent":"705.063863ms","remote":"127.0.0.1:41938","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-08-06T01:13:08.946173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:13:08.241073Z","time spent":"705.040251ms","remote":"127.0.0.1:41938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:13:08.946265Z","caller":"traceutil/trace.go:171","msg":"trace[1333569107] transaction","detail":"{read_only:false; response_revision:6810; number_of_response:1; }","duration":"704.586012ms","start":"2026-08-06T01:13:08.241666Z","end":"2026-08-06T01:13:08.946252Z","steps":["trace[1333569107] 'process raft request' (duration: 704.353562ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:13:08.946316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:13:08.24165Z","time spent":"704.641535ms","remote":"127.0.0.1:41938","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-08-06T01:13:08.946319Z","caller":"traceutil/trace.go:171","msg":"trace[1588264544] transaction","detail":"{read_only:false; response_revision:6812; number_of_response:1; }","duration":"703.734556ms","start":"2026-08-06T01:13:08.242574Z","end":"2026-08-06T01:13:08.946308Z","steps":["trace[1588264544] 'process raft request' (duration: 703.613951ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:13:08.946311Z","caller":"traceutil/trace.go:171","msg":"trace[922409226] linearizableReadLoop","detail":"{readStateIndex:7076; appliedIndex:7068; }","duration":"414.120179ms","start":"2026-08-06T01:13:08.532164Z","end":"2026-08-06T01:13:08.946284Z","steps":["trace[922409226] 'read index received' (duration: 409.359903ms)","trace[922409226] 'applied index is now lower than readState.Index' (duration: 4.759286ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:13:08.946376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:13:08.242565Z","time spent":"703.780617ms","remote":"127.0.0.1:41938","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-08-06T01:13:08.946446Z","caller":"traceutil/trace.go:171","msg":"trace[314110587] transaction","detail":"{read_only:false; response_revision:6813; number_of_response:1; }","duration":"703.523496ms","start":"2026-08-06T01:13:08.242905Z","end":"2026-08-06T01:13:08.946428Z","steps":["trace[314110587] 'process raft request' (duration: 703.331718ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:13:08.94651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.324947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:13:08.946454Z","caller":"traceutil/trace.go:171","msg":"trace[1477217007] transaction","detail":"{read_only:false; response_revision:6811; number_of_response:1; }","duration":"703.904243ms","start":"2026-08-06T01:13:08.242525Z","end":"2026-08-06T01:13:08.946429Z","steps":["trace[1477217007] 'process raft request' (duration: 703.61189ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:13:08.946571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:13:08.242898Z","time spent":"703.59919ms","remote":"127.0.0.1:41938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:13:08.946623Z","caller":"traceutil/trace.go:171","msg":"trace[331904976] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:6813; }","duration":"414.446593ms","start":"2026-08-06T01:13:08.532111Z","end":"2026-08-06T01:13:08.946558Z","steps":["trace[331904976] 'agreement among raft nodes before linearized reading' (duration: 414.333328ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:13:08.946785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:13:08.532088Z","time spent":"414.634301ms","remote":"127.0.0.1:34600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-08-06T01:13:08.946823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:13:08.242516Z","time spent":"704.108051ms","remote":"127.0.0.1:41938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T01:13:08.946659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.751227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.162\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-06T01:13:08.94833Z","caller":"traceutil/trace.go:171","msg":"trace[561221792] range","detail":"{range_begin:/registry/masterleases/199.19.213.162; range_end:; response_count:1; response_revision:6813; }","duration":"193.339633ms","start":"2026-08-06T01:13:08.753864Z","end":"2026-08-06T01:13:08.947203Z","steps":["trace[561221792] 'agreement among raft nodes before linearized reading' (duration: 192.722466ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:13:41.315596Z","caller":"traceutil/trace.go:171","msg":"trace[1183657169] transaction","detail":"{read_only:false; response_revision:7098; number_of_response:1; }","duration":"150.352643ms","start":"2026-08-06T01:13:41.165219Z","end":"2026-08-06T01:13:41.315572Z","steps":["trace[1183657169] 'process raft request' (duration: 150.178806ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:13:41.315922Z","caller":"traceutil/trace.go:171","msg":"trace[1634564807] transaction","detail":"{read_only:false; response_revision:7101; number_of_response:1; }","duration":"149.995148ms","start":"2026-08-06T01:13:41.165909Z","end":"2026-08-06T01:13:41.315904Z","steps":["trace[1634564807] 'process raft request' (duration: 149.952376ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:13:41.315995Z","caller":"traceutil/trace.go:171","msg":"trace[844882862] transaction","detail":"{read_only:false; response_revision:7100; number_of_response:1; }","duration":"150.154664ms","start":"2026-08-06T01:13:41.165828Z","end":"2026-08-06T01:13:41.315983Z","steps":["trace[844882862] 'process raft request' (duration: 149.894964ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:13:41.316048Z","caller":"traceutil/trace.go:171","msg":"trace[790358014] transaction","detail":"{read_only:false; response_revision:7099; number_of_response:1; }","duration":"150.431986ms","start":"2026-08-06T01:13:41.165589Z","end":"2026-08-06T01:13:41.316021Z","steps":["trace[790358014] 'process raft request' (duration: 149.943296ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:14:01.821334Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":4487} {"level":"info","ts":"2026-08-06T01:14:01.878987Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":4487,"took":"56.209183ms","hash":2632591377,"current-db-size-bytes":40845312,"current-db-size":"41 MB","current-db-size-in-use-bytes":33107968,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-06T01:14:01.879294Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2632591377,"revision":4487,"compact-revision":2647}