{"level":"warn","ts":"2026-04-20T17:41:24.903234Z","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-20T17:41:24.903335Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.116: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.116:2380","--initial-cluster=instance=https://199.204.45.116:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.116:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.116: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-20T17:41:24.903408Z","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-20T17:41:24.903424Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-04-20T17:41:24.903444Z","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-20T17:41:24.904338Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"]} {"level":"info","ts":"2026-04-20T17:41:24.904426Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.116: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-20T17:41:24.908892Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.199202ms"} {"level":"info","ts":"2026-04-20T17:41:24.914933Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5caa70ef1dd6dc04","cluster-id":"45afccab143d2c82"} {"level":"info","ts":"2026-04-20T17:41:24.915052Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T17:41:24.91511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 0"} {"level":"info","ts":"2026-04-20T17:41:24.915146Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5caa70ef1dd6dc04 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T17:41:24.915178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 1"} {"level":"info","ts":"2026-04-20T17:41:24.915258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"warn","ts":"2026-04-20T17:41:24.928153Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T17:41:24.930322Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T17:41:24.931716Z","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-20T17:41:24.934965Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5caa70ef1dd6dc04","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T17:41:24.93524Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5caa70ef1dd6dc04","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T17:41:24.935667Z","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-20T17:41:24.935747Z","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-20T17:41:24.936012Z","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-20T17:41:24.94096Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"info","ts":"2026-04-20T17:41:24.941123Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","added-peer-id":"5caa70ef1dd6dc04","added-peer-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-04-20T17:41:24.941278Z","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-20T17:41:24.941372Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-04-20T17:41:24.941392Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-04-20T17:41:24.941534Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5caa70ef1dd6dc04","initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T17:41:24.941707Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T17:41:25.315661Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T17:41:25.315764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T17:41:25.31583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgPreVoteResp from 5caa70ef1dd6dc04 at term 1"} {"level":"info","ts":"2026-04-20T17:41:25.315854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became candidate at term 2"} {"level":"info","ts":"2026-04-20T17:41:25.315862Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgVoteResp from 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-04-20T17:41:25.315883Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became leader at term 2"} {"level":"info","ts":"2026-04-20T17:41:25.315894Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5caa70ef1dd6dc04 elected leader 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-04-20T17:41:25.317099Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T17:41:25.317413Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5caa70ef1dd6dc04","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.116:2379]}","request-path":"/0/members/5caa70ef1dd6dc04/attributes","cluster-id":"45afccab143d2c82","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T17:41:25.318254Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T17:41:25.318386Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T17:41:25.318361Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T17:41:25.318514Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T17:41:25.318544Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T17:41:25.318422Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T17:41:25.320847Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.116:2379"} {"level":"info","ts":"2026-04-20T17:41:25.320915Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T17:41:25.321342Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T17:41:33.626891Z","caller":"traceutil/trace.go:171","msg":"trace[262176683] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"106.793002ms","start":"2026-04-20T17:41:33.520048Z","end":"2026-04-20T17:41:33.626841Z","steps":["trace[262176683] 'process raft request' (duration: 39.448334ms)","trace[262176683] 'compare' (duration: 67.057044ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:41:33.853157Z","caller":"traceutil/trace.go:171","msg":"trace[404421127] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"183.322743ms","start":"2026-04-20T17:41:33.669806Z","end":"2026-04-20T17:41:33.853129Z","steps":["trace[404421127] 'process raft request' (duration: 151.748687ms)","trace[404421127] 'compare' (duration: 31.451984ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:41:34.034159Z","caller":"traceutil/trace.go:171","msg":"trace[1855225627] linearizableReadLoop","detail":"{readStateIndex:304; appliedIndex:303; }","duration":"115.588017ms","start":"2026-04-20T17:41:33.91835Z","end":"2026-04-20T17:41:34.033938Z","steps":["trace[1855225627] 'read index received' (duration: 40.701758ms)","trace[1855225627] 'applied index is now lower than readState.Index' (duration: 74.884989ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:41:34.034235Z","caller":"traceutil/trace.go:171","msg":"trace[1493433148] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"125.002563ms","start":"2026-04-20T17:41:33.909025Z","end":"2026-04-20T17:41:34.034027Z","steps":["trace[1493433148] 'process raft request' (duration: 50.096524ms)","trace[1493433148] 'compare' (duration: 74.588515ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:41:34.034217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.833669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/attachdetach-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T17:41:34.034347Z","caller":"traceutil/trace.go:171","msg":"trace[359828154] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/attachdetach-controller; range_end:; response_count:0; response_revision:295; }","duration":"116.010291ms","start":"2026-04-20T17:41:33.918305Z","end":"2026-04-20T17:41:34.034315Z","steps":["trace[359828154] 'agreement among raft nodes before linearized reading' (duration: 115.816859ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:41:34.32439Z","caller":"traceutil/trace.go:171","msg":"trace[776579573] transaction","detail":"{read_only:false; response_revision:299; number_of_response:1; }","duration":"105.568814ms","start":"2026-04-20T17:41:34.218766Z","end":"2026-04-20T17:41:34.324334Z","steps":["trace[776579573] 'process raft request' (duration: 78.664655ms)","trace[776579573] 'compare' (duration: 26.719227ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:41:34.882337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.044846ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T17:41:34.882565Z","caller":"traceutil/trace.go:171","msg":"trace[902477911] linearizableReadLoop","detail":"{readStateIndex:316; appliedIndex:314; }","duration":"113.179639ms","start":"2026-04-20T17:41:34.769367Z","end":"2026-04-20T17:41:34.882547Z","steps":["trace[902477911] 'read index received' (duration: 432.685µs)","trace[902477911] 'applied index is now lower than readState.Index' (duration: 112.746054ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:41:34.882661Z","caller":"traceutil/trace.go:171","msg":"trace[1164172405] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"155.254647ms","start":"2026-04-20T17:41:34.727396Z","end":"2026-04-20T17:41:34.882651Z","steps":["trace[1164172405] 'process raft request' (duration: 155.079105ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:41:34.882759Z","caller":"traceutil/trace.go:171","msg":"trace[1297141503] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"161.83878ms","start":"2026-04-20T17:41:34.720888Z","end":"2026-04-20T17:41:34.882726Z","steps":["trace[1297141503] 'process raft request' (duration: 48.832053ms)","trace[1297141503] 'compare' (duration: 111.907505ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:41:34.882933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.570873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-20T17:41:34.882976Z","caller":"traceutil/trace.go:171","msg":"trace[363933209] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:307; }","duration":"113.656164ms","start":"2026-04-20T17:41:34.769309Z","end":"2026-04-20T17:41:34.882965Z","steps":["trace[363933209] 'agreement among raft nodes before linearized reading' (duration: 113.580133ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:41:35.047038Z","caller":"traceutil/trace.go:171","msg":"trace[1918432678] linearizableReadLoop","detail":"{readStateIndex:317; appliedIndex:316; }","duration":"126.924116ms","start":"2026-04-20T17:41:34.920096Z","end":"2026-04-20T17:41:35.04702Z","steps":["trace[1918432678] 'read index received' (duration: 67.372589ms)","trace[1918432678] 'applied index is now lower than readState.Index' (duration: 59.550877ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:41:35.047262Z","caller":"traceutil/trace.go:171","msg":"trace[713102837] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"156.03667ms","start":"2026-04-20T17:41:34.891217Z","end":"2026-04-20T17:41:35.047253Z","steps":["trace[713102837] 'process raft request' (duration: 96.296861ms)","trace[713102837] 'compare' (duration: 59.357624ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:41:35.047495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.381541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-04-20T17:41:35.047554Z","caller":"traceutil/trace.go:171","msg":"trace[2115804015] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:308; }","duration":"127.499863ms","start":"2026-04-20T17:41:34.920039Z","end":"2026-04-20T17:41:35.047539Z","steps":["trace[2115804015] 'agreement among raft nodes before linearized reading' (duration: 127.370691ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:41:47.885739Z","caller":"traceutil/trace.go:171","msg":"trace[604073815] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"166.789041ms","start":"2026-04-20T17:41:47.718921Z","end":"2026-04-20T17:41:47.88571Z","steps":["trace[604073815] 'process raft request' (duration: 166.64186ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:41:47.997199Z","caller":"traceutil/trace.go:171","msg":"trace[850043450] linearizableReadLoop","detail":"{readStateIndex:429; appliedIndex:427; }","duration":"166.229645ms","start":"2026-04-20T17:41:47.830942Z","end":"2026-04-20T17:41:47.997172Z","steps":["trace[850043450] 'read index received' (duration: 54.542273ms)","trace[850043450] 'applied index is now lower than readState.Index' (duration: 111.686742ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:41:47.997316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.367857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T17:41:47.997346Z","caller":"traceutil/trace.go:171","msg":"trace[970876729] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:0; response_revision:416; }","duration":"166.443268ms","start":"2026-04-20T17:41:47.830893Z","end":"2026-04-20T17:41:47.997337Z","steps":["trace[970876729] 'agreement among raft nodes before linearized reading' (duration: 166.366467ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:41:47.997499Z","caller":"traceutil/trace.go:171","msg":"trace[150952316] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"197.486091ms","start":"2026-04-20T17:41:47.799989Z","end":"2026-04-20T17:41:47.997475Z","steps":["trace[150952316] 'process raft request' (duration: 173.861261ms)","trace[150952316] 'compare' (duration: 23.252287ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:41:48.403427Z","caller":"traceutil/trace.go:171","msg":"trace[421067931] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"132.532465ms","start":"2026-04-20T17:41:48.270865Z","end":"2026-04-20T17:41:48.403398Z","steps":["trace[421067931] 'process raft request' (duration: 38.963367ms)","trace[421067931] 'compare' (duration: 93.437167ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:41:48.418049Z","caller":"traceutil/trace.go:171","msg":"trace[292501883] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"146.960134ms","start":"2026-04-20T17:41:48.271039Z","end":"2026-04-20T17:41:48.417999Z","steps":["trace[292501883] 'process raft request' (duration: 146.864413ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:41:50.478291Z","caller":"traceutil/trace.go:171","msg":"trace[1505105507] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"330.907807ms","start":"2026-04-20T17:41:50.147352Z","end":"2026-04-20T17:41:50.47826Z","steps":["trace[1505105507] 'process raft request' (duration: 330.757095ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:41:50.47848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:41:50.147332Z","time spent":"331.077739ms","remote":"127.0.0.1:46952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T17:41:50.488832Z","caller":"traceutil/trace.go:171","msg":"trace[2028328870] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"340.821332ms","start":"2026-04-20T17:41:50.147957Z","end":"2026-04-20T17:41:50.488779Z","steps":["trace[2028328870] 'process raft request' (duration: 340.61816ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:41:50.488868Z","caller":"traceutil/trace.go:171","msg":"trace[637048808] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"335.080877ms","start":"2026-04-20T17:41:50.153767Z","end":"2026-04-20T17:41:50.488848Z","steps":["trace[637048808] 'process raft request' (duration: 334.901305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:41:50.489026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:41:50.153749Z","time spent":"335.207648ms","remote":"127.0.0.1:46952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T17:41:50.489072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:41:50.147924Z","time spent":"341.037104ms","remote":"127.0.0.1:46952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T17:41:50.489465Z","caller":"traceutil/trace.go:171","msg":"trace[716722061] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"333.706293ms","start":"2026-04-20T17:41:50.15575Z","end":"2026-04-20T17:41:50.489456Z","steps":["trace[716722061] 'process raft request' (duration: 332.942276ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:41:50.489513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:41:50.15574Z","time spent":"333.750934ms","remote":"127.0.0.1:46952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T17:41:50.489807Z","caller":"traceutil/trace.go:171","msg":"trace[598248865] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"333.420731ms","start":"2026-04-20T17:41:50.156377Z","end":"2026-04-20T17:41:50.489797Z","steps":["trace[598248865] 'process raft request' (duration: 333.034957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:41:50.489849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:41:50.156368Z","time spent":"333.462501ms","remote":"127.0.0.1:46952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T17:41:50.48993Z","caller":"traceutil/trace.go:171","msg":"trace[417037601] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"328.08019ms","start":"2026-04-20T17:41:50.161821Z","end":"2026-04-20T17:41:50.489901Z","steps":["trace[417037601] 'process raft request' (duration: 327.922948ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:41:50.490019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:41:50.161798Z","time spent":"328.182301ms","remote":"127.0.0.1:46952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T17:41:50.490751Z","caller":"traceutil/trace.go:171","msg":"trace[90825047] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"327.091411ms","start":"2026-04-20T17:41:50.163648Z","end":"2026-04-20T17:41:50.490739Z","steps":["trace[90825047] 'process raft request' (duration: 326.830558ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:41:50.491584Z","caller":"traceutil/trace.go:171","msg":"trace[649486010] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"311.2711ms","start":"2026-04-20T17:41:50.180301Z","end":"2026-04-20T17:41:50.491572Z","steps":["trace[649486010] 'process raft request' (duration: 310.24721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:41:50.49163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:41:50.180294Z","time spent":"311.31793ms","remote":"127.0.0.1:46952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T17:41:50.491784Z","caller":"traceutil/trace.go:171","msg":"trace[2091292061] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"307.408393ms","start":"2026-04-20T17:41:50.184367Z","end":"2026-04-20T17:41:50.491776Z","steps":["trace[2091292061] 'process raft request' (duration: 306.235851ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:41:50.491821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:41:50.18436Z","time spent":"307.443784ms","remote":"127.0.0.1:46952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T17:41:50.492544Z","caller":"traceutil/trace.go:171","msg":"trace[1381099472] linearizableReadLoop","detail":"{readStateIndex:480; appliedIndex:469; }","duration":"237.605337ms","start":"2026-04-20T17:41:50.254924Z","end":"2026-04-20T17:41:50.49253Z","steps":["trace[1381099472] 'read index received' (duration: 223.23055ms)","trace[1381099472] 'applied index is now lower than readState.Index' (duration: 14.373707ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:41:50.492598Z","caller":"traceutil/trace.go:171","msg":"trace[947852773] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"280.87242ms","start":"2026-04-20T17:41:50.211718Z","end":"2026-04-20T17:41:50.49259Z","steps":["trace[947852773] 'process raft request' (duration: 278.987772ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:41:50.492688Z","caller":"traceutil/trace.go:171","msg":"trace[2101770886] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"179.644454ms","start":"2026-04-20T17:41:50.313035Z","end":"2026-04-20T17:41:50.492679Z","steps":["trace[2101770886] 'process raft request' (duration: 179.410702ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:41:50.492865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.93365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-20T17:41:50.492891Z","caller":"traceutil/trace.go:171","msg":"trace[379056976] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:467; }","duration":"238.010501ms","start":"2026-04-20T17:41:50.254874Z","end":"2026-04-20T17:41:50.492885Z","steps":["trace[379056976] 'agreement among raft nodes before linearized reading' (duration: 237.88788ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:41:50.495249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:41:50.163638Z","time spent":"327.142361ms","remote":"127.0.0.1:46952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T17:41:50.491332Z","caller":"traceutil/trace.go:171","msg":"trace[603698129] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"329.221481ms","start":"2026-04-20T17:41:50.162064Z","end":"2026-04-20T17:41:50.491286Z","steps":["trace[603698129] 'process raft request' (duration: 328.002389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:41:50.497678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:41:50.162015Z","time spent":"335.496181ms","remote":"127.0.0.1:46952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T17:41:50.714011Z","caller":"traceutil/trace.go:171","msg":"trace[776290403] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"189.937142ms","start":"2026-04-20T17:41:50.524039Z","end":"2026-04-20T17:41:50.713976Z","steps":["trace[776290403] 'process raft request' (duration: 172.197762ms)","trace[776290403] 'compare' (duration: 17.629629ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:41:50.716777Z","caller":"traceutil/trace.go:171","msg":"trace[744293991] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"131.11025ms","start":"2026-04-20T17:41:50.585653Z","end":"2026-04-20T17:41:50.716763Z","steps":["trace[744293991] 'process raft request' (duration: 131.03516ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:41:50.717003Z","caller":"traceutil/trace.go:171","msg":"trace[1275675491] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"192.202963ms","start":"2026-04-20T17:41:50.524791Z","end":"2026-04-20T17:41:50.716993Z","steps":["trace[1275675491] 'process raft request' (duration: 191.305145ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:41:50.717364Z","caller":"traceutil/trace.go:171","msg":"trace[640009765] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"188.255755ms","start":"2026-04-20T17:41:50.529099Z","end":"2026-04-20T17:41:50.717354Z","steps":["trace[640009765] 'process raft request' (duration: 187.136944ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:41:50.717483Z","caller":"traceutil/trace.go:171","msg":"trace[806135987] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"188.378946ms","start":"2026-04-20T17:41:50.529096Z","end":"2026-04-20T17:41:50.717475Z","steps":["trace[806135987] 'process raft request' (duration: 187.220975ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:41:50.717587Z","caller":"traceutil/trace.go:171","msg":"trace[1684898828] transaction","detail":"{read_only:false; number_of_response:1; response_revision:480; }","duration":"181.120737ms","start":"2026-04-20T17:41:50.536458Z","end":"2026-04-20T17:41:50.717579Z","steps":["trace[1684898828] 'process raft request' (duration: 179.918016ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:41:50.717663Z","caller":"traceutil/trace.go:171","msg":"trace[417647136] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"179.065678ms","start":"2026-04-20T17:41:50.538579Z","end":"2026-04-20T17:41:50.717644Z","steps":["trace[417647136] 'process raft request' (duration: 177.847966ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:41:50.717777Z","caller":"traceutil/trace.go:171","msg":"trace[1386414078] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"165.181255ms","start":"2026-04-20T17:41:50.552587Z","end":"2026-04-20T17:41:50.717768Z","steps":["trace[1386414078] 'process raft request' (duration: 163.963553ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:41:50.717855Z","caller":"traceutil/trace.go:171","msg":"trace[434610345] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"149.426235ms","start":"2026-04-20T17:41:50.568421Z","end":"2026-04-20T17:41:50.717847Z","steps":["trace[434610345] 'process raft request' (duration: 148.187583ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:41:50.718013Z","caller":"traceutil/trace.go:171","msg":"trace[2016262344] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"140.611821ms","start":"2026-04-20T17:41:50.577391Z","end":"2026-04-20T17:41:50.718003Z","steps":["trace[2016262344] 'process raft request' (duration: 139.254108ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:41:52.246327Z","caller":"traceutil/trace.go:171","msg":"trace[1181210409] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"399.546694ms","start":"2026-04-20T17:41:51.846728Z","end":"2026-04-20T17:41:52.246274Z","steps":["trace[1181210409] 'process raft request' (duration: 398.972538ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:41:52.246416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.164395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyextensionpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:74769"} {"level":"info","ts":"2026-04-20T17:41:52.2465Z","caller":"traceutil/trace.go:171","msg":"trace[1563126095] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoyextensionpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:531; }","duration":"389.308037ms","start":"2026-04-20T17:41:51.85718Z","end":"2026-04-20T17:41:52.246488Z","steps":["trace[1563126095] 'agreement among raft nodes before linearized reading' (duration: 389.105435ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:41:52.246535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:41:51.857159Z","time spent":"389.369637ms","remote":"127.0.0.1:46952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":1,"response size":74791,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyextensionpolicies.gateway.envoyproxy.io\" "} {"level":"warn","ts":"2026-04-20T17:41:52.246584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:41:51.846675Z","time spent":"399.773355ms","remote":"127.0.0.1:46952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T17:41:52.246743Z","caller":"traceutil/trace.go:171","msg":"trace[628442702] linearizableReadLoop","detail":"{readStateIndex:548; appliedIndex:547; }","duration":"388.938003ms","start":"2026-04-20T17:41:51.857227Z","end":"2026-04-20T17:41:52.246165Z","steps":["trace[628442702] 'read index received' (duration: 388.334387ms)","trace[628442702] 'applied index is now lower than readState.Index' (duration: 602.486µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:41:52.251985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.100692ms","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-20T17:41:52.252058Z","caller":"traceutil/trace.go:171","msg":"trace[1695860104] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:532; }","duration":"318.218214ms","start":"2026-04-20T17:41:51.933819Z","end":"2026-04-20T17:41:52.252037Z","steps":["trace[1695860104] 'agreement among raft nodes before linearized reading' (duration: 318.058442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:41:52.252135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:41:51.933779Z","time spent":"318.347175ms","remote":"127.0.0.1:47138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-20T17:41:52.252284Z","caller":"traceutil/trace.go:171","msg":"trace[970215482] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"340.745127ms","start":"2026-04-20T17:41:51.911517Z","end":"2026-04-20T17:41:52.252262Z","steps":["trace[970215482] 'process raft request' (duration: 340.155542ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:41:52.252567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:41:51.911493Z","time spent":"340.864708ms","remote":"127.0.0.1:46952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208461,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T17:41:55.258004Z","caller":"traceutil/trace.go:171","msg":"trace[898824295] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"194.393787ms","start":"2026-04-20T17:41:55.063585Z","end":"2026-04-20T17:41:55.257979Z","steps":["trace[898824295] 'process raft request' (duration: 176.658264ms)","trace[898824295] 'compare' (duration: 17.279348ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:41:57.649262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.989243ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T17:41:57.649652Z","caller":"traceutil/trace.go:171","msg":"trace[1936938830] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"407.924437ms","start":"2026-04-20T17:41:57.24168Z","end":"2026-04-20T17:41:57.649605Z","steps":["trace[1936938830] 'process raft request' (duration: 272.5355ms)","trace[1936938830] 'compare' (duration: 134.895653ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:41:57.649702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:41:57.241664Z","time spent":"408.020048ms","remote":"127.0.0.1:47368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T17:41:58.341629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.834456ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T17:41:58.34178Z","caller":"traceutil/trace.go:171","msg":"trace[2069134166] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"603.445378ms","start":"2026-04-20T17:41:57.738319Z","end":"2026-04-20T17:41:58.341765Z","steps":["trace[2069134166] 'process raft request' (duration: 400.654263ms)","trace[2069134166] 'compare' (duration: 202.504473ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:41:58.34188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:41:57.738302Z","time spent":"603.519719ms","remote":"127.0.0.1:47076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T17:41:58.362282Z","caller":"traceutil/trace.go:171","msg":"trace[1348337681] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"268.033824ms","start":"2026-04-20T17:41:58.094156Z","end":"2026-04-20T17:41:58.362189Z","steps":["trace[1348337681] 'process raft request' (duration: 267.529599ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:41:58.369324Z","caller":"traceutil/trace.go:171","msg":"trace[983546789] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:584; }","duration":"137.392638ms","start":"2026-04-20T17:41:58.231905Z","end":"2026-04-20T17:41:58.369298Z","steps":["trace[983546789] 'read index received' (duration: 129.606097ms)","trace[983546789] 'applied index is now lower than readState.Index' (duration: 7.785341ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:41:58.369528Z","caller":"traceutil/trace.go:171","msg":"trace[877741331] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"156.201126ms","start":"2026-04-20T17:41:58.213298Z","end":"2026-04-20T17:41:58.369499Z","steps":["trace[877741331] 'process raft request' (duration: 155.903004ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:41:58.369886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.939902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T17:41:58.37002Z","caller":"traceutil/trace.go:171","msg":"trace[431971415] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:568; }","duration":"138.071863ms","start":"2026-04-20T17:41:58.231877Z","end":"2026-04-20T17:41:58.369949Z","steps":["trace[431971415] 'agreement among raft nodes before linearized reading' (duration: 137.602969ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:41:59.061143Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853969950236817322,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T17:41:59.39633Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.020350033s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-20T17:41:59.878949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.312025ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T17:41:59.879184Z","caller":"traceutil/trace.go:171","msg":"trace[1984300855] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"1.503275003s","start":"2026-04-20T17:41:58.375864Z","end":"2026-04-20T17:41:59.879139Z","steps":["trace[1984300855] 'process raft request' (duration: 1.020699106s)","trace[1984300855] 'compare' (duration: 482.186594ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:41:59.879375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:41:58.375839Z","time spent":"1.503427366s","remote":"127.0.0.1:47138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T17:41:59.880478Z","caller":"traceutil/trace.go:171","msg":"trace[824300043] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"1.455508034s","start":"2026-04-20T17:41:58.424925Z","end":"2026-04-20T17:41:59.880433Z","steps":["trace[824300043] 'process raft request' (duration: 1.454942139s)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:41:59.880508Z","caller":"traceutil/trace.go:171","msg":"trace[1518539137] linearizableReadLoop","detail":"{readStateIndex:588; appliedIndex:586; }","duration":"1.319930682s","start":"2026-04-20T17:41:58.56055Z","end":"2026-04-20T17:41:59.88048Z","steps":["trace[1518539137] 'read index received' (duration: 836.004923ms)","trace[1518539137] 'applied index is now lower than readState.Index' (duration: 483.923679ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:41:59.880774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:41:58.424891Z","time spent":"1.455692635s","remote":"127.0.0.1:47120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T17:41:59.880886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.320325816s","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-20T17:41:59.880931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.644307ms","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-20T17:41:59.880955Z","caller":"traceutil/trace.go:171","msg":"trace[248528460] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:570; }","duration":"1.320440377s","start":"2026-04-20T17:41:58.560492Z","end":"2026-04-20T17:41:59.880932Z","steps":["trace[248528460] 'agreement among raft nodes before linearized reading' (duration: 1.320171215s)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:41:59.880996Z","caller":"traceutil/trace.go:171","msg":"trace[1451799517] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:570; }","duration":"207.721238ms","start":"2026-04-20T17:41:59.673236Z","end":"2026-04-20T17:41:59.880958Z","steps":["trace[1451799517] 'agreement among raft nodes before linearized reading' (duration: 207.591497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:41:59.880792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.319384798s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-20T17:41:59.881069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:41:58.560466Z","time spent":"1.320520788s","remote":"127.0.0.1:47138","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-20T17:41:59.88113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.136544842s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.116\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-20T17:41:59.881124Z","caller":"traceutil/trace.go:171","msg":"trace[2093046001] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:570; }","duration":"1.319766771s","start":"2026-04-20T17:41:58.561329Z","end":"2026-04-20T17:41:59.881096Z","steps":["trace[2093046001] 'agreement among raft nodes before linearized reading' (duration: 1.319353447s)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:41:59.881278Z","caller":"traceutil/trace.go:171","msg":"trace[2008151788] range","detail":"{range_begin:/registry/masterleases/199.204.45.116; range_end:; response_count:1; response_revision:570; }","duration":"1.136669413s","start":"2026-04-20T17:41:58.74452Z","end":"2026-04-20T17:41:59.88119Z","steps":["trace[2008151788] 'agreement among raft nodes before linearized reading' (duration: 1.136507361s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:41:59.881336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:41:58.744494Z","time spent":"1.136829504s","remote":"127.0.0.1:46926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.116\" "} {"level":"warn","ts":"2026-04-20T17:41:59.881314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:41:58.561302Z","time spent":"1.319909732s","remote":"127.0.0.1:47138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T17:42:10.158904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.24606ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T17:42:10.159101Z","caller":"traceutil/trace.go:171","msg":"trace[1765726343] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"153.33299ms","start":"2026-04-20T17:42:10.00575Z","end":"2026-04-20T17:42:10.159083Z","steps":["trace[1765726343] 'process raft request' (duration: 153.255409ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:42:10.159105Z","caller":"traceutil/trace.go:171","msg":"trace[1396315021] linearizableReadLoop","detail":"{readStateIndex:712; appliedIndex:710; }","duration":"213.699131ms","start":"2026-04-20T17:42:09.945382Z","end":"2026-04-20T17:42:10.159081Z","steps":["trace[1396315021] 'read index received' (duration: 8.486821ms)","trace[1396315021] 'applied index is now lower than readState.Index' (duration: 205.20946ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:42:10.159099Z","caller":"traceutil/trace.go:171","msg":"trace[1332767345] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"217.751285ms","start":"2026-04-20T17:42:09.941307Z","end":"2026-04-20T17:42:10.159058Z","steps":["trace[1332767345] 'process raft request' (duration: 102.264583ms)","trace[1332767345] 'compare' (duration: 115.074028ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:42:10.159307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.088033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-20T17:42:10.159305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.918563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-20T17:42:10.159343Z","caller":"traceutil/trace.go:171","msg":"trace[646029856] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:691; }","duration":"157.170723ms","start":"2026-04-20T17:42:10.002162Z","end":"2026-04-20T17:42:10.159333Z","steps":["trace[646029856] 'agreement among raft nodes before linearized reading' (duration: 157.035192ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:42:10.159354Z","caller":"traceutil/trace.go:171","msg":"trace[332769691] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:691; }","duration":"213.987033ms","start":"2026-04-20T17:42:09.945354Z","end":"2026-04-20T17:42:10.159341Z","steps":["trace[332769691] 'agreement among raft nodes before linearized reading' (duration: 213.815512ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:42:10.277525Z","caller":"traceutil/trace.go:171","msg":"trace[494537157] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"113.544025ms","start":"2026-04-20T17:42:10.163937Z","end":"2026-04-20T17:42:10.277481Z","steps":["trace[494537157] 'process raft request' (duration: 113.164252ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:42:10.318767Z","caller":"traceutil/trace.go:171","msg":"trace[1925507942] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"154.753113ms","start":"2026-04-20T17:42:10.163978Z","end":"2026-04-20T17:42:10.318731Z","steps":["trace[1925507942] 'process raft request' (duration: 154.4631ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:42:10.319162Z","caller":"traceutil/trace.go:171","msg":"trace[744329089] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"153.656123ms","start":"2026-04-20T17:42:10.165468Z","end":"2026-04-20T17:42:10.319124Z","steps":["trace[744329089] 'process raft request' (duration: 153.138358ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:42:10.319334Z","caller":"traceutil/trace.go:171","msg":"trace[1619509808] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"151.678527ms","start":"2026-04-20T17:42:10.167595Z","end":"2026-04-20T17:42:10.319274Z","steps":["trace[1619509808] 'process raft request' (duration: 151.385025ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:42:10.975423Z","caller":"traceutil/trace.go:171","msg":"trace[1565051974] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"132.553526ms","start":"2026-04-20T17:42:10.842836Z","end":"2026-04-20T17:42:10.97539Z","steps":["trace[1565051974] 'process raft request' (duration: 132.353964ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:42:17.019812Z","caller":"traceutil/trace.go:171","msg":"trace[1657168939] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"177.294722ms","start":"2026-04-20T17:42:16.842498Z","end":"2026-04-20T17:42:17.019793Z","steps":["trace[1657168939] 'process raft request' (duration: 177.161351ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:42:17.511161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.881419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T17:42:17.511236Z","caller":"traceutil/trace.go:171","msg":"trace[1158466950] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:740; }","duration":"278.988771ms","start":"2026-04-20T17:42:17.232229Z","end":"2026-04-20T17:42:17.511217Z","steps":["trace[1158466950] 'range keys from in-memory index tree' (duration: 278.829029ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:42:21.504195Z","caller":"traceutil/trace.go:171","msg":"trace[972418520] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"147.43089ms","start":"2026-04-20T17:42:21.356747Z","end":"2026-04-20T17:42:21.504178Z","steps":["trace[972418520] 'process raft request' (duration: 146.446806ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:42:22.992473Z","caller":"traceutil/trace.go:171","msg":"trace[941016681] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"132.369034ms","start":"2026-04-20T17:42:22.860052Z","end":"2026-04-20T17:42:22.992421Z","steps":["trace[941016681] 'process raft request' (duration: 132.067746ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:42:36.637608Z","caller":"traceutil/trace.go:171","msg":"trace[128626656] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"217.707491ms","start":"2026-04-20T17:42:36.419873Z","end":"2026-04-20T17:42:36.63758Z","steps":["trace[128626656] 'process raft request' (duration: 216.055006ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:42:36.911952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.746601ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T17:42:36.912033Z","caller":"traceutil/trace.go:171","msg":"trace[668026043] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"270.338557ms","start":"2026-04-20T17:42:36.641674Z","end":"2026-04-20T17:42:36.912013Z","steps":["trace[668026043] 'process raft request' (duration: 159.464343ms)","trace[668026043] 'compare' (duration: 110.602568ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:42:39.022514Z","caller":"traceutil/trace.go:171","msg":"trace[436410965] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"109.398747ms","start":"2026-04-20T17:42:38.913101Z","end":"2026-04-20T17:42:39.0225Z","steps":["trace[436410965] 'process raft request' (duration: 109.235994ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:42:39.022518Z","caller":"traceutil/trace.go:171","msg":"trace[2141204965] linearizableReadLoop","detail":"{readStateIndex:970; appliedIndex:969; }","duration":"107.463926ms","start":"2026-04-20T17:42:38.915001Z","end":"2026-04-20T17:42:39.022465Z","steps":["trace[2141204965] 'read index received' (duration: 107.257852ms)","trace[2141204965] 'applied index is now lower than readState.Index' (duration: 205.094µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:42:39.022769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.756332ms","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-20T17:42:39.022859Z","caller":"traceutil/trace.go:171","msg":"trace[1677442243] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:941; }","duration":"107.847325ms","start":"2026-04-20T17:42:38.914966Z","end":"2026-04-20T17:42:39.022813Z","steps":["trace[1677442243] 'agreement among raft nodes before linearized reading' (duration: 107.593959ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:42:39.196351Z","caller":"traceutil/trace.go:171","msg":"trace[1054585433] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"169.386661ms","start":"2026-04-20T17:42:39.026939Z","end":"2026-04-20T17:42:39.196325Z","steps":["trace[1054585433] 'process raft request' (duration: 148.980142ms)","trace[1054585433] 'compare' (duration: 19.988261ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:42:39.201833Z","caller":"traceutil/trace.go:171","msg":"trace[372042666] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"173.198011ms","start":"2026-04-20T17:42:39.02862Z","end":"2026-04-20T17:42:39.201818Z","steps":["trace[372042666] 'process raft request' (duration: 173.117549ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:42:44.434163Z","caller":"traceutil/trace.go:171","msg":"trace[714354541] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"116.038906ms","start":"2026-04-20T17:42:44.318103Z","end":"2026-04-20T17:42:44.434142Z","steps":["trace[714354541] 'process raft request' (duration: 115.882283ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:42:46.787373Z","caller":"traceutil/trace.go:171","msg":"trace[32173609] linearizableReadLoop","detail":"{readStateIndex:997; appliedIndex:996; }","duration":"302.218191ms","start":"2026-04-20T17:42:46.485131Z","end":"2026-04-20T17:42:46.78735Z","steps":["trace[32173609] 'read index received' (duration: 302.005867ms)","trace[32173609] 'applied index is now lower than readState.Index' (duration: 211.384µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:42:46.787471Z","caller":"traceutil/trace.go:171","msg":"trace[2090517331] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"348.175911ms","start":"2026-04-20T17:42:46.439263Z","end":"2026-04-20T17:42:46.787439Z","steps":["trace[2090517331] 'process raft request' (duration: 347.934846ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:42:46.78764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:42:46.439238Z","time spent":"348.304673ms","remote":"127.0.0.1:47138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T17:42:46.78766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.518876ms","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-20T17:42:46.787705Z","caller":"traceutil/trace.go:171","msg":"trace[1164828383] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:967; }","duration":"302.611869ms","start":"2026-04-20T17:42:46.48508Z","end":"2026-04-20T17:42:46.787692Z","steps":["trace[1164828383] 'agreement among raft nodes before linearized reading' (duration: 302.455825ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:42:46.787745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:42:46.485061Z","time spent":"302.676579ms","remote":"127.0.0.1:47138","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-20T17:42:46.787913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.812107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T17:42:46.787958Z","caller":"traceutil/trace.go:171","msg":"trace[628722566] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:967; }","duration":"139.905289ms","start":"2026-04-20T17:42:46.648041Z","end":"2026-04-20T17:42:46.787946Z","steps":["trace[628722566] 'agreement among raft nodes before linearized reading' (duration: 139.826547ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:42:52.117038Z","caller":"traceutil/trace.go:171","msg":"trace[1920564099] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"285.831551ms","start":"2026-04-20T17:42:51.831187Z","end":"2026-04-20T17:42:52.117019Z","steps":["trace[1920564099] 'process raft request' (duration: 285.705878ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:43:02.482541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.837014ms","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-20T17:43:02.482641Z","caller":"traceutil/trace.go:171","msg":"trace[603745030] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1022; }","duration":"117.980046ms","start":"2026-04-20T17:43:02.364634Z","end":"2026-04-20T17:43:02.482614Z","steps":["trace[603745030] 'range keys from in-memory index tree' (duration: 117.667941ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:43:02.647053Z","caller":"traceutil/trace.go:171","msg":"trace[1474879682] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"160.196122ms","start":"2026-04-20T17:43:02.486828Z","end":"2026-04-20T17:43:02.647025Z","steps":["trace[1474879682] 'process raft request' (duration: 160.04652ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:43:02.760773Z","caller":"traceutil/trace.go:171","msg":"trace[955593744] linearizableReadLoop","detail":"{readStateIndex:1058; appliedIndex:1056; }","duration":"150.705267ms","start":"2026-04-20T17:43:02.610046Z","end":"2026-04-20T17:43:02.760751Z","steps":["trace[955593744] 'read index received' (duration: 36.944334ms)","trace[955593744] 'applied index is now lower than readState.Index' (duration: 113.759973ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:43:02.76088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.82789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T17:43:02.760899Z","caller":"traceutil/trace.go:171","msg":"trace[766344613] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1024; }","duration":"150.90017ms","start":"2026-04-20T17:43:02.609993Z","end":"2026-04-20T17:43:02.760893Z","steps":["trace[766344613] 'agreement among raft nodes before linearized reading' (duration: 150.828909ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:43:02.760928Z","caller":"traceutil/trace.go:171","msg":"trace[431636260] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"221.791456ms","start":"2026-04-20T17:43:02.539086Z","end":"2026-04-20T17:43:02.760877Z","steps":["trace[431636260] 'process raft request' (duration: 191.797153ms)","trace[431636260] 'compare' (duration: 29.753229ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:44:07.192689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.165089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T17:44:07.192776Z","caller":"traceutil/trace.go:171","msg":"trace[557634893] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1422; }","duration":"105.303271ms","start":"2026-04-20T17:44:07.087458Z","end":"2026-04-20T17:44:07.192761Z","steps":["trace[557634893] 'count revisions from in-memory index tree' (duration: 105.092978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:44:07.192845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.698604ms","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-20T17:44:07.192935Z","caller":"traceutil/trace.go:171","msg":"trace[1954052175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1422; }","duration":"160.832816ms","start":"2026-04-20T17:44:07.032083Z","end":"2026-04-20T17:44:07.192916Z","steps":["trace[1954052175] 'range keys from in-memory index tree' (duration: 160.525482ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:44:12.894206Z","caller":"traceutil/trace.go:171","msg":"trace[1274784989] transaction","detail":"{read_only:false; response_revision:1488; number_of_response:1; }","duration":"192.654298ms","start":"2026-04-20T17:44:12.701519Z","end":"2026-04-20T17:44:12.894173Z","steps":["trace[1274784989] 'process raft request' (duration: 192.507786ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:44:41.500763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.1696ms","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-20T17:44:41.500881Z","caller":"traceutil/trace.go:171","msg":"trace[123719114] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2169; }","duration":"105.334002ms","start":"2026-04-20T17:44:41.395521Z","end":"2026-04-20T17:44:41.500855Z","steps":["trace[123719114] 'range keys from in-memory index tree' (duration: 104.970988ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:44:41.527112Z","caller":"traceutil/trace.go:171","msg":"trace[1264374960] linearizableReadLoop","detail":"{readStateIndex:2258; appliedIndex:2257; }","duration":"113.083336ms","start":"2026-04-20T17:44:41.414013Z","end":"2026-04-20T17:44:41.527096Z","steps":["trace[1264374960] 'read index received' (duration: 112.936245ms)","trace[1264374960] 'applied index is now lower than readState.Index' (duration: 146.131µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:44:41.527161Z","caller":"traceutil/trace.go:171","msg":"trace[709497839] transaction","detail":"{read_only:false; response_revision:2170; number_of_response:1; }","duration":"129.457426ms","start":"2026-04-20T17:44:41.397681Z","end":"2026-04-20T17:44:41.527138Z","steps":["trace[709497839] 'process raft request' (duration: 129.271974ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:44:41.527303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.293898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1c91d715-7f18-4bca-9697-b9384c523c9b\" ","response":"range_response_count:1 size:3617"} {"level":"info","ts":"2026-04-20T17:44:41.527361Z","caller":"traceutil/trace.go:171","msg":"trace[1651432120] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1c91d715-7f18-4bca-9697-b9384c523c9b; range_end:; response_count:1; response_revision:2170; }","duration":"113.41022ms","start":"2026-04-20T17:44:41.413943Z","end":"2026-04-20T17:44:41.527353Z","steps":["trace[1651432120] 'agreement among raft nodes before linearized reading' (duration: 113.276529ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:44:41.786813Z","caller":"traceutil/trace.go:171","msg":"trace[552322980] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"255.243991ms","start":"2026-04-20T17:44:41.531544Z","end":"2026-04-20T17:44:41.786788Z","steps":["trace[552322980] 'process raft request' (duration: 255.08741ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:44:41.852189Z","caller":"traceutil/trace.go:171","msg":"trace[1825775473] linearizableReadLoop","detail":"{readStateIndex:2260; appliedIndex:2258; }","duration":"203.117111ms","start":"2026-04-20T17:44:41.649031Z","end":"2026-04-20T17:44:41.852148Z","steps":["trace[1825775473] 'read index received' (duration: 137.620985ms)","trace[1825775473] 'applied index is now lower than readState.Index' (duration: 65.494926ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:44:41.852302Z","caller":"traceutil/trace.go:171","msg":"trace[1095916482] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"317.906336ms","start":"2026-04-20T17:44:41.534353Z","end":"2026-04-20T17:44:41.85226Z","steps":["trace[1095916482] 'process raft request' (duration: 317.629293ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:44:41.852458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.398544ms","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-20T17:44:41.852508Z","caller":"traceutil/trace.go:171","msg":"trace[190740044] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2172; }","duration":"203.527776ms","start":"2026-04-20T17:44:41.648968Z","end":"2026-04-20T17:44:41.852496Z","steps":["trace[190740044] 'agreement among raft nodes before linearized reading' (duration: 203.334294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:44:41.852666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:44:41.534326Z","time spent":"318.22398ms","remote":"127.0.0.1:47076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3719,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T17:44:41.852758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.502004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-20T17:44:41.852825Z","caller":"traceutil/trace.go:171","msg":"trace[685999745] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2172; }","duration":"148.605755ms","start":"2026-04-20T17:44:41.704199Z","end":"2026-04-20T17:44:41.852804Z","steps":["trace[685999745] 'agreement among raft nodes before linearized reading' (duration: 148.456164ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:44:41.9906Z","caller":"traceutil/trace.go:171","msg":"trace[756786601] linearizableReadLoop","detail":"{readStateIndex:2261; appliedIndex:2260; }","duration":"130.72564ms","start":"2026-04-20T17:44:41.859849Z","end":"2026-04-20T17:44:41.990574Z","steps":["trace[756786601] 'read index received' (duration: 32.721528ms)","trace[756786601] 'applied index is now lower than readState.Index' (duration: 98.002912ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:44:41.990624Z","caller":"traceutil/trace.go:171","msg":"trace[2089401779] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"131.257726ms","start":"2026-04-20T17:44:41.859324Z","end":"2026-04-20T17:44:41.990582Z","steps":["trace[2089401779] 'process raft request' (duration: 33.406026ms)","trace[2089401779] 'compare' (duration: 97.622357ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:44:41.990781Z","caller":"traceutil/trace.go:171","msg":"trace[1005797997] transaction","detail":"{read_only:false; response_revision:2174; number_of_response:1; }","duration":"130.75311ms","start":"2026-04-20T17:44:41.860013Z","end":"2026-04-20T17:44:41.990766Z","steps":["trace[1005797997] 'process raft request' (duration: 130.521247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:44:41.990965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.098244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-messaging-topology-operator-6f465c979f-n2xtf\" ","response":"range_response_count:1 size:5815"} {"level":"info","ts":"2026-04-20T17:44:41.991062Z","caller":"traceutil/trace.go:171","msg":"trace[1588568107] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-messaging-topology-operator-6f465c979f-n2xtf; range_end:; response_count:1; response_revision:2174; }","duration":"131.249755ms","start":"2026-04-20T17:44:41.859786Z","end":"2026-04-20T17:44:41.991036Z","steps":["trace[1588568107] 'agreement among raft nodes before linearized reading' (duration: 130.940361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:44:42.678727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.978472ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T17:44:42.678835Z","caller":"traceutil/trace.go:171","msg":"trace[177076193] linearizableReadLoop","detail":"{readStateIndex:2264; appliedIndex:2263; }","duration":"602.920807ms","start":"2026-04-20T17:44:42.075896Z","end":"2026-04-20T17:44:42.678817Z","steps":["trace[177076193] 'read index received' (duration: 49.670522ms)","trace[177076193] 'applied index is now lower than readState.Index' (duration: 553.248515ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:44:42.678909Z","caller":"traceutil/trace.go:171","msg":"trace[173172584] transaction","detail":"{read_only:false; response_revision:2176; number_of_response:1; }","duration":"679.381821ms","start":"2026-04-20T17:44:41.999515Z","end":"2026-04-20T17:44:42.678897Z","steps":["trace[173172584] 'process raft request' (duration: 126.133166ms)","trace[173172584] 'compare' (duration: 552.557538ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:44:42.678972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:44:41.999455Z","time spent":"679.483293ms","remote":"127.0.0.1:47076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6129,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T17:44:42.67912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.22628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1c91d715-7f18-4bca-9697-b9384c523c9b\" ","response":"range_response_count:1 size:3734"} {"level":"info","ts":"2026-04-20T17:44:42.6792Z","caller":"traceutil/trace.go:171","msg":"trace[1242814271] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1c91d715-7f18-4bca-9697-b9384c523c9b; range_end:; response_count:1; response_revision:2176; }","duration":"603.386942ms","start":"2026-04-20T17:44:42.075794Z","end":"2026-04-20T17:44:42.67918Z","steps":["trace[1242814271] 'agreement among raft nodes before linearized reading' (duration: 603.16399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:44:42.679258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:44:42.075749Z","time spent":"603.495364ms","remote":"127.0.0.1:47076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3756,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1c91d715-7f18-4bca-9697-b9384c523c9b\" "} {"level":"warn","ts":"2026-04-20T17:44:43.204849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.980313ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T17:44:43.204981Z","caller":"traceutil/trace.go:171","msg":"trace[17310349] linearizableReadLoop","detail":"{readStateIndex:2265; appliedIndex:2264; }","duration":"526.084903ms","start":"2026-04-20T17:44:42.67886Z","end":"2026-04-20T17:44:43.204945Z","steps":["trace[17310349] 'read index received' (duration: 242.262862ms)","trace[17310349] 'applied index is now lower than readState.Index' (duration: 283.820821ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:44:43.205049Z","caller":"traceutil/trace.go:171","msg":"trace[1776069415] transaction","detail":"{read_only:false; response_revision:2177; number_of_response:1; }","duration":"630.645934ms","start":"2026-04-20T17:44:42.574392Z","end":"2026-04-20T17:44:43.205038Z","steps":["trace[1776069415] 'process raft request' (duration: 346.384048ms)","trace[1776069415] 'compare' (duration: 283.873392ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:44:43.205105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:44:42.574355Z","time spent":"630.719844ms","remote":"127.0.0.1:47138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T17:44:43.205371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.443162ms","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-20T17:44:43.20541Z","caller":"traceutil/trace.go:171","msg":"trace[1627223198] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2177; }","duration":"696.521213ms","start":"2026-04-20T17:44:42.508878Z","end":"2026-04-20T17:44:43.205399Z","steps":["trace[1627223198] 'agreement among raft nodes before linearized reading' (duration: 696.395562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:44:43.205444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:44:42.508857Z","time spent":"696.580463ms","remote":"127.0.0.1:43830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-20T17:44:43.205672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"974.449125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T17:44:43.205745Z","caller":"traceutil/trace.go:171","msg":"trace[993650888] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2177; }","duration":"974.586056ms","start":"2026-04-20T17:44:42.231138Z","end":"2026-04-20T17:44:43.205725Z","steps":["trace[993650888] 'agreement among raft nodes before linearized reading' (duration: 974.465434ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:44:43.205774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:44:42.231107Z","time spent":"974.661487ms","remote":"127.0.0.1:46918","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-20T17:44:43.205894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.124770894s","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-20T17:44:43.205958Z","caller":"traceutil/trace.go:171","msg":"trace[814108276] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2177; }","duration":"1.124877676s","start":"2026-04-20T17:44:42.081067Z","end":"2026-04-20T17:44:43.205944Z","steps":["trace[814108276] 'agreement among raft nodes before linearized reading' (duration: 1.12430674s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:44:43.206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:44:42.081039Z","time spent":"1.124952026s","remote":"127.0.0.1:47138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-20T17:44:43.206591Z","caller":"traceutil/trace.go:171","msg":"trace[585098407] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"519.728954ms","start":"2026-04-20T17:44:42.686845Z","end":"2026-04-20T17:44:43.206574Z","steps":["trace[585098407] 'process raft request' (duration: 519.673153ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:44:43.206649Z","caller":"traceutil/trace.go:171","msg":"trace[1425758273] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"521.20342ms","start":"2026-04-20T17:44:42.68542Z","end":"2026-04-20T17:44:43.206623Z","steps":["trace[1425758273] 'process raft request' (duration: 520.984568ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:44:43.206706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:44:42.686821Z","time spent":"519.818674ms","remote":"127.0.0.1:47148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1846,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T17:44:43.206794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:44:42.685395Z","time spent":"521.331561ms","remote":"127.0.0.1:47060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1230,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T17:44:43.209943Z","caller":"traceutil/trace.go:171","msg":"trace[1358593697] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"145.2147ms","start":"2026-04-20T17:44:43.064706Z","end":"2026-04-20T17:44:43.20992Z","steps":["trace[1358593697] 'process raft request' (duration: 144.979348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:44:43.210222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.107976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1c60db8c-35e0-4427-bb79-796c4635762d\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-20T17:44:43.210248Z","caller":"traceutil/trace.go:171","msg":"trace[1031506007] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1c60db8c-35e0-4427-bb79-796c4635762d; range_end:; response_count:1; response_revision:2180; }","duration":"187.180467ms","start":"2026-04-20T17:44:43.02306Z","end":"2026-04-20T17:44:43.210241Z","steps":["trace[1031506007] 'agreement among raft nodes before linearized reading' (duration: 187.106986ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:44:52.626184Z","caller":"traceutil/trace.go:171","msg":"trace[1539836102] transaction","detail":"{read_only:false; response_revision:2286; number_of_response:1; }","duration":"103.074689ms","start":"2026-04-20T17:44:52.523085Z","end":"2026-04-20T17:44:52.626159Z","steps":["trace[1539836102] 'process raft request' (duration: 102.360421ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:44:55.422607Z","caller":"traceutil/trace.go:171","msg":"trace[739230833] linearizableReadLoop","detail":"{readStateIndex:2396; appliedIndex:2395; }","duration":"191.131564ms","start":"2026-04-20T17:44:55.23145Z","end":"2026-04-20T17:44:55.422581Z","steps":["trace[739230833] 'read index received' (duration: 190.922972ms)","trace[739230833] 'applied index is now lower than readState.Index' (duration: 207.542µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:44:55.422648Z","caller":"traceutil/trace.go:171","msg":"trace[1952173392] transaction","detail":"{read_only:false; response_revision:2303; number_of_response:1; }","duration":"324.395316ms","start":"2026-04-20T17:44:55.098221Z","end":"2026-04-20T17:44:55.422616Z","steps":["trace[1952173392] 'process raft request' (duration: 324.172514ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:44:55.422778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:44:55.098194Z","time spent":"324.525908ms","remote":"127.0.0.1:47138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T17:44:55.422802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.321806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T17:44:55.422872Z","caller":"traceutil/trace.go:171","msg":"trace[1689378775] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2303; }","duration":"191.432628ms","start":"2026-04-20T17:44:55.231419Z","end":"2026-04-20T17:44:55.422852Z","steps":["trace[1689378775] 'agreement among raft nodes before linearized reading' (duration: 191.288776ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:44:55.475047Z","caller":"traceutil/trace.go:171","msg":"trace[150834709] transaction","detail":"{read_only:false; response_revision:2304; number_of_response:1; }","duration":"223.873182ms","start":"2026-04-20T17:44:55.251152Z","end":"2026-04-20T17:44:55.475025Z","steps":["trace[150834709] 'process raft request' (duration: 181.480213ms)","trace[150834709] 'compare' (duration: 39.085644ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:44:55.475228Z","caller":"traceutil/trace.go:171","msg":"trace[1914331810] transaction","detail":"{read_only:false; response_revision:2305; number_of_response:1; }","duration":"181.574933ms","start":"2026-04-20T17:44:55.293647Z","end":"2026-04-20T17:44:55.475222Z","steps":["trace[1914331810] 'process raft request' (duration: 181.047347ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:44:55.475491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.241448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-20T17:44:55.475481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.075311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-20T17:44:55.475532Z","caller":"traceutil/trace.go:171","msg":"trace[701819412] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2305; }","duration":"210.316798ms","start":"2026-04-20T17:44:55.265202Z","end":"2026-04-20T17:44:55.475519Z","steps":["trace[701819412] 'agreement among raft nodes before linearized reading' (duration: 210.206767ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:44:55.475545Z","caller":"traceutil/trace.go:171","msg":"trace[30688223] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2305; }","duration":"203.187692ms","start":"2026-04-20T17:44:55.272342Z","end":"2026-04-20T17:44:55.47553Z","steps":["trace[30688223] 'agreement among raft nodes before linearized reading' (duration: 202.98887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:44:55.475666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.86643ms","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-20T17:44:55.475692Z","caller":"traceutil/trace.go:171","msg":"trace[1031079143] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2305; }","duration":"169.92665ms","start":"2026-04-20T17:44:55.305759Z","end":"2026-04-20T17:44:55.475686Z","steps":["trace[1031079143] 'agreement among raft nodes before linearized reading' (duration: 169.853279ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:45:02.429933Z","caller":"traceutil/trace.go:171","msg":"trace[1445628656] linearizableReadLoop","detail":"{readStateIndex:2447; appliedIndex:2446; }","duration":"493.185074ms","start":"2026-04-20T17:45:01.936662Z","end":"2026-04-20T17:45:02.429847Z","steps":["trace[1445628656] 'read index received' (duration: 492.942432ms)","trace[1445628656] 'applied index is now lower than readState.Index' (duration: 240.962µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:45:02.430426Z","caller":"traceutil/trace.go:171","msg":"trace[517141454] transaction","detail":"{read_only:false; response_revision:2353; number_of_response:1; }","duration":"544.134718ms","start":"2026-04-20T17:45:01.886236Z","end":"2026-04-20T17:45:02.43037Z","steps":["trace[517141454] 'process raft request' (duration: 543.3309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:45:02.430549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.854291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-d2qbc\" ","response":"range_response_count:1 size:5852"} {"level":"info","ts":"2026-04-20T17:45:02.430643Z","caller":"traceutil/trace.go:171","msg":"trace[56457936] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-d2qbc; range_end:; response_count:1; response_revision:2353; }","duration":"493.983983ms","start":"2026-04-20T17:45:01.936633Z","end":"2026-04-20T17:45:02.430617Z","steps":["trace[56457936] 'agreement among raft nodes before linearized reading' (duration: 493.653519ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:45:02.430696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:45:01.936619Z","time spent":"494.068293ms","remote":"127.0.0.1:47076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":5874,"request content":"key:\"/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-d2qbc\" "} {"level":"warn","ts":"2026-04-20T17:45:02.43078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:45:01.886214Z","time spent":"544.31409ms","remote":"127.0.0.1:47138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T17:45:02.488563Z","caller":"traceutil/trace.go:171","msg":"trace[580931851] transaction","detail":"{read_only:false; response_revision:2354; number_of_response:1; }","duration":"404.084553ms","start":"2026-04-20T17:45:02.084449Z","end":"2026-04-20T17:45:02.488533Z","steps":["trace[580931851] 'process raft request' (duration: 401.842029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:45:02.488624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.217598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-20T17:45:02.488686Z","caller":"traceutil/trace.go:171","msg":"trace[1077672735] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2354; }","duration":"393.32354ms","start":"2026-04-20T17:45:02.095346Z","end":"2026-04-20T17:45:02.48867Z","steps":["trace[1077672735] 'agreement among raft nodes before linearized reading' (duration: 393.094318ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:45:02.488709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:45:02.084419Z","time spent":"404.211234ms","remote":"127.0.0.1:47138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T17:45:02.488723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:45:02.095289Z","time spent":"393.425751ms","remote":"127.0.0.1:47138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T17:45:02.488889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.824213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T17:45:02.488923Z","caller":"traceutil/trace.go:171","msg":"trace[1136904536] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2354; }","duration":"255.906804ms","start":"2026-04-20T17:45:02.233008Z","end":"2026-04-20T17:45:02.488915Z","steps":["trace[1136904536] 'agreement among raft nodes before linearized reading' (duration: 255.858353ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:45:02.489138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.833618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-20T17:45:02.489177Z","caller":"traceutil/trace.go:171","msg":"trace[1616913810] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2354; }","duration":"355.910629ms","start":"2026-04-20T17:45:02.133256Z","end":"2026-04-20T17:45:02.489167Z","steps":["trace[1616913810] 'agreement among raft nodes before linearized reading' (duration: 355.792598ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:45:02.489198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:45:02.133228Z","time spent":"355.96542ms","remote":"127.0.0.1:47138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-20T17:45:03.238845Z","caller":"traceutil/trace.go:171","msg":"trace[580151568] transaction","detail":"{read_only:false; response_revision:2361; number_of_response:1; }","duration":"388.242682ms","start":"2026-04-20T17:45:02.850566Z","end":"2026-04-20T17:45:03.238809Z","steps":["trace[580151568] 'process raft request' (duration: 387.934519ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:45:03.239026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:45:02.850553Z","time spent":"388.370143ms","remote":"127.0.0.1:47076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5915,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T17:45:15.83392Z","caller":"traceutil/trace.go:171","msg":"trace[1311183674] linearizableReadLoop","detail":"{readStateIndex:2534; appliedIndex:2533; }","duration":"142.091232ms","start":"2026-04-20T17:45:15.691796Z","end":"2026-04-20T17:45:15.833888Z","steps":["trace[1311183674] 'read index received' (duration: 141.893ms)","trace[1311183674] 'applied index is now lower than readState.Index' (duration: 196.742µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:45:15.833978Z","caller":"traceutil/trace.go:171","msg":"trace[1763127385] transaction","detail":"{read_only:false; response_revision:2436; number_of_response:1; }","duration":"142.618657ms","start":"2026-04-20T17:45:15.691323Z","end":"2026-04-20T17:45:15.833941Z","steps":["trace[1763127385] 'process raft request' (duration: 142.399425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:45:15.834154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.337415ms","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-20T17:45:15.834212Z","caller":"traceutil/trace.go:171","msg":"trace[1378913907] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2436; }","duration":"142.414336ms","start":"2026-04-20T17:45:15.691785Z","end":"2026-04-20T17:45:15.834199Z","steps":["trace[1378913907] 'agreement among raft nodes before linearized reading' (duration: 142.224514ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:45:15.85833Z","caller":"traceutil/trace.go:171","msg":"trace[2006716525] transaction","detail":"{read_only:false; response_revision:2437; number_of_response:1; }","duration":"165.558402ms","start":"2026-04-20T17:45:15.692749Z","end":"2026-04-20T17:45:15.858307Z","steps":["trace[2006716525] 'process raft request' (duration: 165.36804ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:45:21.524799Z","caller":"traceutil/trace.go:171","msg":"trace[598061525] transaction","detail":"{read_only:false; response_revision:2480; number_of_response:1; }","duration":"177.617788ms","start":"2026-04-20T17:45:21.347152Z","end":"2026-04-20T17:45:21.52477Z","steps":["trace[598061525] 'process raft request' (duration: 177.420506ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:45:21.917616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.668236ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T17:45:21.918012Z","caller":"traceutil/trace.go:171","msg":"trace[619639911] transaction","detail":"{read_only:false; response_revision:2481; number_of_response:1; }","duration":"329.646848ms","start":"2026-04-20T17:45:21.588224Z","end":"2026-04-20T17:45:21.91787Z","steps":["trace[619639911] 'process raft request' (duration: 100.588438ms)","trace[619639911] 'compare' (duration: 227.913358ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:45:21.91816Z","caller":"traceutil/trace.go:171","msg":"trace[699669682] linearizableReadLoop","detail":"{readStateIndex:2581; appliedIndex:2579; }","duration":"250.916931ms","start":"2026-04-20T17:45:21.667217Z","end":"2026-04-20T17:45:21.918134Z","steps":["trace[699669682] 'read index received' (duration: 21.558968ms)","trace[699669682] 'applied index is now lower than readState.Index' (duration: 229.354983ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:45:21.918244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:45:21.588172Z","time spent":"329.933471ms","remote":"127.0.0.1:47138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T17:45:21.91828Z","caller":"traceutil/trace.go:171","msg":"trace[680268434] transaction","detail":"{read_only:false; response_revision:2482; number_of_response:1; }","duration":"328.067642ms","start":"2026-04-20T17:45:21.590155Z","end":"2026-04-20T17:45:21.918222Z","steps":["trace[680268434] 'process raft request' (duration: 327.617958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:45:21.918448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.215743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-04-20T17:45:21.918519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:45:21.590117Z","time spent":"328.267614ms","remote":"127.0.0.1:47138","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-20T17:45:21.918549Z","caller":"traceutil/trace.go:171","msg":"trace[1257720249] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2482; }","duration":"251.370955ms","start":"2026-04-20T17:45:21.667148Z","end":"2026-04-20T17:45:21.918519Z","steps":["trace[1257720249] 'agreement among raft nodes before linearized reading' (duration: 251.117353ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:45:21.977514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.300582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-04-20T17:45:21.97759Z","caller":"traceutil/trace.go:171","msg":"trace[1863577460] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:2483; }","duration":"227.432303ms","start":"2026-04-20T17:45:21.750138Z","end":"2026-04-20T17:45:21.97757Z","steps":["trace[1863577460] 'agreement among raft nodes before linearized reading' (duration: 227.19441ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:45:21.978262Z","caller":"traceutil/trace.go:171","msg":"trace[1065496356] transaction","detail":"{read_only:false; response_revision:2483; number_of_response:1; }","duration":"120.932184ms","start":"2026-04-20T17:45:21.857311Z","end":"2026-04-20T17:45:21.978243Z","steps":["trace[1065496356] 'process raft request' (duration: 119.889624ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:45:22.327169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.257566ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T17:45:22.327673Z","caller":"traceutil/trace.go:171","msg":"trace[1460658788] transaction","detail":"{read_only:false; response_revision:2485; number_of_response:1; }","duration":"344.207611ms","start":"2026-04-20T17:45:21.98344Z","end":"2026-04-20T17:45:22.327648Z","steps":["trace[1460658788] 'process raft request' (duration: 343.848858ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:45:22.327699Z","caller":"traceutil/trace.go:171","msg":"trace[1525491163] transaction","detail":"{read_only:false; response_revision:2484; number_of_response:1; }","duration":"345.220631ms","start":"2026-04-20T17:45:21.982431Z","end":"2026-04-20T17:45:22.327652Z","steps":["trace[1525491163] 'process raft request' (duration: 44.370879ms)","trace[1525491163] 'compare' (duration: 300.094845ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:45:22.327828Z","caller":"traceutil/trace.go:171","msg":"trace[1630141987] linearizableReadLoop","detail":"{readStateIndex:2583; appliedIndex:2582; }","duration":"345.137751ms","start":"2026-04-20T17:45:21.982647Z","end":"2026-04-20T17:45:22.327785Z","steps":["trace[1630141987] 'read index received' (duration: 44.172977ms)","trace[1630141987] 'applied index is now lower than readState.Index' (duration: 300.962364ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:45:22.32793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:45:21.982398Z","time spent":"345.404333ms","remote":"127.0.0.1:47138","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-20T17:45:22.32804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:45:21.983426Z","time spent":"344.308582ms","remote":"127.0.0.1:47138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T17:45:22.328138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.465584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-1c91d715-7f18-4bca-9697-b9384c523c9b\" ","response":"range_response_count:1 size:1293"} {"level":"info","ts":"2026-04-20T17:45:22.328292Z","caller":"traceutil/trace.go:171","msg":"trace[1082871472] range","detail":"{range_begin:/registry/persistentvolumes/pvc-1c91d715-7f18-4bca-9697-b9384c523c9b; range_end:; response_count:1; response_revision:2485; }","duration":"345.652345ms","start":"2026-04-20T17:45:21.982616Z","end":"2026-04-20T17:45:22.328269Z","steps":["trace[1082871472] 'agreement among raft nodes before linearized reading' (duration: 345.277482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:45:22.328339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:45:21.982597Z","time spent":"345.728676ms","remote":"127.0.0.1:47036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1315,"request content":"key:\"/registry/persistentvolumes/pvc-1c91d715-7f18-4bca-9697-b9384c523c9b\" "} {"level":"info","ts":"2026-04-20T17:45:23.138759Z","caller":"traceutil/trace.go:171","msg":"trace[755882249] transaction","detail":"{read_only:false; response_revision:2488; number_of_response:1; }","duration":"486.388517ms","start":"2026-04-20T17:45:22.652332Z","end":"2026-04-20T17:45:23.13872Z","steps":["trace[755882249] 'process raft request' (duration: 468.423066ms)","trace[755882249] 'compare' (duration: 17.779369ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:45:23.139266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:45:22.652309Z","time spent":"486.548508ms","remote":"127.0.0.1:47138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T17:45:23.155428Z","caller":"traceutil/trace.go:171","msg":"trace[496106320] linearizableReadLoop","detail":"{readStateIndex:2588; appliedIndex:2586; }","duration":"417.971524ms","start":"2026-04-20T17:45:22.737431Z","end":"2026-04-20T17:45:23.155403Z","steps":["trace[496106320] 'read index received' (duration: 383.317825ms)","trace[496106320] 'applied index is now lower than readState.Index' (duration: 34.652729ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:45:23.155685Z","caller":"traceutil/trace.go:171","msg":"trace[718590727] transaction","detail":"{read_only:false; response_revision:2489; number_of_response:1; }","duration":"503.165926ms","start":"2026-04-20T17:45:22.652506Z","end":"2026-04-20T17:45:23.155672Z","steps":["trace[718590727] 'process raft request' (duration: 502.766172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:45:23.156313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:45:22.652477Z","time spent":"503.742922ms","remote":"127.0.0.1:47138","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-20T17:45:23.156506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.062975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-04-20T17:45:23.156657Z","caller":"traceutil/trace.go:171","msg":"trace[292739645] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:2489; }","duration":"419.281538ms","start":"2026-04-20T17:45:22.737354Z","end":"2026-04-20T17:45:23.156636Z","steps":["trace[292739645] 'agreement among raft nodes before linearized reading' (duration: 418.857894ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:45:23.156704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:45:22.737322Z","time spent":"419.371238ms","remote":"127.0.0.1:47048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1655,"request content":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" "} {"level":"warn","ts":"2026-04-20T17:45:23.156877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.953073ms","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-20T17:45:23.157004Z","caller":"traceutil/trace.go:171","msg":"trace[1851181445] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2489; }","duration":"229.098915ms","start":"2026-04-20T17:45:22.927858Z","end":"2026-04-20T17:45:23.156957Z","steps":["trace[1851181445] 'agreement among raft nodes before linearized reading' (duration: 228.779722ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:45:24.113917Z","caller":"traceutil/trace.go:171","msg":"trace[876520943] transaction","detail":"{read_only:false; response_revision:2490; number_of_response:1; }","duration":"455.115204ms","start":"2026-04-20T17:45:23.658784Z","end":"2026-04-20T17:45:24.113899Z","steps":["trace[876520943] 'process raft request' (duration: 454.992393ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:45:24.114036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:45:23.658741Z","time spent":"455.229666ms","remote":"127.0.0.1:47138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T17:45:24.423918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.808424ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T17:45:24.424314Z","caller":"traceutil/trace.go:171","msg":"trace[1699197225] transaction","detail":"{read_only:false; response_revision:2494; number_of_response:1; }","duration":"424.902984ms","start":"2026-04-20T17:45:23.999389Z","end":"2026-04-20T17:45:24.424292Z","steps":["trace[1699197225] 'process raft request' (duration: 424.812903ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:45:24.424319Z","caller":"traceutil/trace.go:171","msg":"trace[1318684973] transaction","detail":"{read_only:false; response_revision:2491; number_of_response:1; }","duration":"500.247165ms","start":"2026-04-20T17:45:23.924049Z","end":"2026-04-20T17:45:24.424296Z","steps":["trace[1318684973] 'process raft request' (duration: 292.976246ms)","trace[1318684973] 'compare' (duration: 206.162628ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:45:24.424422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:45:23.999368Z","time spent":"424.991585ms","remote":"127.0.0.1:47138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T17:45:24.424429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:45:23.924026Z","time spent":"500.339056ms","remote":"127.0.0.1:47138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T17:45:24.42431Z","caller":"traceutil/trace.go:171","msg":"trace[1914139750] transaction","detail":"{read_only:false; response_revision:2492; number_of_response:1; }","duration":"500.162434ms","start":"2026-04-20T17:45:23.924113Z","end":"2026-04-20T17:45:24.424276Z","steps":["trace[1914139750] 'process raft request' (duration: 499.906741ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:45:24.424895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:45:23.924062Z","time spent":"500.74127ms","remote":"127.0.0.1:47138","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-20T17:45:24.424454Z","caller":"traceutil/trace.go:171","msg":"trace[1088410750] linearizableReadLoop","detail":"{readStateIndex:2593; appliedIndex:2588; }","duration":"398.020512ms","start":"2026-04-20T17:45:24.026377Z","end":"2026-04-20T17:45:24.424397Z","steps":["trace[1088410750] 'read index received' (duration: 87.374041ms)","trace[1088410750] 'applied index is now lower than readState.Index' (duration: 310.639541ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:45:24.424545Z","caller":"traceutil/trace.go:171","msg":"trace[1174470980] transaction","detail":"{read_only:false; response_revision:2493; number_of_response:1; }","duration":"438.245028ms","start":"2026-04-20T17:45:23.986286Z","end":"2026-04-20T17:45:24.424531Z","steps":["trace[1174470980] 'process raft request' (duration: 437.870155ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:45:24.425382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:45:23.98626Z","time spent":"439.083567ms","remote":"127.0.0.1:47138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T17:45:24.520416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.115794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T17:45:24.520503Z","caller":"traceutil/trace.go:171","msg":"trace[1499148040] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2494; }","duration":"288.273496ms","start":"2026-04-20T17:45:24.232212Z","end":"2026-04-20T17:45:24.520485Z","steps":["trace[1499148040] 'agreement among raft nodes before linearized reading' (duration: 288.105484ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:45:24.520453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.00994ms","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":"warn","ts":"2026-04-20T17:45:24.520643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.529413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-04-20T17:45:24.520657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.836974ms","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-20T17:45:24.520717Z","caller":"traceutil/trace.go:171","msg":"trace[767008833] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2494; }","duration":"187.959675ms","start":"2026-04-20T17:45:24.332741Z","end":"2026-04-20T17:45:24.520701Z","steps":["trace[767008833] 'agreement among raft nodes before linearized reading' (duration: 187.815914ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:45:24.520708Z","caller":"traceutil/trace.go:171","msg":"trace[812582049] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2494; }","duration":"354.596664ms","start":"2026-04-20T17:45:24.166094Z","end":"2026-04-20T17:45:24.52069Z","steps":["trace[812582049] 'agreement among raft nodes before linearized reading' (duration: 354.392672ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:45:24.520754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:45:24.166045Z","time spent":"354.699565ms","remote":"127.0.0.1:43830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-20T17:45:24.520661Z","caller":"traceutil/trace.go:171","msg":"trace[85867411] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2494; }","duration":"494.298213ms","start":"2026-04-20T17:45:24.026341Z","end":"2026-04-20T17:45:24.520639Z","steps":["trace[85867411] 'agreement among raft nodes before linearized reading' (duration: 398.170464ms)","trace[85867411] 'range keys from in-memory index tree' (duration: 95.752286ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:45:24.520575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.690576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18a821b4fce6d86a\" ","response":"range_response_count:1 size:846"} {"level":"warn","ts":"2026-04-20T17:45:24.520879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:45:24.026326Z","time spent":"494.538896ms","remote":"127.0.0.1:47138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":580,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-04-20T17:45:24.520925Z","caller":"traceutil/trace.go:171","msg":"trace[2084045367] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18a821b4fce6d86a; range_end:; response_count:1; response_revision:2494; }","duration":"373.078901ms","start":"2026-04-20T17:45:24.147823Z","end":"2026-04-20T17:45:24.520901Z","steps":["trace[2084045367] 'agreement among raft nodes before linearized reading' (duration: 372.578366ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:45:24.521014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:45:24.147806Z","time spent":"373.188032ms","remote":"127.0.0.1:46984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":868,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18a821b4fce6d86a\" "} {"level":"warn","ts":"2026-04-20T17:45:24.521985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.196698ms","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-20T17:45:24.522121Z","caller":"traceutil/trace.go:171","msg":"trace[1447139593] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2494; }","duration":"189.368409ms","start":"2026-04-20T17:45:24.33274Z","end":"2026-04-20T17:45:24.522108Z","steps":["trace[1447139593] 'agreement among raft nodes before linearized reading' (duration: 187.574361ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:45:24.805463Z","caller":"traceutil/trace.go:171","msg":"trace[460049168] transaction","detail":"{read_only:false; response_revision:2495; number_of_response:1; }","duration":"280.864072ms","start":"2026-04-20T17:45:24.524571Z","end":"2026-04-20T17:45:24.805435Z","steps":["trace[460049168] 'process raft request' (duration: 280.58536ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:45:24.808345Z","caller":"traceutil/trace.go:171","msg":"trace[1490776003] transaction","detail":"{read_only:false; response_revision:2498; number_of_response:1; }","duration":"281.439748ms","start":"2026-04-20T17:45:24.526866Z","end":"2026-04-20T17:45:24.808306Z","steps":["trace[1490776003] 'process raft request' (duration: 281.375408ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:45:24.808401Z","caller":"traceutil/trace.go:171","msg":"trace[557669380] transaction","detail":"{read_only:false; response_revision:2497; number_of_response:1; }","duration":"281.753631ms","start":"2026-04-20T17:45:24.526627Z","end":"2026-04-20T17:45:24.808381Z","steps":["trace[557669380] 'process raft request' (duration: 281.577859ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:45:24.808372Z","caller":"traceutil/trace.go:171","msg":"trace[988108213] transaction","detail":"{read_only:false; response_revision:2496; number_of_response:1; }","duration":"282.59244ms","start":"2026-04-20T17:45:24.525757Z","end":"2026-04-20T17:45:24.80835Z","steps":["trace[988108213] 'process raft request' (duration: 282.293307ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:45:24.808623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.680665ms","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-20T17:45:24.808657Z","caller":"traceutil/trace.go:171","msg":"trace[942930084] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2499; }","duration":"220.759226ms","start":"2026-04-20T17:45:24.587891Z","end":"2026-04-20T17:45:24.808651Z","steps":["trace[942930084] 'agreement among raft nodes before linearized reading' (duration: 220.611074ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:45:24.808467Z","caller":"traceutil/trace.go:171","msg":"trace[936792876] linearizableReadLoop","detail":"{readStateIndex:2598; appliedIndex:2594; }","duration":"220.515714ms","start":"2026-04-20T17:45:24.587925Z","end":"2026-04-20T17:45:24.80844Z","steps":["trace[936792876] 'read index received' (duration: 217.321171ms)","trace[936792876] 'applied index is now lower than readState.Index' (duration: 3.193353ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:45:24.809512Z","caller":"traceutil/trace.go:171","msg":"trace[52959425] transaction","detail":"{read_only:false; response_revision:2499; number_of_response:1; }","duration":"153.500608ms","start":"2026-04-20T17:45:24.65506Z","end":"2026-04-20T17:45:24.808561Z","steps":["trace[52959425] 'process raft request' (duration: 153.214145ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:47:24.497266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.06868ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-20T17:47:24.497563Z","caller":"traceutil/trace.go:171","msg":"trace[1725657353] linearizableReadLoop","detail":"{readStateIndex:3327; appliedIndex:3326; }","duration":"265.650792ms","start":"2026-04-20T17:47:24.23189Z","end":"2026-04-20T17:47:24.49754Z","steps":["trace[1725657353] 'read index received' (duration: 60.312µs)","trace[1725657353] 'applied index is now lower than readState.Index' (duration: 265.58912ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:47:24.497894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.971143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T17:47:24.498017Z","caller":"traceutil/trace.go:171","msg":"trace[1201568825] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3201; }","duration":"266.153209ms","start":"2026-04-20T17:47:24.231829Z","end":"2026-04-20T17:47:24.497982Z","steps":["trace[1201568825] 'agreement among raft nodes before linearized reading' (duration: 265.951112ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:47:24.499485Z","caller":"traceutil/trace.go:171","msg":"trace[760859159] transaction","detail":"{read_only:false; response_revision:3202; number_of_response:1; }","duration":"247.828815ms","start":"2026-04-20T17:47:24.251634Z","end":"2026-04-20T17:47:24.499463Z","steps":["trace[760859159] 'process raft request' (duration: 245.779454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:47:24.512333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.772552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T17:47:24.512448Z","caller":"traceutil/trace.go:171","msg":"trace[1261878760] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:3202; }","duration":"123.928258ms","start":"2026-04-20T17:47:24.388495Z","end":"2026-04-20T17:47:24.512423Z","steps":["trace[1261878760] 'agreement among raft nodes before linearized reading' (duration: 110.936668ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:47:25.071997Z","caller":"traceutil/trace.go:171","msg":"trace[1623846371] transaction","detail":"{read_only:false; response_revision:3203; number_of_response:1; }","duration":"174.167295ms","start":"2026-04-20T17:47:24.8978Z","end":"2026-04-20T17:47:25.071967Z","steps":["trace[1623846371] 'process raft request' (duration: 173.970388ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:47:25.368003Z","caller":"traceutil/trace.go:171","msg":"trace[65985475] transaction","detail":"{read_only:false; response_revision:3204; number_of_response:1; }","duration":"453.617155ms","start":"2026-04-20T17:47:24.914377Z","end":"2026-04-20T17:47:25.367994Z","steps":["trace[65985475] 'process raft request' (duration: 452.965413ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:47:25.36811Z","caller":"traceutil/trace.go:171","msg":"trace[2065900804] transaction","detail":"{read_only:false; response_revision:3205; number_of_response:1; }","duration":"452.631671ms","start":"2026-04-20T17:47:24.915405Z","end":"2026-04-20T17:47:25.368036Z","steps":["trace[2065900804] 'process raft request' (duration: 452.435114ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:47:25.368182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:47:24.914365Z","time spent":"453.702148ms","remote":"127.0.0.1:47138","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-20T17:47:25.368333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:47:24.915395Z","time spent":"452.813637ms","remote":"127.0.0.1:47138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T17:47:25.367965Z","caller":"traceutil/trace.go:171","msg":"trace[945026694] transaction","detail":"{read_only:false; response_revision:3206; number_of_response:1; }","duration":"370.130786ms","start":"2026-04-20T17:47:24.997805Z","end":"2026-04-20T17:47:25.367935Z","steps":["trace[945026694] 'process raft request' (duration: 370.094255ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:47:25.369099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:47:24.997773Z","time spent":"371.090059ms","remote":"127.0.0.1:47138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T17:47:43.415566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.059951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T17:47:43.415758Z","caller":"traceutil/trace.go:171","msg":"trace[1372047318] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3299; }","duration":"183.320639ms","start":"2026-04-20T17:47:43.232409Z","end":"2026-04-20T17:47:43.41573Z","steps":["trace[1372047318] 'range keys from in-memory index tree' (duration: 182.968049ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:47:43.747763Z","caller":"traceutil/trace.go:171","msg":"trace[1981065498] transaction","detail":"{read_only:false; response_revision:3301; number_of_response:1; }","duration":"275.452354ms","start":"2026-04-20T17:47:43.472265Z","end":"2026-04-20T17:47:43.747718Z","steps":["trace[1981065498] 'process raft request' (duration: 275.121673ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:47:43.747947Z","caller":"traceutil/trace.go:171","msg":"trace[749039102] transaction","detail":"{read_only:false; response_revision:3302; number_of_response:1; }","duration":"268.377041ms","start":"2026-04-20T17:47:43.479551Z","end":"2026-04-20T17:47:43.747928Z","steps":["trace[749039102] 'process raft request' (duration: 268.064441ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:47:43.748193Z","caller":"traceutil/trace.go:171","msg":"trace[1141248635] linearizableReadLoop","detail":"{readStateIndex:3432; appliedIndex:3429; }","duration":"148.556929ms","start":"2026-04-20T17:47:43.599621Z","end":"2026-04-20T17:47:43.748178Z","steps":["trace[1141248635] 'read index received' (duration: 147.678102ms)","trace[1141248635] 'applied index is now lower than readState.Index' (duration: 877.937µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:47:43.748369Z","caller":"traceutil/trace.go:171","msg":"trace[142837304] transaction","detail":"{read_only:false; response_revision:3303; number_of_response:1; }","duration":"268.766103ms","start":"2026-04-20T17:47:43.47959Z","end":"2026-04-20T17:47:43.748356Z","steps":["trace[142837304] 'process raft request' (duration: 268.280578ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:47:43.748641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.993864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-20T17:47:43.748701Z","caller":"traceutil/trace.go:171","msg":"trace[2129269001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3303; }","duration":"149.120337ms","start":"2026-04-20T17:47:43.599566Z","end":"2026-04-20T17:47:43.748686Z","steps":["trace[2129269001] 'agreement among raft nodes before linearized reading' (duration: 148.920141ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:47:59.987694Z","caller":"traceutil/trace.go:171","msg":"trace[1240882605] transaction","detail":"{read_only:false; response_revision:3391; number_of_response:1; }","duration":"109.865206ms","start":"2026-04-20T17:47:59.877799Z","end":"2026-04-20T17:47:59.987664Z","steps":["trace[1240882605] 'process raft request' (duration: 109.815905ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:47:59.987846Z","caller":"traceutil/trace.go:171","msg":"trace[1801840259] transaction","detail":"{read_only:false; response_revision:3390; number_of_response:1; }","duration":"112.106882ms","start":"2026-04-20T17:47:59.875718Z","end":"2026-04-20T17:47:59.987825Z","steps":["trace[1801840259] 'process raft request' (duration: 98.993836ms)","trace[1801840259] 'compare' (duration: 12.790926ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:48:01.719961Z","caller":"traceutil/trace.go:171","msg":"trace[1328686015] transaction","detail":"{read_only:false; response_revision:3394; number_of_response:1; }","duration":"108.335844ms","start":"2026-04-20T17:48:01.611592Z","end":"2026-04-20T17:48:01.719928Z","steps":["trace[1328686015] 'process raft request' (duration: 108.122197ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:48:02.009748Z","caller":"traceutil/trace.go:171","msg":"trace[363049854] linearizableReadLoop","detail":"{readStateIndex:3530; appliedIndex:3528; }","duration":"125.228026ms","start":"2026-04-20T17:48:01.884486Z","end":"2026-04-20T17:48:02.009714Z","steps":["trace[363049854] 'read index received' (duration: 28.606445ms)","trace[363049854] 'applied index is now lower than readState.Index' (duration: 96.620191ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:48:02.009856Z","caller":"traceutil/trace.go:171","msg":"trace[242090630] transaction","detail":"{read_only:false; response_revision:3397; number_of_response:1; }","duration":"134.196899ms","start":"2026-04-20T17:48:01.875613Z","end":"2026-04-20T17:48:02.00981Z","steps":["trace[242090630] 'process raft request' (duration: 118.217693ms)","trace[242090630] 'compare' (duration: 15.619055ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:48:02.010107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.584087ms","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-20T17:48:02.010167Z","caller":"traceutil/trace.go:171","msg":"trace[1832046717] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3397; }","duration":"125.729071ms","start":"2026-04-20T17:48:01.884425Z","end":"2026-04-20T17:48:02.010154Z","steps":["trace[1832046717] 'agreement among raft nodes before linearized reading' (duration: 125.475123ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:48:13.350952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.602785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T17:48:13.351039Z","caller":"traceutil/trace.go:171","msg":"trace[1572912424] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3459; }","duration":"117.722848ms","start":"2026-04-20T17:48:13.233298Z","end":"2026-04-20T17:48:13.351021Z","steps":["trace[1572912424] 'range keys from in-memory index tree' (duration: 117.537264ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:48:17.653922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.43479ms","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-20T17:48:17.653993Z","caller":"traceutil/trace.go:171","msg":"trace[930837385] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:3478; }","duration":"730.575624ms","start":"2026-04-20T17:48:16.923402Z","end":"2026-04-20T17:48:17.653977Z","steps":["trace[930837385] 'count revisions from in-memory index tree' (duration: 730.012698ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:48:17.654023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:48:16.923379Z","time spent":"730.636275ms","remote":"127.0.0.1:46984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":328,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-04-20T17:48:17.681279Z","caller":"traceutil/trace.go:171","msg":"trace[878628949] linearizableReadLoop","detail":"{readStateIndex:3615; appliedIndex:3614; }","duration":"446.459634ms","start":"2026-04-20T17:48:17.234797Z","end":"2026-04-20T17:48:17.681256Z","steps":["trace[878628949] 'read index received' (duration: 446.259748ms)","trace[878628949] 'applied index is now lower than readState.Index' (duration: 199.106µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:48:17.6814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.581537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T17:48:17.681425Z","caller":"traceutil/trace.go:171","msg":"trace[1441813030] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3479; }","duration":"446.67099ms","start":"2026-04-20T17:48:17.234747Z","end":"2026-04-20T17:48:17.681418Z","steps":["trace[1441813030] 'agreement among raft nodes before linearized reading' (duration: 446.608399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:48:17.681453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:48:17.234722Z","time spent":"446.724671ms","remote":"127.0.0.1:46918","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-20T17:48:17.681546Z","caller":"traceutil/trace.go:171","msg":"trace[1291733768] transaction","detail":"{read_only:false; response_revision:3479; number_of_response:1; }","duration":"552.752018ms","start":"2026-04-20T17:48:17.128549Z","end":"2026-04-20T17:48:17.681301Z","steps":["trace[1291733768] 'process raft request' (duration: 552.575373ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:48:17.681721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.516827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T17:48:17.681759Z","caller":"traceutil/trace.go:171","msg":"trace[315020569] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:3479; }","duration":"263.592079ms","start":"2026-04-20T17:48:17.418159Z","end":"2026-04-20T17:48:17.681751Z","steps":["trace[315020569] 'agreement among raft nodes before linearized reading' (duration: 263.530678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:48:17.681771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.770825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/valkey-node-0\" ","response":"range_response_count:1 size:17054"} {"level":"info","ts":"2026-04-20T17:48:17.681969Z","caller":"traceutil/trace.go:171","msg":"trace[2122543017] range","detail":"{range_begin:/registry/pods/openstack/valkey-node-0; range_end:; response_count:1; response_revision:3479; }","duration":"438.935478ms","start":"2026-04-20T17:48:17.242965Z","end":"2026-04-20T17:48:17.6819Z","steps":["trace[2122543017] 'agreement among raft nodes before linearized reading' (duration: 438.651241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:48:17.682336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:48:17.242951Z","time spent":"439.058292ms","remote":"127.0.0.1:47076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":1,"response size":17076,"request content":"key:\"/registry/pods/openstack/valkey-node-0\" "} {"level":"warn","ts":"2026-04-20T17:48:17.682447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:48:17.128518Z","time spent":"553.301972ms","remote":"127.0.0.1:47138","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-20T17:48:17.943347Z","caller":"traceutil/trace.go:171","msg":"trace[693156528] transaction","detail":"{read_only:false; response_revision:3484; number_of_response:1; }","duration":"221.338335ms","start":"2026-04-20T17:48:17.721977Z","end":"2026-04-20T17:48:17.943315Z","steps":["trace[693156528] 'process raft request' (duration: 221.256113ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:48:17.943655Z","caller":"traceutil/trace.go:171","msg":"trace[1377513324] transaction","detail":"{read_only:false; response_revision:3485; number_of_response:1; }","duration":"221.13111ms","start":"2026-04-20T17:48:17.722505Z","end":"2026-04-20T17:48:17.943636Z","steps":["trace[1377513324] 'process raft request' (duration: 220.77253ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:48:17.943881Z","caller":"traceutil/trace.go:171","msg":"trace[1757241698] transaction","detail":"{read_only:false; response_revision:3486; number_of_response:1; }","duration":"221.176661ms","start":"2026-04-20T17:48:17.722672Z","end":"2026-04-20T17:48:17.943849Z","steps":["trace[1757241698] 'process raft request' (duration: 220.813341ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:48:17.944011Z","caller":"traceutil/trace.go:171","msg":"trace[1609677361] transaction","detail":"{read_only:false; response_revision:3487; number_of_response:1; }","duration":"164.02981ms","start":"2026-04-20T17:48:17.779942Z","end":"2026-04-20T17:48:17.943972Z","steps":["trace[1609677361] 'process raft request' (duration: 163.743523ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:48:17.943991Z","caller":"traceutil/trace.go:171","msg":"trace[1380688209] linearizableReadLoop","detail":"{readStateIndex:3622; appliedIndex:3617; }","duration":"216.108203ms","start":"2026-04-20T17:48:17.727833Z","end":"2026-04-20T17:48:17.943941Z","steps":["trace[1380688209] 'read index received' (duration: 286.328µs)","trace[1380688209] 'applied index is now lower than readState.Index' (duration: 215.816584ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:48:17.944225Z","caller":"traceutil/trace.go:171","msg":"trace[1981369289] transaction","detail":"{read_only:false; response_revision:3483; number_of_response:1; }","duration":"222.300292ms","start":"2026-04-20T17:48:17.721912Z","end":"2026-04-20T17:48:17.944212Z","steps":["trace[1981369289] 'process raft request' (duration: 221.260284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:48:17.944255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.3867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/openstack/\" range_end:\"/registry/limitranges/openstack0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T17:48:17.94432Z","caller":"traceutil/trace.go:171","msg":"trace[710361111] range","detail":"{range_begin:/registry/limitranges/openstack/; range_end:/registry/limitranges/openstack0; response_count:0; response_revision:3487; }","duration":"216.485443ms","start":"2026-04-20T17:48:17.727798Z","end":"2026-04-20T17:48:17.944283Z","steps":["trace[710361111] 'agreement among raft nodes before linearized reading' (duration: 216.36327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:48:17.944593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.063745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-04-20T17:48:17.94464Z","caller":"traceutil/trace.go:171","msg":"trace[601191087] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3487; }","duration":"110.169949ms","start":"2026-04-20T17:48:17.834456Z","end":"2026-04-20T17:48:17.944626Z","steps":["trace[601191087] 'agreement among raft nodes before linearized reading' (duration: 109.987254ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:48:17.94342Z","caller":"traceutil/trace.go:171","msg":"trace[1652210246] transaction","detail":"{read_only:false; response_revision:3482; number_of_response:1; }","duration":"221.525521ms","start":"2026-04-20T17:48:17.721844Z","end":"2026-04-20T17:48:17.943369Z","steps":["trace[1652210246] 'process raft request' (duration: 123.517244ms)","trace[1652210246] 'compare' (duration: 97.646577ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:48:17.947003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.474284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T17:48:17.947057Z","caller":"traceutil/trace.go:171","msg":"trace[701462053] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:3487; }","duration":"197.616037ms","start":"2026-04-20T17:48:17.749426Z","end":"2026-04-20T17:48:17.947042Z","steps":["trace[701462053] 'agreement among raft nodes before linearized reading' (duration: 194.841931ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:48:18.150061Z","caller":"traceutil/trace.go:171","msg":"trace[1155132218] transaction","detail":"{read_only:false; response_revision:3488; number_of_response:1; }","duration":"199.436751ms","start":"2026-04-20T17:48:17.950585Z","end":"2026-04-20T17:48:18.150022Z","steps":["trace[1155132218] 'process raft request' (duration: 136.397156ms)","trace[1155132218] 'compare' (duration: 62.719707ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:48:18.150122Z","caller":"traceutil/trace.go:171","msg":"trace[1754583193] transaction","detail":"{read_only:false; response_revision:3490; number_of_response:1; }","duration":"178.070378ms","start":"2026-04-20T17:48:17.972018Z","end":"2026-04-20T17:48:18.150088Z","steps":["trace[1754583193] 'process raft request' (duration: 177.991296ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:48:18.15039Z","caller":"traceutil/trace.go:171","msg":"trace[246673405] linearizableReadLoop","detail":"{readStateIndex:3624; appliedIndex:3623; }","duration":"198.892776ms","start":"2026-04-20T17:48:17.951464Z","end":"2026-04-20T17:48:18.150357Z","steps":["trace[246673405] 'read index received' (duration: 135.537253ms)","trace[246673405] 'applied index is now lower than readState.Index' (duration: 63.353823ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:48:18.150439Z","caller":"traceutil/trace.go:171","msg":"trace[144533920] transaction","detail":"{read_only:false; response_revision:3489; number_of_response:1; }","duration":"180.507834ms","start":"2026-04-20T17:48:17.96988Z","end":"2026-04-20T17:48:18.150388Z","steps":["trace[144533920] 'process raft request' (duration: 180.052482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:48:18.150812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.315728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1319"} {"level":"info","ts":"2026-04-20T17:48:18.150867Z","caller":"traceutil/trace.go:171","msg":"trace[932024850] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:3491; }","duration":"199.40548ms","start":"2026-04-20T17:48:17.951449Z","end":"2026-04-20T17:48:18.150855Z","steps":["trace[932024850] 'agreement among raft nodes before linearized reading' (duration: 199.176104ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:48:22.275106Z","caller":"traceutil/trace.go:171","msg":"trace[821020104] transaction","detail":"{read_only:false; response_revision:3522; number_of_response:1; }","duration":"103.307065ms","start":"2026-04-20T17:48:22.171759Z","end":"2026-04-20T17:48:22.275066Z","steps":["trace[821020104] 'process raft request' (duration: 102.826962ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:48:22.275018Z","caller":"traceutil/trace.go:171","msg":"trace[969831346] transaction","detail":"{read_only:false; response_revision:3521; number_of_response:1; }","duration":"104.457226ms","start":"2026-04-20T17:48:22.170531Z","end":"2026-04-20T17:48:22.274988Z","steps":["trace[969831346] 'process raft request' (duration: 103.929532ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:48:44.567391Z","caller":"traceutil/trace.go:171","msg":"trace[24362872] transaction","detail":"{read_only:false; response_revision:3644; number_of_response:1; }","duration":"119.511552ms","start":"2026-04-20T17:48:44.447864Z","end":"2026-04-20T17:48:44.567376Z","steps":["trace[24362872] 'process raft request' (duration: 119.479351ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:48:44.567518Z","caller":"traceutil/trace.go:171","msg":"trace[1319144409] transaction","detail":"{read_only:false; response_revision:3642; number_of_response:1; }","duration":"120.24588ms","start":"2026-04-20T17:48:44.447256Z","end":"2026-04-20T17:48:44.567502Z","steps":["trace[1319144409] 'process raft request' (duration: 120.008944ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:48:44.567639Z","caller":"traceutil/trace.go:171","msg":"trace[1489555326] transaction","detail":"{read_only:false; response_revision:3643; number_of_response:1; }","duration":"120.291851ms","start":"2026-04-20T17:48:44.44734Z","end":"2026-04-20T17:48:44.567632Z","steps":["trace[1489555326] 'process raft request' (duration: 119.975623ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:48:44.567372Z","caller":"traceutil/trace.go:171","msg":"trace[893789421] transaction","detail":"{read_only:false; response_revision:3641; number_of_response:1; }","duration":"120.358823ms","start":"2026-04-20T17:48:44.446978Z","end":"2026-04-20T17:48:44.567336Z","steps":["trace[893789421] 'process raft request' (duration: 116.281682ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:49:22.388449Z","caller":"traceutil/trace.go:171","msg":"trace[1181712160] transaction","detail":"{read_only:false; response_revision:3852; number_of_response:1; }","duration":"153.332852ms","start":"2026-04-20T17:49:22.235077Z","end":"2026-04-20T17:49:22.38841Z","steps":["trace[1181712160] 'process raft request' (duration: 145.842ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:49:22.388567Z","caller":"traceutil/trace.go:171","msg":"trace[259535261] linearizableReadLoop","detail":"{readStateIndex:4004; appliedIndex:4003; }","duration":"147.123938ms","start":"2026-04-20T17:49:22.241406Z","end":"2026-04-20T17:49:22.38853Z","steps":["trace[259535261] 'read index received' (duration: 139.530113ms)","trace[259535261] 'applied index is now lower than readState.Index' (duration: 7.584855ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:49:22.388848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.424544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T17:49:22.388891Z","caller":"traceutil/trace.go:171","msg":"trace[2068170746] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3852; }","duration":"147.537757ms","start":"2026-04-20T17:49:22.241343Z","end":"2026-04-20T17:49:22.388881Z","steps":["trace[2068170746] 'agreement among raft nodes before linearized reading' (duration: 147.385153ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:49:22.389822Z","caller":"traceutil/trace.go:171","msg":"trace[2114169100] transaction","detail":"{read_only:false; response_revision:3853; number_of_response:1; }","duration":"112.884265ms","start":"2026-04-20T17:49:22.27691Z","end":"2026-04-20T17:49:22.389794Z","steps":["trace[2114169100] 'process raft request' (duration: 112.396455ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:49:22.558554Z","caller":"traceutil/trace.go:171","msg":"trace[1219769286] transaction","detail":"{read_only:false; response_revision:3866; number_of_response:1; }","duration":"105.907085ms","start":"2026-04-20T17:49:22.452614Z","end":"2026-04-20T17:49:22.558521Z","steps":["trace[1219769286] 'process raft request' (duration: 84.383608ms)","trace[1219769286] 'compare' (duration: 21.397634ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:49:22.704092Z","caller":"traceutil/trace.go:171","msg":"trace[1555065006] transaction","detail":"{read_only:false; response_revision:3867; number_of_response:1; }","duration":"147.114358ms","start":"2026-04-20T17:49:22.556931Z","end":"2026-04-20T17:49:22.704045Z","steps":["trace[1555065006] 'process raft request' (duration: 131.07896ms)","trace[1555065006] 'compare' (duration: 15.902895ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:49:22.705905Z","caller":"traceutil/trace.go:171","msg":"trace[1120885354] transaction","detail":"{read_only:false; response_revision:3868; number_of_response:1; }","duration":"136.854045ms","start":"2026-04-20T17:49:22.569033Z","end":"2026-04-20T17:49:22.705887Z","steps":["trace[1120885354] 'process raft request' (duration: 136.745813ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:49:22.706095Z","caller":"traceutil/trace.go:171","msg":"trace[1694134845] transaction","detail":"{read_only:false; number_of_response:1; response_revision:3868; }","duration":"136.194441ms","start":"2026-04-20T17:49:22.569894Z","end":"2026-04-20T17:49:22.706089Z","steps":["trace[1694134845] 'process raft request' (duration: 135.968626ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:49:22.706405Z","caller":"traceutil/trace.go:171","msg":"trace[359966614] transaction","detail":"{read_only:false; response_revision:3869; number_of_response:1; }","duration":"130.468176ms","start":"2026-04-20T17:49:22.57592Z","end":"2026-04-20T17:49:22.706389Z","steps":["trace[359966614] 'process raft request' (duration: 130.098458ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:49:25.286122Z","caller":"traceutil/trace.go:171","msg":"trace[991550269] transaction","detail":"{read_only:false; response_revision:3890; number_of_response:1; }","duration":"128.443058ms","start":"2026-04-20T17:49:25.15766Z","end":"2026-04-20T17:49:25.286103Z","steps":["trace[991550269] 'process raft request' (duration: 127.979298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:49:34.844738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.526691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8dc6adfa-17c5-4193-adc6-d89e6400acdf\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-20T17:49:34.844844Z","caller":"traceutil/trace.go:171","msg":"trace[1454040000] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8dc6adfa-17c5-4193-adc6-d89e6400acdf; range_end:; response_count:1; response_revision:4039; }","duration":"937.691565ms","start":"2026-04-20T17:49:33.907128Z","end":"2026-04-20T17:49:34.84482Z","steps":["trace[1454040000] 'range keys from in-memory index tree' (duration: 937.336057ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:49:34.844887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:49:33.907102Z","time spent":"937.775916ms","remote":"127.0.0.1:47076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8dc6adfa-17c5-4193-adc6-d89e6400acdf\" "} {"level":"warn","ts":"2026-04-20T17:49:34.876765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.751146ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-20T17:49:34.876985Z","caller":"traceutil/trace.go:171","msg":"trace[1473733496] linearizableReadLoop","detail":"{readStateIndex:4196; appliedIndex:4195; }","duration":"645.062002ms","start":"2026-04-20T17:49:34.231903Z","end":"2026-04-20T17:49:34.876965Z","steps":["trace[1473733496] 'read index received' (duration: 343.199813ms)","trace[1473733496] 'applied index is now lower than readState.Index' (duration: 301.861459ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:49:34.877072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.159865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T17:49:34.877096Z","caller":"traceutil/trace.go:171","msg":"trace[107536759] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4039; }","duration":"645.239326ms","start":"2026-04-20T17:49:34.231848Z","end":"2026-04-20T17:49:34.877087Z","steps":["trace[107536759] 'agreement among raft nodes before linearized reading' (duration: 645.189735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:49:34.877125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:49:34.231828Z","time spent":"645.291257ms","remote":"127.0.0.1:46918","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-20T17:49:34.877403Z","caller":"traceutil/trace.go:171","msg":"trace[2071297645] transaction","detail":"{read_only:false; response_revision:4041; number_of_response:1; }","duration":"444.918478ms","start":"2026-04-20T17:49:34.43246Z","end":"2026-04-20T17:49:34.877378Z","steps":["trace[2071297645] 'process raft request' (duration: 444.812236ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:49:34.877587Z","caller":"traceutil/trace.go:171","msg":"trace[585248879] transaction","detail":"{read_only:false; response_revision:4040; number_of_response:1; }","duration":"446.615224ms","start":"2026-04-20T17:49:34.430957Z","end":"2026-04-20T17:49:34.877572Z","steps":["trace[585248879] 'process raft request' (duration: 445.91871ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:49:34.877655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:49:34.432447Z","time spent":"445.118883ms","remote":"127.0.0.1:47138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T17:49:34.877715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:49:34.430918Z","time spent":"446.745347ms","remote":"127.0.0.1:47138","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-20T17:49:34.884305Z","caller":"traceutil/trace.go:171","msg":"trace[989955244] transaction","detail":"{read_only:false; response_revision:4043; number_of_response:1; }","duration":"130.217791ms","start":"2026-04-20T17:49:34.754076Z","end":"2026-04-20T17:49:34.884294Z","steps":["trace[989955244] 'process raft request' (duration: 130.14989ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:49:34.884319Z","caller":"traceutil/trace.go:171","msg":"trace[1682095084] transaction","detail":"{read_only:false; response_revision:4042; number_of_response:1; }","duration":"133.924388ms","start":"2026-04-20T17:49:34.750364Z","end":"2026-04-20T17:49:34.884289Z","steps":["trace[1682095084] 'process raft request' (duration: 133.813176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:49:34.884526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.380297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8dc6adfa-17c5-4193-adc6-d89e6400acdf\" ","response":"range_response_count:1 size:3763"} {"level":"info","ts":"2026-04-20T17:49:34.884561Z","caller":"traceutil/trace.go:171","msg":"trace[1201411377] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8dc6adfa-17c5-4193-adc6-d89e6400acdf; range_end:; response_count:1; response_revision:4044; }","duration":"277.462278ms","start":"2026-04-20T17:49:34.607089Z","end":"2026-04-20T17:49:34.884552Z","steps":["trace[1201411377] 'agreement among raft nodes before linearized reading' (duration: 277.387937ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:49:34.88465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.443852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-04-20T17:49:34.884679Z","caller":"traceutil/trace.go:171","msg":"trace[1101392258] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4044; }","duration":"170.512203ms","start":"2026-04-20T17:49:34.71416Z","end":"2026-04-20T17:49:34.884673Z","steps":["trace[1101392258] 'agreement among raft nodes before linearized reading' (duration: 170.404971ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:49:34.884802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.601561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/valkey-data-valkey-node-1.18a821e01eb1a2c1\" ","response":"range_response_count:1 size:764"} {"level":"info","ts":"2026-04-20T17:49:34.884869Z","caller":"traceutil/trace.go:171","msg":"trace[2110570214] range","detail":"{range_begin:/registry/events/openstack/valkey-data-valkey-node-1.18a821e01eb1a2c1; range_end:; response_count:1; response_revision:4044; }","duration":"409.715643ms","start":"2026-04-20T17:49:34.475138Z","end":"2026-04-20T17:49:34.884853Z","steps":["trace[2110570214] 'agreement among raft nodes before linearized reading' (duration: 409.517798ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:49:34.884911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:49:34.475125Z","time spent":"409.777495ms","remote":"127.0.0.1:46984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":786,"request content":"key:\"/registry/events/openstack/valkey-data-valkey-node-1.18a821e01eb1a2c1\" "} {"level":"warn","ts":"2026-04-20T17:49:34.886092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.675509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-04-20T17:49:34.886169Z","caller":"traceutil/trace.go:171","msg":"trace[1473873872] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4044; }","duration":"306.805792ms","start":"2026-04-20T17:49:34.579345Z","end":"2026-04-20T17:49:34.886151Z","steps":["trace[1473873872] 'agreement among raft nodes before linearized reading' (duration: 305.163327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:49:34.886204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:49:34.579322Z","time spent":"306.875494ms","remote":"127.0.0.1:43624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3338,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"info","ts":"2026-04-20T17:50:29.197339Z","caller":"traceutil/trace.go:171","msg":"trace[917604138] transaction","detail":"{read_only:false; response_revision:4496; number_of_response:1; }","duration":"126.40175ms","start":"2026-04-20T17:50:29.070907Z","end":"2026-04-20T17:50:29.197309Z","steps":["trace[917604138] 'process raft request' (duration: 82.380429ms)","trace[917604138] 'compare' (duration: 43.774836ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:50:29.197726Z","caller":"traceutil/trace.go:171","msg":"trace[1249197943] transaction","detail":"{read_only:false; response_revision:4497; number_of_response:1; }","duration":"109.908989ms","start":"2026-04-20T17:50:29.087763Z","end":"2026-04-20T17:50:29.197672Z","steps":["trace[1249197943] 'process raft request' (duration: 109.499302ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:50:41.471815Z","caller":"traceutil/trace.go:171","msg":"trace[1973615866] transaction","detail":"{read_only:false; response_revision:4653; number_of_response:1; }","duration":"226.828404ms","start":"2026-04-20T17:50:41.244954Z","end":"2026-04-20T17:50:41.471783Z","steps":["trace[1973615866] 'process raft request' (duration: 226.531679ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:50:41.472314Z","caller":"traceutil/trace.go:171","msg":"trace[381553916] transaction","detail":"{read_only:false; response_revision:4654; number_of_response:1; }","duration":"225.590463ms","start":"2026-04-20T17:50:41.246685Z","end":"2026-04-20T17:50:41.472275Z","steps":["trace[381553916] 'process raft request' (duration: 225.038343ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:50:41.472833Z","caller":"traceutil/trace.go:171","msg":"trace[1123407809] transaction","detail":"{read_only:false; response_revision:4655; number_of_response:1; }","duration":"219.750228ms","start":"2026-04-20T17:50:41.253063Z","end":"2026-04-20T17:50:41.472814Z","steps":["trace[1123407809] 'process raft request' (duration: 219.079896ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:50:41.472915Z","caller":"traceutil/trace.go:171","msg":"trace[624931421] linearizableReadLoop","detail":"{readStateIndex:4830; appliedIndex:4826; }","duration":"172.335718ms","start":"2026-04-20T17:50:41.300557Z","end":"2026-04-20T17:50:41.472893Z","steps":["trace[624931421] 'read index received' (duration: 23.453146ms)","trace[624931421] 'applied index is now lower than readState.Index' (duration: 148.880531ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:50:41.473291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.705424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-20T17:50:41.473344Z","caller":"traceutil/trace.go:171","msg":"trace[1303333908] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4656; }","duration":"172.840026ms","start":"2026-04-20T17:50:41.300492Z","end":"2026-04-20T17:50:41.473332Z","steps":["trace[1303333908] 'agreement among raft nodes before linearized reading' (duration: 172.642973ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:50:41.473414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.346447ms","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-20T17:50:41.473484Z","caller":"traceutil/trace.go:171","msg":"trace[503025981] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4656; }","duration":"168.481499ms","start":"2026-04-20T17:50:41.304986Z","end":"2026-04-20T17:50:41.473468Z","steps":["trace[503025981] 'agreement among raft nodes before linearized reading' (duration: 168.246795ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:50:41.473511Z","caller":"traceutil/trace.go:171","msg":"trace[892583062] transaction","detail":"{read_only:false; response_revision:4656; number_of_response:1; }","duration":"150.976718ms","start":"2026-04-20T17:50:41.322521Z","end":"2026-04-20T17:50:41.473498Z","steps":["trace[892583062] 'process raft request' (duration: 150.084592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:50:41.473817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.215385ms","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-20T17:50:41.47386Z","caller":"traceutil/trace.go:171","msg":"trace[148161054] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4656; }","duration":"168.296445ms","start":"2026-04-20T17:50:41.305552Z","end":"2026-04-20T17:50:41.473849Z","steps":["trace[148161054] 'agreement among raft nodes before linearized reading' (duration: 168.190434ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:51:26.021227Z","caller":"traceutil/trace.go:171","msg":"trace[869506318] linearizableReadLoop","detail":"{readStateIndex:5599; appliedIndex:5598; }","duration":"102.150576ms","start":"2026-04-20T17:51:25.91905Z","end":"2026-04-20T17:51:26.021201Z","steps":["trace[869506318] 'read index received' (duration: 101.993974ms)","trace[869506318] 'applied index is now lower than readState.Index' (duration: 155.422µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:51:26.02149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.38625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T17:51:26.021612Z","caller":"traceutil/trace.go:171","msg":"trace[799391696] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:5398; }","duration":"102.549243ms","start":"2026-04-20T17:51:25.919043Z","end":"2026-04-20T17:51:26.021592Z","steps":["trace[799391696] 'agreement among raft nodes before linearized reading' (duration: 102.293259ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:51:26.021312Z","caller":"traceutil/trace.go:171","msg":"trace[293760397] transaction","detail":"{read_only:false; response_revision:5398; number_of_response:1; }","duration":"166.794547ms","start":"2026-04-20T17:51:25.854483Z","end":"2026-04-20T17:51:26.021277Z","steps":["trace[293760397] 'process raft request' (duration: 166.589873ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:51:26.026145Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2840} {"level":"info","ts":"2026-04-20T17:51:26.109443Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2840,"took":"80.951236ms","hash":1288341305,"current-db-size-bytes":53047296,"current-db-size":"53 MB","current-db-size-in-use-bytes":29036544,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-20T17:51:26.109524Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1288341305,"revision":2840,"compact-revision":-1} {"level":"info","ts":"2026-04-20T17:51:28.764063Z","caller":"traceutil/trace.go:171","msg":"trace[1499298237] transaction","detail":"{read_only:false; response_revision:5490; number_of_response:1; }","duration":"264.235166ms","start":"2026-04-20T17:51:28.499801Z","end":"2026-04-20T17:51:28.764036Z","steps":["trace[1499298237] 'process raft request' (duration: 263.986742ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:51:28.764316Z","caller":"traceutil/trace.go:171","msg":"trace[142350120] linearizableReadLoop","detail":"{readStateIndex:5693; appliedIndex:5693; }","duration":"264.199246ms","start":"2026-04-20T17:51:28.500068Z","end":"2026-04-20T17:51:28.764267Z","steps":["trace[142350120] 'read index received' (duration: 264.178345ms)","trace[142350120] 'applied index is now lower than readState.Index' (duration: 16.451µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:51:28.764669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.578081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/keystone-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-04-20T17:51:28.764793Z","caller":"traceutil/trace.go:171","msg":"trace[840497933] range","detail":"{range_begin:/registry/services/endpoints/openstack/keystone-api; range_end:; response_count:1; response_revision:5490; }","duration":"264.775575ms","start":"2026-04-20T17:51:28.499984Z","end":"2026-04-20T17:51:28.76476Z","steps":["trace[840497933] 'agreement among raft nodes before linearized reading' (duration: 264.378688ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:51:28.786229Z","caller":"traceutil/trace.go:171","msg":"trace[1851260370] transaction","detail":"{read_only:false; response_revision:5491; number_of_response:1; }","duration":"224.327224ms","start":"2026-04-20T17:51:28.561878Z","end":"2026-04-20T17:51:28.786205Z","steps":["trace[1851260370] 'process raft request' (duration: 223.904477ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:51:28.786294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.355027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b9cc0142-5c3b-4b49-a988-5ee05bc4a0f5\" ","response":"range_response_count:1 size:3774"} {"level":"info","ts":"2026-04-20T17:51:28.786467Z","caller":"traceutil/trace.go:171","msg":"trace[2015912785] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b9cc0142-5c3b-4b49-a988-5ee05bc4a0f5; range_end:; response_count:1; response_revision:5491; }","duration":"158.589061ms","start":"2026-04-20T17:51:28.627861Z","end":"2026-04-20T17:51:28.78645Z","steps":["trace[2015912785] 'agreement among raft nodes before linearized reading' (duration: 158.211885ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:51:30.860167Z","caller":"traceutil/trace.go:171","msg":"trace[139251514] transaction","detail":"{read_only:false; response_revision:5541; number_of_response:1; }","duration":"136.94557ms","start":"2026-04-20T17:51:30.723191Z","end":"2026-04-20T17:51:30.860137Z","steps":["trace[139251514] 'process raft request' (duration: 136.704716ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:51:30.898094Z","caller":"traceutil/trace.go:171","msg":"trace[301701703] linearizableReadLoop","detail":"{readStateIndex:5749; appliedIndex:5747; }","duration":"127.018698ms","start":"2026-04-20T17:51:30.771048Z","end":"2026-04-20T17:51:30.898066Z","steps":["trace[301701703] 'read index received' (duration: 88.863687ms)","trace[301701703] 'applied index is now lower than readState.Index' (duration: 38.153951ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:51:30.898327Z","caller":"traceutil/trace.go:171","msg":"trace[226252609] transaction","detail":"{read_only:false; response_revision:5542; number_of_response:1; }","duration":"168.431223ms","start":"2026-04-20T17:51:30.72983Z","end":"2026-04-20T17:51:30.898261Z","steps":["trace[226252609] 'process raft request' (duration: 168.052257ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:51:30.898408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.341874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/keystone-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-04-20T17:51:30.898495Z","caller":"traceutil/trace.go:171","msg":"trace[1228134035] range","detail":"{range_begin:/registry/services/endpoints/openstack/keystone-api; range_end:; response_count:1; response_revision:5542; }","duration":"127.479146ms","start":"2026-04-20T17:51:30.771001Z","end":"2026-04-20T17:51:30.89848Z","steps":["trace[1228134035] 'agreement among raft nodes before linearized reading' (duration: 127.219831ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:51:32.289927Z","caller":"traceutil/trace.go:171","msg":"trace[2109986539] linearizableReadLoop","detail":"{readStateIndex:5771; appliedIndex:5770; }","duration":"103.556792ms","start":"2026-04-20T17:51:32.186351Z","end":"2026-04-20T17:51:32.289908Z","steps":["trace[2109986539] 'read index received' (duration: 103.385689ms)","trace[2109986539] 'applied index is now lower than readState.Index' (duration: 170.553µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:51:32.290177Z","caller":"traceutil/trace.go:171","msg":"trace[1989561106] transaction","detail":"{read_only:false; response_revision:5564; number_of_response:1; }","duration":"381.280663ms","start":"2026-04-20T17:51:31.908889Z","end":"2026-04-20T17:51:32.290169Z","steps":["trace[1989561106] 'process raft request' (duration: 380.906527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:51:32.290268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:51:31.908853Z","time spent":"381.346843ms","remote":"127.0.0.1:47076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4079,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T17:51:32.29043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.051529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T17:51:32.290523Z","caller":"traceutil/trace.go:171","msg":"trace[1924029006] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:5564; }","duration":"104.207282ms","start":"2026-04-20T17:51:32.186299Z","end":"2026-04-20T17:51:32.290506Z","steps":["trace[1924029006] 'agreement among raft nodes before linearized reading' (duration: 104.004509ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:51:33.503829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.201572ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T17:51:33.503948Z","caller":"traceutil/trace.go:171","msg":"trace[883542853] linearizableReadLoop","detail":"{readStateIndex:5796; appliedIndex:5795; }","duration":"275.151971ms","start":"2026-04-20T17:51:33.228777Z","end":"2026-04-20T17:51:33.503929Z","steps":["trace[883542853] 'read index received' (duration: 73.703395ms)","trace[883542853] 'applied index is now lower than readState.Index' (duration: 201.446906ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:51:33.504036Z","caller":"traceutil/trace.go:171","msg":"trace[131195910] transaction","detail":"{read_only:false; response_revision:5589; number_of_response:1; }","duration":"282.085373ms","start":"2026-04-20T17:51:33.221902Z","end":"2026-04-20T17:51:33.503988Z","steps":["trace[131195910] 'process raft request' (duration: 80.639837ms)","trace[131195910] 'compare' (duration: 200.741834ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:51:33.504208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.419135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:14424"} {"level":"info","ts":"2026-04-20T17:51:33.504253Z","caller":"traceutil/trace.go:171","msg":"trace[1793321310] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5589; }","duration":"275.498996ms","start":"2026-04-20T17:51:33.228742Z","end":"2026-04-20T17:51:33.504241Z","steps":["trace[1793321310] 'agreement among raft nodes before linearized reading' (duration: 275.265402ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:51:33.512493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.422108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T17:51:33.512534Z","caller":"traceutil/trace.go:171","msg":"trace[1340639669] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:5590; }","duration":"166.51684ms","start":"2026-04-20T17:51:33.346007Z","end":"2026-04-20T17:51:33.512524Z","steps":["trace[1340639669] 'agreement among raft nodes before linearized reading' (duration: 166.429399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:51:33.512896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.090547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-20T17:51:33.512988Z","caller":"traceutil/trace.go:171","msg":"trace[365581156] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5590; }","duration":"273.218169ms","start":"2026-04-20T17:51:33.239747Z","end":"2026-04-20T17:51:33.512965Z","steps":["trace[365581156] 'agreement among raft nodes before linearized reading' (duration: 273.019846ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:51:39.403746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.169775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T17:51:39.403847Z","caller":"traceutil/trace.go:171","msg":"trace[21660314] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5654; }","duration":"170.344488ms","start":"2026-04-20T17:51:39.233481Z","end":"2026-04-20T17:51:39.403825Z","steps":["trace[21660314] 'range keys from in-memory index tree' (duration: 170.038343ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:51:39.988779Z","caller":"traceutil/trace.go:171","msg":"trace[333427350] transaction","detail":"{read_only:false; response_revision:5661; number_of_response:1; }","duration":"111.737286ms","start":"2026-04-20T17:51:39.876911Z","end":"2026-04-20T17:51:39.988648Z","steps":["trace[333427350] 'process raft request' (duration: 76.045252ms)","trace[333427350] 'compare' (duration: 34.968362ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:51:40.152872Z","caller":"traceutil/trace.go:171","msg":"trace[816826179] transaction","detail":"{read_only:false; response_revision:5662; number_of_response:1; }","duration":"159.268315ms","start":"2026-04-20T17:51:39.993571Z","end":"2026-04-20T17:51:40.152839Z","steps":["trace[816826179] 'process raft request' (duration: 158.428082ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:51:40.154101Z","caller":"traceutil/trace.go:171","msg":"trace[1876792706] linearizableReadLoop","detail":"{readStateIndex:5874; appliedIndex:5870; }","duration":"118.586502ms","start":"2026-04-20T17:51:40.035498Z","end":"2026-04-20T17:51:40.154085Z","steps":["trace[1876792706] 'read index received' (duration: 116.622681ms)","trace[1876792706] 'applied index is now lower than readState.Index' (duration: 1.962851ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:51:40.15423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.702514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T17:51:40.154284Z","caller":"traceutil/trace.go:171","msg":"trace[1643659892] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:5665; }","duration":"118.822066ms","start":"2026-04-20T17:51:40.035444Z","end":"2026-04-20T17:51:40.154267Z","steps":["trace[1643659892] 'agreement among raft nodes before linearized reading' (duration: 118.714604ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:51:40.154253Z","caller":"traceutil/trace.go:171","msg":"trace[1627046534] transaction","detail":"{read_only:false; response_revision:5664; number_of_response:1; }","duration":"160.033178ms","start":"2026-04-20T17:51:39.994102Z","end":"2026-04-20T17:51:40.154135Z","steps":["trace[1627046534] 'process raft request' (duration: 159.867955ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:51:40.154574Z","caller":"traceutil/trace.go:171","msg":"trace[428858444] transaction","detail":"{read_only:false; response_revision:5665; number_of_response:1; }","duration":"159.415177ms","start":"2026-04-20T17:51:39.995111Z","end":"2026-04-20T17:51:40.154526Z","steps":["trace[428858444] 'process raft request' (duration: 158.931069ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:51:40.154692Z","caller":"traceutil/trace.go:171","msg":"trace[1923643402] transaction","detail":"{read_only:false; response_revision:5663; number_of_response:1; }","duration":"160.671997ms","start":"2026-04-20T17:51:39.993998Z","end":"2026-04-20T17:51:40.15467Z","steps":["trace[1923643402] 'process raft request' (duration: 159.795413ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:51:44.288282Z","caller":"traceutil/trace.go:171","msg":"trace[123920472] transaction","detail":"{read_only:false; response_revision:5688; number_of_response:1; }","duration":"528.276886ms","start":"2026-04-20T17:51:43.75998Z","end":"2026-04-20T17:51:44.288257Z","steps":["trace[123920472] 'process raft request' (duration: 528.076703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:51:44.288456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:51:43.759959Z","time spent":"528.401418ms","remote":"127.0.0.1:47138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T17:51:44.307199Z","caller":"traceutil/trace.go:171","msg":"trace[172461123] transaction","detail":"{read_only:false; response_revision:5689; number_of_response:1; }","duration":"514.230951ms","start":"2026-04-20T17:51:43.792908Z","end":"2026-04-20T17:51:44.307138Z","steps":["trace[172461123] 'process raft request' (duration: 513.996477ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:51:44.307767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:51:43.792877Z","time spent":"514.451124ms","remote":"127.0.0.1:47138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T17:51:44.386913Z","caller":"traceutil/trace.go:171","msg":"trace[1547561266] linearizableReadLoop","detail":"{readStateIndex:5899; appliedIndex:5896; }","duration":"433.826615ms","start":"2026-04-20T17:51:43.953056Z","end":"2026-04-20T17:51:44.386883Z","steps":["trace[1547561266] 'read index received' (duration: 334.983857ms)","trace[1547561266] 'applied index is now lower than readState.Index' (duration: 98.839858ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:51:44.387191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.064268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T17:51:44.387298Z","caller":"traceutil/trace.go:171","msg":"trace[1708848349] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:5690; }","duration":"434.233851ms","start":"2026-04-20T17:51:43.95304Z","end":"2026-04-20T17:51:44.387274Z","steps":["trace[1708848349] 'agreement among raft nodes before linearized reading' (duration: 433.939906ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:51:44.387284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.214337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-20T17:51:44.387345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.931403ms","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-20T17:51:44.387371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:51:43.953014Z","time spent":"434.331623ms","remote":"127.0.0.1:43884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true "} {"level":"info","ts":"2026-04-20T17:51:44.387391Z","caller":"traceutil/trace.go:171","msg":"trace[418671539] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5690; }","duration":"385.032895ms","start":"2026-04-20T17:51:44.002345Z","end":"2026-04-20T17:51:44.387378Z","steps":["trace[418671539] 'agreement among raft nodes before linearized reading' (duration: 384.701379ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:51:44.387427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:51:44.002321Z","time spent":"385.097905ms","remote":"127.0.0.1:47138","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-20T17:51:44.387487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.708965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T17:51:44.387487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.606329ms","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-20T17:51:44.387519Z","caller":"traceutil/trace.go:171","msg":"trace[1302687953] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5690; }","duration":"155.794547ms","start":"2026-04-20T17:51:44.231716Z","end":"2026-04-20T17:51:44.387511Z","steps":["trace[1302687953] 'agreement among raft nodes before linearized reading' (duration: 155.737336ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:51:44.387558Z","caller":"traceutil/trace.go:171","msg":"trace[954069657] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5690; }","duration":"214.083357ms","start":"2026-04-20T17:51:44.173458Z","end":"2026-04-20T17:51:44.387541Z","steps":["trace[954069657] 'agreement among raft nodes before linearized reading' (duration: 213.884274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:51:44.387681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.772511ms","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-20T17:51:44.387702Z","caller":"traceutil/trace.go:171","msg":"trace[1091688046] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5690; }","duration":"213.807813ms","start":"2026-04-20T17:51:44.173884Z","end":"2026-04-20T17:51:44.387692Z","steps":["trace[1091688046] 'agreement among raft nodes before linearized reading' (duration: 213.751312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:51:44.387779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.888536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-20T17:51:44.387422Z","caller":"traceutil/trace.go:171","msg":"trace[1088513708] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5690; }","duration":"212.38609ms","start":"2026-04-20T17:51:44.175013Z","end":"2026-04-20T17:51:44.387399Z","steps":["trace[1088513708] 'agreement among raft nodes before linearized reading' (duration: 212.087605ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:51:44.387814Z","caller":"traceutil/trace.go:171","msg":"trace[770580590] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5690; }","duration":"215.964386ms","start":"2026-04-20T17:51:44.17184Z","end":"2026-04-20T17:51:44.387804Z","steps":["trace[770580590] 'agreement among raft nodes before linearized reading' (duration: 215.857465ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:53:28.356962Z","caller":"traceutil/trace.go:171","msg":"trace[2051345110] linearizableReadLoop","detail":"{readStateIndex:6735; appliedIndex:6734; }","duration":"118.550668ms","start":"2026-04-20T17:53:28.238387Z","end":"2026-04-20T17:53:28.356938Z","steps":["trace[2051345110] 'read index received' (duration: 118.359185ms)","trace[2051345110] 'applied index is now lower than readState.Index' (duration: 190.153µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:53:28.357032Z","caller":"traceutil/trace.go:171","msg":"trace[184865183] transaction","detail":"{read_only:false; response_revision:6496; number_of_response:1; }","duration":"196.769984ms","start":"2026-04-20T17:53:28.160218Z","end":"2026-04-20T17:53:28.356988Z","steps":["trace[184865183] 'process raft request' (duration: 196.568901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:53:28.357088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.680289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T17:53:28.357127Z","caller":"traceutil/trace.go:171","msg":"trace[1150027361] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6496; }","duration":"118.75804ms","start":"2026-04-20T17:53:28.238355Z","end":"2026-04-20T17:53:28.357113Z","steps":["trace[1150027361] 'agreement among raft nodes before linearized reading' (duration: 118.681359ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:53:33.278975Z","caller":"traceutil/trace.go:171","msg":"trace[185690835] transaction","detail":"{read_only:false; response_revision:6519; number_of_response:1; }","duration":"268.716268ms","start":"2026-04-20T17:53:33.010225Z","end":"2026-04-20T17:53:33.278941Z","steps":["trace[185690835] 'process raft request' (duration: 268.480754ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:53:33.49055Z","caller":"traceutil/trace.go:171","msg":"trace[1028065518] linearizableReadLoop","detail":"{readStateIndex:6760; appliedIndex:6758; }","duration":"434.967785ms","start":"2026-04-20T17:53:33.055557Z","end":"2026-04-20T17:53:33.490524Z","steps":["trace[1028065518] 'read index received' (duration: 223.320436ms)","trace[1028065518] 'applied index is now lower than readState.Index' (duration: 211.646229ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:53:33.490865Z","caller":"traceutil/trace.go:171","msg":"trace[432668274] transaction","detail":"{read_only:false; response_revision:6520; number_of_response:1; }","duration":"480.59722ms","start":"2026-04-20T17:53:33.010255Z","end":"2026-04-20T17:53:33.490852Z","steps":["trace[432668274] 'process raft request' (duration: 480.088953ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:53:33.490973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:53:33.010232Z","time spent":"480.668111ms","remote":"127.0.0.1:47138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T17:53:33.491041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.075942ms","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-20T17:53:33.491124Z","caller":"traceutil/trace.go:171","msg":"trace[1134259914] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6521; }","duration":"434.198614ms","start":"2026-04-20T17:53:33.056906Z","end":"2026-04-20T17:53:33.491104Z","steps":["trace[1134259914] 'agreement among raft nodes before linearized reading' (duration: 433.969311ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:53:33.491157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.614584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-20T17:53:33.491169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:53:33.056894Z","time spent":"434.265875ms","remote":"127.0.0.1:47138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-20T17:53:33.491196Z","caller":"traceutil/trace.go:171","msg":"trace[1788671329] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6521; }","duration":"435.709605ms","start":"2026-04-20T17:53:33.055476Z","end":"2026-04-20T17:53:33.491186Z","steps":["trace[1788671329] 'agreement among raft nodes before linearized reading' (duration: 435.591174ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:53:33.491231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:53:33.055446Z","time spent":"435.779437ms","remote":"127.0.0.1:47138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T17:53:33.491295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.792666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T17:53:33.491317Z","caller":"traceutil/trace.go:171","msg":"trace[2120168893] transaction","detail":"{read_only:false; response_revision:6521; number_of_response:1; }","duration":"422.532567ms","start":"2026-04-20T17:53:33.068772Z","end":"2026-04-20T17:53:33.491304Z","steps":["trace[2120168893] 'process raft request' (duration: 421.707665ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:53:33.491333Z","caller":"traceutil/trace.go:171","msg":"trace[983283272] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6521; }","duration":"258.827276ms","start":"2026-04-20T17:53:33.232495Z","end":"2026-04-20T17:53:33.491323Z","steps":["trace[983283272] 'agreement among raft nodes before linearized reading' (duration: 258.776966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:53:33.491369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:53:33.068743Z","time spent":"422.596938ms","remote":"127.0.0.1:47138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T17:53:33.491516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.117199ms","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-20T17:53:33.491547Z","caller":"traceutil/trace.go:171","msg":"trace[794238406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6521; }","duration":"431.17228ms","start":"2026-04-20T17:53:33.060367Z","end":"2026-04-20T17:53:33.491539Z","steps":["trace[794238406] 'agreement among raft nodes before linearized reading' (duration: 431.089289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:53:33.491571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:53:33.060354Z","time spent":"431.212211ms","remote":"127.0.0.1:47138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-20T17:53:45.779905Z","caller":"traceutil/trace.go:171","msg":"trace[851873626] transaction","detail":"{read_only:false; response_revision:6642; number_of_response:1; }","duration":"133.862728ms","start":"2026-04-20T17:53:45.64602Z","end":"2026-04-20T17:53:45.779883Z","steps":["trace[851873626] 'process raft request' (duration: 133.826918ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:53:45.780254Z","caller":"traceutil/trace.go:171","msg":"trace[1403407571] transaction","detail":"{read_only:false; response_revision:6641; number_of_response:1; }","duration":"134.215443ms","start":"2026-04-20T17:53:45.645942Z","end":"2026-04-20T17:53:45.780158Z","steps":["trace[1403407571] 'process raft request' (duration: 97.202685ms)","trace[1403407571] 'compare' (duration: 36.600592ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:53:50.332464Z","caller":"traceutil/trace.go:171","msg":"trace[356262418] transaction","detail":"{read_only:false; response_revision:6664; number_of_response:1; }","duration":"242.582907ms","start":"2026-04-20T17:53:50.089857Z","end":"2026-04-20T17:53:50.33244Z","steps":["trace[356262418] 'process raft request' (duration: 242.419234ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:53:50.485059Z","caller":"traceutil/trace.go:171","msg":"trace[599943948] transaction","detail":"{read_only:false; response_revision:6665; number_of_response:1; }","duration":"391.043766ms","start":"2026-04-20T17:53:50.093986Z","end":"2026-04-20T17:53:50.485029Z","steps":["trace[599943948] 'process raft request' (duration: 358.336981ms)","trace[599943948] 'compare' (duration: 32.305249ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:53:50.485526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:53:50.093955Z","time spent":"391.188799ms","remote":"127.0.0.1:47138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":38,"request content":"compare: success:> failure: >"}