{"level":"warn","ts":"2026-10-10T10:19:24.807837Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-10T10:19:24.807959Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.80: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://162.253.55.80:2380","--initial-cluster=instance=https://162.253.55.80:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.80:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.80: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-10-10T10:19:24.808095Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-10T10:19:24.808112Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.80:2380"]} {"level":"info","ts":"2026-10-10T10:19:24.80816Z","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-10-10T10:19:24.809087Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.80:2379"]} {"level":"info","ts":"2026-10-10T10:19:24.809298Z","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://162.253.55.80:2380"],"listen-peer-urls":["https://162.253.55.80:2380"],"advertise-client-urls":["https://162.253.55.80:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.80:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.80: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-10-10T10:19:24.815109Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.448423ms"} {"level":"info","ts":"2026-10-10T10:19:24.823075Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a87e3d9cf178847b","cluster-id":"df2e96c7e71d56b9"} {"level":"info","ts":"2026-10-10T10:19:24.823172Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a87e3d9cf178847b switched to configuration voters=()"} {"level":"info","ts":"2026-10-10T10:19:24.823259Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a87e3d9cf178847b became follower at term 0"} {"level":"info","ts":"2026-10-10T10:19:24.823295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a87e3d9cf178847b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-10T10:19:24.823309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a87e3d9cf178847b became follower at term 1"} {"level":"info","ts":"2026-10-10T10:19:24.823394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a87e3d9cf178847b switched to configuration voters=(12141209389712835707)"} {"level":"warn","ts":"2026-10-10T10:19:24.829045Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-10T10:19:24.830869Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-10T10:19:24.832096Z","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-10-10T10:19:24.834978Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a87e3d9cf178847b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-10T10:19:24.835144Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a87e3d9cf178847b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-10T10:19:24.835385Z","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-10-10T10:19:24.835467Z","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-10-10T10:19:24.835479Z","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-10-10T10:19:24.836524Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a87e3d9cf178847b switched to configuration voters=(12141209389712835707)"} {"level":"info","ts":"2026-10-10T10:19:24.837727Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"df2e96c7e71d56b9","local-member-id":"a87e3d9cf178847b","added-peer-id":"a87e3d9cf178847b","added-peer-peer-urls":["https://162.253.55.80:2380"]} {"level":"info","ts":"2026-10-10T10:19:24.840906Z","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-10-10T10:19:24.841127Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a87e3d9cf178847b","initial-advertise-peer-urls":["https://162.253.55.80:2380"],"listen-peer-urls":["https://162.253.55.80:2380"],"advertise-client-urls":["https://162.253.55.80:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.80:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-10T10:19:24.841156Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-10T10:19:24.841304Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.80:2380"} {"level":"info","ts":"2026-10-10T10:19:24.841321Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.80:2380"} {"level":"info","ts":"2026-10-10T10:19:25.525232Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a87e3d9cf178847b is starting a new election at term 1"} {"level":"info","ts":"2026-10-10T10:19:25.52623Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a87e3d9cf178847b became pre-candidate at term 1"} {"level":"info","ts":"2026-10-10T10:19:25.526736Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a87e3d9cf178847b received MsgPreVoteResp from a87e3d9cf178847b at term 1"} {"level":"info","ts":"2026-10-10T10:19:25.52676Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a87e3d9cf178847b became candidate at term 2"} {"level":"info","ts":"2026-10-10T10:19:25.526766Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a87e3d9cf178847b received MsgVoteResp from a87e3d9cf178847b at term 2"} {"level":"info","ts":"2026-10-10T10:19:25.526774Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a87e3d9cf178847b became leader at term 2"} {"level":"info","ts":"2026-10-10T10:19:25.526782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a87e3d9cf178847b elected leader a87e3d9cf178847b at term 2"} {"level":"info","ts":"2026-10-10T10:19:25.528555Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-10T10:19:25.528769Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-10T10:19:25.528674Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a87e3d9cf178847b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.80:2379]}","request-path":"/0/members/a87e3d9cf178847b/attributes","cluster-id":"df2e96c7e71d56b9","publish-timeout":"7s"} {"level":"info","ts":"2026-10-10T10:19:25.528732Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-10T10:19:25.528947Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-10T10:19:25.528962Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-10T10:19:25.530898Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"df2e96c7e71d56b9","local-member-id":"a87e3d9cf178847b","cluster-version":"3.5"} {"level":"info","ts":"2026-10-10T10:19:25.53103Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-10T10:19:25.531057Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-10T10:19:25.531346Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.80:2379"} {"level":"info","ts":"2026-10-10T10:19:25.533062Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-10T10:19:34.513487Z","caller":"traceutil/trace.go:171","msg":"trace[1027419334] linearizableReadLoop","detail":"{readStateIndex:358; appliedIndex:357; }","duration":"111.071625ms","start":"2026-10-10T10:19:34.402386Z","end":"2026-10-10T10:19:34.513457Z","steps":["trace[1027419334] 'read index received' (duration: 30.50087ms)","trace[1027419334] 'applied index is now lower than readState.Index' (duration: 80.569725ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:19:34.513546Z","caller":"traceutil/trace.go:171","msg":"trace[1493493720] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"149.188886ms","start":"2026-10-10T10:19:34.364329Z","end":"2026-10-10T10:19:34.513518Z","steps":["trace[1493493720] 'process raft request' (duration: 141.812101ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:19:34.513695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.290172ms","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-10-10T10:19:34.513802Z","caller":"traceutil/trace.go:171","msg":"trace[417703022] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:346; }","duration":"111.448368ms","start":"2026-10-10T10:19:34.402338Z","end":"2026-10-10T10:19:34.513787Z","steps":["trace[417703022] 'agreement among raft nodes before linearized reading' (duration: 111.273212ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:19:34.709299Z","caller":"traceutil/trace.go:171","msg":"trace[205396685] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"128.836166ms","start":"2026-10-10T10:19:34.575158Z","end":"2026-10-10T10:19:34.703994Z","steps":["trace[205396685] 'process raft request' (duration: 56.615999ms)","trace[205396685] 'compare' (duration: 72.056091ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:19:34.709913Z","caller":"traceutil/trace.go:171","msg":"trace[1524466085] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"133.614121ms","start":"2026-10-10T10:19:34.576282Z","end":"2026-10-10T10:19:34.709896Z","steps":["trace[1524466085] 'process raft request' (duration: 127.679147ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:19:34.709954Z","caller":"traceutil/trace.go:171","msg":"trace[1009266064] linearizableReadLoop","detail":"{readStateIndex:366; appliedIndex:362; }","duration":"119.855217ms","start":"2026-10-10T10:19:34.589992Z","end":"2026-10-10T10:19:34.709848Z","steps":["trace[1009266064] 'read index received' (duration: 17.023097ms)","trace[1009266064] 'applied index is now lower than readState.Index' (duration: 102.82949ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:19:34.710079Z","caller":"traceutil/trace.go:171","msg":"trace[1024160939] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"133.689073ms","start":"2026-10-10T10:19:34.576378Z","end":"2026-10-10T10:19:34.710067Z","steps":["trace[1024160939] 'process raft request' (duration: 133.325921ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:19:34.710275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.264901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5326"} {"level":"info","ts":"2026-10-10T10:19:34.710319Z","caller":"traceutil/trace.go:171","msg":"trace[2140906314] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:354; }","duration":"120.360754ms","start":"2026-10-10T10:19:34.589946Z","end":"2026-10-10T10:19:34.710306Z","steps":["trace[2140906314] 'agreement among raft nodes before linearized reading' (duration: 120.2322ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:19:34.721695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.536921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-10-10T10:19:34.722019Z","caller":"traceutil/trace.go:171","msg":"trace[924161074] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"111.622142ms","start":"2026-10-10T10:19:34.610361Z","end":"2026-10-10T10:19:34.721983Z","steps":["trace[924161074] 'process raft request' (duration: 109.657425ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:19:34.721902Z","caller":"traceutil/trace.go:171","msg":"trace[1842464695] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:355; }","duration":"118.781919ms","start":"2026-10-10T10:19:34.603102Z","end":"2026-10-10T10:19:34.721884Z","steps":["trace[1842464695] 'agreement among raft nodes before linearized reading' (duration: 118.49416ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:19:34.911561Z","caller":"traceutil/trace.go:171","msg":"trace[1083916353] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"146.08956ms","start":"2026-10-10T10:19:34.76544Z","end":"2026-10-10T10:19:34.91153Z","steps":["trace[1083916353] 'process raft request' (duration: 77.109696ms)","trace[1083916353] 'compare' (duration: 68.84434ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:19:34.927897Z","caller":"traceutil/trace.go:171","msg":"trace[1291059110] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"127.185189ms","start":"2026-10-10T10:19:34.800683Z","end":"2026-10-10T10:19:34.927868Z","steps":["trace[1291059110] 'process raft request' (duration: 126.943831ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:19:34.927959Z","caller":"traceutil/trace.go:171","msg":"trace[464068170] linearizableReadLoop","detail":"{readStateIndex:377; appliedIndex:374; }","duration":"126.069071ms","start":"2026-10-10T10:19:34.801871Z","end":"2026-10-10T10:19:34.92794Z","steps":["trace[464068170] 'read index received' (duration: 40.678261ms)","trace[464068170] 'applied index is now lower than readState.Index' (duration: 85.38977ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:19:34.927961Z","caller":"traceutil/trace.go:171","msg":"trace[397011754] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"127.088686ms","start":"2026-10-10T10:19:34.80083Z","end":"2026-10-10T10:19:34.927918Z","steps":["trace[397011754] 'process raft request' (duration: 126.940401ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:19:34.928003Z","caller":"traceutil/trace.go:171","msg":"trace[1955040444] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"125.824062ms","start":"2026-10-10T10:19:34.802157Z","end":"2026-10-10T10:19:34.927981Z","steps":["trace[1955040444] 'process raft request' (duration: 125.675547ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:19:34.928266Z","caller":"traceutil/trace.go:171","msg":"trace[2135463700] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"121.914787ms","start":"2026-10-10T10:19:34.806299Z","end":"2026-10-10T10:19:34.928214Z","steps":["trace[2135463700] 'process raft request' (duration: 121.584156ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:19:34.928521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.63197ms","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-10-10T10:19:34.92857Z","caller":"traceutil/trace.go:171","msg":"trace[1386021719] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:367; }","duration":"126.720713ms","start":"2026-10-10T10:19:34.801836Z","end":"2026-10-10T10:19:34.928557Z","steps":["trace[1386021719] 'agreement among raft nodes before linearized reading' (duration: 126.621549ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:19:35.04386Z","caller":"traceutil/trace.go:171","msg":"trace[1221260916] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"107.660393ms","start":"2026-10-10T10:19:34.936151Z","end":"2026-10-10T10:19:35.043811Z","steps":["trace[1221260916] 'process raft request' (duration: 107.535939ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:19:35.043927Z","caller":"traceutil/trace.go:171","msg":"trace[1933015526] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"107.953263ms","start":"2026-10-10T10:19:34.935937Z","end":"2026-10-10T10:19:35.043891Z","steps":["trace[1933015526] 'process raft request' (duration: 104.040407ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:19:35.044091Z","caller":"traceutil/trace.go:171","msg":"trace[217066816] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"106.40462ms","start":"2026-10-10T10:19:34.937665Z","end":"2026-10-10T10:19:35.04407Z","steps":["trace[217066816] 'process raft request' (duration: 106.104429ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:19:35.044278Z","caller":"traceutil/trace.go:171","msg":"trace[172016868] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"107.667883ms","start":"2026-10-10T10:19:34.93653Z","end":"2026-10-10T10:19:35.044197Z","steps":["trace[172016868] 'process raft request' (duration: 107.199087ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:19:35.044357Z","caller":"traceutil/trace.go:171","msg":"trace[1619615058] linearizableReadLoop","detail":"{readStateIndex:383; appliedIndex:379; }","duration":"107.441617ms","start":"2026-10-10T10:19:34.936896Z","end":"2026-10-10T10:19:35.044338Z","steps":["trace[1619615058] 'read index received' (duration: 17.627487ms)","trace[1619615058] 'applied index is now lower than readState.Index' (duration: 89.81281ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:19:35.044522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.611012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3706"} {"level":"info","ts":"2026-10-10T10:19:35.044566Z","caller":"traceutil/trace.go:171","msg":"trace[1696428813] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:373; }","duration":"107.697875ms","start":"2026-10-10T10:19:34.936855Z","end":"2026-10-10T10:19:35.044553Z","steps":["trace[1696428813] 'agreement among raft nodes before linearized reading' (duration: 107.606112ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:19:50.966006Z","caller":"traceutil/trace.go:171","msg":"trace[1499745944] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"259.691221ms","start":"2026-10-10T10:19:50.706288Z","end":"2026-10-10T10:19:50.965979Z","steps":["trace[1499745944] 'process raft request' (duration: 259.537525ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:20:09.721546Z","caller":"traceutil/trace.go:171","msg":"trace[1196335705] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"151.452521ms","start":"2026-10-10T10:20:09.570067Z","end":"2026-10-10T10:20:09.72152Z","steps":["trace[1196335705] 'process raft request' (duration: 151.245661ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:20:09.721665Z","caller":"traceutil/trace.go:171","msg":"trace[843726791] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"116.621125ms","start":"2026-10-10T10:20:09.60503Z","end":"2026-10-10T10:20:09.721651Z","steps":["trace[843726791] 'process raft request' (duration: 116.5152ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:20:09.721648Z","caller":"traceutil/trace.go:171","msg":"trace[1722204866] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"134.823353ms","start":"2026-10-10T10:20:09.58677Z","end":"2026-10-10T10:20:09.721593Z","steps":["trace[1722204866] 'process raft request' (duration: 134.667966ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:20:09.72182Z","caller":"traceutil/trace.go:171","msg":"trace[1713842391] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"110.093066ms","start":"2026-10-10T10:20:09.61171Z","end":"2026-10-10T10:20:09.721803Z","steps":["trace[1713842391] 'process raft request' (duration: 109.888087ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:20:09.721913Z","caller":"traceutil/trace.go:171","msg":"trace[1680822947] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"119.344519ms","start":"2026-10-10T10:20:09.602465Z","end":"2026-10-10T10:20:09.72181Z","steps":["trace[1680822947] 'process raft request' (duration: 119.012214ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:20:09.73493Z","caller":"traceutil/trace.go:171","msg":"trace[334451967] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"113.958982ms","start":"2026-10-10T10:20:09.620935Z","end":"2026-10-10T10:20:09.734894Z","steps":["trace[334451967] 'process raft request' (duration: 113.733842ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:20:09.735094Z","caller":"traceutil/trace.go:171","msg":"trace[1604779436] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"109.751701ms","start":"2026-10-10T10:20:09.625335Z","end":"2026-10-10T10:20:09.735087Z","steps":["trace[1604779436] 'process raft request' (duration: 109.451857ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:20:09.73515Z","caller":"traceutil/trace.go:171","msg":"trace[1651537814] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"105.187124ms","start":"2026-10-10T10:20:09.629957Z","end":"2026-10-10T10:20:09.735144Z","steps":["trace[1651537814] 'process raft request' (duration: 104.89102ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:20:09.890925Z","caller":"traceutil/trace.go:171","msg":"trace[16851861] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"137.244463ms","start":"2026-10-10T10:20:09.753658Z","end":"2026-10-10T10:20:09.890902Z","steps":["trace[16851861] 'process raft request' (duration: 128.484425ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:20:09.893167Z","caller":"traceutil/trace.go:171","msg":"trace[764376700] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"119.866032ms","start":"2026-10-10T10:20:09.773282Z","end":"2026-10-10T10:20:09.893147Z","steps":["trace[764376700] 'process raft request' (duration: 119.499766ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:20:09.893802Z","caller":"traceutil/trace.go:171","msg":"trace[817489540] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"108.055573ms","start":"2026-10-10T10:20:09.785733Z","end":"2026-10-10T10:20:09.893788Z","steps":["trace[817489540] 'process raft request' (duration: 107.242596ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:20:10.581968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.745542ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-10T10:20:10.582485Z","caller":"traceutil/trace.go:171","msg":"trace[1638404689] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"148.076903ms","start":"2026-10-10T10:20:10.434373Z","end":"2026-10-10T10:20:10.58245Z","steps":["trace[1638404689] 'process raft request' (duration: 24.285773ms)","trace[1638404689] 'compare' (duration: 122.619277ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:20:10.583168Z","caller":"traceutil/trace.go:171","msg":"trace[1132551239] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"127.4764ms","start":"2026-10-10T10:20:10.455675Z","end":"2026-10-10T10:20:10.583151Z","steps":["trace[1132551239] 'process raft request' (duration: 126.804948ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:20:17.032697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.38061ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-10T10:20:17.032827Z","caller":"traceutil/trace.go:171","msg":"trace[1204984062] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"579.09944ms","start":"2026-10-10T10:20:16.453701Z","end":"2026-10-10T10:20:17.032801Z","steps":["trace[1204984062] 'process raft request' (duration: 310.51298ms)","trace[1204984062] 'compare' (duration: 267.987092ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:20:17.032909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:20:16.453677Z","time spent":"579.197504ms","remote":"127.0.0.1:50532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-10T10:20:17.056862Z","caller":"traceutil/trace.go:171","msg":"trace[420445712] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:673; }","duration":"429.829826ms","start":"2026-10-10T10:20:16.627005Z","end":"2026-10-10T10:20:17.056835Z","steps":["trace[420445712] 'read index received' (duration: 136.946237ms)","trace[420445712] 'applied index is now lower than readState.Index' (duration: 292.881919ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:20:17.057115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.085019ms","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-10-10T10:20:17.057153Z","caller":"traceutil/trace.go:171","msg":"trace[666451539] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:652; }","duration":"430.170083ms","start":"2026-10-10T10:20:16.626973Z","end":"2026-10-10T10:20:17.057143Z","steps":["trace[666451539] 'agreement among raft nodes before linearized reading' (duration: 429.983304ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:20:17.05721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:20:16.62696Z","time spent":"430.216925ms","remote":"127.0.0.1:50696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-10T10:20:17.057272Z","caller":"traceutil/trace.go:171","msg":"trace[2024955286] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"469.382441ms","start":"2026-10-10T10:20:16.587488Z","end":"2026-10-10T10:20:17.056871Z","steps":["trace[2024955286] 'process raft request' (duration: 469.190882ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:20:17.057606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:20:16.58747Z","time spent":"470.025282ms","remote":"127.0.0.1:50502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":688,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-10T10:20:17.057644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.157065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-h9wdd\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-10-10T10:20:17.057909Z","caller":"traceutil/trace.go:171","msg":"trace[936956156] range","detail":"{range_begin:/registry/pods/kube-system/cilium-h9wdd; range_end:; response_count:1; response_revision:652; }","duration":"352.479751ms","start":"2026-10-10T10:20:16.705411Z","end":"2026-10-10T10:20:17.05789Z","steps":["trace[936956156] 'agreement among raft nodes before linearized reading' (duration: 351.992758ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:20:17.057954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:20:16.705393Z","time spent":"352.549493ms","remote":"127.0.0.1:50628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-h9wdd\" "} {"level":"info","ts":"2026-10-10T10:20:17.32222Z","caller":"traceutil/trace.go:171","msg":"trace[828150683] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"256.923968ms","start":"2026-10-10T10:20:17.065241Z","end":"2026-10-10T10:20:17.322165Z","steps":["trace[828150683] 'process raft request' (duration: 223.449532ms)","trace[828150683] 'compare' (duration: 33.288097ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:20:17.392189Z","caller":"traceutil/trace.go:171","msg":"trace[1149534584] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"321.190501ms","start":"2026-10-10T10:20:17.070965Z","end":"2026-10-10T10:20:17.392156Z","steps":["trace[1149534584] 'process raft request' (duration: 320.873087ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:20:17.392243Z","caller":"traceutil/trace.go:171","msg":"trace[1219733065] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"296.065362ms","start":"2026-10-10T10:20:17.09615Z","end":"2026-10-10T10:20:17.392215Z","steps":["trace[1219733065] 'process raft request' (duration: 295.834341ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:20:17.392378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:20:17.07093Z","time spent":"321.348039ms","remote":"127.0.0.1:50628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-10T10:20:17.907403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.918588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-10T10:20:17.907Z","caller":"traceutil/trace.go:171","msg":"trace[785025838] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"496.991038ms","start":"2026-10-10T10:20:17.409972Z","end":"2026-10-10T10:20:17.906963Z","steps":["trace[785025838] 'process raft request' (duration: 440.288432ms)","trace[785025838] 'compare' (duration: 56.388201ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:20:17.907523Z","caller":"traceutil/trace.go:171","msg":"trace[1264662778] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:656; }","duration":"238.139608ms","start":"2026-10-10T10:20:17.669365Z","end":"2026-10-10T10:20:17.907504Z","steps":["trace[1264662778] 'agreement among raft nodes before linearized reading' (duration: 237.522589ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:20:17.906876Z","caller":"traceutil/trace.go:171","msg":"trace[843087598] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:678; }","duration":"237.387203ms","start":"2026-10-10T10:20:17.669448Z","end":"2026-10-10T10:20:17.906836Z","steps":["trace[843087598] 'read index received' (duration: 180.823924ms)","trace[843087598] 'applied index is now lower than readState.Index' (duration: 56.562009ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:20:17.907616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:20:17.409955Z","time spent":"497.597016ms","remote":"127.0.0.1:50628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-10T10:20:18.240512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.827856ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-10T10:20:18.240678Z","caller":"traceutil/trace.go:171","msg":"trace[902854595] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"328.041966ms","start":"2026-10-10T10:20:17.91261Z","end":"2026-10-10T10:20:18.240652Z","steps":["trace[902854595] 'process raft request' (duration: 206.007261ms)","trace[902854595] 'compare' (duration: 121.705569ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:20:18.24076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:20:17.912594Z","time spent":"328.13063ms","remote":"127.0.0.1:50502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-10T10:20:18.278021Z","caller":"traceutil/trace.go:171","msg":"trace[514286459] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"364.078371ms","start":"2026-10-10T10:20:17.913915Z","end":"2026-10-10T10:20:18.277994Z","steps":["trace[514286459] 'process raft request' (duration: 363.612999ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:20:18.27815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:20:17.913889Z","time spent":"364.200046ms","remote":"127.0.0.1:50502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-10T10:20:18.278127Z","caller":"traceutil/trace.go:171","msg":"trace[1288512438] linearizableReadLoop","detail":"{readStateIndex:683; appliedIndex:679; }","duration":"244.265429ms","start":"2026-10-10T10:20:18.033834Z","end":"2026-10-10T10:20:18.2781Z","steps":["trace[1288512438] 'read index received' (duration: 84.766512ms)","trace[1288512438] 'applied index is now lower than readState.Index' (duration: 159.497917ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:20:18.278356Z","caller":"traceutil/trace.go:171","msg":"trace[911167757] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"363.720093ms","start":"2026-10-10T10:20:17.914616Z","end":"2026-10-10T10:20:18.278336Z","steps":["trace[911167757] 'process raft request' (duration: 363.242721ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:20:18.278543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.692889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-10T10:20:18.278606Z","caller":"traceutil/trace.go:171","msg":"trace[465706142] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:660; }","duration":"244.816645ms","start":"2026-10-10T10:20:18.033769Z","end":"2026-10-10T10:20:18.278586Z","steps":["trace[465706142] 'agreement among raft nodes before linearized reading' (duration: 244.572893ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:20:18.278382Z","caller":"traceutil/trace.go:171","msg":"trace[1001424019] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"362.675984ms","start":"2026-10-10T10:20:17.915609Z","end":"2026-10-10T10:20:18.278285Z","steps":["trace[1001424019] 'process raft request' (duration: 362.38848ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:20:18.278954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:20:17.914603Z","time spent":"363.973664ms","remote":"127.0.0.1:50674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-10T10:20:18.279038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:20:17.915598Z","time spent":"363.387268ms","remote":"127.0.0.1:50628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-10T10:20:18.280403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.892942ms","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-10-10T10:20:18.28047Z","caller":"traceutil/trace.go:171","msg":"trace[1513350081] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:660; }","duration":"243.984936ms","start":"2026-10-10T10:20:18.036465Z","end":"2026-10-10T10:20:18.28045Z","steps":["trace[1513350081] 'agreement among raft nodes before linearized reading' (duration: 243.822698ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:20:19.988589Z","caller":"traceutil/trace.go:171","msg":"trace[2135509903] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"106.465616ms","start":"2026-10-10T10:20:19.882102Z","end":"2026-10-10T10:20:19.988568Z","steps":["trace[2135509903] 'process raft request' (duration: 106.33554ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:20:33.675503Z","caller":"traceutil/trace.go:171","msg":"trace[469773701] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"273.131698ms","start":"2026-10-10T10:20:33.40234Z","end":"2026-10-10T10:20:33.675471Z","steps":["trace[469773701] 'process raft request' (duration: 272.928587ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:21:39.501801Z","caller":"traceutil/trace.go:171","msg":"trace[1078850490] transaction","detail":"{read_only:false; response_revision:1715; number_of_response:1; }","duration":"169.826477ms","start":"2026-10-10T10:21:39.33195Z","end":"2026-10-10T10:21:39.501776Z","steps":["trace[1078850490] 'process raft request' (duration: 169.751723ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:21:39.501995Z","caller":"traceutil/trace.go:171","msg":"trace[1357568817] transaction","detail":"{read_only:false; response_revision:1714; number_of_response:1; }","duration":"189.109078ms","start":"2026-10-10T10:21:39.312855Z","end":"2026-10-10T10:21:39.501965Z","steps":["trace[1357568817] 'process raft request' (duration: 146.416212ms)","trace[1357568817] 'compare' (duration: 42.273242ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:21:52.185551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.966585ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-10T10:21:52.185794Z","caller":"traceutil/trace.go:171","msg":"trace[1036807325] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"631.741703ms","start":"2026-10-10T10:21:51.554035Z","end":"2026-10-10T10:21:52.185777Z","steps":["trace[1036807325] 'process raft request' (duration: 631.647308ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:21:52.185764Z","caller":"traceutil/trace.go:171","msg":"trace[1272316476] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"866.650371ms","start":"2026-10-10T10:21:51.31908Z","end":"2026-10-10T10:21:52.185731Z","steps":["trace[1272316476] 'process raft request' (duration: 564.411751ms)","trace[1272316476] 'compare' (duration: 301.495817ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:21:52.185864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:21:51.554005Z","time spent":"631.826487ms","remote":"127.0.0.1:50696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-10T10:21:52.185971Z","caller":"traceutil/trace.go:171","msg":"trace[2140494117] linearizableReadLoop","detail":"{readStateIndex:1954; appliedIndex:1953; }","duration":"762.176336ms","start":"2026-10-10T10:21:51.423785Z","end":"2026-10-10T10:21:52.185962Z","steps":["trace[2140494117] 'read index received' (duration: 459.693472ms)","trace[2140494117] 'applied index is now lower than readState.Index' (duration: 302.482004ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:21:52.185983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:21:51.319046Z","time spent":"866.831321ms","remote":"127.0.0.1:50696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-10T10:21:52.186131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.342095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-10T10:21:52.186164Z","caller":"traceutil/trace.go:171","msg":"trace[2144365757] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1886; }","duration":"762.411879ms","start":"2026-10-10T10:21:51.423743Z","end":"2026-10-10T10:21:52.186154Z","steps":["trace[2144365757] 'agreement among raft nodes before linearized reading' (duration: 762.339785ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:21:52.186191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:21:51.423721Z","time spent":"762.462813ms","remote":"127.0.0.1:50962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":36,"response size":31,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-10-10T10:21:52.41335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.752676ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-10T10:21:52.413443Z","caller":"traceutil/trace.go:171","msg":"trace[1864201518] linearizableReadLoop","detail":"{readStateIndex:1956; appliedIndex:1955; }","duration":"227.410177ms","start":"2026-10-10T10:21:52.186017Z","end":"2026-10-10T10:21:52.413427Z","steps":["trace[1864201518] 'read index received' (duration: 118.237766ms)","trace[1864201518] 'applied index is now lower than readState.Index' (duration: 109.171161ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:21:52.413753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.786724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-10T10:21:52.413937Z","caller":"traceutil/trace.go:171","msg":"trace[120717275] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1887; }","duration":"745.026258ms","start":"2026-10-10T10:21:51.668888Z","end":"2026-10-10T10:21:52.413915Z","steps":["trace[120717275] 'agreement among raft nodes before linearized reading' (duration: 744.748542ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:21:52.413959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.206689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-44ae8919-8a19-483e-b9c0-9ad6dd9ea990\" ","response":"range_response_count:1 size:3746"} {"level":"warn","ts":"2026-10-10T10:21:52.414009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.910919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-10-10T10:21:52.414038Z","caller":"traceutil/trace.go:171","msg":"trace[548473808] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-44ae8919-8a19-483e-b9c0-9ad6dd9ea990; range_end:; response_count:1; response_revision:1887; }","duration":"107.330186ms","start":"2026-10-10T10:21:52.306688Z","end":"2026-10-10T10:21:52.414018Z","steps":["trace[548473808] 'agreement among raft nodes before linearized reading' (duration: 107.153116ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:21:52.414032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.918483ms","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-10-10T10:21:52.414127Z","caller":"traceutil/trace.go:171","msg":"trace[219612941] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1887; }","duration":"194.057131ms","start":"2026-10-10T10:21:52.220055Z","end":"2026-10-10T10:21:52.414112Z","steps":["trace[219612941] 'agreement among raft nodes before linearized reading' (duration: 193.839008ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:21:52.414119Z","caller":"traceutil/trace.go:171","msg":"trace[116230476] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1887; }","duration":"209.047437ms","start":"2026-10-10T10:21:52.205017Z","end":"2026-10-10T10:21:52.414065Z","steps":["trace[116230476] 'agreement among raft nodes before linearized reading' (duration: 208.75094ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:21:52.414092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:21:51.668869Z","time spent":"745.177327ms","remote":"127.0.0.1:50412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-10T10:21:52.414297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.740113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-10-10T10:21:52.414366Z","caller":"traceutil/trace.go:171","msg":"trace[804724209] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1887; }","duration":"606.851829ms","start":"2026-10-10T10:21:51.807494Z","end":"2026-10-10T10:21:52.414346Z","steps":["trace[804724209] 'agreement among raft nodes before linearized reading' (duration: 606.312688ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:21:52.413541Z","caller":"traceutil/trace.go:171","msg":"trace[1900782148] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"522.812893ms","start":"2026-10-10T10:21:51.89071Z","end":"2026-10-10T10:21:52.413523Z","steps":["trace[1900782148] 'process raft request' (duration: 413.516614ms)","trace[1900782148] 'compare' (duration: 108.598128ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:21:52.414567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:21:51.807474Z","time spent":"607.076012ms","remote":"127.0.0.1:58580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5468,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-10-10T10:21:52.414872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:21:51.890688Z","time spent":"523.893735ms","remote":"127.0.0.1:50696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-10T10:21:52.621836Z","caller":"traceutil/trace.go:171","msg":"trace[192728380] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"202.445785ms","start":"2026-10-10T10:21:52.419362Z","end":"2026-10-10T10:21:52.621808Z","steps":["trace[192728380] 'process raft request' (duration: 132.566201ms)","trace[192728380] 'compare' (duration: 69.617679ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:21:52.62211Z","caller":"traceutil/trace.go:171","msg":"trace[177767726] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"202.504299ms","start":"2026-10-10T10:21:52.419593Z","end":"2026-10-10T10:21:52.622097Z","steps":["trace[177767726] 'process raft request' (duration: 202.130107ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:22:06.464692Z","caller":"traceutil/trace.go:171","msg":"trace[1330929052] linearizableReadLoop","detail":"{readStateIndex:2075; appliedIndex:2074; }","duration":"190.745822ms","start":"2026-10-10T10:22:06.273921Z","end":"2026-10-10T10:22:06.464667Z","steps":["trace[1330929052] 'read index received' (duration: 190.314747ms)","trace[1330929052] 'applied index is now lower than readState.Index' (duration: 429.515µs)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:22:06.464738Z","caller":"traceutil/trace.go:171","msg":"trace[862221615] transaction","detail":"{read_only:false; response_revision:2003; number_of_response:1; }","duration":"228.418009ms","start":"2026-10-10T10:22:06.236287Z","end":"2026-10-10T10:22:06.464705Z","steps":["trace[862221615] 'process raft request' (duration: 228.127412ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:22:06.465324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.358508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-10T10:22:06.465435Z","caller":"traceutil/trace.go:171","msg":"trace[1566038409] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2003; }","duration":"191.55484ms","start":"2026-10-10T10:22:06.273804Z","end":"2026-10-10T10:22:06.465359Z","steps":["trace[1566038409] 'agreement among raft nodes before linearized reading' (duration: 190.995607ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:22:26.851672Z","caller":"traceutil/trace.go:171","msg":"trace[1893882807] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"267.880484ms","start":"2026-10-10T10:22:26.583767Z","end":"2026-10-10T10:22:26.851647Z","steps":["trace[1893882807] 'process raft request' (duration: 267.695903ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:22:26.871042Z","caller":"traceutil/trace.go:171","msg":"trace[471045750] linearizableReadLoop","detail":"{readStateIndex:2206; appliedIndex:2204; }","duration":"251.686598ms","start":"2026-10-10T10:22:26.619324Z","end":"2026-10-10T10:22:26.87101Z","steps":["trace[471045750] 'read index received' (duration: 232.619045ms)","trace[471045750] 'applied index is now lower than readState.Index' (duration: 19.061993ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:22:26.871152Z","caller":"traceutil/trace.go:171","msg":"trace[1198646389] transaction","detail":"{read_only:false; response_revision:2131; number_of_response:1; }","duration":"212.671259ms","start":"2026-10-10T10:22:26.658452Z","end":"2026-10-10T10:22:26.871123Z","steps":["trace[1198646389] 'process raft request' (duration: 212.469107ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:22:26.87129Z","caller":"traceutil/trace.go:171","msg":"trace[314684467] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"282.879029ms","start":"2026-10-10T10:22:26.588357Z","end":"2026-10-10T10:22:26.871236Z","steps":["trace[314684467] 'process raft request' (duration: 282.329597ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:22:26.871421Z","caller":"traceutil/trace.go:171","msg":"trace[271960227] transaction","detail":"{read_only:false; response_revision:2130; number_of_response:1; }","duration":"263.812334ms","start":"2026-10-10T10:22:26.607596Z","end":"2026-10-10T10:22:26.871408Z","steps":["trace[271960227] 'process raft request' (duration: 263.260621ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:22:26.871474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.131886ms","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-10-10T10:22:26.871518Z","caller":"traceutil/trace.go:171","msg":"trace[1325927776] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2132; }","duration":"252.219921ms","start":"2026-10-10T10:22:26.619283Z","end":"2026-10-10T10:22:26.871503Z","steps":["trace[1325927776] 'agreement among raft nodes before linearized reading' (duration: 252.053561ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:22:26.871642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.968796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-10T10:22:26.871664Z","caller":"traceutil/trace.go:171","msg":"trace[568372720] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2132; }","duration":"204.011719ms","start":"2026-10-10T10:22:26.667644Z","end":"2026-10-10T10:22:26.871656Z","steps":["trace[568372720] 'agreement among raft nodes before linearized reading' (duration: 203.967777ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:22:26.871884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.625351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-10T10:22:26.871917Z","caller":"traceutil/trace.go:171","msg":"trace[1757415404] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:2132; }","duration":"189.679454ms","start":"2026-10-10T10:22:26.682223Z","end":"2026-10-10T10:22:26.871903Z","steps":["trace[1757415404] 'agreement among raft nodes before linearized reading' (duration: 189.625601ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:22:26.872618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.732609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-10-10T10:22:26.872688Z","caller":"traceutil/trace.go:171","msg":"trace[1613461160] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2132; }","duration":"157.856026ms","start":"2026-10-10T10:22:26.714809Z","end":"2026-10-10T10:22:26.872665Z","steps":["trace[1613461160] 'agreement among raft nodes before linearized reading' (duration: 157.015107ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:22:27.220134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.92016ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-10T10:22:27.220323Z","caller":"traceutil/trace.go:171","msg":"trace[1586509077] linearizableReadLoop","detail":"{readStateIndex:2210; appliedIndex:2208; }","duration":"327.627613ms","start":"2026-10-10T10:22:26.892674Z","end":"2026-10-10T10:22:27.220301Z","steps":["trace[1586509077] 'read index received' (duration: 18.447708ms)","trace[1586509077] 'applied index is now lower than readState.Index' (duration: 309.178565ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:22:27.220426Z","caller":"traceutil/trace.go:171","msg":"trace[183845655] transaction","detail":"{read_only:false; response_revision:2133; number_of_response:1; }","duration":"343.073753ms","start":"2026-10-10T10:22:26.877338Z","end":"2026-10-10T10:22:27.220412Z","steps":["trace[183845655] 'process raft request' (duration: 33.774192ms)","trace[183845655] 'compare' (duration: 308.747389ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:22:27.22049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:22:26.877315Z","time spent":"343.140917ms","remote":"127.0.0.1:50696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-10T10:22:27.220945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.153148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-10-10T10:22:27.221008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.298139ms","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-10-10T10:22:27.221054Z","caller":"traceutil/trace.go:171","msg":"trace[2056862318] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2134; }","duration":"196.381434ms","start":"2026-10-10T10:22:27.024655Z","end":"2026-10-10T10:22:27.221037Z","steps":["trace[2056862318] 'agreement among raft nodes before linearized reading' (duration: 196.204194ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:22:27.221035Z","caller":"traceutil/trace.go:171","msg":"trace[1808148967] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2134; }","duration":"323.286757ms","start":"2026-10-10T10:22:26.897727Z","end":"2026-10-10T10:22:27.221014Z","steps":["trace[1808148967] 'agreement among raft nodes before linearized reading' (duration: 323.035062ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:22:27.221234Z","caller":"traceutil/trace.go:171","msg":"trace[1077265359] transaction","detail":"{read_only:false; response_revision:2134; number_of_response:1; }","duration":"343.032621ms","start":"2026-10-10T10:22:26.878191Z","end":"2026-10-10T10:22:27.221224Z","steps":["trace[1077265359] 'process raft request' (duration: 342.045113ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:22:27.221209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:22:26.897715Z","time spent":"323.458577ms","remote":"127.0.0.1:50696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-10T10:22:27.221304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:22:26.878165Z","time spent":"343.101414ms","remote":"127.0.0.1:50696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-10T10:22:27.221411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.476011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-10T10:22:27.221463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.849136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"warn","ts":"2026-10-10T10:22:27.22148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.838946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:107747"} {"level":"info","ts":"2026-10-10T10:22:27.22151Z","caller":"traceutil/trace.go:171","msg":"trace[579087111] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:2134; }","duration":"155.64116ms","start":"2026-10-10T10:22:27.065815Z","end":"2026-10-10T10:22:27.221456Z","steps":["trace[579087111] 'agreement among raft nodes before linearized reading' (duration: 155.497702ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:22:27.221501Z","caller":"traceutil/trace.go:171","msg":"trace[621708300] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2134; }","duration":"287.954032ms","start":"2026-10-10T10:22:26.933537Z","end":"2026-10-10T10:22:27.221491Z","steps":["trace[621708300] 'agreement among raft nodes before linearized reading' (duration: 287.881988ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:22:27.221614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.255986ms","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-10-10T10:22:27.221651Z","caller":"traceutil/trace.go:171","msg":"trace[1887399519] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2134; }","duration":"255.401415ms","start":"2026-10-10T10:22:26.96624Z","end":"2026-10-10T10:22:27.221641Z","steps":["trace[1887399519] 'agreement among raft nodes before linearized reading' (duration: 255.295988ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:22:27.221518Z","caller":"traceutil/trace.go:171","msg":"trace[1985324250] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2134; }","duration":"287.975974ms","start":"2026-10-10T10:22:26.933534Z","end":"2026-10-10T10:22:27.22151Z","steps":["trace[1985324250] 'agreement among raft nodes before linearized reading' (duration: 287.837125ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:22:27.221878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.212227ms","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-10-10T10:22:27.221918Z","caller":"traceutil/trace.go:171","msg":"trace[811991081] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2134; }","duration":"329.287561ms","start":"2026-10-10T10:22:26.892621Z","end":"2026-10-10T10:22:27.221909Z","steps":["trace[811991081] 'agreement among raft nodes before linearized reading' (duration: 329.176385ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:22:27.221952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:22:26.892606Z","time spent":"329.336464ms","remote":"127.0.0.1:50696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-10T10:23:31.428085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.288486ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-10T10:23:31.428313Z","caller":"traceutil/trace.go:171","msg":"trace[1573990662] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2581; }","duration":"108.534629ms","start":"2026-10-10T10:23:31.319759Z","end":"2026-10-10T10:23:31.428294Z","steps":["trace[1573990662] 'range keys from in-memory index tree' (duration: 108.268815ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:24:04.011642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.868484ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-10T10:24:04.011806Z","caller":"traceutil/trace.go:171","msg":"trace[1302997332] transaction","detail":"{read_only:false; response_revision:2761; number_of_response:1; }","duration":"191.73893ms","start":"2026-10-10T10:24:03.820038Z","end":"2026-10-10T10:24:04.011777Z","steps":["trace[1302997332] 'process raft request' (duration: 50.614701ms)","trace[1302997332] 'compare' (duration: 140.67993ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:24:04.038281Z","caller":"traceutil/trace.go:171","msg":"trace[1559766492] transaction","detail":"{read_only:false; response_revision:2762; number_of_response:1; }","duration":"217.743967ms","start":"2026-10-10T10:24:03.820496Z","end":"2026-10-10T10:24:04.03824Z","steps":["trace[1559766492] 'process raft request' (duration: 217.521122ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:24:04.038762Z","caller":"traceutil/trace.go:171","msg":"trace[253917848] transaction","detail":"{read_only:false; response_revision:2763; number_of_response:1; }","duration":"218.140214ms","start":"2026-10-10T10:24:03.820591Z","end":"2026-10-10T10:24:04.038731Z","steps":["trace[253917848] 'process raft request' (duration: 217.602484ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:24:04.039056Z","caller":"traceutil/trace.go:171","msg":"trace[1497720625] transaction","detail":"{read_only:false; response_revision:2764; number_of_response:1; }","duration":"217.215895ms","start":"2026-10-10T10:24:03.821811Z","end":"2026-10-10T10:24:04.039027Z","steps":["trace[1497720625] 'process raft request' (duration: 216.822748ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:24:04.03932Z","caller":"traceutil/trace.go:171","msg":"trace[800291530] transaction","detail":"{read_only:false; response_revision:2765; number_of_response:1; }","duration":"215.777327ms","start":"2026-10-10T10:24:03.823507Z","end":"2026-10-10T10:24:04.039285Z","steps":["trace[800291530] 'process raft request' (duration: 215.401378ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:28:25.328149Z","caller":"traceutil/trace.go:171","msg":"trace[713391600] linearizableReadLoop","detail":"{readStateIndex:4906; appliedIndex:4904; }","duration":"100.575295ms","start":"2026-10-10T10:28:25.227543Z","end":"2026-10-10T10:28:25.328118Z","steps":["trace[713391600] 'read index received' (duration: 55.924483ms)","trace[713391600] 'applied index is now lower than readState.Index' (duration: 44.649792ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:28:25.328439Z","caller":"traceutil/trace.go:171","msg":"trace[487273607] transaction","detail":"{read_only:false; response_revision:4732; number_of_response:1; }","duration":"172.521682ms","start":"2026-10-10T10:28:25.155887Z","end":"2026-10-10T10:28:25.328408Z","steps":["trace[487273607] 'process raft request' (duration: 127.649349ms)","trace[487273607] 'compare' (duration: 44.365009ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:28:25.329029Z","caller":"traceutil/trace.go:171","msg":"trace[1049135385] transaction","detail":"{read_only:false; response_revision:4733; number_of_response:1; }","duration":"172.736883ms","start":"2026-10-10T10:28:25.156279Z","end":"2026-10-10T10:28:25.329016Z","steps":["trace[1049135385] 'process raft request' (duration: 171.788267ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:28:25.329963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.400044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207411"} {"level":"info","ts":"2026-10-10T10:28:25.330044Z","caller":"traceutil/trace.go:171","msg":"trace[1981665173] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4733; }","duration":"102.52591ms","start":"2026-10-10T10:28:25.227495Z","end":"2026-10-10T10:28:25.33002Z","steps":["trace[1981665173] 'agreement among raft nodes before linearized reading' (duration: 100.750364ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:28:29.470002Z","caller":"traceutil/trace.go:171","msg":"trace[1192447869] transaction","detail":"{read_only:false; response_revision:4832; number_of_response:1; }","duration":"109.572389ms","start":"2026-10-10T10:28:29.360406Z","end":"2026-10-10T10:28:29.469979Z","steps":["trace[1192447869] 'process raft request' (duration: 109.356978ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:28:29.831354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.377324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-1f2b9b7d-5d47-41f7-8c3c-7d29549fc19b\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-10-10T10:28:29.831432Z","caller":"traceutil/trace.go:171","msg":"trace[349027997] range","detail":"{range_begin:/registry/persistentvolumes/pvc-1f2b9b7d-5d47-41f7-8c3c-7d29549fc19b; range_end:; response_count:1; response_revision:4835; }","duration":"275.483809ms","start":"2026-10-10T10:28:29.555931Z","end":"2026-10-10T10:28:29.831414Z","steps":["trace[349027997] 'range keys from in-memory index tree' (duration: 275.255988ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:28:29.83204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.557574ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-10T10:28:29.832197Z","caller":"traceutil/trace.go:171","msg":"trace[925318733] transaction","detail":"{read_only:false; response_revision:4836; number_of_response:1; }","duration":"275.476699ms","start":"2026-10-10T10:28:29.556703Z","end":"2026-10-10T10:28:29.83218Z","steps":["trace[925318733] 'process raft request' (duration: 140.671953ms)","trace[925318733] 'compare' (duration: 133.793007ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:28:29.832289Z","caller":"traceutil/trace.go:171","msg":"trace[635739785] linearizableReadLoop","detail":"{readStateIndex:5014; appliedIndex:5013; }","duration":"268.080396ms","start":"2026-10-10T10:28:29.564176Z","end":"2026-10-10T10:28:29.832256Z","steps":["trace[635739785] 'read index received' (duration: 133.215007ms)","trace[635739785] 'applied index is now lower than readState.Index' (duration: 134.859628ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:28:29.832323Z","caller":"traceutil/trace.go:171","msg":"trace[1540437397] transaction","detail":"{read_only:false; response_revision:4837; number_of_response:1; }","duration":"265.158474ms","start":"2026-10-10T10:28:29.567146Z","end":"2026-10-10T10:28:29.832304Z","steps":["trace[1540437397] 'process raft request' (duration: 265.031387ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:28:29.83249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.594726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-10T10:28:29.832523Z","caller":"traceutil/trace.go:171","msg":"trace[393934086] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4837; }","duration":"164.661349ms","start":"2026-10-10T10:28:29.667851Z","end":"2026-10-10T10:28:29.832512Z","steps":["trace[393934086] 'agreement among raft nodes before linearized reading' (duration: 164.605286ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:28:29.83256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.778725ms","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-10-10T10:28:29.832557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.839095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-10T10:28:29.832585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.388792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-10T10:28:29.832617Z","caller":"traceutil/trace.go:171","msg":"trace[1828341645] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4837; }","duration":"254.847598ms","start":"2026-10-10T10:28:29.577752Z","end":"2026-10-10T10:28:29.8326Z","steps":["trace[1828341645] 'agreement among raft nodes before linearized reading' (duration: 254.68165ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:28:29.832625Z","caller":"traceutil/trace.go:171","msg":"trace[1949059588] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4837; }","duration":"268.463606ms","start":"2026-10-10T10:28:29.564147Z","end":"2026-10-10T10:28:29.832611Z","steps":["trace[1949059588] 'agreement among raft nodes before linearized reading' (duration: 268.255296ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:28:29.832625Z","caller":"traceutil/trace.go:171","msg":"trace[495421386] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4837; }","duration":"227.911309ms","start":"2026-10-10T10:28:29.604696Z","end":"2026-10-10T10:28:29.832607Z","steps":["trace[495421386] 'agreement among raft nodes before linearized reading' (duration: 227.809564ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:28:35.439297Z","caller":"traceutil/trace.go:171","msg":"trace[528427039] linearizableReadLoop","detail":"{readStateIndex:5131; appliedIndex:5130; }","duration":"181.989551ms","start":"2026-10-10T10:28:35.25729Z","end":"2026-10-10T10:28:35.439279Z","steps":["trace[528427039] 'read index received' (duration: 181.904657ms)","trace[528427039] 'applied index is now lower than readState.Index' (duration: 84.274µs)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:28:35.439316Z","caller":"traceutil/trace.go:171","msg":"trace[796124906] transaction","detail":"{read_only:false; response_revision:4951; number_of_response:1; }","duration":"244.759069ms","start":"2026-10-10T10:28:35.194545Z","end":"2026-10-10T10:28:35.439304Z","steps":["trace[796124906] 'process raft request' (duration: 244.620762ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:28:35.43953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.227634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-7dc9b86c6-mk7cx\" ","response":"range_response_count:1 size:11251"} {"level":"info","ts":"2026-10-10T10:28:35.439581Z","caller":"traceutil/trace.go:171","msg":"trace[1495937970] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-7dc9b86c6-mk7cx; range_end:; response_count:1; response_revision:4951; }","duration":"182.266996ms","start":"2026-10-10T10:28:35.257283Z","end":"2026-10-10T10:28:35.43955Z","steps":["trace[1495937970] 'agreement among raft nodes before linearized reading' (duration: 182.100357ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:28:35.680497Z","caller":"traceutil/trace.go:171","msg":"trace[1923873780] transaction","detail":"{read_only:false; response_revision:4952; number_of_response:1; }","duration":"422.678862ms","start":"2026-10-10T10:28:35.257799Z","end":"2026-10-10T10:28:35.680478Z","steps":["trace[1923873780] 'process raft request' (duration: 388.850298ms)","trace[1923873780] 'compare' (duration: 33.371171ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:28:35.680661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.467521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-10T10:28:35.680703Z","caller":"traceutil/trace.go:171","msg":"trace[1674748013] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:4952; }","duration":"409.555175ms","start":"2026-10-10T10:28:35.271138Z","end":"2026-10-10T10:28:35.680693Z","steps":["trace[1674748013] 'agreement among raft nodes before linearized reading' (duration: 409.44731ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:28:35.680732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:28:35.271129Z","time spent":"409.593468ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"info","ts":"2026-10-10T10:28:35.680497Z","caller":"traceutil/trace.go:171","msg":"trace[995305251] linearizableReadLoop","detail":"{readStateIndex:5132; appliedIndex:5131; }","duration":"241.12631ms","start":"2026-10-10T10:28:35.439345Z","end":"2026-10-10T10:28:35.680471Z","steps":["trace[995305251] 'read index received' (duration: 207.314577ms)","trace[995305251] 'applied index is now lower than readState.Index' (duration: 33.809893ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:28:35.680868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:28:35.257778Z","time spent":"422.85901ms","remote":"127.0.0.1:50502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":878,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-10T10:28:35.888101Z","caller":"traceutil/trace.go:171","msg":"trace[423515671] transaction","detail":"{read_only:false; response_revision:4953; number_of_response:1; }","duration":"435.483851ms","start":"2026-10-10T10:28:35.452588Z","end":"2026-10-10T10:28:35.888072Z","steps":["trace[423515671] 'process raft request' (duration: 413.964812ms)","trace[423515671] 'compare' (duration: 21.289068ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:28:35.888158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.689877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-10T10:28:35.888199Z","caller":"traceutil/trace.go:171","msg":"trace[946093096] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4953; }","duration":"218.780631ms","start":"2026-10-10T10:28:35.669407Z","end":"2026-10-10T10:28:35.888187Z","steps":["trace[946093096] 'agreement among raft nodes before linearized reading' (duration: 218.661486ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:28:35.888214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:28:35.452572Z","time spent":"435.582925ms","remote":"127.0.0.1:50628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11824,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-10T10:28:35.88824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.936677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-10T10:28:35.888085Z","caller":"traceutil/trace.go:171","msg":"trace[392458871] linearizableReadLoop","detail":"{readStateIndex:5133; appliedIndex:5132; }","duration":"207.223483ms","start":"2026-10-10T10:28:35.680836Z","end":"2026-10-10T10:28:35.888059Z","steps":["trace[392458871] 'read index received' (duration: 185.647622ms)","trace[392458871] 'applied index is now lower than readState.Index' (duration: 21.574681ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:28:35.888296Z","caller":"traceutil/trace.go:171","msg":"trace[623417913] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4953; }","duration":"320.030852ms","start":"2026-10-10T10:28:35.568251Z","end":"2026-10-10T10:28:35.888282Z","steps":["trace[623417913] 'agreement among raft nodes before linearized reading' (duration: 319.883755ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:28:35.88834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:28:35.568232Z","time spent":"320.095145ms","remote":"127.0.0.1:50696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-10T10:28:35.888723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.707298ms","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-10-10T10:28:35.888792Z","caller":"traceutil/trace.go:171","msg":"trace[1956483313] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4953; }","duration":"316.848975ms","start":"2026-10-10T10:28:35.571924Z","end":"2026-10-10T10:28:35.888773Z","steps":["trace[1956483313] 'agreement among raft nodes before linearized reading' (duration: 316.629224ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:28:35.888829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:28:35.571868Z","time spent":"316.95334ms","remote":"127.0.0.1:50696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-10T10:28:36.061404Z","caller":"traceutil/trace.go:171","msg":"trace[1264262884] transaction","detail":"{read_only:false; response_revision:4955; number_of_response:1; }","duration":"167.316222ms","start":"2026-10-10T10:28:35.894058Z","end":"2026-10-10T10:28:36.061374Z","steps":["trace[1264262884] 'process raft request' (duration: 110.37327ms)","trace[1264262884] 'compare' (duration: 56.551013ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:28:36.061769Z","caller":"traceutil/trace.go:171","msg":"trace[77978645] transaction","detail":"{read_only:false; response_revision:4958; number_of_response:1; }","duration":"166.488771ms","start":"2026-10-10T10:28:35.895258Z","end":"2026-10-10T10:28:36.061746Z","steps":["trace[77978645] 'process raft request' (duration: 166.406777ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:28:36.061893Z","caller":"traceutil/trace.go:171","msg":"trace[1454917341] transaction","detail":"{read_only:false; response_revision:4956; number_of_response:1; }","duration":"167.579915ms","start":"2026-10-10T10:28:35.894266Z","end":"2026-10-10T10:28:36.061846Z","steps":["trace[1454917341] 'process raft request' (duration: 167.018648ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:28:36.061959Z","caller":"traceutil/trace.go:171","msg":"trace[982847343] transaction","detail":"{read_only:false; response_revision:4957; number_of_response:1; }","duration":"166.963064ms","start":"2026-10-10T10:28:35.894962Z","end":"2026-10-10T10:28:36.061925Z","steps":["trace[982847343] 'process raft request' (duration: 166.589266ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:28:36.062138Z","caller":"traceutil/trace.go:171","msg":"trace[1518685631] linearizableReadLoop","detail":"{readStateIndex:5138; appliedIndex:5133; }","duration":"165.198208ms","start":"2026-10-10T10:28:35.896899Z","end":"2026-10-10T10:28:36.062097Z","steps":["trace[1518685631] 'read index received' (duration: 24.478824ms)","trace[1518685631] 'applied index is now lower than readState.Index' (duration: 140.718244ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:28:36.062292Z","caller":"traceutil/trace.go:171","msg":"trace[604189161] transaction","detail":"{read_only:false; response_revision:4959; number_of_response:1; }","duration":"165.23775ms","start":"2026-10-10T10:28:35.897043Z","end":"2026-10-10T10:28:36.06228Z","steps":["trace[604189161] 'process raft request' (duration: 164.670512ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:28:36.062302Z","caller":"traceutil/trace.go:171","msg":"trace[987983826] transaction","detail":"{read_only:false; response_revision:4960; number_of_response:1; }","duration":"163.983798ms","start":"2026-10-10T10:28:35.898305Z","end":"2026-10-10T10:28:36.062289Z","steps":["trace[987983826] 'process raft request' (duration: 163.715505ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:28:36.062324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.398937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1c463c4f-4d62-4067-a6c9-813582ba9c04\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-10-10T10:28:36.062375Z","caller":"traceutil/trace.go:171","msg":"trace[39851348] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1c463c4f-4d62-4067-a6c9-813582ba9c04; range_end:; response_count:1; response_revision:4960; }","duration":"165.493332ms","start":"2026-10-10T10:28:35.896864Z","end":"2026-10-10T10:28:36.062357Z","steps":["trace[39851348] 'agreement among raft nodes before linearized reading' (duration: 165.312374ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:28:36.733047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.291379ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-10T10:28:36.733334Z","caller":"traceutil/trace.go:171","msg":"trace[1206007117] transaction","detail":"{read_only:false; response_revision:4964; number_of_response:1; }","duration":"352.473489ms","start":"2026-10-10T10:28:36.380843Z","end":"2026-10-10T10:28:36.733317Z","steps":["trace[1206007117] 'process raft request' (duration: 352.439067ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:28:36.733397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:28:36.380827Z","time spent":"352.538742ms","remote":"127.0.0.1:50696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-10T10:28:36.733452Z","caller":"traceutil/trace.go:171","msg":"trace[1463457897] linearizableReadLoop","detail":"{readStateIndex:5141; appliedIndex:5140; }","duration":"552.749976ms","start":"2026-10-10T10:28:36.180663Z","end":"2026-10-10T10:28:36.733413Z","steps":["trace[1463457897] 'read index received' (duration: 97.032565ms)","trace[1463457897] 'applied index is now lower than readState.Index' (duration: 455.7145ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:28:36.73359Z","caller":"traceutil/trace.go:171","msg":"trace[672868532] transaction","detail":"{read_only:false; response_revision:4963; number_of_response:1; }","duration":"361.516434ms","start":"2026-10-10T10:28:36.372061Z","end":"2026-10-10T10:28:36.733578Z","steps":["trace[672868532] 'process raft request' (duration: 361.187028ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:28:36.733643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:28:36.372043Z","time spent":"361.572757ms","remote":"127.0.0.1:50696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-10T10:28:36.733687Z","caller":"traceutil/trace.go:171","msg":"trace[912711277] transaction","detail":"{read_only:false; response_revision:4961; number_of_response:1; }","duration":"661.808503ms","start":"2026-10-10T10:28:36.071847Z","end":"2026-10-10T10:28:36.733655Z","steps":["trace[912711277] 'process raft request' (duration: 205.806529ms)","trace[912711277] 'compare' (duration: 455.183934ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:28:36.733541Z","caller":"traceutil/trace.go:171","msg":"trace[412568015] transaction","detail":"{read_only:false; response_revision:4962; number_of_response:1; }","duration":"368.600403ms","start":"2026-10-10T10:28:36.364921Z","end":"2026-10-10T10:28:36.733521Z","steps":["trace[412568015] 'process raft request' (duration: 368.216464ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:28:36.733815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.190771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-10T10:28:36.733844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:28:36.364895Z","time spent":"368.890217ms","remote":"127.0.0.1:50696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-10T10:28:36.733848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:28:36.071822Z","time spent":"661.94548ms","remote":"127.0.0.1:50628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3750,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-10T10:28:36.733884Z","caller":"traceutil/trace.go:171","msg":"trace[1595252871] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:4964; }","duration":"537.291296ms","start":"2026-10-10T10:28:36.196579Z","end":"2026-10-10T10:28:36.73387Z","steps":["trace[1595252871] 'agreement among raft nodes before linearized reading' (duration: 537.196431ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:28:36.733906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.132211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-7dc9b86c6-mk7cx.18dd2447436b080a\" ","response":"range_response_count:1 size:893"} {"level":"info","ts":"2026-10-10T10:28:36.733938Z","caller":"traceutil/trace.go:171","msg":"trace[1525468058] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-7dc9b86c6-mk7cx.18dd2447436b080a; range_end:; response_count:1; response_revision:4964; }","duration":"468.188474ms","start":"2026-10-10T10:28:36.265739Z","end":"2026-10-10T10:28:36.733927Z","steps":["trace[1525468058] 'agreement among raft nodes before linearized reading' (duration: 468.076038ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:28:36.733965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:28:36.19656Z","time spent":"537.346908ms","remote":"127.0.0.1:50932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":3,"response size":31,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-10-10T10:28:36.733983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:28:36.265724Z","time spent":"468.249377ms","remote":"127.0.0.1:50502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":917,"request content":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-7dc9b86c6-mk7cx.18dd2447436b080a\" "} {"level":"warn","ts":"2026-10-10T10:28:36.733732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.04765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1c463c4f-4d62-4067-a6c9-813582ba9c04\" ","response":"range_response_count:1 size:3765"} {"level":"info","ts":"2026-10-10T10:28:36.734032Z","caller":"traceutil/trace.go:171","msg":"trace[713693003] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1c463c4f-4d62-4067-a6c9-813582ba9c04; range_end:; response_count:1; response_revision:4964; }","duration":"553.407188ms","start":"2026-10-10T10:28:36.180609Z","end":"2026-10-10T10:28:36.734016Z","steps":["trace[713693003] 'agreement among raft nodes before linearized reading' (duration: 553.04548ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:28:36.734074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:28:36.180588Z","time spent":"553.472771ms","remote":"127.0.0.1:50628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3789,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1c463c4f-4d62-4067-a6c9-813582ba9c04\" "} {"level":"info","ts":"2026-10-10T10:28:36.938864Z","caller":"traceutil/trace.go:171","msg":"trace[1900896382] transaction","detail":"{read_only:false; response_revision:4965; number_of_response:1; }","duration":"202.829383ms","start":"2026-10-10T10:28:36.736008Z","end":"2026-10-10T10:28:36.938837Z","steps":["trace[1900896382] 'process raft request' (duration: 202.679716ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:28:40.725032Z","caller":"traceutil/trace.go:171","msg":"trace[480347952] transaction","detail":"{read_only:false; response_revision:5031; number_of_response:1; }","duration":"151.434121ms","start":"2026-10-10T10:28:40.573546Z","end":"2026-10-10T10:28:40.72498Z","steps":["trace[480347952] 'process raft request' (duration: 151.2056ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:28:50.310069Z","caller":"traceutil/trace.go:171","msg":"trace[1937810726] linearizableReadLoop","detail":"{readStateIndex:5323; appliedIndex:5322; }","duration":"205.724497ms","start":"2026-10-10T10:28:50.104317Z","end":"2026-10-10T10:28:50.310041Z","steps":["trace[1937810726] 'read index received' (duration: 205.489886ms)","trace[1937810726] 'applied index is now lower than readState.Index' (duration: 233.261µs)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:28:50.310129Z","caller":"traceutil/trace.go:171","msg":"trace[1015212180] transaction","detail":"{read_only:false; response_revision:5136; number_of_response:1; }","duration":"306.128059ms","start":"2026-10-10T10:28:50.003963Z","end":"2026-10-10T10:28:50.310091Z","steps":["trace[1015212180] 'process raft request' (duration: 305.906068ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:28:50.310563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.909809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-10-10T10:28:50.310594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.849793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-10-10T10:28:50.310682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.756771ms","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-10-10T10:28:50.310843Z","caller":"traceutil/trace.go:171","msg":"trace[884723958] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5136; }","duration":"172.969682ms","start":"2026-10-10T10:28:50.137856Z","end":"2026-10-10T10:28:50.310825Z","steps":["trace[884723958] 'agreement among raft nodes before linearized reading' (duration: 172.51918ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:28:50.310746Z","caller":"traceutil/trace.go:171","msg":"trace[863386918] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5136; }","duration":"175.062306ms","start":"2026-10-10T10:28:50.135606Z","end":"2026-10-10T10:28:50.310668Z","steps":["trace[863386918] 'agreement among raft nodes before linearized reading' (duration: 174.847686ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:28:50.310843Z","caller":"traceutil/trace.go:171","msg":"trace[507006942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5136; }","duration":"181.097915ms","start":"2026-10-10T10:28:50.129724Z","end":"2026-10-10T10:28:50.310822Z","steps":["trace[507006942] 'agreement among raft nodes before linearized reading' (duration: 180.635442ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:28:50.310578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.974459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-10-10T10:28:50.310415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:28:50.003948Z","time spent":"306.367301ms","remote":"127.0.0.1:50696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-10T10:28:50.311502Z","caller":"traceutil/trace.go:171","msg":"trace[884994916] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5136; }","duration":"156.834912ms","start":"2026-10-10T10:28:50.154555Z","end":"2026-10-10T10:28:50.31139Z","steps":["trace[884994916] 'agreement among raft nodes before linearized reading' (duration: 155.876904ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:28:50.311686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.356409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-7dc9b86c6-mk7cx\" ","response":"range_response_count:1 size:11839"} {"level":"info","ts":"2026-10-10T10:28:50.311735Z","caller":"traceutil/trace.go:171","msg":"trace[191515038] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-7dc9b86c6-mk7cx; range_end:; response_count:1; response_revision:5136; }","duration":"207.462064ms","start":"2026-10-10T10:28:50.104257Z","end":"2026-10-10T10:28:50.311719Z","steps":["trace[191515038] 'agreement among raft nodes before linearized reading' (duration: 205.98384ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:28:50.428758Z","caller":"traceutil/trace.go:171","msg":"trace[1339799537] transaction","detail":"{read_only:false; response_revision:5141; number_of_response:1; }","duration":"101.967121ms","start":"2026-10-10T10:28:50.326767Z","end":"2026-10-10T10:28:50.428734Z","steps":["trace[1339799537] 'process raft request' (duration: 101.809913ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:29:10.689481Z","caller":"traceutil/trace.go:171","msg":"trace[2012517130] transaction","detail":"{read_only:false; response_revision:5273; number_of_response:1; }","duration":"148.515993ms","start":"2026-10-10T10:29:10.540922Z","end":"2026-10-10T10:29:10.689438Z","steps":["trace[2012517130] 'process raft request' (duration: 115.455926ms)","trace[2012517130] 'compare' (duration: 32.863147ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:29:10.700249Z","caller":"traceutil/trace.go:171","msg":"trace[550927170] linearizableReadLoop","detail":"{readStateIndex:5467; appliedIndex:5463; }","duration":"127.474299ms","start":"2026-10-10T10:29:10.572745Z","end":"2026-10-10T10:29:10.70022Z","steps":["trace[550927170] 'read index received' (duration: 83.724506ms)","trace[550927170] 'applied index is now lower than readState.Index' (duration: 43.748713ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:29:10.700361Z","caller":"traceutil/trace.go:171","msg":"trace[1891383617] transaction","detail":"{read_only:false; response_revision:5275; number_of_response:1; }","duration":"157.61985ms","start":"2026-10-10T10:29:10.542711Z","end":"2026-10-10T10:29:10.700331Z","steps":["trace[1891383617] 'process raft request' (duration: 157.375168ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:29:10.700634Z","caller":"traceutil/trace.go:171","msg":"trace[2053755517] transaction","detail":"{read_only:false; response_revision:5274; number_of_response:1; }","duration":"159.113505ms","start":"2026-10-10T10:29:10.541509Z","end":"2026-10-10T10:29:10.700623Z","steps":["trace[2053755517] 'process raft request' (duration: 158.358657ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:29:10.700662Z","caller":"traceutil/trace.go:171","msg":"trace[565123585] transaction","detail":"{read_only:false; response_revision:5276; number_of_response:1; }","duration":"148.355396ms","start":"2026-10-10T10:29:10.552293Z","end":"2026-10-10T10:29:10.700648Z","steps":["trace[565123585] 'process raft request' (duration: 147.865801ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:29:10.700949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.195416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-10-10T10:29:10.700994Z","caller":"traceutil/trace.go:171","msg":"trace[1492974666] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5276; }","duration":"128.29144ms","start":"2026-10-10T10:29:10.572689Z","end":"2026-10-10T10:29:10.70098Z","steps":["trace[1492974666] 'agreement among raft nodes before linearized reading' (duration: 128.151003ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:29:25.561403Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2877} {"level":"info","ts":"2026-10-10T10:29:25.625026Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2877,"took":"62.603498ms","hash":3628884681,"current-db-size-bytes":52633600,"current-db-size":"53 MB","current-db-size-in-use-bytes":28155904,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-10T10:29:25.625093Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3628884681,"revision":2877,"compact-revision":-1} {"level":"info","ts":"2026-10-10T10:29:33.206371Z","caller":"traceutil/trace.go:171","msg":"trace[554966733] transaction","detail":"{read_only:false; response_revision:5493; number_of_response:1; }","duration":"211.196486ms","start":"2026-10-10T10:29:32.995149Z","end":"2026-10-10T10:29:33.206345Z","steps":["trace[554966733] 'process raft request' (duration: 168.858264ms)","trace[554966733] 'compare' (duration: 42.224346ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:29:33.242489Z","caller":"traceutil/trace.go:171","msg":"trace[1726929471] transaction","detail":"{read_only:false; response_revision:5494; number_of_response:1; }","duration":"235.403732ms","start":"2026-10-10T10:29:33.007068Z","end":"2026-10-10T10:29:33.242471Z","steps":["trace[1726929471] 'process raft request' (duration: 235.287046ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:29:33.242819Z","caller":"traceutil/trace.go:171","msg":"trace[1364535904] transaction","detail":"{read_only:false; response_revision:5495; number_of_response:1; }","duration":"234.929888ms","start":"2026-10-10T10:29:33.007869Z","end":"2026-10-10T10:29:33.242799Z","steps":["trace[1364535904] 'process raft request' (duration: 234.565139ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:29:33.24284Z","caller":"traceutil/trace.go:171","msg":"trace[215502091] linearizableReadLoop","detail":"{readStateIndex:5693; appliedIndex:5689; }","duration":"123.558663ms","start":"2026-10-10T10:29:33.119262Z","end":"2026-10-10T10:29:33.24282Z","steps":["trace[215502091] 'read index received' (duration: 44.836369ms)","trace[215502091] 'applied index is now lower than readState.Index' (duration: 78.721194ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:29:33.243171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.89486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/keystone-credential-keys\" ","response":"range_response_count:1 size:353"} {"level":"info","ts":"2026-10-10T10:29:33.243239Z","caller":"traceutil/trace.go:171","msg":"trace[605052239] range","detail":"{range_begin:/registry/secrets/openstack/keystone-credential-keys; range_end:; response_count:1; response_revision:5496; }","duration":"123.972994ms","start":"2026-10-10T10:29:33.119248Z","end":"2026-10-10T10:29:33.243221Z","steps":["trace[605052239] 'agreement among raft nodes before linearized reading' (duration: 123.628546ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:29:33.243277Z","caller":"traceutil/trace.go:171","msg":"trace[1155474014] transaction","detail":"{read_only:false; response_revision:5496; number_of_response:1; }","duration":"191.597846ms","start":"2026-10-10T10:29:33.051633Z","end":"2026-10-10T10:29:33.24323Z","steps":["trace[1155474014] 'process raft request' (duration: 191.105791ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:29:33.934523Z","caller":"traceutil/trace.go:171","msg":"trace[1427282201] transaction","detail":"{read_only:false; response_revision:5497; number_of_response:1; }","duration":"206.464247ms","start":"2026-10-10T10:29:33.728027Z","end":"2026-10-10T10:29:33.934491Z","steps":["trace[1427282201] 'process raft request' (duration: 206.194084ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:29:34.160109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.678576ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-10T10:29:34.161056Z","caller":"traceutil/trace.go:171","msg":"trace[469205839] transaction","detail":"{read_only:false; response_revision:5498; number_of_response:1; }","duration":"217.268557ms","start":"2026-10-10T10:29:33.943486Z","end":"2026-10-10T10:29:34.160754Z","steps":["trace[469205839] 'process raft request' (duration: 33.868224ms)","trace[469205839] 'compare' (duration: 182.532989ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:29:34.725675Z","caller":"traceutil/trace.go:171","msg":"trace[582091230] linearizableReadLoop","detail":"{readStateIndex:5698; appliedIndex:5696; }","duration":"101.508709ms","start":"2026-10-10T10:29:34.624143Z","end":"2026-10-10T10:29:34.725652Z","steps":["trace[582091230] 'read index received' (duration: 31.622491ms)","trace[582091230] 'applied index is now lower than readState.Index' (duration: 69.885208ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:29:34.726038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.877839ms","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-10-10T10:29:34.72608Z","caller":"traceutil/trace.go:171","msg":"trace[308097298] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5500; }","duration":"101.930281ms","start":"2026-10-10T10:29:34.624138Z","end":"2026-10-10T10:29:34.726068Z","steps":["trace[308097298] 'agreement among raft nodes before linearized reading' (duration: 101.771233ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:29:34.726601Z","caller":"traceutil/trace.go:171","msg":"trace[585859004] transaction","detail":"{read_only:false; response_revision:5500; number_of_response:1; }","duration":"149.3366ms","start":"2026-10-10T10:29:34.577241Z","end":"2026-10-10T10:29:34.726577Z","steps":["trace[585859004] 'process raft request' (duration: 92.725604ms)","trace[585859004] 'compare' (duration: 55.49809ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:29:34.888657Z","caller":"traceutil/trace.go:171","msg":"trace[1553158765] transaction","detail":"{read_only:false; response_revision:5502; number_of_response:1; }","duration":"156.339294ms","start":"2026-10-10T10:29:34.732281Z","end":"2026-10-10T10:29:34.88862Z","steps":["trace[1553158765] 'process raft request' (duration: 104.050827ms)","trace[1553158765] 'compare' (duration: 51.876547ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:29:34.888786Z","caller":"traceutil/trace.go:171","msg":"trace[1482051427] transaction","detail":"{read_only:false; response_revision:5503; number_of_response:1; }","duration":"148.194363ms","start":"2026-10-10T10:29:34.740574Z","end":"2026-10-10T10:29:34.888769Z","steps":["trace[1482051427] 'process raft request' (duration: 147.956001ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:29:47.038236Z","caller":"traceutil/trace.go:171","msg":"trace[131739202] transaction","detail":"{read_only:false; response_revision:5588; number_of_response:1; }","duration":"150.728174ms","start":"2026-10-10T10:29:46.887439Z","end":"2026-10-10T10:29:47.038167Z","steps":["trace[131739202] 'process raft request' (duration: 132.57831ms)","trace[131739202] 'compare' (duration: 17.893721ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:32:59.962417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.621859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/ingress-nginx/\" range_end:\"/registry/secrets/ingress-nginx0\" ","response":"range_response_count:2 size:107255"} {"level":"info","ts":"2026-10-10T10:32:59.962481Z","caller":"traceutil/trace.go:171","msg":"trace[1333016948] range","detail":"{range_begin:/registry/secrets/ingress-nginx/; range_end:/registry/secrets/ingress-nginx0; response_count:2; response_revision:6934; }","duration":"158.71946ms","start":"2026-10-10T10:32:59.803747Z","end":"2026-10-10T10:32:59.962467Z","steps":["trace[1333016948] 'range keys from in-memory index tree' (duration: 158.488726ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:34:25.570396Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5443} {"level":"info","ts":"2026-10-10T10:34:25.650266Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5443,"took":"78.532597ms","hash":583137168,"current-db-size-bytes":52633600,"current-db-size":"53 MB","current-db-size-in-use-bytes":18427904,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-10-10T10:34:25.650544Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":583137168,"revision":5443,"compact-revision":2877}