{"level":"warn","ts":"2026-04-12T17:27:04.840622Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-12T17:27:04.840732Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.155: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.155:2380","--initial-cluster=instance=https://199.204.45.155:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.155:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.155: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-04-12T17:27:04.840824Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-12T17:27:04.84084Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.155:2380"]} {"level":"info","ts":"2026-04-12T17:27:04.840861Z","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-04-12T17:27:04.841751Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.155:2379"]} {"level":"info","ts":"2026-04-12T17:27:04.841855Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.155:2380"],"listen-peer-urls":["https://199.204.45.155:2380"],"advertise-client-urls":["https://199.204.45.155:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.155:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.155: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-04-12T17:27:04.845784Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.619224ms"} {"level":"info","ts":"2026-04-12T17:27:04.851474Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"344940fc142d902f","cluster-id":"c7d65bf2a36fbd4"} {"level":"info","ts":"2026-04-12T17:27:04.851568Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f switched to configuration voters=()"} {"level":"info","ts":"2026-04-12T17:27:04.851592Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became follower at term 0"} {"level":"info","ts":"2026-04-12T17:27:04.851611Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 344940fc142d902f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-12T17:27:04.851632Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became follower at term 1"} {"level":"info","ts":"2026-04-12T17:27:04.851676Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f switched to configuration voters=(3767614014686597167)"} {"level":"warn","ts":"2026-04-12T17:27:04.860472Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-12T17:27:04.862003Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-12T17:27:04.863297Z","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-04-12T17:27:04.865578Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"344940fc142d902f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-12T17:27:04.865702Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"344940fc142d902f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-12T17:27:04.865881Z","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-04-12T17:27:04.866037Z","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-04-12T17:27:04.866073Z","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-04-12T17:27:04.869256Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f switched to configuration voters=(3767614014686597167)"} {"level":"info","ts":"2026-04-12T17:27:04.869532Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c7d65bf2a36fbd4","local-member-id":"344940fc142d902f","added-peer-id":"344940fc142d902f","added-peer-peer-urls":["https://199.204.45.155:2380"]} {"level":"info","ts":"2026-04-12T17:27:04.87148Z","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-04-12T17:27:04.871651Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.155:2380"} {"level":"info","ts":"2026-04-12T17:27:04.87172Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.155:2380"} {"level":"info","ts":"2026-04-12T17:27:04.871846Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"344940fc142d902f","initial-advertise-peer-urls":["https://199.204.45.155:2380"],"listen-peer-urls":["https://199.204.45.155:2380"],"advertise-client-urls":["https://199.204.45.155:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.155:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-12T17:27:04.871905Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-12T17:27:05.552619Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f is starting a new election at term 1"} {"level":"info","ts":"2026-04-12T17:27:05.552679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became pre-candidate at term 1"} {"level":"info","ts":"2026-04-12T17:27:05.552715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f received MsgPreVoteResp from 344940fc142d902f at term 1"} {"level":"info","ts":"2026-04-12T17:27:05.552734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became candidate at term 2"} {"level":"info","ts":"2026-04-12T17:27:05.552745Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f received MsgVoteResp from 344940fc142d902f at term 2"} {"level":"info","ts":"2026-04-12T17:27:05.552761Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became leader at term 2"} {"level":"info","ts":"2026-04-12T17:27:05.552781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 344940fc142d902f elected leader 344940fc142d902f at term 2"} {"level":"info","ts":"2026-04-12T17:27:05.553952Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T17:27:05.555044Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"344940fc142d902f","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.155:2379]}","request-path":"/0/members/344940fc142d902f/attributes","cluster-id":"c7d65bf2a36fbd4","publish-timeout":"7s"} {"level":"info","ts":"2026-04-12T17:27:05.555079Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-12T17:27:05.555125Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-12T17:27:05.555428Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-12T17:27:05.555514Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-12T17:27:05.555512Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c7d65bf2a36fbd4","local-member-id":"344940fc142d902f","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T17:27:05.555655Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T17:27:05.555723Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T17:27:05.558369Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-12T17:27:05.558607Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.155:2379"} {"level":"warn","ts":"2026-04-12T17:27:24.064195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.198091ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-12T17:27:33.290792Z","caller":"traceutil/trace.go:171","msg":"trace[1426849301] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"174.300903ms","start":"2026-04-12T17:27:33.116422Z","end":"2026-04-12T17:27:33.290723Z","steps":["trace[1426849301] 'process raft request' (duration: 173.777161ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:27:37.499858Z","caller":"traceutil/trace.go:171","msg":"trace[82396404] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:584; }","duration":"214.140567ms","start":"2026-04-12T17:27:37.285696Z","end":"2026-04-12T17:27:37.499836Z","steps":["trace[82396404] 'read index received' (duration: 213.84118ms)","trace[82396404] 'applied index is now lower than readState.Index' (duration: 297.517µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T17:27:37.499985Z","caller":"traceutil/trace.go:171","msg":"trace[524688881] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"225.605276ms","start":"2026-04-12T17:27:37.274357Z","end":"2026-04-12T17:27:37.499963Z","steps":["trace[524688881] 'process raft request' (duration: 225.161096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:27:37.500076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.367853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:80703"} {"level":"info","ts":"2026-04-12T17:27:37.500129Z","caller":"traceutil/trace.go:171","msg":"trace[2085448537] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:564; }","duration":"214.435465ms","start":"2026-04-12T17:27:37.285681Z","end":"2026-04-12T17:27:37.500116Z","steps":["trace[2085448537] 'agreement among raft nodes before linearized reading' (duration: 214.274441ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:27:37.506853Z","caller":"traceutil/trace.go:171","msg":"trace[2056175080] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"207.550393ms","start":"2026-04-12T17:27:37.299274Z","end":"2026-04-12T17:27:37.506824Z","steps":["trace[2056175080] 'process raft request' (duration: 207.347698ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:27:37.508206Z","caller":"traceutil/trace.go:171","msg":"trace[1688348247] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"175.490732ms","start":"2026-04-12T17:27:37.332703Z","end":"2026-04-12T17:27:37.508194Z","steps":["trace[1688348247] 'process raft request' (duration: 174.612671ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:27:37.508485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.990479ms","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-04-12T17:27:37.508561Z","caller":"traceutil/trace.go:171","msg":"trace[1997618600] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:566; }","duration":"137.095592ms","start":"2026-04-12T17:27:37.371431Z","end":"2026-04-12T17:27:37.508526Z","steps":["trace[1997618600] 'agreement among raft nodes before linearized reading' (duration: 136.929808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:27:37.508757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.619591ms","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-04-12T17:27:37.508874Z","caller":"traceutil/trace.go:171","msg":"trace[2108011849] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:566; }","duration":"203.764164ms","start":"2026-04-12T17:27:37.305098Z","end":"2026-04-12T17:27:37.508862Z","steps":["trace[2108011849] 'agreement among raft nodes before linearized reading' (duration: 203.038757ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:27:37.870098Z","caller":"traceutil/trace.go:171","msg":"trace[1389848010] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:602; }","duration":"124.65932ms","start":"2026-04-12T17:27:37.745413Z","end":"2026-04-12T17:27:37.870073Z","steps":["trace[1389848010] 'read index received' (duration: 50.837191ms)","trace[1389848010] 'applied index is now lower than readState.Index' (duration: 73.820949ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T17:27:37.87018Z","caller":"traceutil/trace.go:171","msg":"trace[1090796179] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"129.544135ms","start":"2026-04-12T17:27:37.7406Z","end":"2026-04-12T17:27:37.870144Z","steps":["trace[1090796179] 'process raft request' (duration: 55.636104ms)","trace[1090796179] 'compare' (duration: 73.738507ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T17:27:37.870443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.002118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:65436"} {"level":"info","ts":"2026-04-12T17:27:37.870575Z","caller":"traceutil/trace.go:171","msg":"trace[1513288196] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:582; }","duration":"125.128092ms","start":"2026-04-12T17:27:37.745377Z","end":"2026-04-12T17:27:37.870505Z","steps":["trace[1513288196] 'agreement among raft nodes before linearized reading' (duration: 124.873276ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:27:43.678544Z","caller":"traceutil/trace.go:171","msg":"trace[1752157680] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:660; }","duration":"138.388341ms","start":"2026-04-12T17:27:43.540127Z","end":"2026-04-12T17:27:43.678515Z","steps":["trace[1752157680] 'read index received' (duration: 138.150146ms)","trace[1752157680] 'applied index is now lower than readState.Index' (duration: 237.115µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T17:27:43.678657Z","caller":"traceutil/trace.go:171","msg":"trace[889011137] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"201.934108ms","start":"2026-04-12T17:27:43.476687Z","end":"2026-04-12T17:27:43.678621Z","steps":["trace[889011137] 'process raft request' (duration: 201.687322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:27:43.678827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.780559ms","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-04-12T17:27:43.678892Z","caller":"traceutil/trace.go:171","msg":"trace[1457373252] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:639; }","duration":"135.887282ms","start":"2026-04-12T17:27:43.542989Z","end":"2026-04-12T17:27:43.678876Z","steps":["trace[1457373252] 'agreement among raft nodes before linearized reading' (duration: 135.694157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:27:43.678882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.729411ms","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-04-12T17:27:43.678952Z","caller":"traceutil/trace.go:171","msg":"trace[52682006] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:639; }","duration":"138.844572ms","start":"2026-04-12T17:27:43.540088Z","end":"2026-04-12T17:27:43.678933Z","steps":["trace[52682006] 'agreement among raft nodes before linearized reading' (duration: 138.582666ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:27:44.047493Z","caller":"traceutil/trace.go:171","msg":"trace[1764994512] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"136.64263ms","start":"2026-04-12T17:27:43.910827Z","end":"2026-04-12T17:27:44.047469Z","steps":["trace[1764994512] 'process raft request' (duration: 136.489797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:28:00.111549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.830775ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T17:28:00.111714Z","caller":"traceutil/trace.go:171","msg":"trace[49568321] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"333.433235ms","start":"2026-04-12T17:27:59.778235Z","end":"2026-04-12T17:28:00.111668Z","steps":["trace[49568321] 'process raft request' (duration: 205.877462ms)","trace[49568321] 'compare' (duration: 126.60757ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T17:28:00.111782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:27:59.778226Z","time spent":"333.524917ms","remote":"127.0.0.1:45916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T17:29:13.278116Z","caller":"traceutil/trace.go:171","msg":"trace[2069533069] linearizableReadLoop","detail":"{readStateIndex:1890; appliedIndex:1889; }","duration":"260.709639ms","start":"2026-04-12T17:29:13.017369Z","end":"2026-04-12T17:29:13.278079Z","steps":["trace[2069533069] 'read index received' (duration: 260.35085ms)","trace[2069533069] 'applied index is now lower than readState.Index' (duration: 357.299µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T17:29:13.278238Z","caller":"traceutil/trace.go:171","msg":"trace[1026725863] transaction","detail":"{read_only:false; response_revision:1822; number_of_response:1; }","duration":"336.671832ms","start":"2026-04-12T17:29:12.941539Z","end":"2026-04-12T17:29:13.278211Z","steps":["trace[1026725863] 'process raft request' (duration: 336.327334ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:29:13.278386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:29:12.94151Z","time spent":"336.793364ms","remote":"127.0.0.1:45840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10970,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T17:29:13.278428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.039427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1e4303a5-7088-4025-aae1-d923b7732690\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-12T17:29:13.278504Z","caller":"traceutil/trace.go:171","msg":"trace[902269543] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1e4303a5-7088-4025-aae1-d923b7732690; range_end:; response_count:1; response_revision:1822; }","duration":"261.153629ms","start":"2026-04-12T17:29:13.017328Z","end":"2026-04-12T17:29:13.278482Z","steps":["trace[902269543] 'agreement among raft nodes before linearized reading' (duration: 260.950234ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:29:13.768466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.413865ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T17:29:13.768665Z","caller":"traceutil/trace.go:171","msg":"trace[1901546930] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"358.078665ms","start":"2026-04-12T17:29:13.41055Z","end":"2026-04-12T17:29:13.768628Z","steps":["trace[1901546930] 'process raft request' (duration: 71.373943ms)","trace[1901546930] 'compare' (duration: 286.180049ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T17:29:13.76879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:29:13.410514Z","time spent":"358.214188ms","remote":"127.0.0.1:45916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T17:29:13.768924Z","caller":"traceutil/trace.go:171","msg":"trace[421644005] linearizableReadLoop","detail":"{readStateIndex:1897; appliedIndex:1896; }","duration":"355.913693ms","start":"2026-04-12T17:29:13.412971Z","end":"2026-04-12T17:29:13.768885Z","steps":["trace[421644005] 'read index received' (duration: 68.942995ms)","trace[421644005] 'applied index is now lower than readState.Index' (duration: 286.968238ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T17:29:13.771084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.083205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-12T17:29:13.771154Z","caller":"traceutil/trace.go:171","msg":"trace[960132484] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1829; }","duration":"358.207808ms","start":"2026-04-12T17:29:13.41293Z","end":"2026-04-12T17:29:13.771138Z","steps":["trace[960132484] 'agreement among raft nodes before linearized reading' (duration: 356.122378ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:29:13.771196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:29:13.412909Z","time spent":"358.275889ms","remote":"127.0.0.1:46126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":229,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-04-12T17:29:13.771595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.14393ms","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-04-12T17:29:13.771653Z","caller":"traceutil/trace.go:171","msg":"trace[1573063685] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1830; }","duration":"129.232752ms","start":"2026-04-12T17:29:13.642401Z","end":"2026-04-12T17:29:13.771633Z","steps":["trace[1573063685] 'agreement among raft nodes before linearized reading' (duration: 128.993026ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:29:26.017655Z","caller":"traceutil/trace.go:171","msg":"trace[631843137] transaction","detail":"{read_only:false; response_revision:1929; number_of_response:1; }","duration":"205.461892ms","start":"2026-04-12T17:29:25.812164Z","end":"2026-04-12T17:29:26.017626Z","steps":["trace[631843137] 'process raft request' (duration: 205.272688ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:29:26.019842Z","caller":"traceutil/trace.go:171","msg":"trace[308528012] transaction","detail":"{read_only:false; response_revision:1930; number_of_response:1; }","duration":"206.462436ms","start":"2026-04-12T17:29:25.813353Z","end":"2026-04-12T17:29:26.019816Z","steps":["trace[308528012] 'process raft request' (duration: 206.2189ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:29:26.020086Z","caller":"traceutil/trace.go:171","msg":"trace[1044565727] linearizableReadLoop","detail":"{readStateIndex:2002; appliedIndex:2000; }","duration":"192.47683ms","start":"2026-04-12T17:29:25.827562Z","end":"2026-04-12T17:29:26.020039Z","steps":["trace[1044565727] 'read index received' (duration: 189.916929ms)","trace[1044565727] 'applied index is now lower than readState.Index' (duration: 2.558511ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T17:29:26.020406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.795328ms","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-04-12T17:29:26.020503Z","caller":"traceutil/trace.go:171","msg":"trace[871593315] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1931; }","duration":"193.005473ms","start":"2026-04-12T17:29:25.82748Z","end":"2026-04-12T17:29:26.020485Z","steps":["trace[871593315] 'agreement among raft nodes before linearized reading' (duration: 192.696246ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:30:03.229227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.947694ms","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-04-12T17:30:03.229475Z","caller":"traceutil/trace.go:171","msg":"trace[61822176] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2148; }","duration":"194.286823ms","start":"2026-04-12T17:30:03.035162Z","end":"2026-04-12T17:30:03.229449Z","steps":["trace[61822176] 'range keys from in-memory index tree' (duration: 193.657617ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:30:03.570974Z","caller":"traceutil/trace.go:171","msg":"trace[327208468] transaction","detail":"{read_only:false; response_revision:2150; number_of_response:1; }","duration":"139.011549ms","start":"2026-04-12T17:30:03.43192Z","end":"2026-04-12T17:30:03.570931Z","steps":["trace[327208468] 'process raft request' (duration: 138.762613ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:30:04.229133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.910182ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-12T17:30:04.229296Z","caller":"traceutil/trace.go:171","msg":"trace[1570505616] linearizableReadLoop","detail":"{readStateIndex:2230; appliedIndex:2229; }","duration":"499.230076ms","start":"2026-04-12T17:30:03.730001Z","end":"2026-04-12T17:30:04.229231Z","steps":["trace[1570505616] 'read index received' (duration: 32.118089ms)","trace[1570505616] 'applied index is now lower than readState.Index' (duration: 467.110317ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T17:30:04.229724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.710087ms","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-04-12T17:30:04.229829Z","caller":"traceutil/trace.go:171","msg":"trace[1635572732] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2150; }","duration":"499.880441ms","start":"2026-04-12T17:30:03.72993Z","end":"2026-04-12T17:30:04.229811Z","steps":["trace[1635572732] 'agreement among raft nodes before linearized reading' (duration: 499.617195ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:30:04.229872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:30:03.729902Z","time spent":"499.961473ms","remote":"127.0.0.1:45916","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-04-12T17:30:04.28455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.398424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-04-12T17:30:04.284651Z","caller":"traceutil/trace.go:171","msg":"trace[246142112] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2153; }","duration":"158.541537ms","start":"2026-04-12T17:30:04.126089Z","end":"2026-04-12T17:30:04.284631Z","steps":["trace[246142112] 'agreement among raft nodes before linearized reading' (duration: 158.356303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:30:18.571285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.991424ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T17:30:18.571538Z","caller":"traceutil/trace.go:171","msg":"trace[190494348] linearizableReadLoop","detail":"{readStateIndex:2315; appliedIndex:2313; }","duration":"222.876568ms","start":"2026-04-12T17:30:18.348637Z","end":"2026-04-12T17:30:18.571513Z","steps":["trace[190494348] 'read index received' (duration: 9.836436ms)","trace[190494348] 'applied index is now lower than readState.Index' (duration: 213.037402ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T17:30:18.57193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.264357ms","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-04-12T17:30:18.572058Z","caller":"traceutil/trace.go:171","msg":"trace[525314867] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2232; }","duration":"223.418061ms","start":"2026-04-12T17:30:18.348615Z","end":"2026-04-12T17:30:18.572033Z","steps":["trace[525314867] 'agreement among raft nodes before linearized reading' (duration: 223.013192ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:30:18.572165Z","caller":"traceutil/trace.go:171","msg":"trace[1610563946] transaction","detail":"{read_only:false; response_revision:2232; number_of_response:1; }","duration":"239.308011ms","start":"2026-04-12T17:30:18.332227Z","end":"2026-04-12T17:30:18.571535Z","steps":["trace[1610563946] 'process raft request' (duration: 52.947878ms)","trace[1610563946] 'compare' (duration: 185.761869ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T17:30:18.597182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.020265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-12T17:30:18.597281Z","caller":"traceutil/trace.go:171","msg":"trace[1969220219] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2233; }","duration":"191.166299ms","start":"2026-04-12T17:30:18.406093Z","end":"2026-04-12T17:30:18.597259Z","steps":["trace[1969220219] 'agreement among raft nodes before linearized reading' (duration: 190.951684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:30:18.59756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.133301ms","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-04-12T17:30:18.597653Z","caller":"traceutil/trace.go:171","msg":"trace[1689631505] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2233; }","duration":"245.275305ms","start":"2026-04-12T17:30:18.352358Z","end":"2026-04-12T17:30:18.597633Z","steps":["trace[1689631505] 'agreement among raft nodes before linearized reading' (duration: 245.047809ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:30:18.597561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.002082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.155\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-12T17:30:18.597761Z","caller":"traceutil/trace.go:171","msg":"trace[1348327313] range","detail":"{range_begin:/registry/masterleases/199.204.45.155; range_end:; response_count:1; response_revision:2233; }","duration":"150.253648ms","start":"2026-04-12T17:30:18.44749Z","end":"2026-04-12T17:30:18.597743Z","steps":["trace[1348327313] 'agreement among raft nodes before linearized reading' (duration: 149.89242ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:30:24.784613Z","caller":"traceutil/trace.go:171","msg":"trace[557333317] transaction","detail":"{read_only:false; response_revision:2272; number_of_response:1; }","duration":"152.82839ms","start":"2026-04-12T17:30:24.63175Z","end":"2026-04-12T17:30:24.784578Z","steps":["trace[557333317] 'process raft request' (duration: 152.598924ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:30:24.798004Z","caller":"traceutil/trace.go:171","msg":"trace[484665538] linearizableReadLoop","detail":"{readStateIndex:2358; appliedIndex:2356; }","duration":"122.679438ms","start":"2026-04-12T17:30:24.675285Z","end":"2026-04-12T17:30:24.797964Z","steps":["trace[484665538] 'read index received' (duration: 109.104342ms)","trace[484665538] 'applied index is now lower than readState.Index' (duration: 13.574376ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T17:30:24.798134Z","caller":"traceutil/trace.go:171","msg":"trace[362139463] transaction","detail":"{read_only:false; response_revision:2273; number_of_response:1; }","duration":"139.351197ms","start":"2026-04-12T17:30:24.658766Z","end":"2026-04-12T17:30:24.798117Z","steps":["trace[362139463] 'process raft request' (duration: 139.04824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:30:24.798652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.348804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5428"} {"level":"info","ts":"2026-04-12T17:30:24.798705Z","caller":"traceutil/trace.go:171","msg":"trace[613397442] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2273; }","duration":"123.466467ms","start":"2026-04-12T17:30:24.675226Z","end":"2026-04-12T17:30:24.798693Z","steps":["trace[613397442] 'agreement among raft nodes before linearized reading' (duration: 123.258152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:30:26.924067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.928119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5428"} {"level":"info","ts":"2026-04-12T17:30:26.924166Z","caller":"traceutil/trace.go:171","msg":"trace[1486714210] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2282; }","duration":"109.084803ms","start":"2026-04-12T17:30:26.815056Z","end":"2026-04-12T17:30:26.924141Z","steps":["trace[1486714210] 'agreement among raft nodes before linearized reading' (duration: 38.803909ms)","trace[1486714210] 'range keys from in-memory index tree' (duration: 70.112529ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T17:30:35.010681Z","caller":"traceutil/trace.go:171","msg":"trace[970694188] transaction","detail":"{read_only:false; response_revision:2324; number_of_response:1; }","duration":"102.163727ms","start":"2026-04-12T17:30:34.908488Z","end":"2026-04-12T17:30:35.010652Z","steps":["trace[970694188] 'process raft request' (duration: 74.137006ms)","trace[970694188] 'compare' (duration: 27.837327ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T17:30:41.078728Z","caller":"traceutil/trace.go:171","msg":"trace[742942239] transaction","detail":"{read_only:false; response_revision:2352; number_of_response:1; }","duration":"139.825148ms","start":"2026-04-12T17:30:40.938869Z","end":"2026-04-12T17:30:41.078694Z","steps":["trace[742942239] 'process raft request' (duration: 139.597213ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:30:43.206802Z","caller":"traceutil/trace.go:171","msg":"trace[1663085196] transaction","detail":"{read_only:false; response_revision:2371; number_of_response:1; }","duration":"104.255977ms","start":"2026-04-12T17:30:43.102495Z","end":"2026-04-12T17:30:43.206751Z","steps":["trace[1663085196] 'process raft request' (duration: 52.472187ms)","trace[1663085196] 'compare' (duration: 51.596886ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T17:30:43.35123Z","caller":"traceutil/trace.go:171","msg":"trace[1888158589] transaction","detail":"{read_only:false; response_revision:2373; number_of_response:1; }","duration":"131.416086ms","start":"2026-04-12T17:30:43.21978Z","end":"2026-04-12T17:30:43.351196Z","steps":["trace[1888158589] 'process raft request' (duration: 109.202565ms)","trace[1888158589] 'compare' (duration: 21.864863ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T17:30:45.012775Z","caller":"traceutil/trace.go:171","msg":"trace[789957003] transaction","detail":"{read_only:false; response_revision:2383; number_of_response:1; }","duration":"110.457405ms","start":"2026-04-12T17:30:44.902288Z","end":"2026-04-12T17:30:45.012745Z","steps":["trace[789957003] 'process raft request' (duration: 106.571272ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:30:45.251551Z","caller":"traceutil/trace.go:171","msg":"trace[736127868] transaction","detail":"{read_only:false; response_revision:2387; number_of_response:1; }","duration":"128.402536ms","start":"2026-04-12T17:30:45.123117Z","end":"2026-04-12T17:30:45.25152Z","steps":["trace[736127868] 'process raft request' (duration: 128.256422ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:30:45.251916Z","caller":"traceutil/trace.go:171","msg":"trace[461384561] transaction","detail":"{read_only:false; response_revision:2386; number_of_response:1; }","duration":"211.794473ms","start":"2026-04-12T17:30:45.040104Z","end":"2026-04-12T17:30:45.251898Z","steps":["trace[461384561] 'process raft request' (duration: 125.239829ms)","trace[461384561] 'compare' (duration: 85.812126ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T17:30:45.527575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.291348ms","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-04-12T17:30:45.52772Z","caller":"traceutil/trace.go:171","msg":"trace[1226995831] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2389; }","duration":"137.456662ms","start":"2026-04-12T17:30:45.390207Z","end":"2026-04-12T17:30:45.527664Z","steps":["trace[1226995831] 'range keys from in-memory index tree' (duration: 137.080613ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:30:45.527607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.494671ms","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-04-12T17:30:45.527897Z","caller":"traceutil/trace.go:171","msg":"trace[1575445031] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2389; }","duration":"134.85404ms","start":"2026-04-12T17:30:45.393016Z","end":"2026-04-12T17:30:45.52787Z","steps":["trace[1575445031] 'range keys from in-memory index tree' (duration: 134.215144ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:30:46.48432Z","caller":"traceutil/trace.go:171","msg":"trace[2145298633] transaction","detail":"{read_only:false; response_revision:2396; number_of_response:1; }","duration":"155.225378ms","start":"2026-04-12T17:30:46.329031Z","end":"2026-04-12T17:30:46.484256Z","steps":["trace[2145298633] 'process raft request' (duration: 125.356432ms)","trace[2145298633] 'compare' (duration: 29.65089ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T17:30:46.484438Z","caller":"traceutil/trace.go:171","msg":"trace[2104858071] transaction","detail":"{read_only:false; number_of_response:1; response_revision:2396; }","duration":"155.32445ms","start":"2026-04-12T17:30:46.329086Z","end":"2026-04-12T17:30:46.484411Z","steps":["trace[2104858071] 'process raft request' (duration: 155.084564ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:30:46.484596Z","caller":"traceutil/trace.go:171","msg":"trace[849190151] transaction","detail":"{read_only:false; response_revision:2397; number_of_response:1; }","duration":"115.240109ms","start":"2026-04-12T17:30:46.36934Z","end":"2026-04-12T17:30:46.484581Z","steps":["trace[849190151] 'process raft request' (duration: 114.879791ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:30:46.604953Z","caller":"traceutil/trace.go:171","msg":"trace[605660908] transaction","detail":"{read_only:false; response_revision:2399; number_of_response:1; }","duration":"111.578402ms","start":"2026-04-12T17:30:46.49335Z","end":"2026-04-12T17:30:46.604929Z","steps":["trace[605660908] 'process raft request' (duration: 94.918134ms)","trace[605660908] 'compare' (duration: 16.477534ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T17:30:46.605233Z","caller":"traceutil/trace.go:171","msg":"trace[757098488] transaction","detail":"{read_only:false; response_revision:2400; number_of_response:1; }","duration":"108.057318ms","start":"2026-04-12T17:30:46.497147Z","end":"2026-04-12T17:30:46.605204Z","steps":["trace[757098488] 'process raft request' (duration: 107.744561ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:30:46.605476Z","caller":"traceutil/trace.go:171","msg":"trace[2070996106] transaction","detail":"{read_only:false; response_revision:2401; number_of_response:1; }","duration":"104.443681ms","start":"2026-04-12T17:30:46.501004Z","end":"2026-04-12T17:30:46.605448Z","steps":["trace[2070996106] 'process raft request' (duration: 104.118123ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:30:46.783534Z","caller":"traceutil/trace.go:171","msg":"trace[358989023] linearizableReadLoop","detail":"{readStateIndex:2494; appliedIndex:2493; }","duration":"171.759312ms","start":"2026-04-12T17:30:46.611735Z","end":"2026-04-12T17:30:46.783494Z","steps":["trace[358989023] 'read index received' (duration: 98.99568ms)","trace[358989023] 'applied index is now lower than readState.Index' (duration: 72.761812ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T17:30:46.783603Z","caller":"traceutil/trace.go:171","msg":"trace[1742787872] transaction","detail":"{read_only:false; response_revision:2404; number_of_response:1; }","duration":"172.542812ms","start":"2026-04-12T17:30:46.611022Z","end":"2026-04-12T17:30:46.783565Z","steps":["trace[1742787872] 'process raft request' (duration: 99.753169ms)","trace[1742787872] 'compare' (duration: 72.469565ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T17:30:46.783902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.142322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/valkey.18a5ac46698ec690\" ","response":"range_response_count:1 size:706"} {"level":"info","ts":"2026-04-12T17:30:46.783979Z","caller":"traceutil/trace.go:171","msg":"trace[948462436] range","detail":"{range_begin:/registry/events/openstack/valkey.18a5ac46698ec690; range_end:; response_count:1; response_revision:2404; }","duration":"172.254434ms","start":"2026-04-12T17:30:46.611707Z","end":"2026-04-12T17:30:46.783961Z","steps":["trace[948462436] 'agreement among raft nodes before linearized reading' (duration: 171.959187ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:30:46.805455Z","caller":"traceutil/trace.go:171","msg":"trace[435309492] transaction","detail":"{read_only:false; number_of_response:1; response_revision:2405; }","duration":"184.491207ms","start":"2026-04-12T17:30:46.620927Z","end":"2026-04-12T17:30:46.805418Z","steps":["trace[435309492] 'process raft request' (duration: 184.428716ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:30:46.805525Z","caller":"traceutil/trace.go:171","msg":"trace[174119733] transaction","detail":"{read_only:false; response_revision:2405; number_of_response:1; }","duration":"185.549263ms","start":"2026-04-12T17:30:46.619933Z","end":"2026-04-12T17:30:46.805482Z","steps":["trace[174119733] 'process raft request' (duration: 185.222805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:30:46.831985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.817256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/openstack/valkey-ca\" ","response":"range_response_count:1 size:2069"} {"level":"info","ts":"2026-04-12T17:30:46.832057Z","caller":"traceutil/trace.go:171","msg":"trace[1040890048] range","detail":"{range_begin:/registry/cert-manager.io/certificates/openstack/valkey-ca; range_end:; response_count:1; response_revision:2408; }","duration":"217.943739ms","start":"2026-04-12T17:30:46.614098Z","end":"2026-04-12T17:30:46.832041Z","steps":["trace[1040890048] 'agreement among raft nodes before linearized reading' (duration: 217.724304ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:30:46.971891Z","caller":"traceutil/trace.go:171","msg":"trace[1730700004] linearizableReadLoop","detail":"{readStateIndex:2501; appliedIndex:2499; }","duration":"136.992741ms","start":"2026-04-12T17:30:46.834875Z","end":"2026-04-12T17:30:46.971867Z","steps":["trace[1730700004] 'read index received' (duration: 86.84523ms)","trace[1730700004] 'applied index is now lower than readState.Index' (duration: 50.146261ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T17:30:46.971907Z","caller":"traceutil/trace.go:171","msg":"trace[396484871] transaction","detail":"{read_only:false; response_revision:2409; number_of_response:1; }","duration":"160.649028ms","start":"2026-04-12T17:30:46.811218Z","end":"2026-04-12T17:30:46.971867Z","steps":["trace[396484871] 'process raft request' (duration: 110.363343ms)","trace[396484871] 'compare' (duration: 50.07403ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T17:30:46.971943Z","caller":"traceutil/trace.go:171","msg":"trace[566939169] transaction","detail":"{read_only:false; response_revision:2410; number_of_response:1; }","duration":"147.943053ms","start":"2026-04-12T17:30:46.82398Z","end":"2026-04-12T17:30:46.971924Z","steps":["trace[566939169] 'process raft request' (duration: 147.81964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:30:46.972119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.229327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/valkey.18a5ac46698fd4b9\" ","response":"range_response_count:1 size:696"} {"level":"info","ts":"2026-04-12T17:30:46.972159Z","caller":"traceutil/trace.go:171","msg":"trace[1508965799] range","detail":"{range_begin:/registry/events/openstack/valkey.18a5ac46698fd4b9; range_end:; response_count:1; response_revision:2410; }","duration":"137.319338ms","start":"2026-04-12T17:30:46.834829Z","end":"2026-04-12T17:30:46.972149Z","steps":["trace[1508965799] 'agreement among raft nodes before linearized reading' (duration: 137.163305ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:30:47.122875Z","caller":"traceutil/trace.go:171","msg":"trace[1954851800] transaction","detail":"{read_only:false; response_revision:2411; number_of_response:1; }","duration":"279.440162ms","start":"2026-04-12T17:30:46.843412Z","end":"2026-04-12T17:30:47.122852Z","steps":["trace[1954851800] 'process raft request' (duration: 270.076458ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:30:47.178208Z","caller":"traceutil/trace.go:171","msg":"trace[672372137] linearizableReadLoop","detail":"{readStateIndex:2503; appliedIndex:2501; }","duration":"206.219889ms","start":"2026-04-12T17:30:46.971968Z","end":"2026-04-12T17:30:47.178188Z","steps":["trace[672372137] 'read index received' (duration: 141.5496ms)","trace[672372137] 'applied index is now lower than readState.Index' (duration: 64.669399ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T17:30:47.178298Z","caller":"traceutil/trace.go:171","msg":"trace[1978130350] transaction","detail":"{read_only:false; response_revision:2413; number_of_response:1; }","duration":"204.20808ms","start":"2026-04-12T17:30:46.97408Z","end":"2026-04-12T17:30:47.178288Z","steps":["trace[1978130350] 'process raft request' (duration: 204.036316ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:30:47.178297Z","caller":"traceutil/trace.go:171","msg":"trace[207549415] transaction","detail":"{read_only:false; response_revision:2412; number_of_response:1; }","duration":"207.993861ms","start":"2026-04-12T17:30:46.970279Z","end":"2026-04-12T17:30:47.178273Z","steps":["trace[207549415] 'process raft request' (duration: 207.745355ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:30:47.178526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.434602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T17:30:47.178637Z","caller":"traceutil/trace.go:171","msg":"trace[1570557003] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:2413; }","duration":"251.585355ms","start":"2026-04-12T17:30:46.927002Z","end":"2026-04-12T17:30:47.178587Z","steps":["trace[1570557003] 'agreement among raft nodes before linearized reading' (duration: 251.317339ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:30:47.468274Z","caller":"traceutil/trace.go:171","msg":"trace[69006548] linearizableReadLoop","detail":"{readStateIndex:2515; appliedIndex:2512; }","duration":"186.705302ms","start":"2026-04-12T17:30:47.281544Z","end":"2026-04-12T17:30:47.468249Z","steps":["trace[69006548] 'read index received' (duration: 126.288735ms)","trace[69006548] 'applied index is now lower than readState.Index' (duration: 60.415226ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T17:30:47.468393Z","caller":"traceutil/trace.go:171","msg":"trace[773910239] transaction","detail":"{read_only:false; response_revision:2423; number_of_response:1; }","duration":"210.885801ms","start":"2026-04-12T17:30:47.257496Z","end":"2026-04-12T17:30:47.468382Z","steps":["trace[773910239] 'process raft request' (duration: 210.653845ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:30:47.468611Z","caller":"traceutil/trace.go:171","msg":"trace[719784846] transaction","detail":"{read_only:false; response_revision:2424; number_of_response:1; }","duration":"210.781448ms","start":"2026-04-12T17:30:47.257819Z","end":"2026-04-12T17:30:47.468601Z","steps":["trace[719784846] 'process raft request' (duration: 210.388869ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:30:47.468625Z","caller":"traceutil/trace.go:171","msg":"trace[959068126] transaction","detail":"{read_only:false; response_revision:2422; number_of_response:1; }","duration":"254.864414ms","start":"2026-04-12T17:30:47.213736Z","end":"2026-04-12T17:30:47.4686Z","steps":["trace[959068126] 'process raft request' (duration: 194.15974ms)","trace[959068126] 'compare' (duration: 60.11825ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T17:30:47.468877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.377343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T17:30:47.468925Z","caller":"traceutil/trace.go:171","msg":"trace[1287467708] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2424; }","duration":"234.464655ms","start":"2026-04-12T17:30:47.234448Z","end":"2026-04-12T17:30:47.468912Z","steps":["trace[1287467708] 'agreement among raft nodes before linearized reading' (duration: 234.377503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:30:47.46907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.234523ms","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-04-12T17:30:47.469172Z","caller":"traceutil/trace.go:171","msg":"trace[1594552370] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2424; }","duration":"202.368987ms","start":"2026-04-12T17:30:47.266773Z","end":"2026-04-12T17:30:47.469142Z","steps":["trace[1594552370] 'agreement among raft nodes before linearized reading' (duration: 202.08071ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:30:47.522362Z","caller":"traceutil/trace.go:171","msg":"trace[1960282090] transaction","detail":"{read_only:false; response_revision:2425; number_of_response:1; }","duration":"228.576294ms","start":"2026-04-12T17:30:47.293761Z","end":"2026-04-12T17:30:47.522337Z","steps":["trace[1960282090] 'process raft request' (duration: 228.443111ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:30:47.522711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.189473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:7 size:229331"} {"level":"info","ts":"2026-04-12T17:30:47.522785Z","caller":"traceutil/trace.go:171","msg":"trace[2128692836] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:7; response_revision:2425; }","duration":"149.321456ms","start":"2026-04-12T17:30:47.373447Z","end":"2026-04-12T17:30:47.522768Z","steps":["trace[2128692836] 'agreement among raft nodes before linearized reading' (duration: 148.969848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:30:48.058743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.879828ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T17:30:48.058879Z","caller":"traceutil/trace.go:171","msg":"trace[1448425204] transaction","detail":"{read_only:false; response_revision:2431; number_of_response:1; }","duration":"436.33717ms","start":"2026-04-12T17:30:47.622517Z","end":"2026-04-12T17:30:48.058854Z","steps":["trace[1448425204] 'process raft request' (duration: 99.225846ms)","trace[1448425204] 'compare' (duration: 336.53402ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T17:30:48.059087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:30:47.622498Z","time spent":"436.510234ms","remote":"127.0.0.1:45732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4930,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T17:30:48.083241Z","caller":"traceutil/trace.go:171","msg":"trace[1700091857] transaction","detail":"{read_only:false; response_revision:2432; number_of_response:1; }","duration":"460.461448ms","start":"2026-04-12T17:30:47.622751Z","end":"2026-04-12T17:30:48.083213Z","steps":["trace[1700091857] 'process raft request' (duration: 460.252793ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:30:48.083285Z","caller":"traceutil/trace.go:171","msg":"trace[197932809] transaction","detail":"{read_only:false; response_revision:2433; number_of_response:1; }","duration":"460.456948ms","start":"2026-04-12T17:30:47.622794Z","end":"2026-04-12T17:30:48.083251Z","steps":["trace[197932809] 'process raft request' (duration: 460.322364ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:30:48.083371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:30:47.622745Z","time spent":"460.55883ms","remote":"127.0.0.1:45916","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-04-12T17:30:48.083447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:30:47.62279Z","time spent":"460.56458ms","remote":"127.0.0.1:45916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T17:30:48.083566Z","caller":"traceutil/trace.go:171","msg":"trace[1544799961] transaction","detail":"{read_only:false; response_revision:2435; number_of_response:1; }","duration":"460.647223ms","start":"2026-04-12T17:30:47.622894Z","end":"2026-04-12T17:30:48.083541Z","steps":["trace[1544799961] 'process raft request' (duration: 460.483849ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:30:48.083565Z","caller":"traceutil/trace.go:171","msg":"trace[224442521] transaction","detail":"{read_only:false; response_revision:2434; number_of_response:1; }","duration":"460.696133ms","start":"2026-04-12T17:30:47.622836Z","end":"2026-04-12T17:30:48.083532Z","steps":["trace[224442521] 'process raft request' (duration: 460.320864ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:30:48.083786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:30:47.622887Z","time spent":"460.754525ms","remote":"127.0.0.1:45916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T17:30:48.083781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:30:47.622813Z","time spent":"460.850726ms","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":804,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T17:30:48.083841Z","caller":"traceutil/trace.go:171","msg":"trace[1939841590] transaction","detail":"{read_only:false; response_revision:2436; number_of_response:1; }","duration":"460.895978ms","start":"2026-04-12T17:30:47.622931Z","end":"2026-04-12T17:30:48.083827Z","steps":["trace[1939841590] 'process raft request' (duration: 460.576471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:30:48.083919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:30:47.622921Z","time spent":"460.96898ms","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":807,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T17:30:48.083917Z","caller":"traceutil/trace.go:171","msg":"trace[1124455911] transaction","detail":"{read_only:false; response_revision:2437; number_of_response:1; }","duration":"460.919028ms","start":"2026-04-12T17:30:47.622985Z","end":"2026-04-12T17:30:48.083904Z","steps":["trace[1124455911] 'process raft request' (duration: 460.661612ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:30:48.084124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:30:47.622978Z","time spent":"461.123904ms","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":798,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T17:30:48.084131Z","caller":"traceutil/trace.go:171","msg":"trace[1402498209] linearizableReadLoop","detail":"{readStateIndex:2533; appliedIndex:2521; }","duration":"365.703388ms","start":"2026-04-12T17:30:47.718407Z","end":"2026-04-12T17:30:48.08411Z","steps":["trace[1402498209] 'read index received' (duration: 3.34276ms)","trace[1402498209] 'applied index is now lower than readState.Index' (duration: 362.359648ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T17:30:48.084221Z","caller":"traceutil/trace.go:171","msg":"trace[1782131597] transaction","detail":"{read_only:false; response_revision:2438; number_of_response:1; }","duration":"461.220946ms","start":"2026-04-12T17:30:47.622989Z","end":"2026-04-12T17:30:48.084209Z","steps":["trace[1782131597] 'process raft request' (duration: 460.755624ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:30:48.084175Z","caller":"traceutil/trace.go:171","msg":"trace[976375698] transaction","detail":"{read_only:false; response_revision:2439; number_of_response:1; }","duration":"461.080382ms","start":"2026-04-12T17:30:47.623079Z","end":"2026-04-12T17:30:48.084159Z","steps":["trace[976375698] 'process raft request' (duration: 460.765245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:30:48.084292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:30:47.622967Z","time spent":"461.292618ms","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":822,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-12T17:30:48.084323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:30:47.623066Z","time spent":"461.234446ms","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":771,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T17:30:48.084289Z","caller":"traceutil/trace.go:171","msg":"trace[764928470] transaction","detail":"{read_only:false; response_revision:2442; number_of_response:1; }","duration":"452.468817ms","start":"2026-04-12T17:30:47.631788Z","end":"2026-04-12T17:30:48.084256Z","steps":["trace[764928470] 'process raft request' (duration: 452.244001ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:30:48.084368Z","caller":"traceutil/trace.go:171","msg":"trace[748512473] transaction","detail":"{read_only:false; response_revision:2441; number_of_response:1; }","duration":"461.100353ms","start":"2026-04-12T17:30:47.623254Z","end":"2026-04-12T17:30:48.084355Z","steps":["trace[748512473] 'process raft request' (duration: 460.679963ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:30:48.084438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:30:47.623225Z","time spent":"461.177155ms","remote":"127.0.0.1:45916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T17:30:48.084337Z","caller":"traceutil/trace.go:171","msg":"trace[500509138] transaction","detail":"{read_only:false; response_revision:2440; number_of_response:1; }","duration":"461.137104ms","start":"2026-04-12T17:30:47.623091Z","end":"2026-04-12T17:30:48.084228Z","steps":["trace[500509138] 'process raft request' (duration: 460.809226ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:30:48.084452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.000736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-12T17:30:48.084496Z","caller":"traceutil/trace.go:171","msg":"trace[1866201661] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:2442; }","duration":"366.123469ms","start":"2026-04-12T17:30:47.718361Z","end":"2026-04-12T17:30:48.084484Z","steps":["trace[1866201661] 'agreement among raft nodes before linearized reading' (duration: 365.912054ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:30:48.084614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:30:47.623079Z","time spent":"461.40386ms","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":810,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-12T17:30:48.084701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:30:47.718341Z","time spent":"366.342954ms","remote":"127.0.0.1:45984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":90,"response size":29,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-04-12T17:30:48.084504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:30:47.631777Z","time spent":"452.61059ms","remote":"127.0.0.1:60696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3956,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T17:30:48.094614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.44758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T17:30:48.094668Z","caller":"traceutil/trace.go:171","msg":"trace[602096651] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:2443; }","duration":"367.505942ms","start":"2026-04-12T17:30:47.727149Z","end":"2026-04-12T17:30:48.094655Z","steps":["trace[602096651] 'agreement among raft nodes before linearized reading' (duration: 367.388259ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:30:48.094692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:30:47.727123Z","time spent":"367.562434ms","remote":"127.0.0.1:45726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-04-12T17:30:48.0947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.150286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/valkey-ports-configmap\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T17:30:48.094784Z","caller":"traceutil/trace.go:171","msg":"trace[1144713166] range","detail":"{range_begin:/registry/configmaps/openstack/valkey-ports-configmap; range_end:; response_count:0; response_revision:2443; }","duration":"231.287159ms","start":"2026-04-12T17:30:47.863476Z","end":"2026-04-12T17:30:48.094763Z","steps":["trace[1144713166] 'agreement among raft nodes before linearized reading' (duration: 231.173787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:31:33.55263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.285329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T17:31:33.552688Z","caller":"traceutil/trace.go:171","msg":"trace[647969008] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2820; }","duration":"316.389191ms","start":"2026-04-12T17:31:33.236279Z","end":"2026-04-12T17:31:33.552669Z","steps":["trace[647969008] 'range keys from in-memory index tree' (duration: 316.194657ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:31:33.552716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:31:33.236257Z","time spent":"316.453042ms","remote":"127.0.0.1:45612","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-04-12T17:31:33.552474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.228523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-04-12T17:31:33.553049Z","caller":"traceutil/trace.go:171","msg":"trace[1490351238] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2820; }","duration":"198.855888ms","start":"2026-04-12T17:31:33.354183Z","end":"2026-04-12T17:31:33.553039Z","steps":["trace[1490351238] 'range keys from in-memory index tree' (duration: 198.0851ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:31:34.006058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.243849ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T17:31:34.006205Z","caller":"traceutil/trace.go:171","msg":"trace[1953769258] transaction","detail":"{read_only:false; response_revision:2822; number_of_response:1; }","duration":"308.710417ms","start":"2026-04-12T17:31:33.697469Z","end":"2026-04-12T17:31:34.00618Z","steps":["trace[1953769258] 'process raft request' (duration: 119.214363ms)","trace[1953769258] 'compare' (duration: 188.820908ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T17:31:34.006277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:31:33.697423Z","time spent":"308.82392ms","remote":"127.0.0.1:45916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T17:31:34.565614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.846601ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T17:31:34.565891Z","caller":"traceutil/trace.go:171","msg":"trace[1666269811] transaction","detail":"{read_only:false; response_revision:2823; number_of_response:1; }","duration":"866.341251ms","start":"2026-04-12T17:31:33.699523Z","end":"2026-04-12T17:31:34.565864Z","steps":["trace[1666269811] 'process raft request' (duration: 605.137961ms)","trace[1666269811] 'compare' (duration: 260.743229ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T17:31:34.56598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:31:33.699495Z","time spent":"866.435623ms","remote":"127.0.0.1:45916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T17:31:34.566235Z","caller":"traceutil/trace.go:171","msg":"trace[1437122722] linearizableReadLoop","detail":"{readStateIndex:2929; appliedIndex:2927; }","duration":"760.938139ms","start":"2026-04-12T17:31:33.805269Z","end":"2026-04-12T17:31:34.566207Z","steps":["trace[1437122722] 'read index received' (duration: 11.393253ms)","trace[1437122722] 'applied index is now lower than readState.Index' (duration: 749.540136ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T17:31:34.600591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.304067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T17:31:34.600676Z","caller":"traceutil/trace.go:171","msg":"trace[276249077] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2823; }","duration":"364.425931ms","start":"2026-04-12T17:31:34.236229Z","end":"2026-04-12T17:31:34.600655Z","steps":["trace[276249077] 'agreement among raft nodes before linearized reading' (duration: 364.292707ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:31:34.600661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.369043ms","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-04-12T17:31:34.600746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.914856ms","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-04-12T17:31:34.600838Z","caller":"traceutil/trace.go:171","msg":"trace[502874322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2823; }","duration":"137.025859ms","start":"2026-04-12T17:31:34.463772Z","end":"2026-04-12T17:31:34.600798Z","steps":["trace[502874322] 'agreement among raft nodes before linearized reading' (duration: 136.830074ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:31:34.600854Z","caller":"traceutil/trace.go:171","msg":"trace[1143203815] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2823; }","duration":"795.546367ms","start":"2026-04-12T17:31:33.805225Z","end":"2026-04-12T17:31:34.600772Z","steps":["trace[1143203815] 'agreement among raft nodes before linearized reading' (duration: 761.043082ms)","trace[1143203815] 'range keys from in-memory index tree' (duration: 34.216449ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T17:31:34.600891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.001667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/valkey-node-0.18a5ac4ce1b9c82c\" ","response":"range_response_count:1 size:765"} {"level":"warn","ts":"2026-04-12T17:31:34.600913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:31:33.805205Z","time spent":"795.694181ms","remote":"127.0.0.1:45916","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-04-12T17:31:34.600902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.93741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-12T17:31:34.600926Z","caller":"traceutil/trace.go:171","msg":"trace[2114176211] range","detail":"{range_begin:/registry/events/openstack/valkey-node-0.18a5ac4ce1b9c82c; range_end:; response_count:1; response_revision:2823; }","duration":"320.072519ms","start":"2026-04-12T17:31:34.280844Z","end":"2026-04-12T17:31:34.600917Z","steps":["trace[2114176211] 'agreement among raft nodes before linearized reading' (duration: 319.914895ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:31:34.600966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:31:34.280823Z","time spent":"320.135581ms","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":787,"request content":"key:\"/registry/events/openstack/valkey-node-0.18a5ac4ce1b9c82c\" "} {"level":"warn","ts":"2026-04-12T17:31:34.601023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.592183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-12T17:31:34.60102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.960636ms","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-04-12T17:31:34.601044Z","caller":"traceutil/trace.go:171","msg":"trace[813019625] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2823; }","duration":"150.648765ms","start":"2026-04-12T17:31:34.45039Z","end":"2026-04-12T17:31:34.601039Z","steps":["trace[813019625] 'agreement among raft nodes before linearized reading' (duration: 150.595974ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:31:34.601054Z","caller":"traceutil/trace.go:171","msg":"trace[396478314] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2823; }","duration":"101.015507ms","start":"2026-04-12T17:31:34.50003Z","end":"2026-04-12T17:31:34.601045Z","steps":["trace[396478314] 'agreement among raft nodes before linearized reading' (duration: 100.924475ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:31:34.60097Z","caller":"traceutil/trace.go:171","msg":"trace[317471436] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2823; }","duration":"199.049843ms","start":"2026-04-12T17:31:34.401902Z","end":"2026-04-12T17:31:34.600951Z","steps":["trace[317471436] 'agreement among raft nodes before linearized reading' (duration: 198.846278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:31:34.601149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.320693ms","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-04-12T17:31:34.601163Z","caller":"traceutil/trace.go:171","msg":"trace[595111770] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2823; }","duration":"121.362944ms","start":"2026-04-12T17:31:34.479796Z","end":"2026-04-12T17:31:34.601159Z","steps":["trace[595111770] 'agreement among raft nodes before linearized reading' (duration: 121.323473ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:31:34.600734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:31:34.236207Z","time spent":"364.516622ms","remote":"127.0.0.1:45612","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-04-12T17:34:16.860245Z","caller":"traceutil/trace.go:171","msg":"trace[1731570815] transaction","detail":"{read_only:false; response_revision:3881; number_of_response:1; }","duration":"158.652244ms","start":"2026-04-12T17:34:16.701554Z","end":"2026-04-12T17:34:16.860206Z","steps":["trace[1731570815] 'process raft request' (duration: 158.427439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:34:17.03995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.804456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-12T17:34:17.040038Z","caller":"traceutil/trace.go:171","msg":"trace[1722299878] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:3881; }","duration":"111.916379ms","start":"2026-04-12T17:34:16.928103Z","end":"2026-04-12T17:34:17.04002Z","steps":["trace[1722299878] 'count revisions from in-memory index tree' (duration: 111.720314ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:34:48.019013Z","caller":"traceutil/trace.go:171","msg":"trace[1009577602] transaction","detail":"{read_only:false; response_revision:4074; number_of_response:1; }","duration":"121.884087ms","start":"2026-04-12T17:34:47.897078Z","end":"2026-04-12T17:34:48.018962Z","steps":["trace[1009577602] 'process raft request' (duration: 116.13766ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:35:00.930712Z","caller":"traceutil/trace.go:171","msg":"trace[622175704] transaction","detail":"{read_only:false; response_revision:4194; number_of_response:1; }","duration":"101.505091ms","start":"2026-04-12T17:35:00.828636Z","end":"2026-04-12T17:35:00.930141Z","steps":["trace[622175704] 'process raft request' (duration: 101.190734ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:35:01.160592Z","caller":"traceutil/trace.go:171","msg":"trace[1733023656] transaction","detail":"{read_only:false; response_revision:4195; number_of_response:1; }","duration":"112.639376ms","start":"2026-04-12T17:35:01.047913Z","end":"2026-04-12T17:35:01.160552Z","steps":["trace[1733023656] 'process raft request' (duration: 35.539987ms)","trace[1733023656] 'compare' (duration: 76.663309ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T17:35:42.444238Z","caller":"traceutil/trace.go:171","msg":"trace[1655503835] transaction","detail":"{read_only:false; response_revision:4963; number_of_response:1; }","duration":"185.473056ms","start":"2026-04-12T17:35:42.258731Z","end":"2026-04-12T17:35:42.444204Z","steps":["trace[1655503835] 'process raft request' (duration: 185.400424ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:35:42.444462Z","caller":"traceutil/trace.go:171","msg":"trace[337423901] linearizableReadLoop","detail":"{readStateIndex:5154; appliedIndex:5153; }","duration":"189.197734ms","start":"2026-04-12T17:35:42.255236Z","end":"2026-04-12T17:35:42.444434Z","steps":["trace[337423901] 'read index received' (duration: 128.966807ms)","trace[337423901] 'applied index is now lower than readState.Index' (duration: 60.228827ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T17:35:42.444671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.78648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T17:35:42.444705Z","caller":"traceutil/trace.go:171","msg":"trace[297644835] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4963; }","duration":"187.852832ms","start":"2026-04-12T17:35:42.256842Z","end":"2026-04-12T17:35:42.444695Z","steps":["trace[297644835] 'agreement among raft nodes before linearized reading' (duration: 187.76632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:35:42.444716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.27751ms","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-04-12T17:35:42.444765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.262583ms","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-04-12T17:35:42.444786Z","caller":"traceutil/trace.go:171","msg":"trace[101517268] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4963; }","duration":"190.409533ms","start":"2026-04-12T17:35:42.254367Z","end":"2026-04-12T17:35:42.444777Z","steps":["trace[101517268] 'agreement among raft nodes before linearized reading' (duration: 190.162337ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:35:42.444804Z","caller":"traceutil/trace.go:171","msg":"trace[675747417] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4963; }","duration":"108.367336ms","start":"2026-04-12T17:35:42.336427Z","end":"2026-04-12T17:35:42.444794Z","steps":["trace[675747417] 'agreement among raft nodes before linearized reading' (duration: 108.223892ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:35:42.444918Z","caller":"traceutil/trace.go:171","msg":"trace[1820183369] transaction","detail":"{read_only:false; response_revision:4962; number_of_response:1; }","duration":"199.454939ms","start":"2026-04-12T17:35:42.245019Z","end":"2026-04-12T17:35:42.444474Z","steps":["trace[1820183369] 'process raft request' (duration: 139.208031ms)","trace[1820183369] 'compare' (duration: 59.727296ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T17:35:42.445053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.708077ms","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-04-12T17:35:42.445186Z","caller":"traceutil/trace.go:171","msg":"trace[1441725133] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4963; }","duration":"114.931832ms","start":"2026-04-12T17:35:42.330226Z","end":"2026-04-12T17:35:42.445157Z","steps":["trace[1441725133] 'agreement among raft nodes before linearized reading' (duration: 114.558753ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:35:42.720125Z","caller":"traceutil/trace.go:171","msg":"trace[195057000] transaction","detail":"{read_only:false; response_revision:4964; number_of_response:1; }","duration":"271.658491ms","start":"2026-04-12T17:35:42.448439Z","end":"2026-04-12T17:35:42.720097Z","steps":["trace[195057000] 'process raft request' (duration: 271.466276ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:35:42.726537Z","caller":"traceutil/trace.go:171","msg":"trace[1811534533] linearizableReadLoop","detail":"{readStateIndex:5158; appliedIndex:5155; }","duration":"116.694604ms","start":"2026-04-12T17:35:42.609817Z","end":"2026-04-12T17:35:42.726512Z","steps":["trace[1811534533] 'read index received' (duration: 110.22615ms)","trace[1811534533] 'applied index is now lower than readState.Index' (duration: 6.467614ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T17:35:42.726765Z","caller":"traceutil/trace.go:171","msg":"trace[291721542] transaction","detail":"{read_only:false; response_revision:4965; number_of_response:1; }","duration":"277.990983ms","start":"2026-04-12T17:35:42.448763Z","end":"2026-04-12T17:35:42.726754Z","steps":["trace[291721542] 'process raft request' (duration: 277.603624ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:35:42.726874Z","caller":"traceutil/trace.go:171","msg":"trace[383310014] transaction","detail":"{read_only:false; response_revision:4966; number_of_response:1; }","duration":"278.067175ms","start":"2026-04-12T17:35:42.448787Z","end":"2026-04-12T17:35:42.726855Z","steps":["trace[383310014] 'process raft request' (duration: 277.692636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:35:42.727024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.165565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T17:35:42.727122Z","caller":"traceutil/trace.go:171","msg":"trace[2135989068] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:4966; }","duration":"117.297188ms","start":"2026-04-12T17:35:42.609805Z","end":"2026-04-12T17:35:42.727102Z","steps":["trace[2135989068] 'agreement among raft nodes before linearized reading' (duration: 117.091784ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:35:46.597179Z","caller":"traceutil/trace.go:171","msg":"trace[1684097177] transaction","detail":"{read_only:false; response_revision:5092; number_of_response:1; }","duration":"371.264838ms","start":"2026-04-12T17:35:46.225889Z","end":"2026-04-12T17:35:46.597154Z","steps":["trace[1684097177] 'process raft request' (duration: 371.076343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:35:46.597336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:35:46.225865Z","time spent":"371.401101ms","remote":"127.0.0.1:45732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58202,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T17:35:46.723466Z","caller":"traceutil/trace.go:171","msg":"trace[1688233425] transaction","detail":"{read_only:false; response_revision:5094; number_of_response:1; }","duration":"119.45479ms","start":"2026-04-12T17:35:46.603995Z","end":"2026-04-12T17:35:46.72345Z","steps":["trace[1688233425] 'process raft request' (duration: 119.400669ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:35:46.723471Z","caller":"traceutil/trace.go:171","msg":"trace[102317110] transaction","detail":"{read_only:false; response_revision:5093; number_of_response:1; }","duration":"471.94833ms","start":"2026-04-12T17:35:46.251476Z","end":"2026-04-12T17:35:46.723424Z","steps":["trace[102317110] 'process raft request' (duration: 428.106354ms)","trace[102317110] 'compare' (duration: 43.604631ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T17:35:46.723584Z","caller":"traceutil/trace.go:171","msg":"trace[930429646] linearizableReadLoop","detail":"{readStateIndex:5289; appliedIndex:5287; }","duration":"349.351365ms","start":"2026-04-12T17:35:46.374207Z","end":"2026-04-12T17:35:46.723559Z","steps":["trace[930429646] 'read index received' (duration: 222.777765ms)","trace[930429646] 'applied index is now lower than readState.Index' (duration: 126.5701ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T17:35:46.723752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:35:46.251437Z","time spent":"472.145155ms","remote":"127.0.0.1:45916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T17:35:46.723822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.627542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-177246eb-ccd0-491a-8af2-715962991e07\" ","response":"range_response_count:1 size:3601"} {"level":"warn","ts":"2026-04-12T17:35:46.723859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.430437ms","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-04-12T17:35:46.723872Z","caller":"traceutil/trace.go:171","msg":"trace[818680543] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-177246eb-ccd0-491a-8af2-715962991e07; range_end:; response_count:1; response_revision:5094; }","duration":"349.730734ms","start":"2026-04-12T17:35:46.374129Z","end":"2026-04-12T17:35:46.723859Z","steps":["trace[818680543] 'agreement among raft nodes before linearized reading' (duration: 349.544379ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:35:46.723902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:35:46.374101Z","time spent":"349.794266ms","remote":"127.0.0.1:45840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3623,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-177246eb-ccd0-491a-8af2-715962991e07\" "} {"level":"info","ts":"2026-04-12T17:35:46.723898Z","caller":"traceutil/trace.go:171","msg":"trace[2098431287] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5094; }","duration":"266.558019ms","start":"2026-04-12T17:35:46.457329Z","end":"2026-04-12T17:35:46.723887Z","steps":["trace[2098431287] 'agreement among raft nodes before linearized reading' (duration: 266.397566ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:35:46.724086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.02248ms","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-04-12T17:35:46.724159Z","caller":"traceutil/trace.go:171","msg":"trace[451175093] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5094; }","duration":"264.153172ms","start":"2026-04-12T17:35:46.459986Z","end":"2026-04-12T17:35:46.724139Z","steps":["trace[451175093] 'agreement among raft nodes before linearized reading' (duration: 263.972528ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:35:46.99105Z","caller":"traceutil/trace.go:171","msg":"trace[985787315] transaction","detail":"{read_only:false; response_revision:5104; number_of_response:1; }","duration":"135.513703ms","start":"2026-04-12T17:35:46.855472Z","end":"2026-04-12T17:35:46.990986Z","steps":["trace[985787315] 'process raft request' (duration: 94.069465ms)","trace[985787315] 'compare' (duration: 41.136591ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T17:35:46.991176Z","caller":"traceutil/trace.go:171","msg":"trace[755305051] transaction","detail":"{read_only:false; response_revision:5105; number_of_response:1; }","duration":"135.619636ms","start":"2026-04-12T17:35:46.855526Z","end":"2026-04-12T17:35:46.991146Z","steps":["trace[755305051] 'process raft request' (duration: 135.340849ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:35:46.991214Z","caller":"traceutil/trace.go:171","msg":"trace[820032721] transaction","detail":"{read_only:false; response_revision:5106; number_of_response:1; }","duration":"135.156225ms","start":"2026-04-12T17:35:46.856032Z","end":"2026-04-12T17:35:46.991189Z","steps":["trace[820032721] 'process raft request' (duration: 134.886619ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:35:46.991057Z","caller":"traceutil/trace.go:171","msg":"trace[248283322] linearizableReadLoop","detail":"{readStateIndex:5301; appliedIndex:5299; }","duration":"135.078383ms","start":"2026-04-12T17:35:46.85595Z","end":"2026-04-12T17:35:46.991029Z","steps":["trace[248283322] 'read index received' (duration: 93.517582ms)","trace[248283322] 'applied index is now lower than readState.Index' (duration: 41.559671ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T17:35:46.991336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.36011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:17319"} {"level":"info","ts":"2026-04-12T17:35:46.99139Z","caller":"traceutil/trace.go:171","msg":"trace[275427874] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5106; }","duration":"135.453972ms","start":"2026-04-12T17:35:46.855924Z","end":"2026-04-12T17:35:46.991378Z","steps":["trace[275427874] 'agreement among raft nodes before linearized reading' (duration: 135.195635ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:35:47.114593Z","caller":"traceutil/trace.go:171","msg":"trace[1129742361] transaction","detail":"{read_only:false; response_revision:5107; number_of_response:1; }","duration":"116.794437ms","start":"2026-04-12T17:35:46.997762Z","end":"2026-04-12T17:35:47.114557Z","steps":["trace[1129742361] 'process raft request' (duration: 102.27548ms)","trace[1129742361] 'compare' (duration: 14.384353ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T17:35:47.150074Z","caller":"traceutil/trace.go:171","msg":"trace[1097508778] transaction","detail":"{read_only:false; response_revision:5108; number_of_response:1; }","duration":"139.057478ms","start":"2026-04-12T17:35:47.010971Z","end":"2026-04-12T17:35:47.150029Z","steps":["trace[1097508778] 'process raft request' (duration: 138.774571ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:35:58.305519Z","caller":"traceutil/trace.go:171","msg":"trace[452989401] transaction","detail":"{read_only:false; response_revision:5326; number_of_response:1; }","duration":"315.720962ms","start":"2026-04-12T17:35:57.989767Z","end":"2026-04-12T17:35:58.305488Z","steps":["trace[452989401] 'process raft request' (duration: 315.479846ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:35:58.305498Z","caller":"traceutil/trace.go:171","msg":"trace[2136977504] linearizableReadLoop","detail":"{readStateIndex:5528; appliedIndex:5527; }","duration":"206.63893ms","start":"2026-04-12T17:35:58.098826Z","end":"2026-04-12T17:35:58.305465Z","steps":["trace[2136977504] 'read index received' (duration: 206.328333ms)","trace[2136977504] 'applied index is now lower than readState.Index' (duration: 309.367µs)"],"step_count":2} {"level":"warn","ts":"2026-04-12T17:35:58.3057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:35:57.989725Z","time spent":"315.879975ms","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":130597,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T17:35:58.305727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.865176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T17:35:58.305813Z","caller":"traceutil/trace.go:171","msg":"trace[1256885587] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:5326; }","duration":"207.05536ms","start":"2026-04-12T17:35:58.098738Z","end":"2026-04-12T17:35:58.305793Z","steps":["trace[1256885587] 'agreement among raft nodes before linearized reading' (duration: 206.833325ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:35:58.322594Z","caller":"traceutil/trace.go:171","msg":"trace[462888713] transaction","detail":"{read_only:false; response_revision:5327; number_of_response:1; }","duration":"210.327468ms","start":"2026-04-12T17:35:58.112243Z","end":"2026-04-12T17:35:58.322571Z","steps":["trace[462888713] 'process raft request' (duration: 210.142714ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:35:58.322936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.896572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-k6ktw\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-04-12T17:35:58.323012Z","caller":"traceutil/trace.go:171","msg":"trace[358756867] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-k6ktw; range_end:; response_count:1; response_revision:5328; }","duration":"105.009435ms","start":"2026-04-12T17:35:58.217985Z","end":"2026-04-12T17:35:58.322995Z","steps":["trace[358756867] 'agreement among raft nodes before linearized reading' (duration: 104.806141ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:35:58.744666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.188425ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-12T17:35:58.744956Z","caller":"traceutil/trace.go:171","msg":"trace[45690853] transaction","detail":"{read_only:false; response_revision:5330; number_of_response:1; }","duration":"410.929985ms","start":"2026-04-12T17:35:58.334002Z","end":"2026-04-12T17:35:58.744932Z","steps":["trace[45690853] 'process raft request' (duration: 410.815432ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:35:58.745051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:35:58.333964Z","time spent":"411.039247ms","remote":"127.0.0.1:45840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8041,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T17:35:58.745394Z","caller":"traceutil/trace.go:171","msg":"trace[806115904] transaction","detail":"{read_only:false; response_revision:5329; number_of_response:1; }","duration":"438.039471ms","start":"2026-04-12T17:35:58.307337Z","end":"2026-04-12T17:35:58.745377Z","steps":["trace[806115904] 'process raft request' (duration: 43.057567ms)","trace[806115904] 'compare' (duration: 393.202422ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T17:35:58.745468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:35:58.307317Z","time spent":"438.122494ms","remote":"127.0.0.1:45854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":750,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T17:35:58.745605Z","caller":"traceutil/trace.go:171","msg":"trace[417208357] linearizableReadLoop","detail":"{readStateIndex:5531; appliedIndex:5530; }","duration":"421.808124ms","start":"2026-04-12T17:35:58.323784Z","end":"2026-04-12T17:35:58.745592Z","steps":["trace[417208357] 'read index received' (duration: 26.622835ms)","trace[417208357] 'applied index is now lower than readState.Index' (duration: 395.184029ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T17:35:58.746062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.279795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T17:35:58.746113Z","caller":"traceutil/trace.go:171","msg":"trace[1824489334] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5330; }","duration":"422.347367ms","start":"2026-04-12T17:35:58.323754Z","end":"2026-04-12T17:35:58.746102Z","steps":["trace[1824489334] 'agreement among raft nodes before linearized reading' (duration: 422.264455ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:35:58.746149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:35:58.32374Z","time spent":"422.398558ms","remote":"127.0.0.1:45622","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-04-12T17:35:58.746418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.891589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.155\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-12T17:35:58.74646Z","caller":"traceutil/trace.go:171","msg":"trace[1843590364] range","detail":"{range_begin:/registry/masterleases/199.204.45.155; range_end:; response_count:1; response_revision:5330; }","duration":"279.995501ms","start":"2026-04-12T17:35:58.466452Z","end":"2026-04-12T17:35:58.746447Z","steps":["trace[1843590364] 'agreement among raft nodes before linearized reading' (duration: 279.848847ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:35:58.969695Z","caller":"traceutil/trace.go:171","msg":"trace[447865769] transaction","detail":"{read_only:false; response_revision:5331; number_of_response:1; }","duration":"216.010055ms","start":"2026-04-12T17:35:58.753645Z","end":"2026-04-12T17:35:58.969655Z","steps":["trace[447865769] 'process raft request' (duration: 152.249533ms)","trace[447865769] 'compare' (duration: 63.538696ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T17:35:58.969979Z","caller":"traceutil/trace.go:171","msg":"trace[382484650] transaction","detail":"{read_only:false; response_revision:5332; number_of_response:1; }","duration":"215.957373ms","start":"2026-04-12T17:35:58.753993Z","end":"2026-04-12T17:35:58.96995Z","steps":["trace[382484650] 'process raft request' (duration: 215.595134ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:35:58.970254Z","caller":"traceutil/trace.go:171","msg":"trace[437680810] transaction","detail":"{read_only:false; response_revision:5333; number_of_response:1; }","duration":"215.83812ms","start":"2026-04-12T17:35:58.754399Z","end":"2026-04-12T17:35:58.970237Z","steps":["trace[437680810] 'process raft request' (duration: 215.447111ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:35:58.970428Z","caller":"traceutil/trace.go:171","msg":"trace[938874945] linearizableReadLoop","detail":"{readStateIndex:5540; appliedIndex:5533; }","duration":"116.53731ms","start":"2026-04-12T17:35:58.85387Z","end":"2026-04-12T17:35:58.970407Z","steps":["trace[938874945] 'read index received' (duration: 52.123743ms)","trace[938874945] 'applied index is now lower than readState.Index' (duration: 64.412417ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T17:35:58.970562Z","caller":"traceutil/trace.go:171","msg":"trace[1034450209] transaction","detail":"{read_only:false; response_revision:5334; number_of_response:1; }","duration":"215.727288ms","start":"2026-04-12T17:35:58.754827Z","end":"2026-04-12T17:35:58.970554Z","steps":["trace[1034450209] 'process raft request' (duration: 215.292658ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:35:58.970593Z","caller":"traceutil/trace.go:171","msg":"trace[1033707559] transaction","detail":"{read_only:false; response_revision:5336; number_of_response:1; }","duration":"153.844531ms","start":"2026-04-12T17:35:58.816733Z","end":"2026-04-12T17:35:58.970577Z","steps":["trace[1033707559] 'process raft request' (duration: 153.562014ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:35:58.970615Z","caller":"traceutil/trace.go:171","msg":"trace[1118246588] transaction","detail":"{read_only:false; response_revision:5335; number_of_response:1; }","duration":"161.108624ms","start":"2026-04-12T17:35:58.809479Z","end":"2026-04-12T17:35:58.970588Z","steps":["trace[1118246588] 'process raft request' (duration: 160.738065ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:35:58.970889Z","caller":"traceutil/trace.go:171","msg":"trace[551876587] transaction","detail":"{read_only:false; response_revision:5337; number_of_response:1; }","duration":"142.285955ms","start":"2026-04-12T17:35:58.828575Z","end":"2026-04-12T17:35:58.970861Z","steps":["trace[551876587] 'process raft request' (duration: 141.780223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:35:58.971036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.936851ms","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-04-12T17:35:58.971084Z","caller":"traceutil/trace.go:171","msg":"trace[229074762] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5337; }","duration":"117.043763ms","start":"2026-04-12T17:35:58.854028Z","end":"2026-04-12T17:35:58.971072Z","steps":["trace[229074762] 'agreement among raft nodes before linearized reading' (duration: 116.89715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:35:58.971108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.423225ms","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-04-12T17:35:58.971157Z","caller":"traceutil/trace.go:171","msg":"trace[961695767] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5337; }","duration":"115.504716ms","start":"2026-04-12T17:35:58.855639Z","end":"2026-04-12T17:35:58.971144Z","steps":["trace[961695767] 'agreement among raft nodes before linearized reading' (duration: 115.316352ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:35:58.971258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.383871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-12T17:35:58.971281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.884259ms","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-04-12T17:35:58.971318Z","caller":"traceutil/trace.go:171","msg":"trace[1760898852] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5337; }","duration":"117.207427ms","start":"2026-04-12T17:35:58.854099Z","end":"2026-04-12T17:35:58.971306Z","steps":["trace[1760898852] 'agreement among raft nodes before linearized reading' (duration: 117.123015ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:35:58.971316Z","caller":"traceutil/trace.go:171","msg":"trace[306503257] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5337; }","duration":"117.507054ms","start":"2026-04-12T17:35:58.853796Z","end":"2026-04-12T17:35:58.971303Z","steps":["trace[306503257] 'agreement among raft nodes before linearized reading' (duration: 117.3653ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:35:58.971442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.062373ms","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-04-12T17:35:58.971473Z","caller":"traceutil/trace.go:171","msg":"trace[1072058891] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5337; }","duration":"117.31648ms","start":"2026-04-12T17:35:58.854149Z","end":"2026-04-12T17:35:58.971465Z","steps":["trace[1072058891] 'agreement among raft nodes before linearized reading' (duration: 117.239678ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:35:59.108803Z","caller":"traceutil/trace.go:171","msg":"trace[555213927] transaction","detail":"{read_only:false; response_revision:5338; number_of_response:1; }","duration":"132.487401ms","start":"2026-04-12T17:35:58.97629Z","end":"2026-04-12T17:35:59.108778Z","steps":["trace[555213927] 'process raft request' (duration: 132.312187ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:35:59.324645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.673475ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-04-12T17:35:59.325073Z","caller":"traceutil/trace.go:171","msg":"trace[1058487218] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5338; }","duration":"348.257509ms","start":"2026-04-12T17:35:58.976785Z","end":"2026-04-12T17:35:59.325042Z","steps":["trace[1058487218] 'process raft request' (duration: 223.107083ms)","trace[1058487218] 'compare' (duration: 124.629853ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T17:35:59.325187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:35:58.976751Z","time spent":"348.390992ms","remote":"127.0.0.1:45816","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T17:35:59.325297Z","caller":"traceutil/trace.go:171","msg":"trace[989483847] transaction","detail":"{read_only:false; response_revision:5340; number_of_response:1; }","duration":"348.034194ms","start":"2026-04-12T17:35:58.977245Z","end":"2026-04-12T17:35:59.32528Z","steps":["trace[989483847] 'process raft request' (duration: 347.901921ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:35:59.32542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:35:58.977231Z","time spent":"348.123096ms","remote":"127.0.0.1:45916","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-04-12T17:35:59.325451Z","caller":"traceutil/trace.go:171","msg":"trace[656821108] transaction","detail":"{read_only:false; response_revision:5339; number_of_response:1; }","duration":"348.108086ms","start":"2026-04-12T17:35:58.977232Z","end":"2026-04-12T17:35:59.32534Z","steps":["trace[656821108] 'process raft request' (duration: 347.523772ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:35:59.325831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:35:58.977219Z","time spent":"348.458734ms","remote":"127.0.0.1:45930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1564,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T17:35:59.32587Z","caller":"traceutil/trace.go:171","msg":"trace[697813454] transaction","detail":"{read_only:false; response_revision:5342; number_of_response:1; }","duration":"348.422933ms","start":"2026-04-12T17:35:58.977415Z","end":"2026-04-12T17:35:59.325838Z","steps":["trace[697813454] 'process raft request' (duration: 348.121845ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:35:59.325969Z","caller":"traceutil/trace.go:171","msg":"trace[700779124] transaction","detail":"{read_only:false; response_revision:5343; number_of_response:1; }","duration":"347.843679ms","start":"2026-04-12T17:35:58.978106Z","end":"2026-04-12T17:35:59.32595Z","steps":["trace[700779124] 'process raft request' (duration: 347.605794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:35:59.326019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:35:58.976874Z","time spent":"349.070418ms","remote":"127.0.0.1:45916","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-04-12T17:35:59.326083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:35:58.978099Z","time spent":"347.922221ms","remote":"127.0.0.1:45816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1046,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T17:35:59.326141Z","caller":"traceutil/trace.go:171","msg":"trace[1129633337] transaction","detail":"{read_only:false; response_revision:5345; number_of_response:1; }","duration":"344.599552ms","start":"2026-04-12T17:35:58.981534Z","end":"2026-04-12T17:35:59.326133Z","steps":["trace[1129633337] 'process raft request' (duration: 344.424517ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:35:59.326183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:35:58.981525Z","time spent":"344.643042ms","remote":"127.0.0.1:46078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5085,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T17:35:59.326086Z","caller":"traceutil/trace.go:171","msg":"trace[1964847644] linearizableReadLoop","detail":"{readStateIndex:5546; appliedIndex:5540; }","duration":"347.986043ms","start":"2026-04-12T17:35:58.978063Z","end":"2026-04-12T17:35:59.326049Z","steps":["trace[1964847644] 'read index received' (duration: 130.649917ms)","trace[1964847644] 'applied index is now lower than readState.Index' (duration: 217.334436ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T17:35:59.32617Z","caller":"traceutil/trace.go:171","msg":"trace[1899862797] transaction","detail":"{read_only:false; response_revision:5344; number_of_response:1; }","duration":"347.365378ms","start":"2026-04-12T17:35:58.97879Z","end":"2026-04-12T17:35:59.326155Z","steps":["trace[1899862797] 'process raft request' (duration: 347.091382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:35:59.326352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:35:58.978768Z","time spent":"347.47942ms","remote":"127.0.0.1:45916","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-04-12T17:35:59.32638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.31296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-04-12T17:35:59.326448Z","caller":"traceutil/trace.go:171","msg":"trace[1443489416] transaction","detail":"{read_only:false; response_revision:5341; number_of_response:1; }","duration":"348.658469ms","start":"2026-04-12T17:35:58.977351Z","end":"2026-04-12T17:35:59.32601Z","steps":["trace[1443489416] 'process raft request' (duration: 347.87967ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:35:59.326451Z","caller":"traceutil/trace.go:171","msg":"trace[1675161521] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:5345; }","duration":"348.412993ms","start":"2026-04-12T17:35:58.97802Z","end":"2026-04-12T17:35:59.326433Z","steps":["trace[1675161521] 'agreement among raft nodes before linearized reading' (duration: 348.275069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:35:59.326512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:35:58.97734Z","time spent":"349.14527ms","remote":"127.0.0.1:45916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T17:35:59.326514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:35:58.978006Z","time spent":"348.494924ms","remote":"127.0.0.1:45816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-04-12T17:35:59.32654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.157251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wflzr\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-12T17:35:59.326615Z","caller":"traceutil/trace.go:171","msg":"trace[1984985678] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wflzr; range_end:; response_count:1; response_revision:5345; }","duration":"256.297495ms","start":"2026-04-12T17:35:59.070298Z","end":"2026-04-12T17:35:59.326595Z","steps":["trace[1984985678] 'agreement among raft nodes before linearized reading' (duration: 256.015388ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:35:59.615089Z","caller":"traceutil/trace.go:171","msg":"trace[1460932178] linearizableReadLoop","detail":"{readStateIndex:5550; appliedIndex:5549; }","duration":"281.792963ms","start":"2026-04-12T17:35:59.333274Z","end":"2026-04-12T17:35:59.615067Z","steps":["trace[1460932178] 'read index received' (duration: 281.703691ms)","trace[1460932178] 'applied index is now lower than readState.Index' (duration: 84.882µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T17:35:59.615177Z","caller":"traceutil/trace.go:171","msg":"trace[1073518814] transaction","detail":"{read_only:false; response_revision:5346; number_of_response:1; }","duration":"282.040649ms","start":"2026-04-12T17:35:59.33307Z","end":"2026-04-12T17:35:59.615111Z","steps":["trace[1073518814] 'process raft request' (duration: 281.862305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:35:59.615361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.0624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:8 size:71042"} {"level":"info","ts":"2026-04-12T17:35:59.616038Z","caller":"traceutil/trace.go:171","msg":"trace[1689351520] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:8; response_revision:5346; }","duration":"282.750707ms","start":"2026-04-12T17:35:59.33325Z","end":"2026-04-12T17:35:59.616001Z","steps":["trace[1689351520] 'agreement among raft nodes before linearized reading' (duration: 281.901056ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:35:59.619649Z","caller":"traceutil/trace.go:171","msg":"trace[1248976000] transaction","detail":"{read_only:false; response_revision:5347; number_of_response:1; }","duration":"195.393992ms","start":"2026-04-12T17:35:59.424231Z","end":"2026-04-12T17:35:59.619625Z","steps":["trace[1248976000] 'process raft request' (duration: 195.233088ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:36:01.254054Z","caller":"traceutil/trace.go:171","msg":"trace[968678913] transaction","detail":"{read_only:false; response_revision:5366; number_of_response:1; }","duration":"135.764559ms","start":"2026-04-12T17:36:01.118256Z","end":"2026-04-12T17:36:01.25402Z","steps":["trace[968678913] 'process raft request' (duration: 135.441342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:36:07.573267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.412542ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T17:36:07.573392Z","caller":"traceutil/trace.go:171","msg":"trace[949705224] linearizableReadLoop","detail":"{readStateIndex:5701; appliedIndex:5700; }","duration":"266.992631ms","start":"2026-04-12T17:36:07.30638Z","end":"2026-04-12T17:36:07.573373Z","steps":["trace[949705224] 'read index received' (duration: 45.353063ms)","trace[949705224] 'applied index is now lower than readState.Index' (duration: 221.638358ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T17:36:07.573501Z","caller":"traceutil/trace.go:171","msg":"trace[1021691635] transaction","detail":"{read_only:false; response_revision:5494; number_of_response:1; }","duration":"277.120702ms","start":"2026-04-12T17:36:07.296354Z","end":"2026-04-12T17:36:07.573475Z","steps":["trace[1021691635] 'process raft request' (duration: 55.390212ms)","trace[1021691635] 'compare' (duration: 221.270679ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T17:36:07.573725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.355563ms","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-04-12T17:36:07.573728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.024507ms","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-04-12T17:36:07.573765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.482547ms","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-04-12T17:36:07.573793Z","caller":"traceutil/trace.go:171","msg":"trace[673963258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5494; }","duration":"206.477416ms","start":"2026-04-12T17:36:07.367298Z","end":"2026-04-12T17:36:07.573775Z","steps":["trace[673963258] 'agreement among raft nodes before linearized reading' (duration: 206.316752ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:36:07.573803Z","caller":"traceutil/trace.go:171","msg":"trace[1238254729] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5494; }","duration":"214.14239ms","start":"2026-04-12T17:36:07.359643Z","end":"2026-04-12T17:36:07.573785Z","steps":["trace[1238254729] 'agreement among raft nodes before linearized reading' (duration: 213.933015ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:36:07.573816Z","caller":"traceutil/trace.go:171","msg":"trace[1739332886] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5494; }","duration":"206.554308ms","start":"2026-04-12T17:36:07.367248Z","end":"2026-04-12T17:36:07.573803Z","steps":["trace[1739332886] 'agreement among raft nodes before linearized reading' (duration: 206.372044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:36:07.573874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.336568ms","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-04-12T17:36:07.573917Z","caller":"traceutil/trace.go:171","msg":"trace[1545086272] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5494; }","duration":"210.444911ms","start":"2026-04-12T17:36:07.363461Z","end":"2026-04-12T17:36:07.573906Z","steps":["trace[1545086272] 'agreement among raft nodes before linearized reading' (duration: 210.292458ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:36:07.573922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.507813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-aa056a3e-7b4c-490f-85c3-9fa8965eecc3\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-12T17:36:07.574062Z","caller":"traceutil/trace.go:171","msg":"trace[1404779777] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-aa056a3e-7b4c-490f-85c3-9fa8965eecc3; range_end:; response_count:1; response_revision:5494; }","duration":"267.685826ms","start":"2026-04-12T17:36:07.306346Z","end":"2026-04-12T17:36:07.574032Z","steps":["trace[1404779777] 'agreement among raft nodes before linearized reading' (duration: 267.291647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:36:07.573934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.34777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-aa056a3e-7b4c-490f-85c3-9fa8965eecc3\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-12T17:36:07.574214Z","caller":"traceutil/trace.go:171","msg":"trace[725958457] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-aa056a3e-7b4c-490f-85c3-9fa8965eecc3; range_end:; response_count:1; response_revision:5494; }","duration":"161.697678ms","start":"2026-04-12T17:36:07.412497Z","end":"2026-04-12T17:36:07.574195Z","steps":["trace[725958457] 'agreement among raft nodes before linearized reading' (duration: 161.200266ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:36:07.936214Z","caller":"traceutil/trace.go:171","msg":"trace[422399305] transaction","detail":"{read_only:false; response_revision:5496; number_of_response:1; }","duration":"357.409328ms","start":"2026-04-12T17:36:07.578776Z","end":"2026-04-12T17:36:07.936185Z","steps":["trace[422399305] 'process raft request' (duration: 357.08051ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:36:07.936397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:36:07.578754Z","time spent":"357.537491ms","remote":"127.0.0.1:45916","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-04-12T17:36:07.93663Z","caller":"traceutil/trace.go:171","msg":"trace[763981533] transaction","detail":"{read_only:false; response_revision:5497; number_of_response:1; }","duration":"354.98558ms","start":"2026-04-12T17:36:07.581601Z","end":"2026-04-12T17:36:07.936586Z","steps":["trace[763981533] 'process raft request' (duration: 354.514869ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:36:07.936895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:36:07.581579Z","time spent":"355.162615ms","remote":"127.0.0.1:45840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3751,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T17:36:07.937194Z","caller":"traceutil/trace.go:171","msg":"trace[267389581] transaction","detail":"{read_only:false; response_revision:5498; number_of_response:1; }","duration":"355.39873ms","start":"2026-04-12T17:36:07.581781Z","end":"2026-04-12T17:36:07.93718Z","steps":["trace[267389581] 'process raft request' (duration: 354.599561ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:36:07.937245Z","caller":"traceutil/trace.go:171","msg":"trace[623568970] transaction","detail":"{read_only:false; response_revision:5499; number_of_response:1; }","duration":"354.943049ms","start":"2026-04-12T17:36:07.58227Z","end":"2026-04-12T17:36:07.937213Z","steps":["trace[623568970] 'process raft request' (duration: 354.772565ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:36:07.937266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:36:07.581763Z","time spent":"355.468751ms","remote":"127.0.0.1:45916","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-04-12T17:36:07.937447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:36:07.582241Z","time spent":"355.063232ms","remote":"127.0.0.1:45916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T17:36:08.270721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.056587ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T17:36:08.271051Z","caller":"traceutil/trace.go:171","msg":"trace[976341065] linearizableReadLoop","detail":"{readStateIndex:5707; appliedIndex:5702; }","duration":"613.788605ms","start":"2026-04-12T17:36:07.657238Z","end":"2026-04-12T17:36:08.271027Z","steps":["trace[976341065] 'read index received' (duration: 278.649148ms)","trace[976341065] 'applied index is now lower than readState.Index' (duration: 335.138087ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T17:36:08.27107Z","caller":"traceutil/trace.go:171","msg":"trace[1979077413] transaction","detail":"{read_only:false; response_revision:5501; number_of_response:1; }","duration":"484.842328ms","start":"2026-04-12T17:36:07.786204Z","end":"2026-04-12T17:36:08.271047Z","steps":["trace[1979077413] 'process raft request' (duration: 484.662234ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:36:08.271208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:36:07.786185Z","time spent":"484.965061ms","remote":"127.0.0.1:45916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T17:36:08.271261Z","caller":"traceutil/trace.go:171","msg":"trace[666114624] transaction","detail":"{read_only:false; response_revision:5500; number_of_response:1; }","duration":"620.836513ms","start":"2026-04-12T17:36:07.650377Z","end":"2026-04-12T17:36:08.271214Z","steps":["trace[666114624] 'process raft request' (duration: 388.157691ms)","trace[666114624] 'compare' (duration: 231.876463ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T17:36:08.271423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.187295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wflzr\" ","response":"range_response_count:1 size:6531"} {"level":"warn","ts":"2026-04-12T17:36:08.271429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.869767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-12T17:36:08.271456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:36:07.650351Z","time spent":"621.004077ms","remote":"127.0.0.1:45916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T17:36:08.271497Z","caller":"traceutil/trace.go:171","msg":"trace[2068679751] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wflzr; range_end:; response_count:1; response_revision:5502; }","duration":"614.346388ms","start":"2026-04-12T17:36:07.657131Z","end":"2026-04-12T17:36:08.271477Z","steps":["trace[2068679751] 'agreement among raft nodes before linearized reading' (duration: 614.082272ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:36:08.271458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.967124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/goldpinger-rwz2t\" ","response":"range_response_count:1 size:5199"} {"level":"info","ts":"2026-04-12T17:36:08.271633Z","caller":"traceutil/trace.go:171","msg":"trace[1408438720] range","detail":"{range_begin:/registry/pods/monitoring/goldpinger-rwz2t; range_end:; response_count:1; response_revision:5502; }","duration":"330.193629ms","start":"2026-04-12T17:36:07.941427Z","end":"2026-04-12T17:36:08.27162Z","steps":["trace[1408438720] 'agreement among raft nodes before linearized reading' (duration: 329.82477ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:36:08.271665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:36:07.941404Z","time spent":"330.25242ms","remote":"127.0.0.1:45840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":5221,"request content":"key:\"/registry/pods/monitoring/goldpinger-rwz2t\" "} {"level":"info","ts":"2026-04-12T17:36:08.271513Z","caller":"traceutil/trace.go:171","msg":"trace[283717651] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:5502; }","duration":"184.044482ms","start":"2026-04-12T17:36:08.08745Z","end":"2026-04-12T17:36:08.271495Z","steps":["trace[283717651] 'agreement among raft nodes before linearized reading' (duration: 183.879078ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:36:08.271552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:36:07.6571Z","time spent":"614.438201ms","remote":"127.0.0.1:45840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6553,"request content":"key:\"/registry/pods/openstack/keepalived-wflzr\" "} {"level":"info","ts":"2026-04-12T17:36:08.271352Z","caller":"traceutil/trace.go:171","msg":"trace[943218236] transaction","detail":"{read_only:false; response_revision:5502; number_of_response:1; }","duration":"452.144528ms","start":"2026-04-12T17:36:07.819157Z","end":"2026-04-12T17:36:08.271301Z","steps":["trace[943218236] 'process raft request' (duration: 451.80556ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:36:08.272253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:36:07.819128Z","time spent":"453.05884ms","remote":"127.0.0.1:45916","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-04-12T17:36:08.684506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.899201ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T17:36:08.684829Z","caller":"traceutil/trace.go:171","msg":"trace[1566802538] linearizableReadLoop","detail":"{readStateIndex:5711; appliedIndex:5709; }","duration":"217.92751ms","start":"2026-04-12T17:36:08.466882Z","end":"2026-04-12T17:36:08.684809Z","steps":["trace[1566802538] 'read index received' (duration: 79.6063ms)","trace[1566802538] 'applied index is now lower than readState.Index' (duration: 138.31996ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T17:36:08.684948Z","caller":"traceutil/trace.go:171","msg":"trace[238342922] transaction","detail":"{read_only:false; response_revision:5505; number_of_response:1; }","duration":"194.980632ms","start":"2026-04-12T17:36:08.489947Z","end":"2026-04-12T17:36:08.684927Z","steps":["trace[238342922] 'process raft request' (duration: 194.791357ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:36:08.684807Z","caller":"traceutil/trace.go:171","msg":"trace[522636445] transaction","detail":"{read_only:false; response_revision:5503; number_of_response:1; }","duration":"405.007313ms","start":"2026-04-12T17:36:08.279764Z","end":"2026-04-12T17:36:08.684771Z","steps":["trace[522636445] 'process raft request' (duration: 266.724154ms)","trace[522636445] 'compare' (duration: 137.706155ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T17:36:08.685146Z","caller":"traceutil/trace.go:171","msg":"trace[1213988805] transaction","detail":"{read_only:false; response_revision:5504; number_of_response:1; }","duration":"296.874113ms","start":"2026-04-12T17:36:08.388239Z","end":"2026-04-12T17:36:08.685113Z","steps":["trace[1213988805] 'process raft request' (duration: 296.387321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:36:08.685136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.246277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.155\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-12T17:36:08.685279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.696181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-aa056a3e-7b4c-490f-85c3-9fa8965eecc3\" ","response":"range_response_count:1 size:3766"} {"level":"info","ts":"2026-04-12T17:36:08.685332Z","caller":"traceutil/trace.go:171","msg":"trace[1665695276] range","detail":"{range_begin:/registry/masterleases/199.204.45.155; range_end:; response_count:1; response_revision:5505; }","duration":"218.464363ms","start":"2026-04-12T17:36:08.466834Z","end":"2026-04-12T17:36:08.685298Z","steps":["trace[1665695276] 'agreement among raft nodes before linearized reading' (duration: 218.075853ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:36:08.685356Z","caller":"traceutil/trace.go:171","msg":"trace[197111003] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-aa056a3e-7b4c-490f-85c3-9fa8965eecc3; range_end:; response_count:1; response_revision:5505; }","duration":"105.846165ms","start":"2026-04-12T17:36:08.57948Z","end":"2026-04-12T17:36:08.685327Z","steps":["trace[197111003] 'agreement among raft nodes before linearized reading' (duration: 105.63593ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:36:08.685366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:36:08.279738Z","time spent":"405.456524ms","remote":"127.0.0.1:45840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4986,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T17:36:19.96979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.198897ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T17:36:19.969943Z","caller":"traceutil/trace.go:171","msg":"trace[2046050159] transaction","detail":"{read_only:false; response_revision:5658; number_of_response:1; }","duration":"257.068778ms","start":"2026-04-12T17:36:19.712845Z","end":"2026-04-12T17:36:19.969913Z","steps":["trace[2046050159] 'process raft request' (duration: 108.618895ms)","trace[2046050159] 'compare' (duration: 147.913451ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T17:36:20.478986Z","caller":"traceutil/trace.go:171","msg":"trace[626586677] transaction","detail":"{read_only:false; response_revision:5662; number_of_response:1; }","duration":"155.956242ms","start":"2026-04-12T17:36:20.322992Z","end":"2026-04-12T17:36:20.478948Z","steps":["trace[626586677] 'process raft request' (duration: 155.693985ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:36:20.490351Z","caller":"traceutil/trace.go:171","msg":"trace[1755628682] linearizableReadLoop","detail":"{readStateIndex:5874; appliedIndex:5872; }","duration":"157.204281ms","start":"2026-04-12T17:36:20.333103Z","end":"2026-04-12T17:36:20.490307Z","steps":["trace[1755628682] 'read index received' (duration: 145.610795ms)","trace[1755628682] 'applied index is now lower than readState.Index' (duration: 11.591586ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T17:36:20.490411Z","caller":"traceutil/trace.go:171","msg":"trace[1240715290] transaction","detail":"{read_only:false; response_revision:5663; number_of_response:1; }","duration":"166.782519ms","start":"2026-04-12T17:36:20.3236Z","end":"2026-04-12T17:36:20.490382Z","steps":["trace[1240715290] 'process raft request' (duration: 166.473932ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:36:20.490673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.533309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wflzr\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-12T17:36:20.490725Z","caller":"traceutil/trace.go:171","msg":"trace[215303250] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wflzr; range_end:; response_count:1; response_revision:5663; }","duration":"157.635762ms","start":"2026-04-12T17:36:20.333075Z","end":"2026-04-12T17:36:20.490711Z","steps":["trace[215303250] 'agreement among raft nodes before linearized reading' (duration: 157.392876ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:37:05.583885Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2999} {"level":"info","ts":"2026-04-12T17:37:05.652125Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2999,"took":"67.030015ms","hash":3703416447,"current-db-size-bytes":57937920,"current-db-size":"58 MB","current-db-size-in-use-bytes":33566720,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-12T17:37:05.652186Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3703416447,"revision":2999,"compact-revision":-1} {"level":"info","ts":"2026-04-12T17:42:05.592051Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6063} {"level":"info","ts":"2026-04-12T17:42:05.728213Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6063,"took":"133.18926ms","hash":1376599100,"current-db-size-bytes":57937920,"current-db-size":"58 MB","current-db-size-in-use-bytes":21741568,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-04-12T17:42:05.728349Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1376599100,"revision":6063,"compact-revision":2999} {"level":"warn","ts":"2026-04-12T17:42:17.056975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.015816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T17:42:17.057067Z","caller":"traceutil/trace.go:171","msg":"trace[411124859] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:8348; }","duration":"554.155749ms","start":"2026-04-12T17:42:16.502893Z","end":"2026-04-12T17:42:17.057049Z","steps":["trace[411124859] 'range keys from in-memory index tree' (duration: 553.905273ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:42:17.057115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:42:16.502882Z","time spent":"554.221191ms","remote":"127.0.0.1:48274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" limit:10000 "} {"level":"warn","ts":"2026-04-12T17:42:17.057281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.236458ms","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":"info","ts":"2026-04-12T17:42:17.057358Z","caller":"traceutil/trace.go:171","msg":"trace[697568513] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:8348; }","duration":"369.391061ms","start":"2026-04-12T17:42:16.687951Z","end":"2026-04-12T17:42:17.057342Z","steps":["trace[697568513] 'count revisions from in-memory index tree' (duration: 369.127695ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:42:17.057408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:42:16.687925Z","time spent":"369.469363ms","remote":"127.0.0.1:47028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true "} {"level":"warn","ts":"2026-04-12T17:42:17.057601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.667041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T17:42:17.057629Z","caller":"traceutil/trace.go:171","msg":"trace[969581562] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:8348; }","duration":"554.727853ms","start":"2026-04-12T17:42:16.502894Z","end":"2026-04-12T17:42:17.057622Z","steps":["trace[969581562] 'count revisions from in-memory index tree' (duration: 554.555429ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:42:17.057647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:42:16.502877Z","time spent":"554.764793ms","remote":"127.0.0.1:48274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true "} {"level":"warn","ts":"2026-04-12T17:42:17.057747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.476317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T17:42:17.05777Z","caller":"traceutil/trace.go:171","msg":"trace[933279985] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:8348; }","duration":"554.523058ms","start":"2026-04-12T17:42:16.503241Z","end":"2026-04-12T17:42:17.057764Z","steps":["trace[933279985] 'range keys from in-memory index tree' (duration: 554.416796ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:42:17.057794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:42:16.50323Z","time spent":"554.557839ms","remote":"127.0.0.1:48274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" limit:500 "} {"level":"warn","ts":"2026-04-12T17:42:17.057894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.484709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T17:42:17.057917Z","caller":"traceutil/trace.go:171","msg":"trace[1549769756] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:8348; }","duration":"531.523571ms","start":"2026-04-12T17:42:16.526389Z","end":"2026-04-12T17:42:17.057912Z","steps":["trace[1549769756] 'count revisions from in-memory index tree' (duration: 531.430488ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:42:17.057935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:42:16.526381Z","time spent":"531.549041ms","remote":"127.0.0.1:48280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true "} {"level":"warn","ts":"2026-04-12T17:42:17.058062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.534281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T17:42:17.058084Z","caller":"traceutil/trace.go:171","msg":"trace[322754343] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:8348; }","duration":"531.567512ms","start":"2026-04-12T17:42:16.526512Z","end":"2026-04-12T17:42:17.058079Z","steps":["trace[322754343] 'range keys from in-memory index tree' (duration: 531.441639ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:42:17.058101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:42:16.526452Z","time spent":"531.643393ms","remote":"127.0.0.1:48280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":113,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" limit:10000 "} {"level":"warn","ts":"2026-04-12T17:42:17.058137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.684301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T17:42:17.058181Z","caller":"traceutil/trace.go:171","msg":"trace[1582215992] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:8348; }","duration":"530.728852ms","start":"2026-04-12T17:42:16.527441Z","end":"2026-04-12T17:42:17.05817Z","steps":["trace[1582215992] 'range keys from in-memory index tree' (duration: 530.587039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:42:17.058212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:42:16.527404Z","time spent":"530.799774ms","remote":"127.0.0.1:48280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":113,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" limit:500 "} {"level":"warn","ts":"2026-04-12T17:42:17.057356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.58222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-12T17:42:17.058284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.995033ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-12T17:42:17.058385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.153876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wflzr\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-12T17:42:17.058387Z","caller":"traceutil/trace.go:171","msg":"trace[157670563] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:8348; }","duration":"406.120226ms","start":"2026-04-12T17:42:16.652249Z","end":"2026-04-12T17:42:17.058369Z","steps":["trace[157670563] 'range keys from in-memory index tree' (duration: 405.974562ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:42:17.058363Z","caller":"traceutil/trace.go:171","msg":"trace[169806622] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:8348; }","duration":"337.521223ms","start":"2026-04-12T17:42:16.720742Z","end":"2026-04-12T17:42:17.058263Z","steps":["trace[169806622] 'count revisions from in-memory index tree' (duration: 336.527149ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:42:17.058419Z","caller":"traceutil/trace.go:171","msg":"trace[1581205605] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wflzr; range_end:; response_count:1; response_revision:8348; }","duration":"519.196938ms","start":"2026-04-12T17:42:16.539212Z","end":"2026-04-12T17:42:17.058409Z","steps":["trace[1581205605] 'range keys from in-memory index tree' (duration: 519.026504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:42:17.058445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:42:16.539203Z","time spent":"519.235558ms","remote":"127.0.0.1:45840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6553,"request content":"key:\"/registry/pods/openstack/keepalived-wflzr\" "} {"level":"warn","ts":"2026-04-12T17:42:17.058476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:42:16.720726Z","time spent":"337.708837ms","remote":"127.0.0.1:45980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":57,"response size":29,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-04-12T17:42:17.058454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.372949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-12T17:42:17.058454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.01023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T17:42:17.05855Z","caller":"traceutil/trace.go:171","msg":"trace[275625538] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:8348; }","duration":"513.492851ms","start":"2026-04-12T17:42:16.54503Z","end":"2026-04-12T17:42:17.058523Z","steps":["trace[275625538] 'range keys from in-memory index tree' (duration: 513.321957ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:42:17.058582Z","caller":"traceutil/trace.go:171","msg":"trace[1733483962] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:8348; }","duration":"513.152404ms","start":"2026-04-12T17:42:16.54538Z","end":"2026-04-12T17:42:17.058533Z","steps":["trace[1733483962] 'range keys from in-memory index tree' (duration: 512.949278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:42:17.058595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:42:16.545025Z","time spent":"513.558833ms","remote":"127.0.0.1:48282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":27,"request content":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" limit:10000 "} {"level":"warn","ts":"2026-04-12T17:42:17.058635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:42:16.54537Z","time spent":"513.248656ms","remote":"127.0.0.1:48282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":27,"request content":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" limit:500 "} {"level":"warn","ts":"2026-04-12T17:42:17.058459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.44333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T17:42:17.058809Z","caller":"traceutil/trace.go:171","msg":"trace[61790000] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:8348; }","duration":"513.824559ms","start":"2026-04-12T17:42:16.544975Z","end":"2026-04-12T17:42:17.058799Z","steps":["trace[61790000] 'count revisions from in-memory index tree' (duration: 513.394619ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:42:17.058842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:42:16.544969Z","time spent":"513.86306ms","remote":"127.0.0.1:48282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":27,"request content":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true "} {"level":"warn","ts":"2026-04-12T17:42:28.625852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.220818ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T17:42:28.625941Z","caller":"traceutil/trace.go:171","msg":"trace[961051616] linearizableReadLoop","detail":"{readStateIndex:8712; appliedIndex:8711; }","duration":"389.93122ms","start":"2026-04-12T17:42:28.235995Z","end":"2026-04-12T17:42:28.625926Z","steps":["trace[961051616] 'read index received' (duration: 67.547777ms)","trace[961051616] 'applied index is now lower than readState.Index' (duration: 322.382393ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T17:42:28.62602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.034542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T17:42:28.626039Z","caller":"traceutil/trace.go:171","msg":"trace[2134355053] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8409; }","duration":"390.109474ms","start":"2026-04-12T17:42:28.235923Z","end":"2026-04-12T17:42:28.626033Z","steps":["trace[2134355053] 'agreement among raft nodes before linearized reading' (duration: 390.058693ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:42:28.626064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:42:28.235892Z","time spent":"390.163866ms","remote":"127.0.0.1:45612","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-04-12T17:42:28.626047Z","caller":"traceutil/trace.go:171","msg":"trace[701884256] transaction","detail":"{read_only:false; response_revision:8409; number_of_response:1; }","duration":"427.690888ms","start":"2026-04-12T17:42:28.198298Z","end":"2026-04-12T17:42:28.625988Z","steps":["trace[701884256] 'process raft request' (duration: 105.234194ms)","trace[701884256] 'compare' (duration: 321.84828ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T17:42:28.626293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:42:28.19827Z","time spent":"427.879713ms","remote":"127.0.0.1:45916","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-04-12T17:42:28.684517Z","caller":"traceutil/trace.go:171","msg":"trace[733341914] transaction","detail":"{read_only:false; response_revision:8410; number_of_response:1; }","duration":"225.483707ms","start":"2026-04-12T17:42:28.458994Z","end":"2026-04-12T17:42:28.684478Z","steps":["trace[733341914] 'process raft request' (duration: 225.19393ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:42:28.684744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.389508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.155\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-12T17:42:28.684838Z","caller":"traceutil/trace.go:171","msg":"trace[747875975] range","detail":"{range_begin:/registry/masterleases/199.204.45.155; range_end:; response_count:1; response_revision:8410; }","duration":"197.547522ms","start":"2026-04-12T17:42:28.487265Z","end":"2026-04-12T17:42:28.684812Z","steps":["trace[747875975] 'agreement among raft nodes before linearized reading' (duration: 197.267455ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:42:58.571398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.88314ms","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-04-12T17:42:58.571445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.586608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-04-12T17:42:58.571485Z","caller":"traceutil/trace.go:171","msg":"trace[1581851003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8624; }","duration":"300.022423ms","start":"2026-04-12T17:42:58.271445Z","end":"2026-04-12T17:42:58.571468Z","steps":["trace[1581851003] 'range keys from in-memory index tree' (duration: 299.709496ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:42:58.571554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:42:58.271424Z","time spent":"300.113516ms","remote":"127.0.0.1:45916","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-04-12T17:42:58.571575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.54982ms","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-04-12T17:42:58.571654Z","caller":"traceutil/trace.go:171","msg":"trace[1281417024] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8624; }","duration":"213.675263ms","start":"2026-04-12T17:42:58.357961Z","end":"2026-04-12T17:42:58.571637Z","steps":["trace[1281417024] 'range keys from in-memory index tree' (duration: 213.380876ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:42:58.57151Z","caller":"traceutil/trace.go:171","msg":"trace[490116963] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:8624; }","duration":"367.6788ms","start":"2026-04-12T17:42:58.203811Z","end":"2026-04-12T17:42:58.57149Z","steps":["trace[490116963] 'range keys from in-memory index tree' (duration: 367.423474ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:42:58.571912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:42:58.203799Z","time spent":"368.061249ms","remote":"127.0.0.1:49584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5673,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-12T17:42:58.857621Z","caller":"traceutil/trace.go:171","msg":"trace[1513125717] transaction","detail":"{read_only:false; response_revision:8626; number_of_response:1; }","duration":"278.83742ms","start":"2026-04-12T17:42:58.578764Z","end":"2026-04-12T17:42:58.857602Z","steps":["trace[1513125717] 'process raft request' (duration: 278.724528ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:42:58.857662Z","caller":"traceutil/trace.go:171","msg":"trace[117617441] transaction","detail":"{read_only:false; response_revision:8625; number_of_response:1; }","duration":"281.075803ms","start":"2026-04-12T17:42:58.576562Z","end":"2026-04-12T17:42:58.857637Z","steps":["trace[117617441] 'process raft request' (duration: 271.407693ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:42:58.860386Z","caller":"traceutil/trace.go:171","msg":"trace[340447822] transaction","detail":"{read_only:false; response_revision:8627; number_of_response:1; }","duration":"107.286388ms","start":"2026-04-12T17:42:58.753061Z","end":"2026-04-12T17:42:58.860348Z","steps":["trace[340447822] 'process raft request' (duration: 107.163835ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:43:01.505169Z","caller":"traceutil/trace.go:171","msg":"trace[772956141] linearizableReadLoop","detail":"{readStateIndex:8952; appliedIndex:8951; }","duration":"180.23606ms","start":"2026-04-12T17:43:01.324908Z","end":"2026-04-12T17:43:01.505144Z","steps":["trace[772956141] 'read index received' (duration: 180.012084ms)","trace[772956141] 'applied index is now lower than readState.Index' (duration: 222.976µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T17:43:01.505286Z","caller":"traceutil/trace.go:171","msg":"trace[1326611208] transaction","detail":"{read_only:false; response_revision:8640; number_of_response:1; }","duration":"284.377102ms","start":"2026-04-12T17:43:01.220876Z","end":"2026-04-12T17:43:01.505253Z","steps":["trace[1326611208] 'process raft request' (duration: 284.105326ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:43:01.505367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.448044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wflzr\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-12T17:43:01.505403Z","caller":"traceutil/trace.go:171","msg":"trace[516198946] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wflzr; range_end:; response_count:1; response_revision:8640; }","duration":"180.538226ms","start":"2026-04-12T17:43:01.324854Z","end":"2026-04-12T17:43:01.505392Z","steps":["trace[516198946] 'agreement among raft nodes before linearized reading' (duration: 180.380803ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:43:10.995023Z","caller":"traceutil/trace.go:171","msg":"trace[499964821] transaction","detail":"{read_only:false; response_revision:8731; number_of_response:1; }","duration":"125.852038ms","start":"2026-04-12T17:43:10.869152Z","end":"2026-04-12T17:43:10.995004Z","steps":["trace[499964821] 'process raft request' (duration: 125.664134ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:43:11.316204Z","caller":"traceutil/trace.go:171","msg":"trace[333865434] transaction","detail":"{read_only:false; response_revision:8732; number_of_response:1; }","duration":"442.977226ms","start":"2026-04-12T17:43:10.873205Z","end":"2026-04-12T17:43:11.316183Z","steps":["trace[333865434] 'process raft request' (duration: 442.784232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:43:11.316511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:43:10.873186Z","time spent":"443.16403ms","remote":"127.0.0.1:45840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4638,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T17:43:11.316607Z","caller":"traceutil/trace.go:171","msg":"trace[426745844] transaction","detail":"{read_only:false; response_revision:8733; number_of_response:1; }","duration":"410.639519ms","start":"2026-04-12T17:43:10.905933Z","end":"2026-04-12T17:43:11.316572Z","steps":["trace[426745844] 'process raft request' (duration: 410.192188ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:43:11.316816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:43:10.905914Z","time spent":"410.794302ms","remote":"127.0.0.1:45916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T17:43:11.316863Z","caller":"traceutil/trace.go:171","msg":"trace[1973643442] linearizableReadLoop","detail":"{readStateIndex:9051; appliedIndex:9047; }","duration":"356.967844ms","start":"2026-04-12T17:43:10.959867Z","end":"2026-04-12T17:43:11.316835Z","steps":["trace[1973643442] 'read index received' (duration: 34.885058ms)","trace[1973643442] 'applied index is now lower than readState.Index' (duration: 322.081246ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T17:43:11.318796Z","caller":"traceutil/trace.go:171","msg":"trace[533282611] transaction","detail":"{read_only:false; response_revision:8734; number_of_response:1; }","duration":"389.401135ms","start":"2026-04-12T17:43:10.92936Z","end":"2026-04-12T17:43:11.318761Z","steps":["trace[533282611] 'process raft request' (duration: 387.194662ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:43:11.319272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.981428ms","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-04-12T17:43:11.319459Z","caller":"traceutil/trace.go:171","msg":"trace[1306565962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8734; }","duration":"347.141221ms","start":"2026-04-12T17:43:10.972245Z","end":"2026-04-12T17:43:11.319386Z","steps":["trace[1306565962] 'agreement among raft nodes before linearized reading' (duration: 346.897975ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:43:11.319545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:43:10.97223Z","time spent":"347.297645ms","remote":"127.0.0.1:45916","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-04-12T17:43:11.319843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:43:10.929339Z","time spent":"389.618269ms","remote":"127.0.0.1:45916","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-04-12T17:43:11.320227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.329465ms","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-04-12T17:43:11.320309Z","caller":"traceutil/trace.go:171","msg":"trace[1217845806] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8734; }","duration":"360.453927ms","start":"2026-04-12T17:43:10.959833Z","end":"2026-04-12T17:43:11.320287Z","steps":["trace[1217845806] 'agreement among raft nodes before linearized reading' (duration: 360.127489ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:43:11.320406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T17:43:10.959816Z","time spent":"360.574981ms","remote":"127.0.0.1:45916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-12T17:43:12.058929Z","caller":"traceutil/trace.go:171","msg":"trace[1636831884] transaction","detail":"{read_only:false; response_revision:8741; number_of_response:1; }","duration":"171.85086ms","start":"2026-04-12T17:43:11.887052Z","end":"2026-04-12T17:43:12.058903Z","steps":["trace[1636831884] 'process raft request' (duration: 171.676956ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:43:12.447837Z","caller":"traceutil/trace.go:171","msg":"trace[137431176] transaction","detail":"{read_only:false; response_revision:8745; number_of_response:1; }","duration":"192.667815ms","start":"2026-04-12T17:43:12.255137Z","end":"2026-04-12T17:43:12.447805Z","steps":["trace[137431176] 'process raft request' (duration: 192.435769ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:43:26.632047Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023f0.wal"} {"level":"info","ts":"2026-04-12T17:44:09.637729Z","caller":"traceutil/trace.go:171","msg":"trace[1893717110] transaction","detail":"{read_only:false; response_revision:9352; number_of_response:1; }","duration":"115.918573ms","start":"2026-04-12T17:44:09.521787Z","end":"2026-04-12T17:44:09.637706Z","steps":["trace[1893717110] 'process raft request' (duration: 115.617845ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:44:09.637957Z","caller":"traceutil/trace.go:171","msg":"trace[1697158086] transaction","detail":"{read_only:false; response_revision:9353; number_of_response:1; }","duration":"108.430035ms","start":"2026-04-12T17:44:09.529491Z","end":"2026-04-12T17:44:09.637921Z","steps":["trace[1697158086] 'process raft request' (duration: 108.179669ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:44:22.530975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.460991ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T17:44:22.531088Z","caller":"traceutil/trace.go:171","msg":"trace[353733171] linearizableReadLoop","detail":"{readStateIndex:9793; appliedIndex:9792; }","duration":"177.409723ms","start":"2026-04-12T17:44:22.353661Z","end":"2026-04-12T17:44:22.531071Z","steps":["trace[353733171] 'read index received' (duration: 72.713807ms)","trace[353733171] 'applied index is now lower than readState.Index' (duration: 104.694766ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T17:44:22.531181Z","caller":"traceutil/trace.go:171","msg":"trace[154720675] transaction","detail":"{read_only:false; response_revision:9456; number_of_response:1; }","duration":"206.959804ms","start":"2026-04-12T17:44:22.32421Z","end":"2026-04-12T17:44:22.53117Z","steps":["trace[154720675] 'process raft request' (duration: 102.204916ms)","trace[154720675] 'compare' (duration: 104.321278ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T17:44:22.531509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.843673ms","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-04-12T17:44:22.53155Z","caller":"traceutil/trace.go:171","msg":"trace[1154494873] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9456; }","duration":"177.913455ms","start":"2026-04-12T17:44:22.353625Z","end":"2026-04-12T17:44:22.531539Z","steps":["trace[1154494873] 'agreement among raft nodes before linearized reading' (duration: 177.787902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T17:44:22.531802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.339096ms","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-04-12T17:44:22.531927Z","caller":"traceutil/trace.go:171","msg":"trace[1948749573] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9456; }","duration":"149.51214ms","start":"2026-04-12T17:44:22.382389Z","end":"2026-04-12T17:44:22.531901Z","steps":["trace[1948749573] 'agreement among raft nodes before linearized reading' (duration: 149.125571ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:44:49.778647Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"344940fc142d902f","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-12T17:44:49.789318Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-12T17:44:49.789462Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-12T17:47:05.601625Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8150} {"level":"info","ts":"2026-04-12T17:47:05.669854Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8150,"took":"66.068005ms","hash":3715626269,"current-db-size-bytes":57937920,"current-db-size":"58 MB","current-db-size-in-use-bytes":29196288,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-12T17:47:05.66995Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3715626269,"revision":8150,"compact-revision":6063} {"level":"warn","ts":"2026-04-12T17:49:47.378729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.556002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T17:49:47.378802Z","caller":"traceutil/trace.go:171","msg":"trace[763177553] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11533; }","duration":"143.642124ms","start":"2026-04-12T17:49:47.235146Z","end":"2026-04-12T17:49:47.378788Z","steps":["trace[763177553] 'range keys from in-memory index tree' (duration: 143.441078ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T17:52:05.607737Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10683} {"level":"info","ts":"2026-04-12T17:52:05.689521Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10683,"took":"79.947798ms","hash":3515857233,"current-db-size-bytes":57937920,"current-db-size":"58 MB","current-db-size-in-use-bytes":21127168,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-12T17:52:05.689571Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3515857233,"revision":10683,"compact-revision":8150}