{"level":"warn","ts":"2026-07-24T04:37:03.941677Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-24T04:37:03.941788Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.177:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.177:2380","--initial-cluster=instance=https://199.204.45.177:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.177:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.177:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-24T04:37:03.941874Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-24T04:37:03.941893Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.177:2380"]} {"level":"info","ts":"2026-07-24T04:37:03.94192Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-24T04:37:03.942812Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.177:2379"]} {"level":"info","ts":"2026-07-24T04:37:03.942908Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.177:2380"],"listen-peer-urls":["https://199.204.45.177:2380"],"advertise-client-urls":["https://199.204.45.177:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.177:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.177:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-24T04:37:04.085485Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"142.287563ms"} {"level":"info","ts":"2026-07-24T04:37:04.102307Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"7c53a18098324dc9","cluster-id":"4717e8aa221973c8"} {"level":"info","ts":"2026-07-24T04:37:04.102421Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 switched to configuration voters=()"} {"level":"info","ts":"2026-07-24T04:37:04.102457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became follower at term 0"} {"level":"info","ts":"2026-07-24T04:37:04.102476Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 7c53a18098324dc9 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-24T04:37:04.102492Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became follower at term 1"} {"level":"info","ts":"2026-07-24T04:37:04.102562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 switched to configuration voters=(8958681657451367881)"} {"level":"warn","ts":"2026-07-24T04:37:04.114877Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-24T04:37:04.116418Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-24T04:37:04.117734Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-24T04:37:04.120154Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"7c53a18098324dc9","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-24T04:37:04.120392Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"7c53a18098324dc9","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-24T04:37:04.120593Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-24T04:37:04.120661Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-24T04:37:04.120674Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-24T04:37:04.12193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 switched to configuration voters=(8958681657451367881)"} {"level":"info","ts":"2026-07-24T04:37:04.122369Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4717e8aa221973c8","local-member-id":"7c53a18098324dc9","added-peer-id":"7c53a18098324dc9","added-peer-peer-urls":["https://199.204.45.177:2380"]} {"level":"info","ts":"2026-07-24T04:37:04.126779Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-24T04:37:04.126775Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.177:2380"} {"level":"info","ts":"2026-07-24T04:37:04.126837Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.177:2380"} {"level":"info","ts":"2026-07-24T04:37:04.127356Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-24T04:37:04.127298Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"7c53a18098324dc9","initial-advertise-peer-urls":["https://199.204.45.177:2380"],"listen-peer-urls":["https://199.204.45.177:2380"],"advertise-client-urls":["https://199.204.45.177:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.177:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-24T04:37:04.902847Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 is starting a new election at term 1"} {"level":"info","ts":"2026-07-24T04:37:04.902926Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-24T04:37:04.903007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 received MsgPreVoteResp from 7c53a18098324dc9 at term 1"} {"level":"info","ts":"2026-07-24T04:37:04.903038Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became candidate at term 2"} {"level":"info","ts":"2026-07-24T04:37:04.903048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 received MsgVoteResp from 7c53a18098324dc9 at term 2"} {"level":"info","ts":"2026-07-24T04:37:04.903077Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became leader at term 2"} {"level":"info","ts":"2026-07-24T04:37:04.903094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 7c53a18098324dc9 elected leader 7c53a18098324dc9 at term 2"} {"level":"info","ts":"2026-07-24T04:37:04.904765Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T04:37:04.905572Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-24T04:37:04.905569Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"7c53a18098324dc9","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.177:2379]}","request-path":"/0/members/7c53a18098324dc9/attributes","cluster-id":"4717e8aa221973c8","publish-timeout":"7s"} {"level":"info","ts":"2026-07-24T04:37:04.905816Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-24T04:37:04.905875Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-24T04:37:04.905915Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-24T04:37:04.906615Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4717e8aa221973c8","local-member-id":"7c53a18098324dc9","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T04:37:04.906693Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T04:37:04.906725Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T04:37:04.907177Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.177:2379"} {"level":"info","ts":"2026-07-24T04:37:04.908885Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-24T04:37:09.605623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.905453ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:37:09.605745Z","caller":"traceutil/trace.go:171","msg":"trace[1828599326] linearizableReadLoop","detail":"{readStateIndex:270; appliedIndex:269; }","duration":"365.535032ms","start":"2026-07-24T04:37:09.240195Z","end":"2026-07-24T04:37:09.60573Z","steps":["trace[1828599326] 'read index received' (duration: 22.040542ms)","trace[1828599326] 'applied index is now lower than readState.Index' (duration: 343.49342ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:37:09.605831Z","caller":"traceutil/trace.go:171","msg":"trace[1909312527] transaction","detail":"{read_only:false; response_revision:264; number_of_response:1; }","duration":"401.944312ms","start":"2026-07-24T04:37:09.203874Z","end":"2026-07-24T04:37:09.605819Z","steps":["trace[1909312527] 'process raft request' (duration: 58.40689ms)","trace[1909312527] 'compare' (duration: 342.772687ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:37:09.605891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:37:09.203849Z","time spent":"402.015125ms","remote":"127.0.0.1:40850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":786,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T04:37:09.606133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.882197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-24T04:37:09.6062Z","caller":"traceutil/trace.go:171","msg":"trace[1413523661] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:264; }","duration":"366.039035ms","start":"2026-07-24T04:37:09.240144Z","end":"2026-07-24T04:37:09.606183Z","steps":["trace[1413523661] 'agreement among raft nodes before linearized reading' (duration: 365.829775ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:09.606183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.256409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3709"} {"level":"warn","ts":"2026-07-24T04:37:09.606275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:37:09.240122Z","time spent":"366.137459ms","remote":"127.0.0.1:40882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-07-24T04:37:09.606447Z","caller":"traceutil/trace.go:171","msg":"trace[1969896009] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:264; }","duration":"105.420967ms","start":"2026-07-24T04:37:09.500854Z","end":"2026-07-24T04:37:09.606275Z","steps":["trace[1969896009] 'agreement among raft nodes before linearized reading' (duration: 105.098342ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:37:10.147177Z","caller":"traceutil/trace.go:171","msg":"trace[271050987] linearizableReadLoop","detail":"{readStateIndex:273; appliedIndex:271; }","duration":"527.446393ms","start":"2026-07-24T04:37:09.619711Z","end":"2026-07-24T04:37:10.147157Z","steps":["trace[271050987] 'read index received' (duration: 489.64503ms)","trace[271050987] 'applied index is now lower than readState.Index' (duration: 37.800693ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:37:10.147308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.57219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-07-24T04:37:10.147342Z","caller":"traceutil/trace.go:171","msg":"trace[1474170270] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:267; }","duration":"527.650833ms","start":"2026-07-24T04:37:09.619682Z","end":"2026-07-24T04:37:10.147333Z","steps":["trace[1474170270] 'agreement among raft nodes before linearized reading' (duration: 527.561379ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:10.147371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:37:09.619672Z","time spent":"527.692585ms","remote":"127.0.0.1:40986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":236,"request content":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" "} {"level":"info","ts":"2026-07-24T04:37:10.147545Z","caller":"traceutil/trace.go:171","msg":"trace[1654585577] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"536.467377ms","start":"2026-07-24T04:37:09.611068Z","end":"2026-07-24T04:37:10.147536Z","steps":["trace[1654585577] 'process raft request' (duration: 498.37279ms)","trace[1654585577] 'compare' (duration: 37.553263ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:37:10.147577Z","caller":"traceutil/trace.go:171","msg":"trace[876592775] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"532.972317ms","start":"2026-07-24T04:37:09.614576Z","end":"2026-07-24T04:37:10.147549Z","steps":["trace[876592775] 'process raft request' (duration: 532.530717ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:10.147702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:37:09.61106Z","time spent":"536.511539ms","remote":"127.0.0.1:40850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":696,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T04:37:10.147924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:37:09.61456Z","time spent":"533.087552ms","remote":"127.0.0.1:40942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4003,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:37:10.886249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.092066ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:37:10.886397Z","caller":"traceutil/trace.go:171","msg":"trace[1354115509] transaction","detail":"{read_only:false; response_revision:269; number_of_response:1; }","duration":"731.696805ms","start":"2026-07-24T04:37:10.154687Z","end":"2026-07-24T04:37:10.886384Z","steps":["trace[1354115509] 'process raft request' (duration: 731.653233ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:10.886436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:37:10.154666Z","time spent":"731.754068ms","remote":"127.0.0.1:40874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":677,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T04:37:10.886566Z","caller":"traceutil/trace.go:171","msg":"trace[1177680969] transaction","detail":"{read_only:false; response_revision:268; number_of_response:1; }","duration":"734.939113ms","start":"2026-07-24T04:37:10.151619Z","end":"2026-07-24T04:37:10.886558Z","steps":["trace[1177680969] 'process raft request' (duration: 406.47459ms)","trace[1177680969] 'compare' (duration: 327.883027ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:37:10.886595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:37:10.151586Z","time spent":"734.994265ms","remote":"127.0.0.1:40850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":696,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T04:37:10.886855Z","caller":"traceutil/trace.go:171","msg":"trace[943946556] linearizableReadLoop","detail":"{readStateIndex:274; appliedIndex:273; }","duration":"735.11003ms","start":"2026-07-24T04:37:10.151733Z","end":"2026-07-24T04:37:10.886843Z","steps":["trace[943946556] 'read index received' (duration: 406.370775ms)","trace[943946556] 'applied index is now lower than readState.Index' (duration: 328.737115ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:37:10.886987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.129885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-07-24T04:37:10.887009Z","caller":"traceutil/trace.go:171","msg":"trace[1385326496] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:269; }","duration":"732.176717ms","start":"2026-07-24T04:37:10.154825Z","end":"2026-07-24T04:37:10.887002Z","steps":["trace[1385326496] 'agreement among raft nodes before linearized reading' (duration: 732.098044ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:10.887006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.542263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T04:37:10.887067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.704811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-24T04:37:10.887065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.097178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T04:37:10.887073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.585317ms","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-07-24T04:37:10.88709Z","caller":"traceutil/trace.go:171","msg":"trace[557201410] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:269; }","duration":"436.762973ms","start":"2026-07-24T04:37:10.450318Z","end":"2026-07-24T04:37:10.887081Z","steps":["trace[557201410] 'agreement among raft nodes before linearized reading' (duration: 436.727771ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:37:10.887092Z","caller":"traceutil/trace.go:171","msg":"trace[15317770] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:269; }","duration":"407.14384ms","start":"2026-07-24T04:37:10.47994Z","end":"2026-07-24T04:37:10.887084Z","steps":["trace[15317770] 'agreement among raft nodes before linearized reading' (duration: 407.100348ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:37:10.887098Z","caller":"traceutil/trace.go:171","msg":"trace[436870231] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:269; }","duration":"163.643429ms","start":"2026-07-24T04:37:10.723448Z","end":"2026-07-24T04:37:10.887091Z","steps":["trace[436870231] 'agreement among raft nodes before linearized reading' (duration: 163.596257ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:10.887109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:37:10.450296Z","time spent":"436.807395ms","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-24T04:37:10.887112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:37:10.479929Z","time spent":"407.178262ms","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-24T04:37:10.887066Z","caller":"traceutil/trace.go:171","msg":"trace[422406094] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:269; }","duration":"350.610706ms","start":"2026-07-24T04:37:10.536441Z","end":"2026-07-24T04:37:10.887052Z","steps":["trace[422406094] 'agreement among raft nodes before linearized reading' (duration: 350.506242ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:10.887144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:37:10.536403Z","time spent":"350.731372ms","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-24T04:37:10.887032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:37:10.154818Z","time spent":"732.206948ms","remote":"127.0.0.1:40986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":236,"request content":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" "} {"level":"info","ts":"2026-07-24T04:37:11.080339Z","caller":"traceutil/trace.go:171","msg":"trace[1684828619] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"109.962605ms","start":"2026-07-24T04:37:10.970349Z","end":"2026-07-24T04:37:11.080312Z","steps":["trace[1684828619] 'process raft request' (duration: 36.093525ms)","trace[1684828619] 'compare' (duration: 73.489743ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:37:11.080436Z","caller":"traceutil/trace.go:171","msg":"trace[806094744] linearizableReadLoop","detail":"{readStateIndex:284; appliedIndex:283; }","duration":"107.86676ms","start":"2026-07-24T04:37:10.972535Z","end":"2026-07-24T04:37:11.080401Z","steps":["trace[806094744] 'read index received' (duration: 34.014871ms)","trace[806094744] 'applied index is now lower than readState.Index' (duration: 73.848549ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:37:11.080628Z","caller":"traceutil/trace.go:171","msg":"trace[1960201396] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"106.606842ms","start":"2026-07-24T04:37:10.973985Z","end":"2026-07-24T04:37:11.080592Z","steps":["trace[1960201396] 'process raft request' (duration: 106.09994ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:11.080654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.087269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-24T04:37:11.080743Z","caller":"traceutil/trace.go:171","msg":"trace[2039606530] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:280; }","duration":"108.226676ms","start":"2026-07-24T04:37:10.972504Z","end":"2026-07-24T04:37:11.080731Z","steps":["trace[2039606530] 'agreement among raft nodes before linearized reading' (duration: 108.020456ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:37:13.550762Z","caller":"traceutil/trace.go:171","msg":"trace[1841377310] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"107.319616ms","start":"2026-07-24T04:37:13.44342Z","end":"2026-07-24T04:37:13.55074Z","steps":["trace[1841377310] 'process raft request' (duration: 106.122094ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:37:13.700293Z","caller":"traceutil/trace.go:171","msg":"trace[734937067] linearizableReadLoop","detail":"{readStateIndex:368; appliedIndex:366; }","duration":"114.422418ms","start":"2026-07-24T04:37:13.585798Z","end":"2026-07-24T04:37:13.700221Z","steps":["trace[734937067] 'read index received' (duration: 51.40693ms)","trace[734937067] 'applied index is now lower than readState.Index' (duration: 63.014078ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:37:13.70035Z","caller":"traceutil/trace.go:171","msg":"trace[1082491399] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"140.278269ms","start":"2026-07-24T04:37:13.560035Z","end":"2026-07-24T04:37:13.700313Z","steps":["trace[1082491399] 'process raft request' (duration: 138.777053ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:13.700544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.7135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-24T04:37:13.700591Z","caller":"traceutil/trace.go:171","msg":"trace[883095846] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:356; }","duration":"114.845586ms","start":"2026-07-24T04:37:13.585731Z","end":"2026-07-24T04:37:13.700576Z","steps":["trace[883095846] 'agreement among raft nodes before linearized reading' (duration: 114.719031ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:14.143662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.125266ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:37:14.143861Z","caller":"traceutil/trace.go:171","msg":"trace[1977845600] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"414.272139ms","start":"2026-07-24T04:37:13.729558Z","end":"2026-07-24T04:37:14.14383Z","steps":["trace[1977845600] 'process raft request' (duration: 123.899793ms)","trace[1977845600] 'compare' (duration: 289.965128ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:37:14.143877Z","caller":"traceutil/trace.go:171","msg":"trace[64114510] linearizableReadLoop","detail":"{readStateIndex:376; appliedIndex:372; }","duration":"408.045215ms","start":"2026-07-24T04:37:13.735818Z","end":"2026-07-24T04:37:14.143864Z","steps":["trace[64114510] 'read index received' (duration: 28.471245ms)","trace[64114510] 'applied index is now lower than readState.Index' (duration: 379.57282ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:37:14.143941Z","caller":"traceutil/trace.go:171","msg":"trace[33732590] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"412.611315ms","start":"2026-07-24T04:37:13.731322Z","end":"2026-07-24T04:37:14.143933Z","steps":["trace[33732590] 'process raft request' (duration: 412.431977ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:14.143959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:37:13.729529Z","time spent":"414.378183ms","remote":"127.0.0.1:40878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1739,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T04:37:14.143988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:37:13.73129Z","time spent":"412.670168ms","remote":"127.0.0.1:40850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T04:37:14.144405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.575999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-07-24T04:37:14.144442Z","caller":"traceutil/trace.go:171","msg":"trace[697153860] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:364; }","duration":"408.652872ms","start":"2026-07-24T04:37:13.735778Z","end":"2026-07-24T04:37:14.144431Z","steps":["trace[697153860] 'agreement among raft nodes before linearized reading' (duration: 408.515406ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:14.144475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:37:13.735763Z","time spent":"408.705534ms","remote":"127.0.0.1:40986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":219,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"info","ts":"2026-07-24T04:37:14.14454Z","caller":"traceutil/trace.go:171","msg":"trace[1135323534] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"411.181652ms","start":"2026-07-24T04:37:13.733325Z","end":"2026-07-24T04:37:14.144506Z","steps":["trace[1135323534] 'process raft request' (duration: 410.485152ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:14.144628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:37:13.733301Z","time spent":"411.278097ms","remote":"127.0.0.1:41254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3691,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:37:14.161896Z","caller":"traceutil/trace.go:171","msg":"trace[489813082] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"390.106551ms","start":"2026-07-24T04:37:13.771754Z","end":"2026-07-24T04:37:14.161861Z","steps":["trace[489813082] 'process raft request' (duration: 389.899122ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:14.162145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:37:13.771717Z","time spent":"390.270558ms","remote":"127.0.0.1:40958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3424,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:37:14.162242Z","caller":"traceutil/trace.go:171","msg":"trace[1861923805] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"390.186234ms","start":"2026-07-24T04:37:13.772037Z","end":"2026-07-24T04:37:14.162223Z","steps":["trace[1861923805] 'process raft request' (duration: 389.717634ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:14.162318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:37:13.772029Z","time spent":"390.254718ms","remote":"127.0.0.1:40850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":669,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T04:37:14.162375Z","caller":"traceutil/trace.go:171","msg":"trace[458084142] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"389.61339ms","start":"2026-07-24T04:37:13.772743Z","end":"2026-07-24T04:37:14.162357Z","steps":["trace[458084142] 'process raft request' (duration: 389.048575ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:14.162482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:37:13.772731Z","time spent":"389.711584ms","remote":"127.0.0.1:41266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2886,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:37:14.162555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.702178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-24T04:37:14.16261Z","caller":"traceutil/trace.go:171","msg":"trace[825129843] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:367; }","duration":"377.794122ms","start":"2026-07-24T04:37:13.784799Z","end":"2026-07-24T04:37:14.162593Z","steps":["trace[825129843] 'agreement among raft nodes before linearized reading' (duration: 377.697048ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:14.162654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:37:13.784782Z","time spent":"377.861065ms","remote":"127.0.0.1:40986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"info","ts":"2026-07-24T04:37:14.419002Z","caller":"traceutil/trace.go:171","msg":"trace[1785873404] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"251.749968ms","start":"2026-07-24T04:37:14.167228Z","end":"2026-07-24T04:37:14.418978Z","steps":["trace[1785873404] 'process raft request' (duration: 251.704746ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:37:14.419476Z","caller":"traceutil/trace.go:171","msg":"trace[1647934334] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"271.543555ms","start":"2026-07-24T04:37:14.147915Z","end":"2026-07-24T04:37:14.419459Z","steps":["trace[1647934334] 'process raft request' (duration: 197.340406ms)","trace[1647934334] 'compare' (duration: 73.425726ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:37:14.419592Z","caller":"traceutil/trace.go:171","msg":"trace[268598240] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"267.744621ms","start":"2026-07-24T04:37:14.151839Z","end":"2026-07-24T04:37:14.419584Z","steps":["trace[268598240] 'process raft request' (duration: 267.010589ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:37:14.419676Z","caller":"traceutil/trace.go:171","msg":"trace[772196419] linearizableReadLoop","detail":"{readStateIndex:381; appliedIndex:379; }","duration":"257.835662ms","start":"2026-07-24T04:37:14.161831Z","end":"2026-07-24T04:37:14.419667Z","steps":["trace[772196419] 'read index received' (duration: 183.357531ms)","trace[772196419] 'applied index is now lower than readState.Index' (duration: 74.477201ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:37:14.420015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.365035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-07-24T04:37:14.420051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.25134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-wqnbr\" ","response":"range_response_count:1 size:3439"} {"level":"info","ts":"2026-07-24T04:37:14.420134Z","caller":"traceutil/trace.go:171","msg":"trace[179190225] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-wqnbr; range_end:; response_count:1; response_revision:370; }","duration":"252.403427ms","start":"2026-07-24T04:37:14.167714Z","end":"2026-07-24T04:37:14.420117Z","steps":["trace[179190225] 'agreement among raft nodes before linearized reading' (duration: 252.233119ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:37:14.42013Z","caller":"traceutil/trace.go:171","msg":"trace[382285228] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:370; }","duration":"252.561484ms","start":"2026-07-24T04:37:14.167544Z","end":"2026-07-24T04:37:14.420106Z","steps":["trace[382285228] 'agreement among raft nodes before linearized reading' (duration: 252.253681ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:14.420037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.770211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"warn","ts":"2026-07-24T04:37:14.420517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.952843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3706"} {"level":"info","ts":"2026-07-24T04:37:14.420562Z","caller":"traceutil/trace.go:171","msg":"trace[2050623243] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:370; }","duration":"269.033607ms","start":"2026-07-24T04:37:14.151519Z","end":"2026-07-24T04:37:14.420553Z","steps":["trace[2050623243] 'agreement among raft nodes before linearized reading' (duration: 268.852329ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:37:14.420531Z","caller":"traceutil/trace.go:171","msg":"trace[203428583] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:370; }","duration":"111.231021ms","start":"2026-07-24T04:37:14.309102Z","end":"2026-07-24T04:37:14.420333Z","steps":["trace[203428583] 'agreement among raft nodes before linearized reading' (duration: 110.818563ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:37:14.663753Z","caller":"traceutil/trace.go:171","msg":"trace[1838174982] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"235.406452ms","start":"2026-07-24T04:37:14.428267Z","end":"2026-07-24T04:37:14.663674Z","steps":["trace[1838174982] 'process raft request' (duration: 148.982204ms)","trace[1838174982] 'compare' (duration: 86.165387ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:37:14.664703Z","caller":"traceutil/trace.go:171","msg":"trace[116183541] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"236.324951ms","start":"2026-07-24T04:37:14.428364Z","end":"2026-07-24T04:37:14.664689Z","steps":["trace[116183541] 'process raft request' (duration: 235.819859ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:37:14.664822Z","caller":"traceutil/trace.go:171","msg":"trace[2019803789] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"236.454777ms","start":"2026-07-24T04:37:14.428358Z","end":"2026-07-24T04:37:14.664813Z","steps":["trace[2019803789] 'process raft request' (duration: 236.203906ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:37:14.66494Z","caller":"traceutil/trace.go:171","msg":"trace[594318176] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"233.141914ms","start":"2026-07-24T04:37:14.431584Z","end":"2026-07-24T04:37:14.664726Z","steps":["trace[594318176] 'process raft request' (duration: 233.04631ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:37:43.226732Z","caller":"traceutil/trace.go:171","msg":"trace[535808655] linearizableReadLoop","detail":"{readStateIndex:514; appliedIndex:513; }","duration":"163.679203ms","start":"2026-07-24T04:37:43.063022Z","end":"2026-07-24T04:37:43.226702Z","steps":["trace[535808655] 'read index received' (duration: 162.964331ms)","trace[535808655] 'applied index is now lower than readState.Index' (duration: 713.512µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:37:43.226905Z","caller":"traceutil/trace.go:171","msg":"trace[1164548287] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"358.647335ms","start":"2026-07-24T04:37:42.868178Z","end":"2026-07-24T04:37:43.226826Z","steps":["trace[1164548287] 'process raft request' (duration: 358.108768ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:43.227043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.000303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-24T04:37:43.22708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:37:42.868158Z","time spent":"358.838001ms","remote":"127.0.0.1:40874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317296,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T04:37:43.227097Z","caller":"traceutil/trace.go:171","msg":"trace[1015491941] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:496; }","duration":"164.118187ms","start":"2026-07-24T04:37:43.062964Z","end":"2026-07-24T04:37:43.227083Z","steps":["trace[1015491941] 'agreement among raft nodes before linearized reading' (duration: 163.971303ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:43.250605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.059492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-24T04:37:43.250787Z","caller":"traceutil/trace.go:171","msg":"trace[1812238260] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:496; }","duration":"183.27474ms","start":"2026-07-24T04:37:43.067493Z","end":"2026-07-24T04:37:43.250767Z","steps":["trace[1812238260] 'agreement among raft nodes before linearized reading' (duration: 183.018032ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:43.250963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.036326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-24T04:37:43.251044Z","caller":"traceutil/trace.go:171","msg":"trace[735078500] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:497; }","duration":"118.1702ms","start":"2026-07-24T04:37:43.132857Z","end":"2026-07-24T04:37:43.251027Z","steps":["trace[735078500] 'agreement among raft nodes before linearized reading' (duration: 118.048136ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:37:45.740712Z","caller":"traceutil/trace.go:171","msg":"trace[768415645] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"632.688678ms","start":"2026-07-24T04:37:45.107966Z","end":"2026-07-24T04:37:45.740655Z","steps":["trace[768415645] 'process raft request' (duration: 630.371367ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:37:45.740955Z","caller":"traceutil/trace.go:171","msg":"trace[2077938059] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"632.878804ms","start":"2026-07-24T04:37:45.10804Z","end":"2026-07-24T04:37:45.740919Z","steps":["trace[2077938059] 'process raft request' (duration: 632.4016ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:45.741131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:37:45.107941Z","time spent":"633.025659ms","remote":"127.0.0.1:41340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T04:37:45.74115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:37:45.108036Z","time spent":"633.024509ms","remote":"127.0.0.1:41340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T04:37:45.741628Z","caller":"traceutil/trace.go:171","msg":"trace[848224083] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"629.745628ms","start":"2026-07-24T04:37:45.111862Z","end":"2026-07-24T04:37:45.741608Z","steps":["trace[848224083] 'process raft request' (duration: 629.380026ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:45.74177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:37:45.111856Z","time spent":"629.854921ms","remote":"127.0.0.1:40826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:37:45.741997Z","caller":"traceutil/trace.go:171","msg":"trace[2038285721] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"628.994464ms","start":"2026-07-24T04:37:45.112986Z","end":"2026-07-24T04:37:45.74198Z","steps":["trace[2038285721] 'process raft request' (duration: 628.435747ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:45.742109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:37:45.112957Z","time spent":"629.083417ms","remote":"127.0.0.1:40826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:37:45.74347Z","caller":"traceutil/trace.go:171","msg":"trace[701378690] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:570; }","duration":"635.167355ms","start":"2026-07-24T04:37:45.108156Z","end":"2026-07-24T04:37:45.743324Z","steps":["trace[701378690] 'read index received' (duration: 8.132451ms)","trace[701378690] 'applied index is now lower than readState.Index' (duration: 627.030763ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:37:45.743731Z","caller":"traceutil/trace.go:171","msg":"trace[1697909340] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"629.997646ms","start":"2026-07-24T04:37:45.113693Z","end":"2026-07-24T04:37:45.74369Z","steps":["trace[1697909340] 'process raft request' (duration: 628.167789ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:45.743923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:37:45.113685Z","time spent":"630.098199ms","remote":"127.0.0.1:40826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:37:45.74554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.368722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:10078"} {"level":"info","ts":"2026-07-24T04:37:45.745618Z","caller":"traceutil/trace.go:171","msg":"trace[1631518994] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:555; }","duration":"637.465116ms","start":"2026-07-24T04:37:45.108129Z","end":"2026-07-24T04:37:45.745594Z","steps":["trace[1631518994] 'agreement among raft nodes before linearized reading' (duration: 636.063822ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:45.745689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:37:45.108123Z","time spent":"637.549438ms","remote":"127.0.0.1:40826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":10100,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-07-24T04:37:45.811276Z","caller":"traceutil/trace.go:171","msg":"trace[317714618] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"685.364584ms","start":"2026-07-24T04:37:45.125872Z","end":"2026-07-24T04:37:45.811236Z","steps":["trace[317714618] 'process raft request' (duration: 685.090245ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:45.811508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:37:45.125841Z","time spent":"685.520649ms","remote":"127.0.0.1:40826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:37:45.811711Z","caller":"traceutil/trace.go:171","msg":"trace[1930884524] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"669.504774ms","start":"2026-07-24T04:37:45.142186Z","end":"2026-07-24T04:37:45.811691Z","steps":["trace[1930884524] 'process raft request' (duration: 668.979878ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:45.811796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:37:45.14214Z","time spent":"669.613858ms","remote":"127.0.0.1:40826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:37:45.812022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.916361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-24T04:37:45.812094Z","caller":"traceutil/trace.go:171","msg":"trace[1852854132] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:558; }","duration":"557.045315ms","start":"2026-07-24T04:37:45.255035Z","end":"2026-07-24T04:37:45.812081Z","steps":["trace[1852854132] 'agreement among raft nodes before linearized reading' (duration: 556.935292ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:45.812125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:37:45.255008Z","time spent":"557.109657ms","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-24T04:37:45.812475Z","caller":"traceutil/trace.go:171","msg":"trace[1396616240] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"639.400766ms","start":"2026-07-24T04:37:45.173048Z","end":"2026-07-24T04:37:45.812448Z","steps":["trace[1396616240] 'process raft request' (duration: 638.570791ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:45.812537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.512834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-24T04:37:45.812576Z","caller":"traceutil/trace.go:171","msg":"trace[719621444] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:558; }","duration":"550.569895ms","start":"2026-07-24T04:37:45.261994Z","end":"2026-07-24T04:37:45.812564Z","steps":["trace[719621444] 'agreement among raft nodes before linearized reading' (duration: 550.453161ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:45.812578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:37:45.17297Z","time spent":"639.564311ms","remote":"127.0.0.1:40826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T04:37:45.81261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:37:45.261984Z","time spent":"550.619757ms","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-24T04:37:45.812722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.131939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:37:45.812783Z","caller":"traceutil/trace.go:171","msg":"trace[133478003] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:558; }","duration":"241.185691ms","start":"2026-07-24T04:37:45.571577Z","end":"2026-07-24T04:37:45.812763Z","steps":["trace[133478003] 'agreement among raft nodes before linearized reading' (duration: 241.104678ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:45.812627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.233724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-24T04:37:45.812403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.304186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-24T04:37:45.813009Z","caller":"traceutil/trace.go:171","msg":"trace[1097625983] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:558; }","duration":"537.608946ms","start":"2026-07-24T04:37:45.275248Z","end":"2026-07-24T04:37:45.812857Z","steps":["trace[1097625983] 'agreement among raft nodes before linearized reading' (duration: 537.048029ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:37:45.813086Z","caller":"traceutil/trace.go:171","msg":"trace[839301862] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:558; }","duration":"538.014938ms","start":"2026-07-24T04:37:45.275041Z","end":"2026-07-24T04:37:45.813055Z","steps":["trace[839301862] 'agreement among raft nodes before linearized reading' (duration: 537.272355ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:45.813191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:37:45.275016Z","time spent":"538.135742ms","remote":"127.0.0.1:41050","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-07-24T04:37:45.813142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:37:45.275241Z","time spent":"537.885564ms","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-24T04:37:45.91205Z","caller":"traceutil/trace.go:171","msg":"trace[1798711920] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"127.388111ms","start":"2026-07-24T04:37:45.784616Z","end":"2026-07-24T04:37:45.912004Z","steps":["trace[1798711920] 'process raft request' (duration: 127.191605ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:37:45.911974Z","caller":"traceutil/trace.go:171","msg":"trace[1932863822] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"143.91861ms","start":"2026-07-24T04:37:45.767997Z","end":"2026-07-24T04:37:45.911915Z","steps":["trace[1932863822] 'process raft request' (duration: 116.025219ms)","trace[1932863822] 'compare' (duration: 27.444287ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:37:46.402118Z","caller":"traceutil/trace.go:171","msg":"trace[192299150] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"151.612501ms","start":"2026-07-24T04:37:46.25045Z","end":"2026-07-24T04:37:46.402062Z","steps":["trace[192299150] 'process raft request' (duration: 109.080502ms)","trace[192299150] 'compare' (duration: 42.180578ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:37:46.402437Z","caller":"traceutil/trace.go:171","msg":"trace[1346691793] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"145.768932ms","start":"2026-07-24T04:37:46.256641Z","end":"2026-07-24T04:37:46.40241Z","steps":["trace[1346691793] 'process raft request' (duration: 145.395531ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:37:46.402785Z","caller":"traceutil/trace.go:171","msg":"trace[977281832] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"118.789618ms","start":"2026-07-24T04:37:46.283962Z","end":"2026-07-24T04:37:46.402751Z","steps":["trace[977281832] 'process raft request' (duration: 118.400626ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:37:52.269557Z","caller":"traceutil/trace.go:171","msg":"trace[1898826912] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"313.441029ms","start":"2026-07-24T04:37:51.956081Z","end":"2026-07-24T04:37:52.269522Z","steps":["trace[1898826912] 'process raft request' (duration: 313.316715ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:37:52.269647Z","caller":"traceutil/trace.go:171","msg":"trace[67460351] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"311.43873ms","start":"2026-07-24T04:37:51.958199Z","end":"2026-07-24T04:37:52.269638Z","steps":["trace[67460351] 'process raft request' (duration: 311.247095ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:52.269827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:37:51.956067Z","time spent":"313.652154ms","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:37:52.269829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:37:51.958191Z","time spent":"311.540273ms","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:37:52.269584Z","caller":"traceutil/trace.go:171","msg":"trace[1125270275] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"314.328794ms","start":"2026-07-24T04:37:51.955208Z","end":"2026-07-24T04:37:52.269537Z","steps":["trace[1125270275] 'process raft request' (duration: 303.222754ms)","trace[1125270275] 'compare' (duration: 10.791791ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:37:52.269938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:37:51.955202Z","time spent":"314.700235ms","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:37:52.440548Z","caller":"traceutil/trace.go:171","msg":"trace[905405557] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"277.800769ms","start":"2026-07-24T04:37:52.162709Z","end":"2026-07-24T04:37:52.44051Z","steps":["trace[905405557] 'process raft request' (duration: 277.101519ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:52.793149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.611303ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:37:52.793283Z","caller":"traceutil/trace.go:171","msg":"trace[593853178] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"277.609314ms","start":"2026-07-24T04:37:52.51565Z","end":"2026-07-24T04:37:52.793259Z","steps":["trace[593853178] 'process raft request' (duration: 164.807536ms)","trace[593853178] 'compare' (duration: 112.405316ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:37:52.820819Z","caller":"traceutil/trace.go:171","msg":"trace[1870690923] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:664; }","duration":"250.103502ms","start":"2026-07-24T04:37:52.570696Z","end":"2026-07-24T04:37:52.820799Z","steps":["trace[1870690923] 'read index received' (duration: 109.861214ms)","trace[1870690923] 'applied index is now lower than readState.Index' (duration: 140.240938ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:37:52.820919Z","caller":"traceutil/trace.go:171","msg":"trace[928693033] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"288.902419ms","start":"2026-07-24T04:37:52.531971Z","end":"2026-07-24T04:37:52.820873Z","steps":["trace[928693033] 'process raft request' (duration: 288.701383ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:52.820989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.285826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:37:52.821025Z","caller":"traceutil/trace.go:171","msg":"trace[95922228] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:645; }","duration":"250.367819ms","start":"2026-07-24T04:37:52.570646Z","end":"2026-07-24T04:37:52.821013Z","steps":["trace[95922228] 'agreement among raft nodes before linearized reading' (duration: 250.296227ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:52.821291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.921192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-j4wl6\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-24T04:37:52.821397Z","caller":"traceutil/trace.go:171","msg":"trace[564880506] range","detail":"{range_begin:/registry/pods/kube-system/cilium-j4wl6; range_end:; response_count:1; response_revision:645; }","duration":"162.096847ms","start":"2026-07-24T04:37:52.659278Z","end":"2026-07-24T04:37:52.821375Z","steps":["trace[564880506] 'agreement among raft nodes before linearized reading' (duration: 161.84524ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:37:53.175355Z","caller":"traceutil/trace.go:171","msg":"trace[914640178] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"339.130747ms","start":"2026-07-24T04:37:52.836193Z","end":"2026-07-24T04:37:53.175324Z","steps":["trace[914640178] 'process raft request' (duration: 329.640087ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:53.175532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:37:52.83617Z","time spent":"339.266802ms","remote":"127.0.0.1:40958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:37:54.163528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"971.130241ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-07-24T04:37:54.37352Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.126840494s","expected-duration":"1s"} {"level":"info","ts":"2026-07-24T04:37:54.375727Z","caller":"traceutil/trace.go:171","msg":"trace[1623252698] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"1.137703083s","start":"2026-07-24T04:37:53.237999Z","end":"2026-07-24T04:37:54.375703Z","steps":["trace[1623252698] 'process raft request' (duration: 1.136379006s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:54.375931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:37:53.237966Z","time spent":"1.137838057s","remote":"127.0.0.1:40874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:37:54.375701Z","caller":"traceutil/trace.go:171","msg":"trace[1388926715] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:668; }","duration":"342.316369ms","start":"2026-07-24T04:37:54.033292Z","end":"2026-07-24T04:37:54.375609Z","steps":["trace[1388926715] 'read index received' (duration: 340.607891ms)","trace[1388926715] 'applied index is now lower than readState.Index' (duration: 1.707238ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:37:54.376091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.796852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-24T04:37:54.376189Z","caller":"traceutil/trace.go:171","msg":"trace[1179779365] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:647; }","duration":"342.949207ms","start":"2026-07-24T04:37:54.03322Z","end":"2026-07-24T04:37:54.376169Z","steps":["trace[1179779365] 'agreement among raft nodes before linearized reading' (duration: 342.565086ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:54.376306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:37:54.033191Z","time spent":"343.099062ms","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-24T04:37:54.376607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.038753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-24T04:37:54.376621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.833007ms","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-07-24T04:37:54.37687Z","caller":"traceutil/trace.go:171","msg":"trace[725712693] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:647; }","duration":"102.186576ms","start":"2026-07-24T04:37:54.274659Z","end":"2026-07-24T04:37:54.376845Z","steps":["trace[725712693] 'agreement among raft nodes before linearized reading' (duration: 101.652451ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:37:54.376708Z","caller":"traceutil/trace.go:171","msg":"trace[1897417770] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:647; }","duration":"102.205627ms","start":"2026-07-24T04:37:54.27448Z","end":"2026-07-24T04:37:54.376685Z","steps":["trace[1897417770] 'agreement among raft nodes before linearized reading' (duration: 101.855567ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:54.377158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.397311ms","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-07-24T04:37:54.377226Z","caller":"traceutil/trace.go:171","msg":"trace[744760965] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:647; }","duration":"103.503924ms","start":"2026-07-24T04:37:54.273708Z","end":"2026-07-24T04:37:54.377212Z","steps":["trace[744760965] 'agreement among raft nodes before linearized reading' (duration: 102.591238ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:56.067094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.895285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:37:56.067224Z","caller":"traceutil/trace.go:171","msg":"trace[1115503396] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:682; }","duration":"181.119051ms","start":"2026-07-24T04:37:55.886078Z","end":"2026-07-24T04:37:56.067197Z","steps":["trace[1115503396] 'range keys from in-memory index tree' (duration: 180.731821ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:38:09.398005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.691178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T04:38:09.398137Z","caller":"traceutil/trace.go:171","msg":"trace[1497289046] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:811; }","duration":"250.905433ms","start":"2026-07-24T04:38:09.147208Z","end":"2026-07-24T04:38:09.398114Z","steps":["trace[1497289046] 'count revisions from in-memory index tree' (duration: 250.450142ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:38:40.320892Z","caller":"traceutil/trace.go:171","msg":"trace[1908325289] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"337.187854ms","start":"2026-07-24T04:38:39.983673Z","end":"2026-07-24T04:38:40.320861Z","steps":["trace[1908325289] 'process raft request' (duration: 336.953579ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:38:40.321067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:38:39.983635Z","time spent":"337.345867ms","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:39:04.700041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.651448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:39:04.700193Z","caller":"traceutil/trace.go:171","msg":"trace[1393725825] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1482; }","duration":"130.874562ms","start":"2026-07-24T04:39:04.569291Z","end":"2026-07-24T04:39:04.700165Z","steps":["trace[1393725825] 'range keys from in-memory index tree' (duration: 130.480015ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:39:14.362222Z","caller":"traceutil/trace.go:171","msg":"trace[1094671061] transaction","detail":"{read_only:false; response_revision:1591; number_of_response:1; }","duration":"225.838963ms","start":"2026-07-24T04:39:14.136363Z","end":"2026-07-24T04:39:14.362202Z","steps":["trace[1094671061] 'process raft request' (duration: 225.708351ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:39:14.490839Z","caller":"traceutil/trace.go:171","msg":"trace[1307665986] transaction","detail":"{read_only:false; response_revision:1592; number_of_response:1; }","duration":"257.163013ms","start":"2026-07-24T04:39:14.233657Z","end":"2026-07-24T04:39:14.49082Z","steps":["trace[1307665986] 'process raft request' (duration: 209.638403ms)","trace[1307665986] 'compare' (duration: 47.281686ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:39:14.684803Z","caller":"traceutil/trace.go:171","msg":"trace[1267362115] linearizableReadLoop","detail":"{readStateIndex:1653; appliedIndex:1652; }","duration":"147.501579ms","start":"2026-07-24T04:39:14.537271Z","end":"2026-07-24T04:39:14.684773Z","steps":["trace[1267362115] 'read index received' (duration: 147.269026ms)","trace[1267362115] 'applied index is now lower than readState.Index' (duration: 231.573µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:39:14.684992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.685082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:39:14.685034Z","caller":"traceutil/trace.go:171","msg":"trace[1218756063] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:1593; }","duration":"147.807464ms","start":"2026-07-24T04:39:14.537215Z","end":"2026-07-24T04:39:14.685023Z","steps":["trace[1218756063] 'agreement among raft nodes before linearized reading' (duration: 147.710113ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:39:14.6849Z","caller":"traceutil/trace.go:171","msg":"trace[676157528] transaction","detail":"{read_only:false; response_revision:1593; number_of_response:1; }","duration":"156.31159ms","start":"2026-07-24T04:39:14.528557Z","end":"2026-07-24T04:39:14.684868Z","steps":["trace[676157528] 'process raft request' (duration: 156.047456ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:14.685644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.791179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:39:14.685713Z","caller":"traceutil/trace.go:171","msg":"trace[1406281114] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:1593; }","duration":"121.90262ms","start":"2026-07-24T04:39:14.563793Z","end":"2026-07-24T04:39:14.685696Z","steps":["trace[1406281114] 'agreement among raft nodes before linearized reading' (duration: 121.789948ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:14.685745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.160104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T04:39:14.68572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.734893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:39:14.685861Z","caller":"traceutil/trace.go:171","msg":"trace[182740871] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:1593; }","duration":"122.300096ms","start":"2026-07-24T04:39:14.563529Z","end":"2026-07-24T04:39:14.685829Z","steps":["trace[182740871] 'agreement among raft nodes before linearized reading' (duration: 122.158404ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:14.685937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.895956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:39:14.685867Z","caller":"traceutil/trace.go:171","msg":"trace[717937525] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:1593; }","duration":"132.902305ms","start":"2026-07-24T04:39:14.55294Z","end":"2026-07-24T04:39:14.685842Z","steps":["trace[717937525] 'agreement among raft nodes before linearized reading' (duration: 132.720683ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:39:14.685984Z","caller":"traceutil/trace.go:171","msg":"trace[680215952] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:1593; }","duration":"132.979368ms","start":"2026-07-24T04:39:14.552993Z","end":"2026-07-24T04:39:14.685972Z","steps":["trace[680215952] 'agreement among raft nodes before linearized reading' (duration: 132.903326ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:14.68612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.7467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:39:14.686239Z","caller":"traceutil/trace.go:171","msg":"trace[965212904] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:1593; }","duration":"148.880041ms","start":"2026-07-24T04:39:14.53734Z","end":"2026-07-24T04:39:14.68622Z","steps":["trace[965212904] 'agreement among raft nodes before linearized reading' (duration: 147.653572ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:14.686268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.403348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:39:14.688659Z","caller":"traceutil/trace.go:171","msg":"trace[1841470907] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:1593; }","duration":"124.804936ms","start":"2026-07-24T04:39:14.563831Z","end":"2026-07-24T04:39:14.688636Z","steps":["trace[1841470907] 'agreement among raft nodes before linearized reading' (duration: 122.392398ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:14.686588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.735319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:39:14.689048Z","caller":"traceutil/trace.go:171","msg":"trace[627638568] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:1593; }","duration":"136.211198ms","start":"2026-07-24T04:39:14.552816Z","end":"2026-07-24T04:39:14.689028Z","steps":["trace[627638568] 'agreement among raft nodes before linearized reading' (duration: 133.730629ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:14.6866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.580206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:39:14.689188Z","caller":"traceutil/trace.go:171","msg":"trace[1625464307] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:1593; }","duration":"151.180209ms","start":"2026-07-24T04:39:14.53799Z","end":"2026-07-24T04:39:14.68917Z","steps":["trace[1625464307] 'agreement among raft nodes before linearized reading' (duration: 148.565657ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:30.312794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.360215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6e6e6511-f518-47be-8d3b-e9beedac314a\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-24T04:39:30.312912Z","caller":"traceutil/trace.go:171","msg":"trace[562814241] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6e6e6511-f518-47be-8d3b-e9beedac314a; range_end:; response_count:1; response_revision:1891; }","duration":"124.582108ms","start":"2026-07-24T04:39:30.18831Z","end":"2026-07-24T04:39:30.312892Z","steps":["trace[562814241] 'range keys from in-memory index tree' (duration: 124.118061ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:39:30.436185Z","caller":"traceutil/trace.go:171","msg":"trace[724953144] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"116.353285ms","start":"2026-07-24T04:39:30.319815Z","end":"2026-07-24T04:39:30.436168Z","steps":["trace[724953144] 'process raft request' (duration: 116.01796ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:31.068881Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605186662425556196,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T04:39:31.521104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.374021ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:39:31.521334Z","caller":"traceutil/trace.go:171","msg":"trace[1583246785] linearizableReadLoop","detail":"{readStateIndex:1964; appliedIndex:1962; }","duration":"952.494515ms","start":"2026-07-24T04:39:30.568822Z","end":"2026-07-24T04:39:31.521317Z","steps":["trace[1583246785] 'read index received' (duration: 614.801759ms)","trace[1583246785] 'applied index is now lower than readState.Index' (duration: 337.691876ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:39:31.521422Z","caller":"traceutil/trace.go:171","msg":"trace[1983011680] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"503.923106ms","start":"2026-07-24T04:39:31.01749Z","end":"2026-07-24T04:39:31.521413Z","steps":["trace[1983011680] 'process raft request' (duration: 503.716323ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:31.521475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:39:31.017465Z","time spent":"503.978597ms","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:39:31.521498Z","caller":"traceutil/trace.go:171","msg":"trace[1486969841] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"1.034505859s","start":"2026-07-24T04:39:30.486972Z","end":"2026-07-24T04:39:31.521478Z","steps":["trace[1486969841] 'process raft request' (duration: 696.68495ms)","trace[1486969841] 'compare' (duration: 337.050277ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:39:31.521572Z","caller":"traceutil/trace.go:171","msg":"trace[878393067] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"391.13591ms","start":"2026-07-24T04:39:31.130428Z","end":"2026-07-24T04:39:31.521564Z","steps":["trace[878393067] 'process raft request' (duration: 390.854856ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:31.521575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:39:30.486934Z","time spent":"1.03460106s","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:39:31.521614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:39:31.130412Z","time spent":"391.179382ms","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:39:31.521943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.137228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6e6e6511-f518-47be-8d3b-e9beedac314a\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-07-24T04:39:31.522015Z","caller":"traceutil/trace.go:171","msg":"trace[855421405] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6e6e6511-f518-47be-8d3b-e9beedac314a; range_end:; response_count:1; response_revision:1895; }","duration":"799.21988ms","start":"2026-07-24T04:39:30.722777Z","end":"2026-07-24T04:39:31.521997Z","steps":["trace[855421405] 'agreement among raft nodes before linearized reading' (duration: 798.847634ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:31.522102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.441909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-24T04:39:31.522101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.314673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-24T04:39:31.522142Z","caller":"traceutil/trace.go:171","msg":"trace[897760209] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1895; }","duration":"361.514261ms","start":"2026-07-24T04:39:31.160614Z","end":"2026-07-24T04:39:31.522128Z","steps":["trace[897760209] 'agreement among raft nodes before linearized reading' (duration: 361.368078ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:39:31.522153Z","caller":"traceutil/trace.go:171","msg":"trace[1969499824] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1895; }","duration":"393.394014ms","start":"2026-07-24T04:39:31.128748Z","end":"2026-07-24T04:39:31.522142Z","steps":["trace[1969499824] 'agreement among raft nodes before linearized reading' (duration: 393.204701ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:31.522144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:39:30.722698Z","time spent":"799.427742ms","remote":"127.0.0.1:40958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3769,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6e6e6511-f518-47be-8d3b-e9beedac314a\" "} {"level":"warn","ts":"2026-07-24T04:39:31.522175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:39:31.160593Z","time spent":"361.576321ms","remote":"127.0.0.1:41050","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-07-24T04:39:31.522183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:39:31.128737Z","time spent":"393.440414ms","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-24T04:39:31.522184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"953.367758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T04:39:31.522117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.925967ms","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-07-24T04:39:31.522224Z","caller":"traceutil/trace.go:171","msg":"trace[23300738] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1895; }","duration":"953.431129ms","start":"2026-07-24T04:39:30.568778Z","end":"2026-07-24T04:39:31.522209Z","steps":["trace[23300738] 'agreement among raft nodes before linearized reading' (duration: 953.385148ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:39:31.522246Z","caller":"traceutil/trace.go:171","msg":"trace[916861709] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1895; }","duration":"154.17884ms","start":"2026-07-24T04:39:31.368058Z","end":"2026-07-24T04:39:31.522237Z","steps":["trace[916861709] 'agreement among raft nodes before linearized reading' (duration: 153.919606ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:31.522264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:39:30.568761Z","time spent":"953.495289ms","remote":"127.0.0.1:40780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-24T04:39:31.522331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.412905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-24T04:39:31.52237Z","caller":"traceutil/trace.go:171","msg":"trace[12144153] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1895; }","duration":"387.491386ms","start":"2026-07-24T04:39:31.134867Z","end":"2026-07-24T04:39:31.522358Z","steps":["trace[12144153] 'agreement among raft nodes before linearized reading' (duration: 387.381884ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:31.522406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.630103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-24T04:39:31.522418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:39:31.134848Z","time spent":"387.562408ms","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-24T04:39:31.522435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.468642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-24T04:39:31.52244Z","caller":"traceutil/trace.go:171","msg":"trace[2078272104] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1895; }","duration":"427.700124ms","start":"2026-07-24T04:39:31.094729Z","end":"2026-07-24T04:39:31.522429Z","steps":["trace[2078272104] 'agreement among raft nodes before linearized reading' (duration: 427.606393ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:39:31.522452Z","caller":"traceutil/trace.go:171","msg":"trace[2102718116] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1895; }","duration":"756.536372ms","start":"2026-07-24T04:39:30.76591Z","end":"2026-07-24T04:39:31.522447Z","steps":["trace[2102718116] 'agreement among raft nodes before linearized reading' (duration: 756.489952ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:31.522472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:39:30.765879Z","time spent":"756.586494ms","remote":"127.0.0.1:41050","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-07-24T04:39:31.522468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:39:31.094707Z","time spent":"427.754524ms","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-24T04:39:31.522511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.523154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-07-24T04:39:31.522536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.805355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-07-24T04:39:31.522549Z","caller":"traceutil/trace.go:171","msg":"trace[1424411392] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1895; }","duration":"603.852845ms","start":"2026-07-24T04:39:30.918691Z","end":"2026-07-24T04:39:31.522544Z","steps":["trace[1424411392] 'agreement among raft nodes before linearized reading' (duration: 603.813414ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:39:31.522548Z","caller":"traceutil/trace.go:171","msg":"trace[1441760444] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1895; }","duration":"797.596015ms","start":"2026-07-24T04:39:30.724942Z","end":"2026-07-24T04:39:31.522538Z","steps":["trace[1441760444] 'agreement among raft nodes before linearized reading' (duration: 797.506843ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:31.522561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:39:30.918672Z","time spent":"603.886106ms","remote":"127.0.0.1:39398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5466,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-24T04:39:31.522575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:39:30.724921Z","time spent":"797.647076ms","remote":"127.0.0.1:41050","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-07-24T04:39:31.749203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.374708ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:39:31.749394Z","caller":"traceutil/trace.go:171","msg":"trace[138344217] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"222.932941ms","start":"2026-07-24T04:39:31.526437Z","end":"2026-07-24T04:39:31.74937Z","steps":["trace[138344217] 'process raft request' (duration: 104.316099ms)","trace[138344217] 'compare' (duration: 118.219726ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:39:31.749462Z","caller":"traceutil/trace.go:171","msg":"trace[363397936] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"221.912706ms","start":"2026-07-24T04:39:31.527532Z","end":"2026-07-24T04:39:31.749445Z","steps":["trace[363397936] 'process raft request' (duration: 221.844365ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:39:31.749595Z","caller":"traceutil/trace.go:171","msg":"trace[986590475] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"222.018827ms","start":"2026-07-24T04:39:31.527462Z","end":"2026-07-24T04:39:31.74948Z","steps":["trace[986590475] 'process raft request' (duration: 221.835684ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:39:31.749744Z","caller":"traceutil/trace.go:171","msg":"trace[1422409962] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"222.153489ms","start":"2026-07-24T04:39:31.527566Z","end":"2026-07-24T04:39:31.749719Z","steps":["trace[1422409962] 'process raft request' (duration: 221.847454ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:39:31.749889Z","caller":"traceutil/trace.go:171","msg":"trace[1276398172] linearizableReadLoop","detail":"{readStateIndex:1971; appliedIndex:1966; }","duration":"181.79323ms","start":"2026-07-24T04:39:31.568069Z","end":"2026-07-24T04:39:31.749862Z","steps":["trace[1276398172] 'read index received' (duration: 62.61947ms)","trace[1276398172] 'applied index is now lower than readState.Index' (duration: 119.17244ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:39:31.750031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.943972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:39:31.750023Z","caller":"traceutil/trace.go:171","msg":"trace[1026958511] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"222.343922ms","start":"2026-07-24T04:39:31.527614Z","end":"2026-07-24T04:39:31.749958Z","steps":["trace[1026958511] 'process raft request' (duration: 222.057428ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:39:31.750108Z","caller":"traceutil/trace.go:171","msg":"trace[1218503819] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1901; }","duration":"182.067303ms","start":"2026-07-24T04:39:31.568026Z","end":"2026-07-24T04:39:31.750093Z","steps":["trace[1218503819] 'agreement among raft nodes before linearized reading' (duration: 181.936021ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:32.293855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.280632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-24T04:39:32.29393Z","caller":"traceutil/trace.go:171","msg":"trace[822934647] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1901; }","duration":"233.403134ms","start":"2026-07-24T04:39:32.060506Z","end":"2026-07-24T04:39:32.293909Z","steps":["trace[822934647] 'range keys from in-memory index tree' (duration: 233.065809ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:39:32.918767Z","caller":"traceutil/trace.go:171","msg":"trace[994690986] linearizableReadLoop","detail":"{readStateIndex:1973; appliedIndex:1972; }","duration":"391.466863ms","start":"2026-07-24T04:39:32.527273Z","end":"2026-07-24T04:39:32.91874Z","steps":["trace[994690986] 'read index received' (duration: 354.350294ms)","trace[994690986] 'applied index is now lower than readState.Index' (duration: 37.115149ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:39:32.918897Z","caller":"traceutil/trace.go:171","msg":"trace[10368806] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"514.594524ms","start":"2026-07-24T04:39:32.404289Z","end":"2026-07-24T04:39:32.918884Z","steps":["trace[10368806] 'process raft request' (duration: 477.396014ms)","trace[10368806] 'compare' (duration: 36.605342ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:39:32.918999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:39:32.404219Z","time spent":"514.704146ms","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:39:32.919198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.940297ms","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":"warn","ts":"2026-07-24T04:39:32.919252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.724392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-07-24T04:39:32.919354Z","caller":"traceutil/trace.go:171","msg":"trace[1757372867] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1903; }","duration":"388.881215ms","start":"2026-07-24T04:39:32.530445Z","end":"2026-07-24T04:39:32.919327Z","steps":["trace[1757372867] 'agreement among raft nodes before linearized reading' (duration: 388.588491ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:32.919433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:39:32.530416Z","time spent":"389.003686ms","remote":"127.0.0.1:39398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5466,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-24T04:39:32.919431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.511982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T04:39:32.919455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.184814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6e6e6511-f518-47be-8d3b-e9beedac314a\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-07-24T04:39:32.919482Z","caller":"traceutil/trace.go:171","msg":"trace[40318699] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1903; }","duration":"349.588693ms","start":"2026-07-24T04:39:32.569881Z","end":"2026-07-24T04:39:32.919469Z","steps":["trace[40318699] 'agreement among raft nodes before linearized reading' (duration: 349.510542ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:39:32.919285Z","caller":"traceutil/trace.go:171","msg":"trace[1030329718] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1903; }","duration":"133.08376ms","start":"2026-07-24T04:39:32.786179Z","end":"2026-07-24T04:39:32.919263Z","steps":["trace[1030329718] 'agreement among raft nodes before linearized reading' (duration: 132.857667ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:32.919503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.273651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:39:32.919681Z","caller":"traceutil/trace.go:171","msg":"trace[41245332] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1903; }","duration":"362.465104ms","start":"2026-07-24T04:39:32.557191Z","end":"2026-07-24T04:39:32.919656Z","steps":["trace[41245332] 'agreement among raft nodes before linearized reading' (duration: 362.264521ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:32.919747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:39:32.557181Z","time spent":"362.545225ms","remote":"127.0.0.1:57824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-24T04:39:32.91952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:39:32.569866Z","time spent":"349.646254ms","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-24T04:39:32.919515Z","caller":"traceutil/trace.go:171","msg":"trace[227459757] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6e6e6511-f518-47be-8d3b-e9beedac314a; range_end:; response_count:1; response_revision:1903; }","duration":"392.284234ms","start":"2026-07-24T04:39:32.527216Z","end":"2026-07-24T04:39:32.919501Z","steps":["trace[227459757] 'agreement among raft nodes before linearized reading' (duration: 392.101692ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:32.920454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:39:32.527196Z","time spent":"393.042566ms","remote":"127.0.0.1:40958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3769,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6e6e6511-f518-47be-8d3b-e9beedac314a\" "} {"level":"info","ts":"2026-07-24T04:39:36.089861Z","caller":"traceutil/trace.go:171","msg":"trace[575389698] transaction","detail":"{read_only:false; response_revision:1937; number_of_response:1; }","duration":"119.542844ms","start":"2026-07-24T04:39:35.970293Z","end":"2026-07-24T04:39:36.089836Z","steps":["trace[575389698] 'process raft request' (duration: 118.874434ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:39:36.856027Z","caller":"traceutil/trace.go:171","msg":"trace[161710408] linearizableReadLoop","detail":"{readStateIndex:2013; appliedIndex:2012; }","duration":"163.897008ms","start":"2026-07-24T04:39:36.6921Z","end":"2026-07-24T04:39:36.855997Z","steps":["trace[161710408] 'read index received' (duration: 126.9415ms)","trace[161710408] 'applied index is now lower than readState.Index' (duration: 36.954058ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:39:36.856125Z","caller":"traceutil/trace.go:171","msg":"trace[679187953] transaction","detail":"{read_only:false; response_revision:1941; number_of_response:1; }","duration":"165.902078ms","start":"2026-07-24T04:39:36.69019Z","end":"2026-07-24T04:39:36.856092Z","steps":["trace[679187953] 'process raft request' (duration: 128.913878ms)","trace[679187953] 'compare' (duration: 36.550823ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:39:36.85643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.311985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:10374"} {"level":"info","ts":"2026-07-24T04:39:36.856488Z","caller":"traceutil/trace.go:171","msg":"trace[1537337064] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1941; }","duration":"164.67976ms","start":"2026-07-24T04:39:36.691793Z","end":"2026-07-24T04:39:36.856473Z","steps":["trace[1537337064] 'agreement among raft nodes before linearized reading' (duration: 164.359706ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:39:45.808451Z","caller":"traceutil/trace.go:171","msg":"trace[165982636] linearizableReadLoop","detail":"{readStateIndex:2079; appliedIndex:2078; }","duration":"237.483233ms","start":"2026-07-24T04:39:45.57094Z","end":"2026-07-24T04:39:45.808423Z","steps":["trace[165982636] 'read index received' (duration: 237.224659ms)","trace[165982636] 'applied index is now lower than readState.Index' (duration: 257.464µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:39:45.808566Z","caller":"traceutil/trace.go:171","msg":"trace[303864109] transaction","detail":"{read_only:false; response_revision:2005; number_of_response:1; }","duration":"246.302378ms","start":"2026-07-24T04:39:45.562226Z","end":"2026-07-24T04:39:45.808528Z","steps":["trace[303864109] 'process raft request' (duration: 245.991273ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:45.808698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.741817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:39:45.808771Z","caller":"traceutil/trace.go:171","msg":"trace[154460081] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2005; }","duration":"237.882469ms","start":"2026-07-24T04:39:45.570869Z","end":"2026-07-24T04:39:45.808752Z","steps":["trace[154460081] 'agreement among raft nodes before linearized reading' (duration: 237.716016ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:39:45.823487Z","caller":"traceutil/trace.go:171","msg":"trace[1292508029] transaction","detail":"{read_only:false; response_revision:2006; number_of_response:1; }","duration":"239.938368ms","start":"2026-07-24T04:39:45.58353Z","end":"2026-07-24T04:39:45.823469Z","steps":["trace[1292508029] 'process raft request' (duration: 239.814416ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:39:45.82379Z","caller":"traceutil/trace.go:171","msg":"trace[1412960915] transaction","detail":"{read_only:false; response_revision:2007; number_of_response:1; }","duration":"240.161691ms","start":"2026-07-24T04:39:45.583602Z","end":"2026-07-24T04:39:45.823763Z","steps":["trace[1412960915] 'process raft request' (duration: 239.839516ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:39:45.824674Z","caller":"traceutil/trace.go:171","msg":"trace[738104193] transaction","detail":"{read_only:false; response_revision:2008; number_of_response:1; }","duration":"185.074723ms","start":"2026-07-24T04:39:45.639573Z","end":"2026-07-24T04:39:45.824648Z","steps":["trace[738104193] 'process raft request' (duration: 184.092299ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:45.824973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.882772ms","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-07-24T04:39:45.825101Z","caller":"traceutil/trace.go:171","msg":"trace[681919343] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2008; }","duration":"183.037725ms","start":"2026-07-24T04:39:45.642029Z","end":"2026-07-24T04:39:45.825067Z","steps":["trace[681919343] 'agreement among raft nodes before linearized reading' (duration: 182.604639ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:48.392877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.213821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-24T04:39:48.392989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.87048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-24T04:39:48.393024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.243413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-07-24T04:39:48.393044Z","caller":"traceutil/trace.go:171","msg":"trace[1429284603] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:2030; }","duration":"356.461775ms","start":"2026-07-24T04:39:48.036553Z","end":"2026-07-24T04:39:48.393015Z","steps":["trace[1429284603] 'count revisions from in-memory index tree' (duration: 356.12227ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:39:48.393078Z","caller":"traceutil/trace.go:171","msg":"trace[358999666] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2030; }","duration":"366.988972ms","start":"2026-07-24T04:39:48.026065Z","end":"2026-07-24T04:39:48.393054Z","steps":["trace[358999666] 'range keys from in-memory index tree' (duration: 366.613227ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:39:48.393106Z","caller":"traceutil/trace.go:171","msg":"trace[1857201248] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2030; }","duration":"133.396956ms","start":"2026-07-24T04:39:48.259689Z","end":"2026-07-24T04:39:48.393086Z","steps":["trace[1857201248] 'range keys from in-memory index tree' (duration: 133.06884ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:48.393121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:39:48.036542Z","time spent":"356.565696ms","remote":"127.0.0.1:41094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":29,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-24T04:39:48.39311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.377214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-07-24T04:39:48.393134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:39:48.026031Z","time spent":"367.093253ms","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-24T04:39:48.393188Z","caller":"traceutil/trace.go:171","msg":"trace[309544671] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2030; }","duration":"381.493685ms","start":"2026-07-24T04:39:48.011671Z","end":"2026-07-24T04:39:48.393165Z","steps":["trace[309544671] 'range keys from in-memory index tree' (duration: 381.12637ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:48.393236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:39:48.011621Z","time spent":"381.600176ms","remote":"127.0.0.1:41050","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-07-24T04:39:48.393232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.371529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.177\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-07-24T04:39:48.393347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.89942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-24T04:39:48.393359Z","caller":"traceutil/trace.go:171","msg":"trace[1756295071] range","detail":"{range_begin:/registry/masterleases/199.204.45.177; range_end:; response_count:1; response_revision:2030; }","duration":"257.535281ms","start":"2026-07-24T04:39:48.135794Z","end":"2026-07-24T04:39:48.393329Z","steps":["trace[1756295071] 'range keys from in-memory index tree' (duration: 257.044434ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:39:48.393382Z","caller":"traceutil/trace.go:171","msg":"trace[1246163657] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2030; }","duration":"366.948912ms","start":"2026-07-24T04:39:48.026423Z","end":"2026-07-24T04:39:48.393372Z","steps":["trace[1246163657] 'range keys from in-memory index tree' (duration: 366.788149ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:48.393411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:39:48.026415Z","time spent":"366.990662ms","remote":"127.0.0.1:41050","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-07-24T04:39:48.39355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.959859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-07-24T04:39:48.393583Z","caller":"traceutil/trace.go:171","msg":"trace[1920143116] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2030; }","duration":"369.03379ms","start":"2026-07-24T04:39:48.02454Z","end":"2026-07-24T04:39:48.393573Z","steps":["trace[1920143116] 'range keys from in-memory index tree' (duration: 368.832177ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:48.393611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:39:48.024519Z","time spent":"369.085221ms","remote":"127.0.0.1:39398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5466,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-24T04:39:48.394158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.273449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-24T04:39:48.394388Z","caller":"traceutil/trace.go:171","msg":"trace[1591133123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2030; }","duration":"368.537044ms","start":"2026-07-24T04:39:48.025837Z","end":"2026-07-24T04:39:48.394374Z","steps":["trace[1591133123] 'range keys from in-memory index tree' (duration: 368.110968ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:48.394444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:39:48.02582Z","time spent":"368.608534ms","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-24T04:40:22.408354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.331018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:40:22.408581Z","caller":"traceutil/trace.go:171","msg":"trace[2132672803] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:2217; }","duration":"208.604372ms","start":"2026-07-24T04:40:22.199949Z","end":"2026-07-24T04:40:22.408553Z","steps":["trace[2132672803] 'count revisions from in-memory index tree' (duration: 208.261047ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:40:22.408673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.556534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-24T04:40:22.4087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.438493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-24T04:40:22.408754Z","caller":"traceutil/trace.go:171","msg":"trace[1388925685] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2217; }","duration":"298.684455ms","start":"2026-07-24T04:40:22.110049Z","end":"2026-07-24T04:40:22.408734Z","steps":["trace[1388925685] 'range keys from in-memory index tree' (duration: 298.365981ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:40:22.408761Z","caller":"traceutil/trace.go:171","msg":"trace[493295095] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2217; }","duration":"345.540725ms","start":"2026-07-24T04:40:22.063202Z","end":"2026-07-24T04:40:22.408743Z","steps":["trace[493295095] 'range keys from in-memory index tree' (duration: 345.253281ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:40:22.408807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:40:22.063179Z","time spent":"345.615006ms","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-24T04:40:22.661604Z","caller":"traceutil/trace.go:171","msg":"trace[132785463] transaction","detail":"{read_only:false; response_revision:2218; number_of_response:1; }","duration":"248.633244ms","start":"2026-07-24T04:40:22.412943Z","end":"2026-07-24T04:40:22.661577Z","steps":["trace[132785463] 'process raft request' (duration: 248.480682ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:40:23.898166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.346984ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:40:23.898442Z","caller":"traceutil/trace.go:171","msg":"trace[1161740890] transaction","detail":"{read_only:false; response_revision:2219; number_of_response:1; }","duration":"1.484065672s","start":"2026-07-24T04:40:22.414338Z","end":"2026-07-24T04:40:23.898404Z","steps":["trace[1161740890] 'process raft request' (duration: 983.368153ms)","trace[1161740890] 'compare' (duration: 499.926989ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:40:23.898657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:40:22.414325Z","time spent":"1.484191433s","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:40:23.898931Z","caller":"traceutil/trace.go:171","msg":"trace[1980753028] linearizableReadLoop","detail":"{readStateIndex:2301; appliedIndex:2299; }","duration":"1.337716498s","start":"2026-07-24T04:40:22.561179Z","end":"2026-07-24T04:40:23.898896Z","steps":["trace[1980753028] 'read index received' (duration: 100.256354ms)","trace[1980753028] 'applied index is now lower than readState.Index' (duration: 1.237458464s)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:40:23.900393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.337083s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-24T04:40:23.900508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.562428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T04:40:23.900564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.339391139s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-07-24T04:40:23.900545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.297815657s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:40:23.900632Z","caller":"traceutil/trace.go:171","msg":"trace[1730338330] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2219; }","duration":"1.33946272s","start":"2026-07-24T04:40:22.561126Z","end":"2026-07-24T04:40:23.900588Z","steps":["trace[1730338330] 'agreement among raft nodes before linearized reading' (duration: 1.33790677s)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:40:23.90063Z","caller":"traceutil/trace.go:171","msg":"trace[1627610981] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:2219; }","duration":"1.297941788s","start":"2026-07-24T04:40:22.602663Z","end":"2026-07-24T04:40:23.900605Z","steps":["trace[1627610981] 'agreement among raft nodes before linearized reading' (duration: 1.297797106s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:40:23.900687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:40:22.602648Z","time spent":"1.29802436s","remote":"127.0.0.1:35940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true "} {"level":"warn","ts":"2026-07-24T04:40:23.9007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:40:22.561103Z","time spent":"1.339562362s","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-07-24T04:40:23.900703Z","caller":"traceutil/trace.go:171","msg":"trace[1767133687] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:2219; }","duration":"299.6656ms","start":"2026-07-24T04:40:23.600888Z","end":"2026-07-24T04:40:23.900553Z","steps":["trace[1767133687] 'agreement among raft nodes before linearized reading' (duration: 299.583559ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:40:23.90053Z","caller":"traceutil/trace.go:171","msg":"trace[1659019760] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2219; }","duration":"1.337257102s","start":"2026-07-24T04:40:22.563256Z","end":"2026-07-24T04:40:23.900513Z","steps":["trace[1659019760] 'agreement among raft nodes before linearized reading' (duration: 1.336964498s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:40:23.90086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:40:22.563214Z","time spent":"1.337625977s","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-24T04:40:23.900431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.166509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:40:23.901062Z","caller":"traceutil/trace.go:171","msg":"trace[1113964728] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2219; }","duration":"330.830558ms","start":"2026-07-24T04:40:23.57022Z","end":"2026-07-24T04:40:23.90105Z","steps":["trace[1113964728] 'agreement among raft nodes before linearized reading' (duration: 330.15911ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:40:23.901119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:40:23.570203Z","time spent":"330.906269ms","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-24T04:40:23.901238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.297685445s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-07-24T04:40:23.901282Z","caller":"traceutil/trace.go:171","msg":"trace[1541774876] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2219; }","duration":"1.297745545s","start":"2026-07-24T04:40:22.603523Z","end":"2026-07-24T04:40:23.901269Z","steps":["trace[1541774876] 'agreement among raft nodes before linearized reading' (duration: 1.297284999s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:40:23.900451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.353599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18c51ffccf88166c\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-07-24T04:40:23.901359Z","caller":"traceutil/trace.go:171","msg":"trace[1938600380] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18c51ffccf88166c; range_end:; response_count:1; response_revision:2219; }","duration":"578.29109ms","start":"2026-07-24T04:40:23.323053Z","end":"2026-07-24T04:40:23.901344Z","steps":["trace[1938600380] 'agreement among raft nodes before linearized reading' (duration: 577.186836ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:40:23.900767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.773807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T04:40:23.901427Z","caller":"traceutil/trace.go:171","msg":"trace[631323174] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:2219; }","duration":"633.470586ms","start":"2026-07-24T04:40:23.267947Z","end":"2026-07-24T04:40:23.901418Z","steps":["trace[631323174] 'agreement among raft nodes before linearized reading' (duration: 632.773837ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:40:23.901441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.44753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12386"} {"level":"warn","ts":"2026-07-24T04:40:23.901492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:40:23.26793Z","time spent":"633.518177ms","remote":"127.0.0.1:40924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":29,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"info","ts":"2026-07-24T04:40:23.901492Z","caller":"traceutil/trace.go:171","msg":"trace[1712712816] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2219; }","duration":"167.541641ms","start":"2026-07-24T04:40:23.733936Z","end":"2026-07-24T04:40:23.901478Z","steps":["trace[1712712816] 'agreement among raft nodes before linearized reading' (duration: 166.382326ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:40:23.901505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:40:23.323038Z","time spent":"578.372312ms","remote":"127.0.0.1:40850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":868,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18c51ffccf88166c\" "} {"level":"warn","ts":"2026-07-24T04:40:23.901369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:40:22.603515Z","time spent":"1.297786306s","remote":"127.0.0.1:39398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5466,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-24T04:40:23.901816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.33166859s","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-07-24T04:40:23.901882Z","caller":"traceutil/trace.go:171","msg":"trace[1556422548] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2219; }","duration":"1.331781062s","start":"2026-07-24T04:40:22.57009Z","end":"2026-07-24T04:40:23.901871Z","steps":["trace[1556422548] 'agreement among raft nodes before linearized reading' (duration: 1.33081657s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:40:23.901915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:40:22.570067Z","time spent":"1.331838322s","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-24T04:40:23.902386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.33167516s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-24T04:40:23.902453Z","caller":"traceutil/trace.go:171","msg":"trace[24482863] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2219; }","duration":"1.332266958s","start":"2026-07-24T04:40:22.570171Z","end":"2026-07-24T04:40:23.902438Z","steps":["trace[24482863] 'agreement among raft nodes before linearized reading' (duration: 1.330643477s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:40:23.90255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:40:22.570162Z","time spent":"1.332327749s","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-24T04:41:01.049698Z","caller":"traceutil/trace.go:171","msg":"trace[266056414] linearizableReadLoop","detail":"{readStateIndex:2563; appliedIndex:2562; }","duration":"122.973052ms","start":"2026-07-24T04:41:00.9267Z","end":"2026-07-24T04:41:01.049673Z","steps":["trace[266056414] 'read index received' (duration: 122.876011ms)","trace[266056414] 'applied index is now lower than readState.Index' (duration: 95.931µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:41:01.049915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.202395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:8551"} {"level":"info","ts":"2026-07-24T04:41:01.049975Z","caller":"traceutil/trace.go:171","msg":"trace[1989752624] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:2472; }","duration":"123.279196ms","start":"2026-07-24T04:41:00.926668Z","end":"2026-07-24T04:41:01.049947Z","steps":["trace[1989752624] 'agreement among raft nodes before linearized reading' (duration: 123.110273ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:01.04999Z","caller":"traceutil/trace.go:171","msg":"trace[832647897] transaction","detail":"{read_only:false; response_revision:2472; number_of_response:1; }","duration":"123.821063ms","start":"2026-07-24T04:41:00.926147Z","end":"2026-07-24T04:41:01.049968Z","steps":["trace[832647897] 'process raft request' (duration: 123.405828ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:09.350289Z","caller":"traceutil/trace.go:171","msg":"trace[1592359040] linearizableReadLoop","detail":"{readStateIndex:2665; appliedIndex:2664; }","duration":"105.373738ms","start":"2026-07-24T04:41:09.2449Z","end":"2026-07-24T04:41:09.350273Z","steps":["trace[1592359040] 'read index received' (duration: 105.223836ms)","trace[1592359040] 'applied index is now lower than readState.Index' (duration: 149.122µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:41:09.350408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.495819ms","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-07-24T04:41:09.350371Z","caller":"traceutil/trace.go:171","msg":"trace[1279444189] transaction","detail":"{read_only:false; response_revision:2572; number_of_response:1; }","duration":"261.162738ms","start":"2026-07-24T04:41:09.089173Z","end":"2026-07-24T04:41:09.350336Z","steps":["trace[1279444189] 'process raft request' (duration: 260.994586ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:09.350428Z","caller":"traceutil/trace.go:171","msg":"trace[1532629922] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:2572; }","duration":"105.560661ms","start":"2026-07-24T04:41:09.244861Z","end":"2026-07-24T04:41:09.350422Z","steps":["trace[1532629922] 'agreement among raft nodes before linearized reading' (duration: 105.50863ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:42.358599Z","caller":"traceutil/trace.go:171","msg":"trace[1836566693] linearizableReadLoop","detail":"{readStateIndex:2851; appliedIndex:2850; }","duration":"106.558104ms","start":"2026-07-24T04:41:42.252022Z","end":"2026-07-24T04:41:42.35858Z","steps":["trace[1836566693] 'read index received' (duration: 106.435589ms)","trace[1836566693] 'applied index is now lower than readState.Index' (duration: 121.335µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:41:42.358883Z","caller":"traceutil/trace.go:171","msg":"trace[1390291840] transaction","detail":"{read_only:false; response_revision:2751; number_of_response:1; }","duration":"124.736653ms","start":"2026-07-24T04:41:42.234134Z","end":"2026-07-24T04:41:42.358871Z","steps":["trace[1390291840] 'process raft request' (duration: 124.330167ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:42.35922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.190228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-24T04:41:42.359379Z","caller":"traceutil/trace.go:171","msg":"trace[1664821508] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2751; }","duration":"107.378665ms","start":"2026-07-24T04:41:42.251981Z","end":"2026-07-24T04:41:42.35936Z","steps":["trace[1664821508] 'agreement among raft nodes before linearized reading' (duration: 107.152477ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:42.433446Z","caller":"traceutil/trace.go:171","msg":"trace[1036164876] transaction","detail":"{read_only:false; response_revision:2752; number_of_response:1; }","duration":"176.764582ms","start":"2026-07-24T04:41:42.256663Z","end":"2026-07-24T04:41:42.433427Z","steps":["trace[1036164876] 'process raft request' (duration: 176.637757ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:42.433646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.87935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-24T04:41:42.433669Z","caller":"traceutil/trace.go:171","msg":"trace[2034207720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2753; }","duration":"137.938882ms","start":"2026-07-24T04:41:42.295723Z","end":"2026-07-24T04:41:42.433662Z","steps":["trace[2034207720] 'agreement among raft nodes before linearized reading' (duration: 137.844948ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:42.433697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.011316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-07-24T04:41:42.433716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.292551ms","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-07-24T04:41:42.433725Z","caller":"traceutil/trace.go:171","msg":"trace[1974451233] transaction","detail":"{read_only:false; response_revision:2753; number_of_response:1; }","duration":"175.757574ms","start":"2026-07-24T04:41:42.257961Z","end":"2026-07-24T04:41:42.433719Z","steps":["trace[1974451233] 'process raft request' (duration: 175.437882ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:42.433763Z","caller":"traceutil/trace.go:171","msg":"trace[1190959344] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2753; }","duration":"140.348963ms","start":"2026-07-24T04:41:42.293402Z","end":"2026-07-24T04:41:42.433751Z","steps":["trace[1190959344] 'agreement among raft nodes before linearized reading' (duration: 140.237069ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:42.433762Z","caller":"traceutil/trace.go:171","msg":"trace[839628525] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2753; }","duration":"142.11092ms","start":"2026-07-24T04:41:42.291635Z","end":"2026-07-24T04:41:42.433746Z","steps":["trace[839628525] 'agreement among raft nodes before linearized reading' (duration: 141.894182ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:42.433753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.563352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-24T04:41:42.43384Z","caller":"traceutil/trace.go:171","msg":"trace[1679549054] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2753; }","duration":"121.714237ms","start":"2026-07-24T04:41:42.312111Z","end":"2026-07-24T04:41:42.433826Z","steps":["trace[1679549054] 'agreement among raft nodes before linearized reading' (duration: 121.452017ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:43.162113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.007669ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:41:43.162414Z","caller":"traceutil/trace.go:171","msg":"trace[2027081566] transaction","detail":"{read_only:false; response_revision:2756; number_of_response:1; }","duration":"722.265185ms","start":"2026-07-24T04:41:42.440131Z","end":"2026-07-24T04:41:43.162396Z","steps":["trace[2027081566] 'process raft request' (duration: 722.090618ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:43.162449Z","caller":"traceutil/trace.go:171","msg":"trace[162030947] transaction","detail":"{read_only:false; response_revision:2757; number_of_response:1; }","duration":"721.720705ms","start":"2026-07-24T04:41:42.44072Z","end":"2026-07-24T04:41:43.16244Z","steps":["trace[162030947] 'process raft request' (duration: 721.579459ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:43.162496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:42.440712Z","time spent":"721.758136ms","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:41:43.162485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:42.440126Z","time spent":"722.325917ms","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:41:43.162599Z","caller":"traceutil/trace.go:171","msg":"trace[1010944958] linearizableReadLoop","detail":"{readStateIndex:2858; appliedIndex:2854; }","duration":"549.701102ms","start":"2026-07-24T04:41:42.612879Z","end":"2026-07-24T04:41:43.16258Z","steps":["trace[1010944958] 'read index received' (duration: 33.271µs)","trace[1010944958] 'applied index is now lower than readState.Index' (duration: 549.666421ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:41:43.162628Z","caller":"traceutil/trace.go:171","msg":"trace[972752372] transaction","detail":"{read_only:false; response_revision:2758; number_of_response:1; }","duration":"720.80945ms","start":"2026-07-24T04:41:42.441793Z","end":"2026-07-24T04:41:43.162603Z","steps":["trace[972752372] 'process raft request' (duration: 720.616343ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:43.162722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.838867ms","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-07-24T04:41:43.162748Z","caller":"traceutil/trace.go:171","msg":"trace[1850851988] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:2758; }","duration":"549.897999ms","start":"2026-07-24T04:41:42.612842Z","end":"2026-07-24T04:41:43.162741Z","steps":["trace[1850851988] 'agreement among raft nodes before linearized reading' (duration: 549.851837ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:43.162776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:42.612824Z","time spent":"549.945471ms","remote":"127.0.0.1:45880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":29,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "} {"level":"warn","ts":"2026-07-24T04:41:43.162921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:42.441784Z","time spent":"720.953495ms","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:41:43.162958Z","caller":"traceutil/trace.go:171","msg":"trace[1854310015] transaction","detail":"{read_only:false; response_revision:2755; number_of_response:1; }","duration":"722.882168ms","start":"2026-07-24T04:41:42.440061Z","end":"2026-07-24T04:41:43.162943Z","steps":["trace[1854310015] 'process raft request' (duration: 24.948614ms)","trace[1854310015] 'compare' (duration: 695.782992ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:41:43.163111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.415917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18c5201083ca5ba0\" ","response":"range_response_count:1 size:779"} {"level":"info","ts":"2026-07-24T04:41:43.16345Z","caller":"traceutil/trace.go:171","msg":"trace[431475499] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18c5201083ca5ba0; range_end:; response_count:1; response_revision:2758; }","duration":"537.78543ms","start":"2026-07-24T04:41:42.625652Z","end":"2026-07-24T04:41:43.163438Z","steps":["trace[431475499] 'agreement among raft nodes before linearized reading' (duration: 537.069133ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:43.163045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:42.440045Z","time spent":"722.974262ms","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:41:43.163568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:42.625643Z","time spent":"537.884715ms","remote":"127.0.0.1:40850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":801,"request content":"key:\"/registry/events/auth-system/keycloak-0.18c5201083ca5ba0\" "} {"level":"warn","ts":"2026-07-24T04:41:43.163209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.127929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T04:41:43.163695Z","caller":"traceutil/trace.go:171","msg":"trace[1852184423] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:2758; }","duration":"470.629588ms","start":"2026-07-24T04:41:42.693037Z","end":"2026-07-24T04:41:43.163667Z","steps":["trace[1852184423] 'agreement among raft nodes before linearized reading' (duration: 469.974013ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:43.163774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:42.693019Z","time spent":"470.727992ms","remote":"127.0.0.1:40972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":21,"response size":29,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"info","ts":"2026-07-24T04:41:43.381012Z","caller":"traceutil/trace.go:171","msg":"trace[1718697331] linearizableReadLoop","detail":"{readStateIndex:2859; appliedIndex:2858; }","duration":"105.464544ms","start":"2026-07-24T04:41:43.27552Z","end":"2026-07-24T04:41:43.380984Z","steps":["trace[1718697331] 'read index received' (duration: 67.67683ms)","trace[1718697331] 'applied index is now lower than readState.Index' (duration: 37.786114ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:41:43.381041Z","caller":"traceutil/trace.go:171","msg":"trace[1289474771] transaction","detail":"{read_only:false; response_revision:2759; number_of_response:1; }","duration":"215.437917ms","start":"2026-07-24T04:41:43.165571Z","end":"2026-07-24T04:41:43.381009Z","steps":["trace[1289474771] 'process raft request' (duration: 177.646563ms)","trace[1289474771] 'compare' (duration: 37.650998ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:41:43.381345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.798016ms","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-07-24T04:41:43.381864Z","caller":"traceutil/trace.go:171","msg":"trace[963392256] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2759; }","duration":"105.882239ms","start":"2026-07-24T04:41:43.275515Z","end":"2026-07-24T04:41:43.381397Z","steps":["trace[963392256] 'agreement among raft nodes before linearized reading' (duration: 105.62905ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:45:28.251958Z","caller":"traceutil/trace.go:171","msg":"trace[120219683] transaction","detail":"{read_only:false; response_revision:4564; number_of_response:1; }","duration":"170.261238ms","start":"2026-07-24T04:45:28.081673Z","end":"2026-07-24T04:45:28.251934Z","steps":["trace[120219683] 'process raft request' (duration: 170.113856ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:28.50271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.23024ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:45:28.502799Z","caller":"traceutil/trace.go:171","msg":"trace[2041040313] linearizableReadLoop","detail":"{readStateIndex:4733; appliedIndex:4732; }","duration":"357.688195ms","start":"2026-07-24T04:45:28.145096Z","end":"2026-07-24T04:45:28.502784Z","steps":["trace[2041040313] 'read index received' (duration: 107.107576ms)","trace[2041040313] 'applied index is now lower than readState.Index' (duration: 250.579649ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:45:28.502805Z","caller":"traceutil/trace.go:171","msg":"trace[1343139314] transaction","detail":"{read_only:false; response_revision:4565; number_of_response:1; }","duration":"367.500608ms","start":"2026-07-24T04:45:28.135285Z","end":"2026-07-24T04:45:28.502786Z","steps":["trace[1343139314] 'process raft request' (duration: 247.133515ms)","trace[1343139314] 'compare' (duration: 120.082397ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:45:28.502891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:45:28.135267Z","time spent":"367.580659ms","remote":"127.0.0.1:59760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2228,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T04:45:28.503288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.069266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-24T04:45:28.503652Z","caller":"traceutil/trace.go:171","msg":"trace[1375718110] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4565; }","duration":"197.493104ms","start":"2026-07-24T04:45:28.30615Z","end":"2026-07-24T04:45:28.503643Z","steps":["trace[1375718110] 'agreement among raft nodes before linearized reading' (duration: 197.089186ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:28.503361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.181837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-24T04:45:28.503713Z","caller":"traceutil/trace.go:171","msg":"trace[243955141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4565; }","duration":"197.577425ms","start":"2026-07-24T04:45:28.306129Z","end":"2026-07-24T04:45:28.503706Z","steps":["trace[243955141] 'agreement among raft nodes before linearized reading' (duration: 197.190908ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:28.503798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.701223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bb5dd023-d9ab-4b3f-9d4e-d04dbf52df78\" ","response":"range_response_count:1 size:3774"} {"level":"warn","ts":"2026-07-24T04:45:28.503792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.769987ms","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-07-24T04:45:28.503813Z","caller":"traceutil/trace.go:171","msg":"trace[723771146] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bb5dd023-d9ab-4b3f-9d4e-d04dbf52df78; range_end:; response_count:1; response_revision:4565; }","duration":"358.745435ms","start":"2026-07-24T04:45:28.145063Z","end":"2026-07-24T04:45:28.503809Z","steps":["trace[723771146] 'agreement among raft nodes before linearized reading' (duration: 357.913769ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:45:28.503823Z","caller":"traceutil/trace.go:171","msg":"trace[1941518678] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4565; }","duration":"184.847879ms","start":"2026-07-24T04:45:28.318967Z","end":"2026-07-24T04:45:28.503815Z","steps":["trace[1941518678] 'agreement among raft nodes before linearized reading' (duration: 184.248688ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:28.503954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:45:28.145033Z","time spent":"358.790416ms","remote":"127.0.0.1:40958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3796,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bb5dd023-d9ab-4b3f-9d4e-d04dbf52df78\" "} {"level":"warn","ts":"2026-07-24T04:45:28.504425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.083211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.177\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-24T04:45:28.504453Z","caller":"traceutil/trace.go:171","msg":"trace[1967433238] range","detail":"{range_begin:/registry/masterleases/199.204.45.177; range_end:; response_count:1; response_revision:4565; }","duration":"352.155843ms","start":"2026-07-24T04:45:28.15229Z","end":"2026-07-24T04:45:28.504446Z","steps":["trace[1967433238] 'agreement among raft nodes before linearized reading' (duration: 350.826518ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:28.504466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:45:28.152219Z","time spent":"352.244024ms","remote":"127.0.0.1:40812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.177\" "} {"level":"info","ts":"2026-07-24T04:45:36.766096Z","caller":"traceutil/trace.go:171","msg":"trace[523740227] transaction","detail":"{read_only:false; response_revision:4699; number_of_response:1; }","duration":"584.328218ms","start":"2026-07-24T04:45:36.181744Z","end":"2026-07-24T04:45:36.766072Z","steps":["trace[523740227] 'process raft request' (duration: 492.940686ms)","trace[523740227] 'compare' (duration: 91.039876ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:45:36.766127Z","caller":"traceutil/trace.go:171","msg":"trace[267873763] linearizableReadLoop","detail":"{readStateIndex:4873; appliedIndex:4872; }","duration":"475.804741ms","start":"2026-07-24T04:45:36.290311Z","end":"2026-07-24T04:45:36.766115Z","steps":["trace[267873763] 'read index received' (duration: 384.382828ms)","trace[267873763] 'applied index is now lower than readState.Index' (duration: 91.420933ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:45:36.76623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:45:36.181716Z","time spent":"584.44771ms","remote":"127.0.0.1:35930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2724,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:45:36.766498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.724472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:45:36.766564Z","caller":"traceutil/trace.go:171","msg":"trace[1388241473] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:4700; }","duration":"462.833113ms","start":"2026-07-24T04:45:36.303719Z","end":"2026-07-24T04:45:36.766552Z","steps":["trace[1388241473] 'agreement among raft nodes before linearized reading' (duration: 462.618749ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:36.766574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.173928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-24T04:45:36.766607Z","caller":"traceutil/trace.go:171","msg":"trace[437707648] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4700; }","duration":"174.226499ms","start":"2026-07-24T04:45:36.592371Z","end":"2026-07-24T04:45:36.766597Z","steps":["trace[437707648] 'agreement among raft nodes before linearized reading' (duration: 174.095176ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:36.766602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:45:36.303699Z","time spent":"462.891345ms","remote":"127.0.0.1:57796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-07-24T04:45:36.766611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.267619ms","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-07-24T04:45:36.766699Z","caller":"traceutil/trace.go:171","msg":"trace[1883210218] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:4700; }","duration":"476.407481ms","start":"2026-07-24T04:45:36.29027Z","end":"2026-07-24T04:45:36.766678Z","steps":["trace[1883210218] 'agreement among raft nodes before linearized reading' (duration: 475.882022ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:36.766731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.390062ms","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-07-24T04:45:36.766747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:45:36.290254Z","time spent":"476.481573ms","remote":"127.0.0.1:40958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":31,"response size":29,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"info","ts":"2026-07-24T04:45:36.766783Z","caller":"traceutil/trace.go:171","msg":"trace[2026020856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4700; }","duration":"174.477594ms","start":"2026-07-24T04:45:36.59229Z","end":"2026-07-24T04:45:36.766768Z","steps":["trace[2026020856] 'agreement among raft nodes before linearized reading' (duration: 174.315021ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:36.766822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.881022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-24T04:45:36.766904Z","caller":"traceutil/trace.go:171","msg":"trace[1205296922] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4700; }","duration":"173.963154ms","start":"2026-07-24T04:45:36.592917Z","end":"2026-07-24T04:45:36.76688Z","steps":["trace[1205296922] 'agreement among raft nodes before linearized reading' (duration: 173.780331ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:36.766932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.432187ms","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-07-24T04:45:36.766966Z","caller":"traceutil/trace.go:171","msg":"trace[1549166400] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4700; }","duration":"130.512278ms","start":"2026-07-24T04:45:36.636445Z","end":"2026-07-24T04:45:36.766957Z","steps":["trace[1549166400] 'agreement among raft nodes before linearized reading' (duration: 130.424167ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:36.76717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.135229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-24T04:45:36.767239Z","caller":"traceutil/trace.go:171","msg":"trace[1336965385] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4700; }","duration":"173.250171ms","start":"2026-07-24T04:45:36.593949Z","end":"2026-07-24T04:45:36.7672Z","steps":["trace[1336965385] 'agreement among raft nodes before linearized reading' (duration: 172.443746ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:37.451621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.895099ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:45:37.451791Z","caller":"traceutil/trace.go:171","msg":"trace[1428952225] transaction","detail":"{read_only:false; response_revision:4713; number_of_response:1; }","duration":"455.816263ms","start":"2026-07-24T04:45:36.995949Z","end":"2026-07-24T04:45:37.451766Z","steps":["trace[1428952225] 'process raft request' (duration: 48.721371ms)","trace[1428952225] 'compare' (duration: 406.576153ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:45:37.451934Z","caller":"traceutil/trace.go:171","msg":"trace[1834038669] transaction","detail":"{read_only:false; response_revision:4715; number_of_response:1; }","duration":"449.864984ms","start":"2026-07-24T04:45:37.002054Z","end":"2026-07-24T04:45:37.451919Z","steps":["trace[1834038669] 'process raft request' (duration: 449.713051ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:37.451898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:45:36.995928Z","time spent":"455.930685ms","remote":"127.0.0.1:40972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1436,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T04:45:37.452014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:45:37.00203Z","time spent":"449.937325ms","remote":"127.0.0.1:40936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:45:37.452046Z","caller":"traceutil/trace.go:171","msg":"trace[606422010] linearizableReadLoop","detail":"{readStateIndex:4887; appliedIndex:4886; }","duration":"454.374936ms","start":"2026-07-24T04:45:36.997652Z","end":"2026-07-24T04:45:37.452027Z","steps":["trace[606422010] 'read index received' (duration: 47.03373ms)","trace[606422010] 'applied index is now lower than readState.Index' (duration: 407.339546ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:45:37.452053Z","caller":"traceutil/trace.go:171","msg":"trace[903479607] transaction","detail":"{read_only:false; response_revision:4714; number_of_response:1; }","duration":"451.409492ms","start":"2026-07-24T04:45:37.000615Z","end":"2026-07-24T04:45:37.452025Z","steps":["trace[903479607] 'process raft request' (duration: 451.092736ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:37.452195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:45:37.000593Z","time spent":"451.524865ms","remote":"127.0.0.1:40936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1282,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:45:37.452309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.638972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-bb5dd023-d9ab-4b3f-9d4e-d04dbf52df78\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-07-24T04:45:37.452544Z","caller":"traceutil/trace.go:171","msg":"trace[1476003370] range","detail":"{range_begin:/registry/persistentvolumes/pvc-bb5dd023-d9ab-4b3f-9d4e-d04dbf52df78; range_end:; response_count:1; response_revision:4716; }","duration":"454.742023ms","start":"2026-07-24T04:45:36.997605Z","end":"2026-07-24T04:45:37.452347Z","steps":["trace[1476003370] 'agreement among raft nodes before linearized reading' (duration: 454.56133ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:37.452596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:45:36.997589Z","time spent":"454.982598ms","remote":"127.0.0.1:40924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1472,"request content":"key:\"/registry/persistentvolumes/pvc-bb5dd023-d9ab-4b3f-9d4e-d04dbf52df78\" "} {"level":"info","ts":"2026-07-24T04:45:37.452595Z","caller":"traceutil/trace.go:171","msg":"trace[1275776005] transaction","detail":"{read_only:false; response_revision:4716; number_of_response:1; }","duration":"449.12942ms","start":"2026-07-24T04:45:37.003456Z","end":"2026-07-24T04:45:37.452585Z","steps":["trace[1275776005] 'process raft request' (duration: 448.347756ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:37.452655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:45:37.003448Z","time spent":"449.176211ms","remote":"127.0.0.1:41064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1454,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:45:37.452803Z","caller":"traceutil/trace.go:171","msg":"trace[1359078628] transaction","detail":"{read_only:false; response_revision:4717; number_of_response:1; }","duration":"448.800185ms","start":"2026-07-24T04:45:37.003752Z","end":"2026-07-24T04:45:37.452552Z","steps":["trace[1359078628] 'process raft request' (duration: 448.111612ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:37.452861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:45:37.003743Z","time spent":"449.08628ms","remote":"127.0.0.1:41064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1868,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:45:37.472915Z","caller":"traceutil/trace.go:171","msg":"trace[139354210] transaction","detail":"{read_only:false; response_revision:4719; number_of_response:1; }","duration":"251.126171ms","start":"2026-07-24T04:45:37.221765Z","end":"2026-07-24T04:45:37.472891Z","steps":["trace[139354210] 'process raft request' (duration: 251.07384ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:45:37.473143Z","caller":"traceutil/trace.go:171","msg":"trace[2070350031] transaction","detail":"{read_only:false; response_revision:4718; number_of_response:1; }","duration":"364.270909ms","start":"2026-07-24T04:45:37.108863Z","end":"2026-07-24T04:45:37.473134Z","steps":["trace[2070350031] 'process raft request' (duration: 363.881252ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:37.473225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:45:37.108833Z","time spent":"364.334731ms","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:45:37.473617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.649878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16233"} {"level":"info","ts":"2026-07-24T04:45:37.473793Z","caller":"traceutil/trace.go:171","msg":"trace[1001951227] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4721; }","duration":"474.848531ms","start":"2026-07-24T04:45:36.998925Z","end":"2026-07-24T04:45:37.473774Z","steps":["trace[1001951227] 'agreement among raft nodes before linearized reading' (duration: 474.563575ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:37.47392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:45:36.998912Z","time spent":"474.989423ms","remote":"127.0.0.1:40958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":16255,"request content":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"info","ts":"2026-07-24T04:45:37.651827Z","caller":"traceutil/trace.go:171","msg":"trace[577966778] transaction","detail":"{read_only:false; response_revision:4726; number_of_response:1; }","duration":"166.973612ms","start":"2026-07-24T04:45:37.484824Z","end":"2026-07-24T04:45:37.651797Z","steps":["trace[577966778] 'process raft request' (duration: 164.858423ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:45:37.656421Z","caller":"traceutil/trace.go:171","msg":"trace[1451103260] linearizableReadLoop","detail":"{readStateIndex:4901; appliedIndex:4899; }","duration":"144.736826ms","start":"2026-07-24T04:45:37.511667Z","end":"2026-07-24T04:45:37.656404Z","steps":["trace[1451103260] 'read index received' (duration: 138.031914ms)","trace[1451103260] 'applied index is now lower than readState.Index' (duration: 6.704162ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:45:37.658553Z","caller":"traceutil/trace.go:171","msg":"trace[303643539] transaction","detail":"{read_only:false; response_revision:4727; number_of_response:1; }","duration":"146.943166ms","start":"2026-07-24T04:45:37.511601Z","end":"2026-07-24T04:45:37.658544Z","steps":["trace[303643539] 'process raft request' (duration: 144.532222ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:45:37.658778Z","caller":"traceutil/trace.go:171","msg":"trace[310356327] transaction","detail":"{read_only:false; response_revision:4728; number_of_response:1; }","duration":"146.968166ms","start":"2026-07-24T04:45:37.511803Z","end":"2026-07-24T04:45:37.658772Z","steps":["trace[310356327] 'process raft request' (duration: 144.561883ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:37.65898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.298623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-07-24T04:45:37.659017Z","caller":"traceutil/trace.go:171","msg":"trace[1716062232] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4728; }","duration":"147.334864ms","start":"2026-07-24T04:45:37.511663Z","end":"2026-07-24T04:45:37.658997Z","steps":["trace[1716062232] 'agreement among raft nodes before linearized reading' (duration: 147.191361ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:37.659156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.432605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-07-24T04:45:37.659177Z","caller":"traceutil/trace.go:171","msg":"trace[1207189886] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4728; }","duration":"147.487026ms","start":"2026-07-24T04:45:37.511684Z","end":"2026-07-24T04:45:37.659171Z","steps":["trace[1207189886] 'agreement among raft nodes before linearized reading' (duration: 147.435415ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:45:40.96487Z","caller":"traceutil/trace.go:171","msg":"trace[1887672662] linearizableReadLoop","detail":"{readStateIndex:4972; appliedIndex:4971; }","duration":"158.403992ms","start":"2026-07-24T04:45:40.806447Z","end":"2026-07-24T04:45:40.964851Z","steps":["trace[1887672662] 'read index received' (duration: 158.223569ms)","trace[1887672662] 'applied index is now lower than readState.Index' (duration: 179.843µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:45:40.964957Z","caller":"traceutil/trace.go:171","msg":"trace[10062220] transaction","detail":"{read_only:false; response_revision:4797; number_of_response:1; }","duration":"169.335551ms","start":"2026-07-24T04:45:40.795596Z","end":"2026-07-24T04:45:40.964931Z","steps":["trace[10062220] 'process raft request' (duration: 169.144758ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:40.965171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.176472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-24T04:45:40.965285Z","caller":"traceutil/trace.go:171","msg":"trace[1690639324] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4797; }","duration":"139.317775ms","start":"2026-07-24T04:45:40.825932Z","end":"2026-07-24T04:45:40.96525Z","steps":["trace[1690639324] 'agreement among raft nodes before linearized reading' (duration: 139.093661ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:40.965033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.578636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8c7a74f0-da92-4777-972c-43aa42d13fe8\" ","response":"range_response_count:1 size:3939"} {"level":"info","ts":"2026-07-24T04:45:40.965392Z","caller":"traceutil/trace.go:171","msg":"trace[290250023] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8c7a74f0-da92-4777-972c-43aa42d13fe8; range_end:; response_count:1; response_revision:4797; }","duration":"158.965772ms","start":"2026-07-24T04:45:40.806407Z","end":"2026-07-24T04:45:40.965372Z","steps":["trace[290250023] 'agreement among raft nodes before linearized reading' (duration: 158.535464ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:40.965091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.84587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-24T04:45:40.965634Z","caller":"traceutil/trace.go:171","msg":"trace[823556473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4797; }","duration":"126.39128ms","start":"2026-07-24T04:45:40.839215Z","end":"2026-07-24T04:45:40.965607Z","steps":["trace[823556473] 'agreement among raft nodes before linearized reading' (duration: 125.84224ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:40.965138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.653243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-24T04:45:40.965746Z","caller":"traceutil/trace.go:171","msg":"trace[86013944] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4797; }","duration":"128.286725ms","start":"2026-07-24T04:45:40.837444Z","end":"2026-07-24T04:45:40.965731Z","steps":["trace[86013944] 'agreement among raft nodes before linearized reading' (duration: 127.663533ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:40.965176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.287649ms","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-07-24T04:45:40.965844Z","caller":"traceutil/trace.go:171","msg":"trace[1429304657] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4797; }","duration":"125.980692ms","start":"2026-07-24T04:45:40.839844Z","end":"2026-07-24T04:45:40.965825Z","steps":["trace[1429304657] 'agreement among raft nodes before linearized reading' (duration: 125.197428ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:40.965202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.891673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-24T04:45:40.965935Z","caller":"traceutil/trace.go:171","msg":"trace[120922557] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4797; }","duration":"125.632986ms","start":"2026-07-24T04:45:40.840278Z","end":"2026-07-24T04:45:40.965911Z","steps":["trace[120922557] 'agreement among raft nodes before linearized reading' (duration: 124.838732ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:45:41.166515Z","caller":"traceutil/trace.go:171","msg":"trace[2007909222] transaction","detail":"{read_only:false; response_revision:4798; number_of_response:1; }","duration":"196.708885ms","start":"2026-07-24T04:45:40.969779Z","end":"2026-07-24T04:45:41.166488Z","steps":["trace[2007909222] 'process raft request' (duration: 195.360061ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:45:41.179861Z","caller":"traceutil/trace.go:171","msg":"trace[2015378260] transaction","detail":"{read_only:false; response_revision:4799; number_of_response:1; }","duration":"209.947266ms","start":"2026-07-24T04:45:40.969881Z","end":"2026-07-24T04:45:41.179828Z","steps":["trace[2015378260] 'process raft request' (duration: 209.695191ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:45:41.179939Z","caller":"traceutil/trace.go:171","msg":"trace[467098282] linearizableReadLoop","detail":"{readStateIndex:4978; appliedIndex:4972; }","duration":"155.495465ms","start":"2026-07-24T04:45:41.024422Z","end":"2026-07-24T04:45:41.179917Z","steps":["trace[467098282] 'read index received' (duration: 140.730707ms)","trace[467098282] 'applied index is now lower than readState.Index' (duration: 14.763978ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:45:41.179979Z","caller":"traceutil/trace.go:171","msg":"trace[1240005747] transaction","detail":"{read_only:false; response_revision:4800; number_of_response:1; }","duration":"209.982116ms","start":"2026-07-24T04:45:40.969941Z","end":"2026-07-24T04:45:41.179923Z","steps":["trace[1240005747] 'process raft request' (duration: 209.807163ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:45:41.180121Z","caller":"traceutil/trace.go:171","msg":"trace[1868356275] transaction","detail":"{read_only:false; response_revision:4801; number_of_response:1; }","duration":"210.135249ms","start":"2026-07-24T04:45:40.96997Z","end":"2026-07-24T04:45:41.180105Z","steps":["trace[1868356275] 'process raft request' (duration: 209.828213ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:45:41.180122Z","caller":"traceutil/trace.go:171","msg":"trace[349457413] transaction","detail":"{read_only:false; response_revision:4802; number_of_response:1; }","duration":"210.070958ms","start":"2026-07-24T04:45:40.969998Z","end":"2026-07-24T04:45:41.180068Z","steps":["trace[349457413] 'process raft request' (duration: 209.838334ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:45:41.18019Z","caller":"traceutil/trace.go:171","msg":"trace[838967649] transaction","detail":"{read_only:false; response_revision:4803; number_of_response:1; }","duration":"208.081501ms","start":"2026-07-24T04:45:40.971952Z","end":"2026-07-24T04:45:41.180033Z","steps":["trace[838967649] 'process raft request' (duration: 207.916788ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:41.180195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.76121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-07-24T04:45:41.180456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.848908ms","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-07-24T04:45:41.180474Z","caller":"traceutil/trace.go:171","msg":"trace[709041004] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4803; }","duration":"156.105356ms","start":"2026-07-24T04:45:41.024356Z","end":"2026-07-24T04:45:41.180461Z","steps":["trace[709041004] 'agreement among raft nodes before linearized reading' (duration: 155.685438ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:45:41.180534Z","caller":"traceutil/trace.go:171","msg":"trace[1721732296] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4803; }","duration":"124.99208ms","start":"2026-07-24T04:45:41.055493Z","end":"2026-07-24T04:45:41.180485Z","steps":["trace[1721732296] 'agreement among raft nodes before linearized reading' (duration: 124.734576ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:45:51.466555Z","caller":"traceutil/trace.go:171","msg":"trace[1395275274] linearizableReadLoop","detail":"{readStateIndex:5100; appliedIndex:5099; }","duration":"439.516165ms","start":"2026-07-24T04:45:51.027012Z","end":"2026-07-24T04:45:51.466528Z","steps":["trace[1395275274] 'read index received' (duration: 439.080577ms)","trace[1395275274] 'applied index is now lower than readState.Index' (duration: 434.378µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:45:51.466736Z","caller":"traceutil/trace.go:171","msg":"trace[1417261252] transaction","detail":"{read_only:false; response_revision:4920; number_of_response:1; }","duration":"454.24684ms","start":"2026-07-24T04:45:51.012416Z","end":"2026-07-24T04:45:51.466663Z","steps":["trace[1417261252] 'process raft request' (duration: 453.943664ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:51.466815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.77883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"warn","ts":"2026-07-24T04:45:51.466817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.142795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:45:51.466864Z","caller":"traceutil/trace.go:171","msg":"trace[1754995430] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4920; }","duration":"439.874212ms","start":"2026-07-24T04:45:51.026976Z","end":"2026-07-24T04:45:51.466851Z","steps":["trace[1754995430] 'agreement among raft nodes before linearized reading' (duration: 439.698949ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:45:51.466894Z","caller":"traceutil/trace.go:171","msg":"trace[61532720] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:4920; }","duration":"384.276017ms","start":"2026-07-24T04:45:51.0826Z","end":"2026-07-24T04:45:51.466876Z","steps":["trace[61532720] 'agreement among raft nodes before linearized reading' (duration: 384.120635ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:51.466914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:45:51.026962Z","time spent":"439.941283ms","remote":"127.0.0.1:40934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-07-24T04:45:51.466935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:45:51.082587Z","time spent":"384.340339ms","remote":"127.0.0.1:57824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-24T04:45:51.466917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.57598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T04:45:51.466938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.679785ms","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-07-24T04:45:51.467015Z","caller":"traceutil/trace.go:171","msg":"trace[1365161650] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:4920; }","duration":"219.729862ms","start":"2026-07-24T04:45:51.247257Z","end":"2026-07-24T04:45:51.466987Z","steps":["trace[1365161650] 'agreement among raft nodes before linearized reading' (duration: 219.555359ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:45:51.467026Z","caller":"traceutil/trace.go:171","msg":"trace[246299573] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4920; }","duration":"165.798288ms","start":"2026-07-24T04:45:51.301205Z","end":"2026-07-24T04:45:51.467003Z","steps":["trace[246299573] 'agreement among raft nodes before linearized reading' (duration: 165.588304ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:51.467023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:45:51.012401Z","time spent":"454.459273ms","remote":"127.0.0.1:40958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10670,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:45:51.467241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.926962ms","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-07-24T04:45:51.467271Z","caller":"traceutil/trace.go:171","msg":"trace[365510337] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4920; }","duration":"245.979412ms","start":"2026-07-24T04:45:51.221284Z","end":"2026-07-24T04:45:51.467264Z","steps":["trace[365510337] 'agreement among raft nodes before linearized reading' (duration: 245.87406ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:51.46734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.152792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-24T04:45:51.467387Z","caller":"traceutil/trace.go:171","msg":"trace[2000370512] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4920; }","duration":"119.241564ms","start":"2026-07-24T04:45:51.348135Z","end":"2026-07-24T04:45:51.467376Z","steps":["trace[2000370512] 'agreement among raft nodes before linearized reading' (duration: 119.113182ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:51.467403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.81525ms","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-07-24T04:45:51.467449Z","caller":"traceutil/trace.go:171","msg":"trace[321954638] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4920; }","duration":"164.896481ms","start":"2026-07-24T04:45:51.302541Z","end":"2026-07-24T04:45:51.467437Z","steps":["trace[321954638] 'agreement among raft nodes before linearized reading' (duration: 164.788189ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:51.467357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.575489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-24T04:45:51.467511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.353236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-24T04:45:51.467536Z","caller":"traceutil/trace.go:171","msg":"trace[1927683063] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4920; }","duration":"167.795693ms","start":"2026-07-24T04:45:51.299733Z","end":"2026-07-24T04:45:51.467528Z","steps":["trace[1927683063] 'agreement among raft nodes before linearized reading' (duration: 167.529038ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:45:51.467551Z","caller":"traceutil/trace.go:171","msg":"trace[11673900] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4920; }","duration":"129.430636ms","start":"2026-07-24T04:45:51.338111Z","end":"2026-07-24T04:45:51.467542Z","steps":["trace[11673900] 'agreement among raft nodes before linearized reading' (duration: 129.322334ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:51.467791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.446039ms","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-07-24T04:45:51.467817Z","caller":"traceutil/trace.go:171","msg":"trace[2055789524] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4920; }","duration":"266.471389ms","start":"2026-07-24T04:45:51.201338Z","end":"2026-07-24T04:45:51.467809Z","steps":["trace[2055789524] 'agreement among raft nodes before linearized reading' (duration: 266.035821ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:52.012569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.963478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:11126"} {"level":"info","ts":"2026-07-24T04:45:52.012715Z","caller":"traceutil/trace.go:171","msg":"trace[1738669739] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:4920; }","duration":"538.12225ms","start":"2026-07-24T04:45:51.474551Z","end":"2026-07-24T04:45:52.012674Z","steps":["trace[1738669739] 'range keys from in-memory index tree' (duration: 537.819765ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:52.012802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:45:51.474536Z","time spent":"538.256423ms","remote":"127.0.0.1:40942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":11148,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-07-24T04:45:52.013389Z","caller":"traceutil/trace.go:171","msg":"trace[935000294] transaction","detail":"{read_only:false; response_revision:4921; number_of_response:1; }","duration":"538.63527ms","start":"2026-07-24T04:45:51.474718Z","end":"2026-07-24T04:45:52.013353Z","steps":["trace[935000294] 'process raft request' (duration: 440.83057ms)","trace[935000294] 'compare' (duration: 97.034126ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:45:52.013378Z","caller":"traceutil/trace.go:171","msg":"trace[1751797498] linearizableReadLoop","detail":"{readStateIndex:5101; appliedIndex:5100; }","duration":"538.569028ms","start":"2026-07-24T04:45:51.474773Z","end":"2026-07-24T04:45:52.013342Z","steps":["trace[1751797498] 'read index received' (duration: 440.737908ms)","trace[1751797498] 'applied index is now lower than readState.Index' (duration: 97.82975ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:45:52.013606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.805853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-8c7a74f0-da92-4777-972c-43aa42d13fe8\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-07-24T04:45:52.013687Z","caller":"traceutil/trace.go:171","msg":"trace[1983977488] range","detail":"{range_begin:/registry/persistentvolumes/pvc-8c7a74f0-da92-4777-972c-43aa42d13fe8; range_end:; response_count:1; response_revision:4921; }","duration":"538.899925ms","start":"2026-07-24T04:45:51.47476Z","end":"2026-07-24T04:45:52.01366Z","steps":["trace[1983977488] 'agreement among raft nodes before linearized reading' (duration: 538.690331ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:52.013738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:45:51.474754Z","time spent":"538.974656ms","remote":"127.0.0.1:40924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1344,"request content":"key:\"/registry/persistentvolumes/pvc-8c7a74f0-da92-4777-972c-43aa42d13fe8\" "} {"level":"warn","ts":"2026-07-24T04:45:52.013604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:45:51.474698Z","time spent":"538.825464ms","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:45:52.058479Z","caller":"traceutil/trace.go:171","msg":"trace[1812686983] transaction","detail":"{read_only:false; response_revision:4923; number_of_response:1; }","duration":"581.779411ms","start":"2026-07-24T04:45:51.476677Z","end":"2026-07-24T04:45:52.058456Z","steps":["trace[1812686983] 'process raft request' (duration: 581.551646ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:52.058867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:45:51.476667Z","time spent":"581.968195ms","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:45:52.05894Z","caller":"traceutil/trace.go:171","msg":"trace[1206371289] transaction","detail":"{read_only:false; response_revision:4929; number_of_response:1; }","duration":"579.184585ms","start":"2026-07-24T04:45:51.479745Z","end":"2026-07-24T04:45:52.05893Z","steps":["trace[1206371289] 'process raft request' (duration: 578.956711ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:52.059148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:45:51.479738Z","time spent":"579.224206ms","remote":"127.0.0.1:40936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":893,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:45:52.059253Z","caller":"traceutil/trace.go:171","msg":"trace[851540411] transaction","detail":"{read_only:false; response_revision:4922; number_of_response:1; }","duration":"584.334007ms","start":"2026-07-24T04:45:51.474907Z","end":"2026-07-24T04:45:52.059241Z","steps":["trace[851540411] 'process raft request' (duration: 583.182906ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:45:52.059602Z","caller":"traceutil/trace.go:171","msg":"trace[2041513188] transaction","detail":"{read_only:false; response_revision:4930; number_of_response:1; }","duration":"516.208007ms","start":"2026-07-24T04:45:51.543374Z","end":"2026-07-24T04:45:52.059582Z","steps":["trace[2041513188] 'process raft request' (duration: 515.351931ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:52.059775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:45:51.543345Z","time spent":"516.37615ms","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:45:52.060185Z","caller":"traceutil/trace.go:171","msg":"trace[1638010197] transaction","detail":"{read_only:false; response_revision:4926; number_of_response:1; }","duration":"583.317368ms","start":"2026-07-24T04:45:51.476854Z","end":"2026-07-24T04:45:52.060172Z","steps":["trace[1638010197] 'process raft request' (duration: 581.662979ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:45:52.060382Z","caller":"traceutil/trace.go:171","msg":"trace[92983279] transaction","detail":"{read_only:false; response_revision:4928; number_of_response:1; }","duration":"583.45284ms","start":"2026-07-24T04:45:51.476899Z","end":"2026-07-24T04:45:52.060352Z","steps":["trace[92983279] 'process raft request' (duration: 581.673449ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:52.060524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:45:51.476891Z","time spent":"583.557373ms","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:45:52.059665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:45:51.474896Z","time spent":"584.495839ms","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:45:52.060763Z","caller":"traceutil/trace.go:171","msg":"trace[1241781802] transaction","detail":"{read_only:false; response_revision:4924; number_of_response:1; }","duration":"583.95564ms","start":"2026-07-24T04:45:51.476771Z","end":"2026-07-24T04:45:52.060726Z","steps":["trace[1241781802] 'process raft request' (duration: 581.509146ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:45:52.060828Z","caller":"traceutil/trace.go:171","msg":"trace[2104536659] transaction","detail":"{read_only:false; response_revision:4925; number_of_response:1; }","duration":"584.012541ms","start":"2026-07-24T04:45:51.476778Z","end":"2026-07-24T04:45:52.060791Z","steps":["trace[2104536659] 'process raft request' (duration: 581.590478ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:52.060976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:45:51.476755Z","time spent":"584.091502ms","remote":"127.0.0.1:41064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1429,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:45:52.060995Z","caller":"traceutil/trace.go:171","msg":"trace[2118153231] transaction","detail":"{read_only:false; response_revision:4931; number_of_response:1; }","duration":"465.371297ms","start":"2026-07-24T04:45:51.595612Z","end":"2026-07-24T04:45:52.060983Z","steps":["trace[2118153231] 'process raft request' (duration: 463.908211ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:52.061074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:45:51.595586Z","time spent":"465.459019ms","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:45:52.061152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:45:51.476758Z","time spent":"584.156404ms","remote":"127.0.0.1:40936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":951,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:45:52.061259Z","caller":"traceutil/trace.go:171","msg":"trace[1002671260] transaction","detail":"{read_only:false; response_revision:4927; number_of_response:1; }","duration":"584.349017ms","start":"2026-07-24T04:45:51.476894Z","end":"2026-07-24T04:45:52.061243Z","steps":["trace[1002671260] 'process raft request' (duration: 581.655629ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:52.061336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:45:51.476886Z","time spent":"584.413458ms","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:45:52.060402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:45:51.476846Z","time spent":"583.501831ms","remote":"127.0.0.1:41064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1506,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:45:52.061936Z","caller":"traceutil/trace.go:171","msg":"trace[1431085023] transaction","detail":"{read_only:false; response_revision:4932; number_of_response:1; }","duration":"420.674287ms","start":"2026-07-24T04:45:51.641241Z","end":"2026-07-24T04:45:52.061916Z","steps":["trace[1431085023] 'process raft request' (duration: 419.658709ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:45:52.062302Z","caller":"traceutil/trace.go:171","msg":"trace[1841323386] transaction","detail":"{read_only:false; response_revision:4933; number_of_response:1; }","duration":"228.154392ms","start":"2026-07-24T04:45:51.834133Z","end":"2026-07-24T04:45:52.062288Z","steps":["trace[1841323386] 'process raft request' (duration: 227.4542ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:52.062956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.445563ms","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-07-24T04:45:52.062958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.086805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:45:52.062997Z","caller":"traceutil/trace.go:171","msg":"trace[2123927789] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4933; }","duration":"206.520755ms","start":"2026-07-24T04:45:51.856466Z","end":"2026-07-24T04:45:52.062986Z","steps":["trace[2123927789] 'agreement among raft nodes before linearized reading' (duration: 206.381152ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:45:52.063008Z","caller":"traceutil/trace.go:171","msg":"trace[344515420] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4933; }","duration":"492.164957ms","start":"2026-07-24T04:45:51.570827Z","end":"2026-07-24T04:45:52.062992Z","steps":["trace[344515420] 'agreement among raft nodes before linearized reading' (duration: 492.085035ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:52.063039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:45:51.570809Z","time spent":"492.223267ms","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-24T04:45:52.062502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:45:51.641159Z","time spent":"421.066844ms","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:45:52.063514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.667985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-cf686cd65-qhdb5\" ","response":"range_response_count:1 size:11840"} {"level":"info","ts":"2026-07-24T04:45:52.063744Z","caller":"traceutil/trace.go:171","msg":"trace[933196753] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-cf686cd65-qhdb5; range_end:; response_count:1; response_revision:4933; }","duration":"583.93013ms","start":"2026-07-24T04:45:51.479791Z","end":"2026-07-24T04:45:52.063721Z","steps":["trace[933196753] 'agreement among raft nodes before linearized reading' (duration: 582.234729ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:52.066015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:45:51.479772Z","time spent":"586.127549ms","remote":"127.0.0.1:40958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":11862,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-cf686cd65-qhdb5\" "} {"level":"info","ts":"2026-07-24T04:45:52.208714Z","caller":"traceutil/trace.go:171","msg":"trace[363551195] linearizableReadLoop","detail":"{readStateIndex:5115; appliedIndex:5114; }","duration":"106.149327ms","start":"2026-07-24T04:45:52.102538Z","end":"2026-07-24T04:45:52.208688Z","steps":["trace[363551195] 'read index received' (duration: 62.016379ms)","trace[363551195] 'applied index is now lower than readState.Index' (duration: 44.131668ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:45:52.208853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.30361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:45:52.208898Z","caller":"traceutil/trace.go:171","msg":"trace[232658622] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4935; }","duration":"106.392121ms","start":"2026-07-24T04:45:52.102494Z","end":"2026-07-24T04:45:52.208886Z","steps":["trace[232658622] 'agreement among raft nodes before linearized reading' (duration: 106.287909ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:45:52.209173Z","caller":"traceutil/trace.go:171","msg":"trace[2012000532] transaction","detail":"{read_only:false; response_revision:4935; number_of_response:1; }","duration":"134.461042ms","start":"2026-07-24T04:45:52.074672Z","end":"2026-07-24T04:45:52.209133Z","steps":["trace[2012000532] 'process raft request' (duration: 89.831405ms)","trace[2012000532] 'compare' (duration: 43.858864ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:45:52.509535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.097939ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:45:52.509615Z","caller":"traceutil/trace.go:171","msg":"trace[1763482005] transaction","detail":"{read_only:false; response_revision:4939; number_of_response:1; }","duration":"222.6702ms","start":"2026-07-24T04:45:52.286931Z","end":"2026-07-24T04:45:52.509601Z","steps":["trace[1763482005] 'process raft request' (duration: 28.431038ms)","trace[1763482005] 'compare' (duration: 193.969107ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:46:38.408837Z","caller":"traceutil/trace.go:171","msg":"trace[631862738] transaction","detail":"{read_only:false; response_revision:5322; number_of_response:1; }","duration":"105.672174ms","start":"2026-07-24T04:46:38.303133Z","end":"2026-07-24T04:46:38.408805Z","steps":["trace[631862738] 'process raft request' (duration: 105.504931ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:46:38.645706Z","caller":"traceutil/trace.go:171","msg":"trace[278149332] linearizableReadLoop","detail":"{readStateIndex:5514; appliedIndex:5513; }","duration":"102.292627ms","start":"2026-07-24T04:46:38.543394Z","end":"2026-07-24T04:46:38.645687Z","steps":["trace[278149332] 'read index received' (duration: 63.643505ms)","trace[278149332] 'applied index is now lower than readState.Index' (duration: 38.648402ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:46:38.645819Z","caller":"traceutil/trace.go:171","msg":"trace[68197887] transaction","detail":"{read_only:false; response_revision:5323; number_of_response:1; }","duration":"123.530665ms","start":"2026-07-24T04:46:38.52228Z","end":"2026-07-24T04:46:38.64581Z","steps":["trace[68197887] 'process raft request' (duration: 84.774401ms)","trace[68197887] 'compare' (duration: 38.52679ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:46:38.646276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.863857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:46:38.646312Z","caller":"traceutil/trace.go:171","msg":"trace[1773240672] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:5323; }","duration":"102.953067ms","start":"2026-07-24T04:46:38.543351Z","end":"2026-07-24T04:46:38.646304Z","steps":["trace[1773240672] 'agreement among raft nodes before linearized reading' (duration: 102.880256ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:47:04.93763Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2873} {"level":"info","ts":"2026-07-24T04:47:05.015391Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2873,"took":"75.800557ms","hash":1667099269,"current-db-size-bytes":53768192,"current-db-size":"54 MB","current-db-size-in-use-bytes":29048832,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-24T04:47:05.015462Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1667099269,"revision":2873,"compact-revision":-1} {"level":"info","ts":"2026-07-24T04:49:04.433309Z","caller":"traceutil/trace.go:171","msg":"trace[15522763] transaction","detail":"{read_only:false; response_revision:6237; number_of_response:1; }","duration":"112.962031ms","start":"2026-07-24T04:49:04.320311Z","end":"2026-07-24T04:49:04.433273Z","steps":["trace[15522763] 'process raft request' (duration: 112.777378ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:49:33.493415Z","caller":"traceutil/trace.go:171","msg":"trace[489326871] transaction","detail":"{read_only:false; response_revision:6545; number_of_response:1; }","duration":"112.932925ms","start":"2026-07-24T04:49:33.380442Z","end":"2026-07-24T04:49:33.493375Z","steps":["trace[489326871] 'process raft request' (duration: 86.428982ms)","trace[489326871] 'compare' (duration: 26.374531ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:50:14.821892Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605186662425568484,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T04:50:15.322257Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605186662425568484,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T04:50:15.82314Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605186662425568484,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T04:50:15.949125Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.826678877s","expected-duration":"1s"} {"level":"info","ts":"2026-07-24T04:50:15.949524Z","caller":"traceutil/trace.go:171","msg":"trace[1122795556] transaction","detail":"{read_only:false; response_revision:6805; number_of_response:1; }","duration":"2.827303785s","start":"2026-07-24T04:50:13.122196Z","end":"2026-07-24T04:50:15.9495Z","steps":["trace[1122795556] 'process raft request' (duration: 2.827175602s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:50:15.949684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:50:13.12217Z","time spent":"2.82743632s","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:50:15.952826Z","caller":"traceutil/trace.go:171","msg":"trace[1395756879] transaction","detail":"{read_only:false; response_revision:6806; number_of_response:1; }","duration":"1.911274138s","start":"2026-07-24T04:50:14.041531Z","end":"2026-07-24T04:50:15.952805Z","steps":["trace[1395756879] 'process raft request' (duration: 1.910978539s)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:50:15.952939Z","caller":"traceutil/trace.go:171","msg":"trace[668642151] transaction","detail":"{read_only:false; response_revision:6808; number_of_response:1; }","duration":"1.911061532s","start":"2026-07-24T04:50:14.041851Z","end":"2026-07-24T04:50:15.952913Z","steps":["trace[668642151] 'process raft request' (duration: 1.910769403s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:50:15.953037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:50:14.041522Z","time spent":"1.911356321s","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:50:15.95305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:50:14.041846Z","time spent":"1.911148074s","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:50:15.95343Z","caller":"traceutil/trace.go:171","msg":"trace[939486085] transaction","detail":"{read_only:false; response_revision:6807; number_of_response:1; }","duration":"1.911759533s","start":"2026-07-24T04:50:14.041655Z","end":"2026-07-24T04:50:15.953415Z","steps":["trace[939486085] 'process raft request' (duration: 1.910937968s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:50:15.953523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:50:14.04165Z","time spent":"1.911821784s","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:50:15.957089Z","caller":"traceutil/trace.go:171","msg":"trace[410106736] transaction","detail":"{read_only:false; response_revision:6809; number_of_response:1; }","duration":"1.786034308s","start":"2026-07-24T04:50:14.170996Z","end":"2026-07-24T04:50:15.95703Z","steps":["trace[410106736] 'process raft request' (duration: 1.785314907s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:50:15.957206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:50:14.170977Z","time spent":"1.786179463s","remote":"127.0.0.1:40850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T04:50:15.957781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.916737032s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:50:15.957832Z","caller":"traceutil/trace.go:171","msg":"trace[788979982] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:6809; }","duration":"1.916818645s","start":"2026-07-24T04:50:14.040999Z","end":"2026-07-24T04:50:15.957818Z","steps":["trace[788979982] 'agreement among raft nodes before linearized reading' (duration: 1.916730392s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:50:15.957866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:50:14.040988Z","time spent":"1.916870176s","remote":"127.0.0.1:57740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"info","ts":"2026-07-24T04:50:15.956566Z","caller":"traceutil/trace.go:171","msg":"trace[1464710814] linearizableReadLoop","detail":"{readStateIndex:7054; appliedIndex:7048; }","duration":"1.915521526s","start":"2026-07-24T04:50:14.041031Z","end":"2026-07-24T04:50:15.956553Z","steps":["trace[1464710814] 'read index received' (duration: 1.908346841s)","trace[1464710814] 'applied index is now lower than readState.Index' (duration: 7.173805ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:50:15.958896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.917506935s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-24T04:50:15.958933Z","caller":"traceutil/trace.go:171","msg":"trace[113998637] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6809; }","duration":"1.917563487s","start":"2026-07-24T04:50:14.041358Z","end":"2026-07-24T04:50:15.958921Z","steps":["trace[113998637] 'agreement among raft nodes before linearized reading' (duration: 1.917311549s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:50:15.959077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:50:14.041352Z","time spent":"1.91766576s","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-24T04:50:15.959344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.110102401s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:50:15.959379Z","caller":"traceutil/trace.go:171","msg":"trace[2096572241] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:6809; }","duration":"1.110141182s","start":"2026-07-24T04:50:14.849229Z","end":"2026-07-24T04:50:15.95937Z","steps":["trace[2096572241] 'agreement among raft nodes before linearized reading' (duration: 1.11008322s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:50:15.959562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:50:14.849182Z","time spent":"1.110314328s","remote":"127.0.0.1:57764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-24T04:50:15.959875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.826969616s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-07-24T04:50:15.959907Z","caller":"traceutil/trace.go:171","msg":"trace[1831371428] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6809; }","duration":"1.827045779s","start":"2026-07-24T04:50:14.132854Z","end":"2026-07-24T04:50:15.959899Z","steps":["trace[1831371428] 'agreement among raft nodes before linearized reading' (duration: 1.826911635s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:50:15.959926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:50:14.132834Z","time spent":"1.82708721s","remote":"127.0.0.1:40934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":1875,"request content":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-24T04:50:15.960669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.919076842s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-24T04:50:15.960706Z","caller":"traceutil/trace.go:171","msg":"trace[563604382] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6809; }","duration":"1.919123553s","start":"2026-07-24T04:50:14.041573Z","end":"2026-07-24T04:50:15.960697Z","steps":["trace[563604382] 'agreement among raft nodes before linearized reading' (duration: 1.919000789s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:50:15.960728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:50:14.041569Z","time spent":"1.919154525s","remote":"127.0.0.1:41050","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-07-24T04:50:15.960836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.915597417s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:50:15.96086Z","caller":"traceutil/trace.go:171","msg":"trace[1878753530] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:6809; }","duration":"1.915637759s","start":"2026-07-24T04:50:14.045215Z","end":"2026-07-24T04:50:15.960853Z","steps":["trace[1878753530] 'agreement among raft nodes before linearized reading' (duration: 1.915600587s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:50:15.96088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:50:14.045205Z","time spent":"1.915670329s","remote":"127.0.0.1:37750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true "} {"level":"warn","ts":"2026-07-24T04:50:15.96098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.919193935s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:50:15.961008Z","caller":"traceutil/trace.go:171","msg":"trace[2047120508] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6809; }","duration":"1.919231336s","start":"2026-07-24T04:50:14.041771Z","end":"2026-07-24T04:50:15.961003Z","steps":["trace[2047120508] 'agreement among raft nodes before linearized reading' (duration: 1.919194265s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:50:15.961024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:50:14.041769Z","time spent":"1.919251958s","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-24T04:50:15.961171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.919442773s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:50:15.961197Z","caller":"traceutil/trace.go:171","msg":"trace[1874648483] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:6809; }","duration":"1.919484274s","start":"2026-07-24T04:50:14.041705Z","end":"2026-07-24T04:50:15.961189Z","steps":["trace[1874648483] 'agreement among raft nodes before linearized reading' (duration: 1.919437792s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:50:15.961216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:50:14.041702Z","time spent":"1.919508715s","remote":"127.0.0.1:36046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true "} {"level":"warn","ts":"2026-07-24T04:50:15.961354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.919653009s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-24T04:50:15.961382Z","caller":"traceutil/trace.go:171","msg":"trace[1542735861] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6809; }","duration":"1.91969045s","start":"2026-07-24T04:50:14.041686Z","end":"2026-07-24T04:50:15.961377Z","steps":["trace[1542735861] 'agreement among raft nodes before linearized reading' (duration: 1.919612128s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:50:15.961402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:50:14.041683Z","time spent":"1.919712211s","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-24T04:50:15.96149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.919887576s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-24T04:50:15.961511Z","caller":"traceutil/trace.go:171","msg":"trace[160413736] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6809; }","duration":"1.919917897s","start":"2026-07-24T04:50:14.041588Z","end":"2026-07-24T04:50:15.961506Z","steps":["trace[160413736] 'agreement among raft nodes before linearized reading' (duration: 1.919882736s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:50:15.961529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:50:14.041585Z","time spent":"1.919939458s","remote":"127.0.0.1:41050","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-07-24T04:50:15.962152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.920653379s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-24T04:50:15.962181Z","caller":"traceutil/trace.go:171","msg":"trace[1838185102] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6809; }","duration":"1.92069039s","start":"2026-07-24T04:50:14.041483Z","end":"2026-07-24T04:50:15.962174Z","steps":["trace[1838185102] 'agreement among raft nodes before linearized reading' (duration: 1.920617188s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:50:15.9622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:50:14.041481Z","time spent":"1.920714531s","remote":"127.0.0.1:41050","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-07-24T04:50:15.96232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.920865885s","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-07-24T04:50:15.962342Z","caller":"traceutil/trace.go:171","msg":"trace[1027145656] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6809; }","duration":"1.920897196s","start":"2026-07-24T04:50:14.04144Z","end":"2026-07-24T04:50:15.962337Z","steps":["trace[1027145656] 'agreement among raft nodes before linearized reading' (duration: 1.920841374s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:50:15.962359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:50:14.041436Z","time spent":"1.920919208s","remote":"127.0.0.1:41050","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-07-24T04:52:04.944102Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5539} {"level":"info","ts":"2026-07-24T04:52:05.017942Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5539,"took":"72.239475ms","hash":3691335039,"current-db-size-bytes":53768192,"current-db-size":"54 MB","current-db-size-in-use-bytes":18219008,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-24T04:52:05.018087Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3691335039,"revision":5539,"compact-revision":2873}