{"level":"warn","ts":"2026-04-28T06:08:25.081818Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T06:08:25.081921Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.19:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.19:2380","--initial-cluster=instance=https://199.204.45.19:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.19:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.19:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-28T06:08:25.082005Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T06:08:25.082017Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.19:2380"]} {"level":"info","ts":"2026-04-28T06:08:25.08205Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-28T06:08:25.0829Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"]} {"level":"info","ts":"2026-04-28T06:08:25.082994Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.19:2380"],"listen-peer-urls":["https://199.204.45.19:2380"],"advertise-client-urls":["https://199.204.45.19:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.19:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-28T06:08:25.087887Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.645383ms"} {"level":"info","ts":"2026-04-28T06:08:25.094033Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a847c2e9c7010db6","cluster-id":"2b11a68e27507b05"} {"level":"info","ts":"2026-04-28T06:08:25.094088Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=()"} {"level":"info","ts":"2026-04-28T06:08:25.094105Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became follower at term 0"} {"level":"info","ts":"2026-04-28T06:08:25.094118Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a847c2e9c7010db6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-28T06:08:25.094126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became follower at term 1"} {"level":"info","ts":"2026-04-28T06:08:25.094153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=(12125874831040253366)"} {"level":"warn","ts":"2026-04-28T06:08:25.106294Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-28T06:08:25.107368Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-28T06:08:25.108291Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-28T06:08:25.109892Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a847c2e9c7010db6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-28T06:08:25.109989Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-28T06:08:25.110079Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-28T06:08:25.110098Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-28T06:08:25.110055Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a847c2e9c7010db6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-28T06:08:25.112432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=(12125874831040253366)"} {"level":"info","ts":"2026-04-28T06:08:25.112534Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2b11a68e27507b05","local-member-id":"a847c2e9c7010db6","added-peer-id":"a847c2e9c7010db6","added-peer-peer-urls":["https://199.204.45.19:2380"]} {"level":"info","ts":"2026-04-28T06:08:25.112984Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-28T06:08:25.113166Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a847c2e9c7010db6","initial-advertise-peer-urls":["https://199.204.45.19:2380"],"listen-peer-urls":["https://199.204.45.19:2380"],"advertise-client-urls":["https://199.204.45.19:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-28T06:08:25.113203Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-28T06:08:25.113202Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.19:2380"} {"level":"info","ts":"2026-04-28T06:08:25.11325Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.19:2380"} {"level":"info","ts":"2026-04-28T06:08:25.594523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 is starting a new election at term 1"} {"level":"info","ts":"2026-04-28T06:08:25.594597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-28T06:08:25.594651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 received MsgPreVoteResp from a847c2e9c7010db6 at term 1"} {"level":"info","ts":"2026-04-28T06:08:25.594672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became candidate at term 2"} {"level":"info","ts":"2026-04-28T06:08:25.594684Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 received MsgVoteResp from a847c2e9c7010db6 at term 2"} {"level":"info","ts":"2026-04-28T06:08:25.594707Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became leader at term 2"} {"level":"info","ts":"2026-04-28T06:08:25.594721Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a847c2e9c7010db6 elected leader a847c2e9c7010db6 at term 2"} {"level":"info","ts":"2026-04-28T06:08:25.595725Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:08:25.596825Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a847c2e9c7010db6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.19:2379]}","request-path":"/0/members/a847c2e9c7010db6/attributes","cluster-id":"2b11a68e27507b05","publish-timeout":"7s"} {"level":"info","ts":"2026-04-28T06:08:25.596996Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T06:08:25.597063Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T06:08:25.597559Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-28T06:08:25.597614Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-28T06:08:25.601221Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-28T06:08:25.601413Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.19:2379"} {"level":"info","ts":"2026-04-28T06:08:25.605229Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2b11a68e27507b05","local-member-id":"a847c2e9c7010db6","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:08:25.605363Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:08:25.605405Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-04-28T06:08:27.390088Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988150696542996085,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T06:08:27.396932Z","caller":"traceutil/trace.go:171","msg":"trace[1400704643] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"509.549819ms","start":"2026-04-28T06:08:26.887353Z","end":"2026-04-28T06:08:27.396902Z","steps":["trace[1400704643] 'process raft request' (duration: 509.315084ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:27.397542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:08:26.887338Z","time spent":"509.669741ms","remote":"127.0.0.1:59178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:08:28.358776Z","caller":"traceutil/trace.go:171","msg":"trace[482904510] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"1.405344605s","start":"2026-04-28T06:08:26.953398Z","end":"2026-04-28T06:08:28.358742Z","steps":["trace[482904510] 'process raft request' (duration: 1.341606092s)","trace[482904510] 'compare' (duration: 63.502828ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:08:28.358935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:08:26.953374Z","time spent":"1.405511398s","remote":"127.0.0.1:59534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:08:28.359059Z","caller":"traceutil/trace.go:171","msg":"trace[1605234750] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"1.40558179s","start":"2026-04-28T06:08:26.953446Z","end":"2026-04-28T06:08:28.359028Z","steps":["trace[1605234750] 'process raft request' (duration: 1.405226283s)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:08:28.359213Z","caller":"traceutil/trace.go:171","msg":"trace[143155389] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"1.405717843s","start":"2026-04-28T06:08:26.953481Z","end":"2026-04-28T06:08:28.359199Z","steps":["trace[143155389] 'process raft request' (duration: 1.405234332s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:28.35921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:08:26.95342Z","time spent":"1.405727163s","remote":"127.0.0.1:59634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:08:28.359302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:08:26.953456Z","time spent":"1.405790874s","remote":"127.0.0.1:59634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:08:28.35958Z","caller":"traceutil/trace.go:171","msg":"trace[171386450] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"1.405770724s","start":"2026-04-28T06:08:26.953802Z","end":"2026-04-28T06:08:28.359573Z","steps":["trace[171386450] 'process raft request' (duration: 1.404968496s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:28.359643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:08:26.953792Z","time spent":"1.405835355s","remote":"127.0.0.1:59634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:08:28.359674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.470494849s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T06:08:28.359746Z","caller":"traceutil/trace.go:171","msg":"trace[889718154] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:0; response_revision:10; }","duration":"1.470613861s","start":"2026-04-28T06:08:26.889122Z","end":"2026-04-28T06:08:28.359735Z","steps":["trace[889718154] 'agreement among raft nodes before linearized reading' (duration: 1.470477569s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:28.359782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:08:26.889105Z","time spent":"1.470669452s","remote":"127.0.0.1:59166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-04-28T06:08:28.359573Z","caller":"traceutil/trace.go:171","msg":"trace[1293709883] linearizableReadLoop","detail":"{readStateIndex:13; appliedIndex:4; }","duration":"1.470391187s","start":"2026-04-28T06:08:26.889168Z","end":"2026-04-28T06:08:28.359559Z","steps":["trace[1293709883] 'read index received' (duration: 507.612896ms)","trace[1293709883] 'applied index is now lower than readState.Index' (duration: 962.777281ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:08:28.35995Z","caller":"traceutil/trace.go:171","msg":"trace[1663071005] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"1.390039783s","start":"2026-04-28T06:08:26.969901Z","end":"2026-04-28T06:08:28.359941Z","steps":["trace[1663071005] 'process raft request' (duration: 1.389589153s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:28.360004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:08:26.969877Z","time spent":"1.390101945s","remote":"127.0.0.1:59274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:08:28.360054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.4701147s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3170"} {"level":"info","ts":"2026-04-28T06:08:28.360089Z","caller":"traceutil/trace.go:171","msg":"trace[715816940] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:10; }","duration":"1.470181451s","start":"2026-04-28T06:08:26.8899Z","end":"2026-04-28T06:08:28.360081Z","steps":["trace[715816940] 'agreement among raft nodes before linearized reading' (duration: 1.470053858s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:28.360124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:08:26.889895Z","time spent":"1.470224082s","remote":"127.0.0.1:59274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3193,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-28T06:08:28.36015Z","caller":"traceutil/trace.go:171","msg":"trace[229999343] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"1.406103471s","start":"2026-04-28T06:08:26.954039Z","end":"2026-04-28T06:08:28.360142Z","steps":["trace[229999343] 'process raft request' (duration: 1.405424857s)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:08:28.360196Z","caller":"traceutil/trace.go:171","msg":"trace[762320477] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"1.388655782s","start":"2026-04-28T06:08:26.971531Z","end":"2026-04-28T06:08:28.360187Z","steps":["trace[762320477] 'process raft request' (duration: 1.387987448s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:28.360253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:08:26.971516Z","time spent":"1.388706863s","remote":"127.0.0.1:59360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":845,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:08:28.360315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.018744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T06:08:28.360344Z","caller":"traceutil/trace.go:171","msg":"trace[95264927] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10; }","duration":"153.079976ms","start":"2026-04-28T06:08:28.207259Z","end":"2026-04-28T06:08:28.360339Z","steps":["trace[95264927] 'agreement among raft nodes before linearized reading' (duration: 153.016274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:28.360447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.861933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T06:08:28.360518Z","caller":"traceutil/trace.go:171","msg":"trace[1437283861] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:10; }","duration":"509.018096ms","start":"2026-04-28T06:08:27.851495Z","end":"2026-04-28T06:08:28.360513Z","steps":["trace[1437283861] 'agreement among raft nodes before linearized reading' (duration: 508.935414ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:28.36055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:08:27.851461Z","time spent":"509.084688ms","remote":"127.0.0.1:59458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-04-28T06:08:28.360637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:08:26.953996Z","time spent":"1.406218244s","remote":"127.0.0.1:59634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:08:28.360699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.419385117s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-28T06:08:28.360657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.418409026s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-28T06:08:28.360836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.470899406s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3170"} {"level":"info","ts":"2026-04-28T06:08:28.360866Z","caller":"traceutil/trace.go:171","msg":"trace[1987303296] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:10; }","duration":"1.470938749s","start":"2026-04-28T06:08:26.889923Z","end":"2026-04-28T06:08:28.360861Z","steps":["trace[1987303296] 'agreement among raft nodes before linearized reading' (duration: 1.470877706s)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:08:28.360873Z","caller":"traceutil/trace.go:171","msg":"trace[954437627] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"1.418650271s","start":"2026-04-28T06:08:26.942163Z","end":"2026-04-28T06:08:28.360814Z","steps":["trace[954437627] 'agreement among raft nodes before linearized reading' (duration: 1.418396345s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:28.360901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:08:26.889919Z","time spent":"1.47097714s","remote":"127.0.0.1:59274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3193,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-28T06:08:28.360045Z","caller":"traceutil/trace.go:171","msg":"trace[2093170303] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"1.40604957s","start":"2026-04-28T06:08:26.953969Z","end":"2026-04-28T06:08:28.360019Z","steps":["trace[2093170303] 'process raft request' (duration: 1.405424765s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:28.361012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:08:26.953941Z","time spent":"1.407053813s","remote":"127.0.0.1:59634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:08:28.361038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.408821343s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-28T06:08:28.361072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.863687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-28T06:08:28.361129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.356024745s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T06:08:28.361154Z","caller":"traceutil/trace.go:171","msg":"trace[1549046402] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:10; }","duration":"509.986819ms","start":"2026-04-28T06:08:27.85115Z","end":"2026-04-28T06:08:28.361137Z","steps":["trace[1549046402] 'agreement among raft nodes before linearized reading' (duration: 509.830765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:28.361194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:08:27.851134Z","time spent":"510.053701ms","remote":"127.0.0.1:59444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"info","ts":"2026-04-28T06:08:28.361175Z","caller":"traceutil/trace.go:171","msg":"trace[1535320607] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"1.356122216s","start":"2026-04-28T06:08:27.005037Z","end":"2026-04-28T06:08:28.36116Z","steps":["trace[1535320607] 'agreement among raft nodes before linearized reading' (duration: 1.356026634s)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:08:28.360788Z","caller":"traceutil/trace.go:171","msg":"trace[902070909] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"1.419476099s","start":"2026-04-28T06:08:26.941305Z","end":"2026-04-28T06:08:28.360781Z","steps":["trace[902070909] 'agreement among raft nodes before linearized reading' (duration: 1.419329896s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:28.361248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:08:27.005021Z","time spent":"1.356218559s","remote":"127.0.0.1:59220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-28T06:08:28.361301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:08:26.941275Z","time spent":"1.420002962s","remote":"127.0.0.1:59220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-28T06:08:28.361294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.418926758s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T06:08:28.36137Z","caller":"traceutil/trace.go:171","msg":"trace[1445457504] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"1.419031411s","start":"2026-04-28T06:08:26.942326Z","end":"2026-04-28T06:08:28.361358Z","steps":["trace[1445457504] 'agreement among raft nodes before linearized reading' (duration: 1.418939739s)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:08:28.361079Z","caller":"traceutil/trace.go:171","msg":"trace[1993223636] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"1.408977586s","start":"2026-04-28T06:08:26.952089Z","end":"2026-04-28T06:08:28.361067Z","steps":["trace[1993223636] 'agreement among raft nodes before linearized reading' (duration: 1.408886674s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:28.36142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:08:26.952035Z","time spent":"1.409376685s","remote":"127.0.0.1:59220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-28T06:08:28.361434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:08:26.942286Z","time spent":"1.419139293s","remote":"127.0.0.1:59220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-28T06:08:28.361035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.406671914s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T06:08:28.361586Z","caller":"traceutil/trace.go:171","msg":"trace[44346335] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"1.407310968s","start":"2026-04-28T06:08:26.954265Z","end":"2026-04-28T06:08:28.361576Z","steps":["trace[44346335] 'agreement among raft nodes before linearized reading' (duration: 1.406679585s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:28.360951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:08:26.942153Z","time spent":"1.418760205s","remote":"127.0.0.1:59220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-28T06:08:28.361614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:08:26.954255Z","time spent":"1.407351999s","remote":"127.0.0.1:59196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-04-28T06:08:28.36116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.410666693s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.19\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T06:08:28.361797Z","caller":"traceutil/trace.go:171","msg":"trace[1440184994] range","detail":"{range_begin:/registry/masterleases/199.204.45.19; range_end:; response_count:0; response_revision:10; }","duration":"1.411308167s","start":"2026-04-28T06:08:26.950458Z","end":"2026-04-28T06:08:28.361766Z","steps":["trace[1440184994] 'agreement among raft nodes before linearized reading' (duration: 1.410670714s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:28.361833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:08:26.950444Z","time spent":"1.411382349s","remote":"127.0.0.1:59138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.204.45.19\" "} {"level":"info","ts":"2026-04-28T06:08:28.659356Z","caller":"traceutil/trace.go:171","msg":"trace[1227488481] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:13; }","duration":"295.293289ms","start":"2026-04-28T06:08:28.364037Z","end":"2026-04-28T06:08:28.65933Z","steps":["trace[1227488481] 'read index received' (duration: 288.854365ms)","trace[1227488481] 'applied index is now lower than readState.Index' (duration: 6.438004ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:08:28.659476Z","caller":"traceutil/trace.go:171","msg":"trace[1620136207] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"295.950354ms","start":"2026-04-28T06:08:28.363513Z","end":"2026-04-28T06:08:28.659463Z","steps":["trace[1620136207] 'process raft request' (duration: 289.384617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:28.659714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.649286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T06:08:28.659785Z","caller":"traceutil/trace.go:171","msg":"trace[114827312] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11; }","duration":"295.759248ms","start":"2026-04-28T06:08:28.364011Z","end":"2026-04-28T06:08:28.659771Z","steps":["trace[114827312] 'agreement among raft nodes before linearized reading' (duration: 295.566244ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:08:28.660335Z","caller":"traceutil/trace.go:171","msg":"trace[676558738] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"294.241755ms","start":"2026-04-28T06:08:28.366072Z","end":"2026-04-28T06:08:28.660313Z","steps":["trace[676558738] 'process raft request' (duration: 293.998ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:08:28.660484Z","caller":"traceutil/trace.go:171","msg":"trace[1776056833] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"294.355367ms","start":"2026-04-28T06:08:28.366107Z","end":"2026-04-28T06:08:28.660463Z","steps":["trace[1776056833] 'process raft request' (duration: 294.046081ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:08:28.660564Z","caller":"traceutil/trace.go:171","msg":"trace[1114497607] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"294.235046ms","start":"2026-04-28T06:08:28.366315Z","end":"2026-04-28T06:08:28.660551Z","steps":["trace[1114497607] 'process raft request' (duration: 293.888558ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:08:28.660622Z","caller":"traceutil/trace.go:171","msg":"trace[866397569] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"294.135383ms","start":"2026-04-28T06:08:28.366475Z","end":"2026-04-28T06:08:28.660611Z","steps":["trace[866397569] 'process raft request' (duration: 294.00641ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:08:28.661004Z","caller":"traceutil/trace.go:171","msg":"trace[2011061666] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"293.347676ms","start":"2026-04-28T06:08:28.367642Z","end":"2026-04-28T06:08:28.66099Z","steps":["trace[2011061666] 'process raft request' (duration: 292.911986ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:08:28.661048Z","caller":"traceutil/trace.go:171","msg":"trace[283219629] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"291.464013ms","start":"2026-04-28T06:08:28.369573Z","end":"2026-04-28T06:08:28.661037Z","steps":["trace[283219629] 'process raft request' (duration: 291.017883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:28.661113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.512919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T06:08:28.661183Z","caller":"traceutil/trace.go:171","msg":"trace[389030356] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:21; }","duration":"293.601851ms","start":"2026-04-28T06:08:28.367561Z","end":"2026-04-28T06:08:28.661163Z","steps":["trace[389030356] 'agreement among raft nodes before linearized reading' (duration: 293.478539ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:08:28.661002Z","caller":"traceutil/trace.go:171","msg":"trace[2021078836] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"291.200798ms","start":"2026-04-28T06:08:28.369784Z","end":"2026-04-28T06:08:28.660984Z","steps":["trace[2021078836] 'process raft request' (duration: 290.84463ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:08:28.66139Z","caller":"traceutil/trace.go:171","msg":"trace[1708212843] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"289.015488ms","start":"2026-04-28T06:08:28.372348Z","end":"2026-04-28T06:08:28.661364Z","steps":["trace[1708212843] 'process raft request' (duration: 288.385694ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:28.661421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.882419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-node-lease\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T06:08:28.661466Z","caller":"traceutil/trace.go:171","msg":"trace[2056746218] range","detail":"{range_begin:/registry/namespaces/kube-node-lease; range_end:; response_count:0; response_revision:21; }","duration":"242.962871ms","start":"2026-04-28T06:08:28.41849Z","end":"2026-04-28T06:08:28.661453Z","steps":["trace[2056746218] 'agreement among raft nodes before linearized reading' (duration: 242.895099ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:08:28.661483Z","caller":"traceutil/trace.go:171","msg":"trace[1699206984] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"285.290856ms","start":"2026-04-28T06:08:28.376167Z","end":"2026-04-28T06:08:28.661458Z","steps":["trace[1699206984] 'process raft request' (duration: 284.799165ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:08:28.661556Z","caller":"traceutil/trace.go:171","msg":"trace[988348081] transaction","detail":"{read_only:false; number_of_response:0; response_revision:19; }","duration":"288.949307ms","start":"2026-04-28T06:08:28.372597Z","end":"2026-04-28T06:08:28.661547Z","steps":["trace[988348081] 'process raft request' (duration: 288.201151ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:08:28.661638Z","caller":"traceutil/trace.go:171","msg":"trace[363023260] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"288.703102ms","start":"2026-04-28T06:08:28.372926Z","end":"2026-04-28T06:08:28.661629Z","steps":["trace[363023260] 'process raft request' (duration: 287.964126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:28.661419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.596869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-04-28T06:08:28.661748Z","caller":"traceutil/trace.go:171","msg":"trace[91849395] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:21; }","duration":"236.940997ms","start":"2026-04-28T06:08:28.424791Z","end":"2026-04-28T06:08:28.661732Z","steps":["trace[91849395] 'agreement among raft nodes before linearized reading' (duration: 236.577299ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:28.661862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.064016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-ksh6z\" ","response":"range_response_count:1 size:857"} {"level":"info","ts":"2026-04-28T06:08:28.662032Z","caller":"traceutil/trace.go:171","msg":"trace[1060196364] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-ksh6z; range_end:; response_count:1; response_revision:21; }","duration":"292.24899ms","start":"2026-04-28T06:08:28.369655Z","end":"2026-04-28T06:08:28.661904Z","steps":["trace[1060196364] 'agreement among raft nodes before linearized reading' (duration: 291.928213ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:08:30.407782Z","caller":"traceutil/trace.go:171","msg":"trace[528692370] transaction","detail":"{read_only:false; response_revision:204; number_of_response:1; }","duration":"163.363094ms","start":"2026-04-28T06:08:30.244389Z","end":"2026-04-28T06:08:30.407752Z","steps":["trace[528692370] 'process raft request' (duration: 163.17128ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:08:30.407726Z","caller":"traceutil/trace.go:171","msg":"trace[1315219655] linearizableReadLoop","detail":"{readStateIndex:209; appliedIndex:208; }","duration":"138.652204ms","start":"2026-04-28T06:08:30.26905Z","end":"2026-04-28T06:08:30.407702Z","steps":["trace[1315219655] 'read index received' (duration: 138.422559ms)","trace[1315219655] 'applied index is now lower than readState.Index' (duration: 228.525µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:08:30.407956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.87273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:08:30.408102Z","caller":"traceutil/trace.go:171","msg":"trace[1525514800] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:204; }","duration":"139.058123ms","start":"2026-04-28T06:08:30.269023Z","end":"2026-04-28T06:08:30.408081Z","steps":["trace[1525514800] 'agreement among raft nodes before linearized reading' (duration: 138.847088ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:03.528833Z","caller":"traceutil/trace.go:171","msg":"trace[685802771] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"484.5223ms","start":"2026-04-28T06:09:03.044298Z","end":"2026-04-28T06:09:03.52882Z","steps":["trace[685802771] 'process raft request' (duration: 484.445848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:03.52898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:03.044279Z","time spent":"484.658094ms","remote":"127.0.0.1:59164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:09:03.537935Z","caller":"traceutil/trace.go:171","msg":"trace[1646357720] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"489.90353ms","start":"2026-04-28T06:09:03.048004Z","end":"2026-04-28T06:09:03.537908Z","steps":["trace[1646357720] 'process raft request' (duration: 489.671534ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:03.538059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:03.047985Z","time spent":"490.012602ms","remote":"127.0.0.1:59164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:09:03.538187Z","caller":"traceutil/trace.go:171","msg":"trace[674374514] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"466.735443ms","start":"2026-04-28T06:09:03.071412Z","end":"2026-04-28T06:09:03.538148Z","steps":["trace[674374514] 'process raft request' (duration: 466.502818ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:03.538354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:03.0714Z","time spent":"466.877497ms","remote":"127.0.0.1:59164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:09:03.538241Z","caller":"traceutil/trace.go:171","msg":"trace[358047747] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"465.369173ms","start":"2026-04-28T06:09:03.072863Z","end":"2026-04-28T06:09:03.538232Z","steps":["trace[358047747] 'process raft request' (duration: 465.099217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:03.538464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:03.072856Z","time spent":"465.588218ms","remote":"127.0.0.1:59164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:09:03.538586Z","caller":"traceutil/trace.go:171","msg":"trace[1261996030] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"459.190535ms","start":"2026-04-28T06:09:03.079389Z","end":"2026-04-28T06:09:03.53858Z","steps":["trace[1261996030] 'process raft request' (duration: 458.654323ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:03.538633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:03.079371Z","time spent":"459.245877ms","remote":"127.0.0.1:59164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:09:03.538672Z","caller":"traceutil/trace.go:171","msg":"trace[782790951] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"459.202215ms","start":"2026-04-28T06:09:03.079442Z","end":"2026-04-28T06:09:03.538644Z","steps":["trace[782790951] 'process raft request' (duration: 459.097463ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:03.538727Z","caller":"traceutil/trace.go:171","msg":"trace[934138483] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"454.634764ms","start":"2026-04-28T06:09:03.084068Z","end":"2026-04-28T06:09:03.538703Z","steps":["trace[934138483] 'process raft request' (duration: 454.516281ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:03.538811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:03.079439Z","time spent":"459.301347ms","remote":"127.0.0.1:59164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:09:03.538869Z","caller":"traceutil/trace.go:171","msg":"trace[2081244357] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"448.280362ms","start":"2026-04-28T06:09:03.09058Z","end":"2026-04-28T06:09:03.53886Z","steps":["trace[2081244357] 'process raft request' (duration: 448.044097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:03.53886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:03.084059Z","time spent":"454.740776ms","remote":"127.0.0.1:59164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:09:03.53891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:03.090564Z","time spent":"448.330693ms","remote":"127.0.0.1:59164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:09:03.538939Z","caller":"traceutil/trace.go:171","msg":"trace[77129413] linearizableReadLoop","detail":"{readStateIndex:513; appliedIndex:502; }","duration":"230.712857ms","start":"2026-04-28T06:09:03.308209Z","end":"2026-04-28T06:09:03.538922Z","steps":["trace[77129413] 'read index received' (duration: 220.635953ms)","trace[77129413] 'applied index is now lower than readState.Index' (duration: 10.075864ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:09:03.538981Z","caller":"traceutil/trace.go:171","msg":"trace[227823057] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"422.584248ms","start":"2026-04-28T06:09:03.116381Z","end":"2026-04-28T06:09:03.538965Z","steps":["trace[227823057] 'process raft request' (duration: 422.364583ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:03.538992Z","caller":"traceutil/trace.go:171","msg":"trace[172511431] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"485.098744ms","start":"2026-04-28T06:09:03.053017Z","end":"2026-04-28T06:09:03.538116Z","steps":["trace[172511431] 'process raft request' (duration: 484.784116ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:03.539029Z","caller":"traceutil/trace.go:171","msg":"trace[344545584] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"433.689236ms","start":"2026-04-28T06:09:03.105303Z","end":"2026-04-28T06:09:03.538992Z","steps":["trace[344545584] 'process raft request' (duration: 433.392849ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:03.539063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.882881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-28T06:09:03.539086Z","caller":"traceutil/trace.go:171","msg":"trace[563549954] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"321.626985ms","start":"2026-04-28T06:09:03.217449Z","end":"2026-04-28T06:09:03.539076Z","steps":["trace[563549954] 'process raft request' (duration: 321.371019ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:03.539087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:03.052999Z","time spent":"486.042164ms","remote":"127.0.0.1:59164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:09:03.539103Z","caller":"traceutil/trace.go:171","msg":"trace[2045377082] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:498; }","duration":"230.961753ms","start":"2026-04-28T06:09:03.308131Z","end":"2026-04-28T06:09:03.539092Z","steps":["trace[2045377082] 'agreement among raft nodes before linearized reading' (duration: 230.856021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:03.539109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:03.105293Z","time spent":"433.782488ms","remote":"127.0.0.1:59164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:09:03.539133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:03.217426Z","time spent":"321.689116ms","remote":"127.0.0.1:59164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:09:03.539034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:03.116367Z","time spent":"422.643969ms","remote":"127.0.0.1:59164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:09:06.803007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.005625ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:09:06.803121Z","caller":"traceutil/trace.go:171","msg":"trace[470435503] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"661.317046ms","start":"2026-04-28T06:09:06.141786Z","end":"2026-04-28T06:09:06.803103Z","steps":["trace[470435503] 'process raft request' (duration: 360.015543ms)","trace[470435503] 'compare' (duration: 300.914403ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:09:06.80321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:06.141772Z","time spent":"661.411388ms","remote":"127.0.0.1:59208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1749,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:09:06.818272Z","caller":"traceutil/trace.go:171","msg":"trace[817551488] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"675.970822ms","start":"2026-04-28T06:09:06.142088Z","end":"2026-04-28T06:09:06.818058Z","steps":["trace[817551488] 'process raft request' (duration: 675.849069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:06.818478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:06.14208Z","time spent":"676.307289ms","remote":"127.0.0.1:59300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":182,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:09:06.826858Z","caller":"traceutil/trace.go:171","msg":"trace[765004506] linearizableReadLoop","detail":"{readStateIndex:591; appliedIndex:589; }","duration":"194.403077ms","start":"2026-04-28T06:09:06.632433Z","end":"2026-04-28T06:09:06.826836Z","steps":["trace[765004506] 'read index received' (duration: 185.552379ms)","trace[765004506] 'applied index is now lower than readState.Index' (duration: 8.849908ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:09:06.827379Z","caller":"traceutil/trace.go:171","msg":"trace[5289260] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"287.797362ms","start":"2026-04-28T06:09:06.539093Z","end":"2026-04-28T06:09:06.82689Z","steps":["trace[5289260] 'process raft request' (duration: 287.360551ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:06.828223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.767627ms","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-04-28T06:09:06.82828Z","caller":"traceutil/trace.go:171","msg":"trace[2144936965] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:571; }","duration":"195.89075ms","start":"2026-04-28T06:09:06.632378Z","end":"2026-04-28T06:09:06.828269Z","steps":["trace[2144936965] 'agreement among raft nodes before linearized reading' (duration: 194.99883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:09.768799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.206324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.19\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-28T06:09:09.7689Z","caller":"traceutil/trace.go:171","msg":"trace[1747856468] range","detail":"{range_begin:/registry/masterleases/199.204.45.19; range_end:; response_count:1; response_revision:587; }","duration":"298.318696ms","start":"2026-04-28T06:09:09.470562Z","end":"2026-04-28T06:09:09.76888Z","steps":["trace[1747856468] 'range keys from in-memory index tree' (duration: 298.03331ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:09.768984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.460927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-28T06:09:09.769081Z","caller":"traceutil/trace.go:171","msg":"trace[114446786] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:587; }","duration":"189.617861ms","start":"2026-04-28T06:09:09.579443Z","end":"2026-04-28T06:09:09.769061Z","steps":["trace[114446786] 'range keys from in-memory index tree' (duration: 189.276583ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:09.769084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.133282ms","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-04-28T06:09:09.769331Z","caller":"traceutil/trace.go:171","msg":"trace[533328782] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:587; }","duration":"199.412698ms","start":"2026-04-28T06:09:09.569905Z","end":"2026-04-28T06:09:09.769318Z","steps":["trace[533328782] 'range keys from in-memory index tree' (duration: 199.126542ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:10.456164Z","caller":"traceutil/trace.go:171","msg":"trace[1818322700] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"157.591186ms","start":"2026-04-28T06:09:10.29855Z","end":"2026-04-28T06:09:10.456141Z","steps":["trace[1818322700] 'process raft request' (duration: 155.870527ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:10.99906Z","caller":"traceutil/trace.go:171","msg":"trace[858908817] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:625; }","duration":"131.187416ms","start":"2026-04-28T06:09:10.867826Z","end":"2026-04-28T06:09:10.999013Z","steps":["trace[858908817] 'read index received' (duration: 130.994721ms)","trace[858908817] 'applied index is now lower than readState.Index' (duration: 188.705µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:09:10.999286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.432383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-28T06:09:10.999345Z","caller":"traceutil/trace.go:171","msg":"trace[1262093347] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:605; }","duration":"131.564715ms","start":"2026-04-28T06:09:10.867766Z","end":"2026-04-28T06:09:10.99933Z","steps":["trace[1262093347] 'agreement among raft nodes before linearized reading' (duration: 131.37413ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:10.999233Z","caller":"traceutil/trace.go:171","msg":"trace[1818024542] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"132.469855ms","start":"2026-04-28T06:09:10.866703Z","end":"2026-04-28T06:09:10.999173Z","steps":["trace[1818024542] 'process raft request' (duration: 132.129328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:12.303814Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988150696542997317,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T06:09:12.316571Z","caller":"traceutil/trace.go:171","msg":"trace[1963123044] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"532.701875ms","start":"2026-04-28T06:09:11.783845Z","end":"2026-04-28T06:09:12.316547Z","steps":["trace[1963123044] 'process raft request' (duration: 532.551582ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:12.316739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:11.783828Z","time spent":"532.801107ms","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:09:12.369724Z","caller":"traceutil/trace.go:171","msg":"trace[68375] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:627; }","duration":"566.751724ms","start":"2026-04-28T06:09:11.802952Z","end":"2026-04-28T06:09:12.369704Z","steps":["trace[68375] 'read index received' (duration: 513.444795ms)","trace[68375] 'applied index is now lower than readState.Index' (duration: 53.305949ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:09:12.37023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.248716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:09:12.370343Z","caller":"traceutil/trace.go:171","msg":"trace[1132903914] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:608; }","duration":"567.400819ms","start":"2026-04-28T06:09:11.802916Z","end":"2026-04-28T06:09:12.370317Z","steps":["trace[1132903914] 'agreement among raft nodes before linearized reading' (duration: 567.191665ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:12.370377Z","caller":"traceutil/trace.go:171","msg":"trace[956904915] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"353.519018ms","start":"2026-04-28T06:09:12.016834Z","end":"2026-04-28T06:09:12.370353Z","steps":["trace[956904915] 'process raft request' (duration: 352.75059ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:12.370397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:11.8029Z","time spent":"567.483362ms","remote":"127.0.0.1:59078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T06:09:12.370512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:12.016809Z","time spent":"353.626861ms","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:09:12.370834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.937631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-28T06:09:12.370919Z","caller":"traceutil/trace.go:171","msg":"trace[1551549071] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:608; }","duration":"559.057543ms","start":"2026-04-28T06:09:11.811832Z","end":"2026-04-28T06:09:12.370889Z","steps":["trace[1551549071] 'agreement among raft nodes before linearized reading' (duration: 558.587302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:12.371799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:11.811815Z","time spent":"559.156945ms","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-28T06:09:14.02399Z","caller":"traceutil/trace.go:171","msg":"trace[1947508614] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"269.089253ms","start":"2026-04-28T06:09:13.754859Z","end":"2026-04-28T06:09:14.023948Z","steps":["trace[1947508614] 'process raft request' (duration: 268.853948ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:14.027206Z","caller":"traceutil/trace.go:171","msg":"trace[1741323366] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:646; }","duration":"222.31385ms","start":"2026-04-28T06:09:13.804866Z","end":"2026-04-28T06:09:14.02718Z","steps":["trace[1741323366] 'read index received' (duration: 218.864613ms)","trace[1741323366] 'applied index is now lower than readState.Index' (duration: 3.448157ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:09:14.027378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.457493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:09:14.027461Z","caller":"traceutil/trace.go:171","msg":"trace[669664584] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:629; }","duration":"222.645837ms","start":"2026-04-28T06:09:13.804801Z","end":"2026-04-28T06:09:14.027447Z","steps":["trace[669664584] 'agreement among raft nodes before linearized reading' (duration: 222.478643ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:14.027581Z","caller":"traceutil/trace.go:171","msg":"trace[2129271807] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"271.148249ms","start":"2026-04-28T06:09:13.756356Z","end":"2026-04-28T06:09:14.027505Z","steps":["trace[2129271807] 'process raft request' (duration: 270.581697ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:14.027972Z","caller":"traceutil/trace.go:171","msg":"trace[1194217539] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"270.659959ms","start":"2026-04-28T06:09:13.757274Z","end":"2026-04-28T06:09:14.027934Z","steps":["trace[1194217539] 'process raft request' (duration: 269.80021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:28.342449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.8802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:09:28.342549Z","caller":"traceutil/trace.go:171","msg":"trace[90209615] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:763; }","duration":"538.017773ms","start":"2026-04-28T06:09:27.804501Z","end":"2026-04-28T06:09:28.342519Z","steps":["trace[90209615] 'range keys from in-memory index tree' (duration: 537.778528ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:28.342599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:27.804481Z","time spent":"538.107614ms","remote":"127.0.0.1:59088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T06:09:28.342592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.634929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-28T06:09:28.342691Z","caller":"traceutil/trace.go:171","msg":"trace[330039305] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:763; }","duration":"229.801492ms","start":"2026-04-28T06:09:28.112871Z","end":"2026-04-28T06:09:28.342673Z","steps":["trace[330039305] 'range keys from in-memory index tree' (duration: 229.448685ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:28.477846Z","caller":"traceutil/trace.go:171","msg":"trace[1463439389] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"131.068111ms","start":"2026-04-28T06:09:28.346751Z","end":"2026-04-28T06:09:28.477819Z","steps":["trace[1463439389] 'process raft request' (duration: 130.837977ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:10:03.079873Z","caller":"traceutil/trace.go:171","msg":"trace[1800534116] linearizableReadLoop","detail":"{readStateIndex:1013; appliedIndex:1012; }","duration":"419.250653ms","start":"2026-04-28T06:10:02.660603Z","end":"2026-04-28T06:10:03.079853Z","steps":["trace[1800534116] 'read index received' (duration: 419.138961ms)","trace[1800534116] 'applied index is now lower than readState.Index' (duration: 110.712µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:10:03.079923Z","caller":"traceutil/trace.go:171","msg":"trace[990170404] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"431.755597ms","start":"2026-04-28T06:10:02.648143Z","end":"2026-04-28T06:10:03.079899Z","steps":["trace[990170404] 'process raft request' (duration: 431.588633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:10:03.080047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:10:02.648123Z","time spent":"431.852559ms","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:10:03.080066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.442506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-28T06:10:03.080107Z","caller":"traceutil/trace.go:171","msg":"trace[1572258338] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:978; }","duration":"419.540459ms","start":"2026-04-28T06:10:02.660557Z","end":"2026-04-28T06:10:03.080097Z","steps":["trace[1572258338] 'agreement among raft nodes before linearized reading' (duration: 419.405156ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:10:03.080139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:10:02.660519Z","time spent":"419.61232ms","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T06:10:03.71252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.129427ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:10:03.712786Z","caller":"traceutil/trace.go:171","msg":"trace[1337316815] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"626.445873ms","start":"2026-04-28T06:10:03.086323Z","end":"2026-04-28T06:10:03.712769Z","steps":["trace[1337316815] 'process raft request' (duration: 626.325691ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:10:03.712867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:10:03.086299Z","time spent":"626.527216ms","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:10:03.712987Z","caller":"traceutil/trace.go:171","msg":"trace[1581926217] linearizableReadLoop","detail":"{readStateIndex:1014; appliedIndex:1013; }","duration":"632.992288ms","start":"2026-04-28T06:10:03.079941Z","end":"2026-04-28T06:10:03.712934Z","steps":["trace[1581926217] 'read index received' (duration: 61.378211ms)","trace[1581926217] 'applied index is now lower than readState.Index' (duration: 571.611537ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:10:03.713113Z","caller":"traceutil/trace.go:171","msg":"trace[759913786] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"639.97453ms","start":"2026-04-28T06:10:03.073122Z","end":"2026-04-28T06:10:03.713096Z","steps":["trace[759913786] 'process raft request' (duration: 68.123418ms)","trace[759913786] 'compare' (duration: 570.822321ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:10:03.713199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.051232759s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-28T06:10:03.713213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:10:03.073099Z","time spent":"640.054991ms","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:10:03.713244Z","caller":"traceutil/trace.go:171","msg":"trace[2074668393] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:980; }","duration":"1.051320921s","start":"2026-04-28T06:10:02.661909Z","end":"2026-04-28T06:10:03.71323Z","steps":["trace[2074668393] 'agreement among raft nodes before linearized reading' (duration: 1.051171929s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:10:03.71328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:10:02.661891Z","time spent":"1.051379722s","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-28T06:10:03.714101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.231997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:10:03.714143Z","caller":"traceutil/trace.go:171","msg":"trace[928006634] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:980; }","duration":"910.307578ms","start":"2026-04-28T06:10:02.803823Z","end":"2026-04-28T06:10:03.714131Z","steps":["trace[928006634] 'agreement among raft nodes before linearized reading' (duration: 909.251157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:10:03.714167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:10:02.803806Z","time spent":"910.353959ms","remote":"127.0.0.1:59088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T06:10:04.213718Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988150696542998089,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T06:10:04.281321Z","caller":"traceutil/trace.go:171","msg":"trace[1188347673] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"825.336766ms","start":"2026-04-28T06:10:03.455954Z","end":"2026-04-28T06:10:04.28129Z","steps":["trace[1188347673] 'process raft request' (duration: 825.093612ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:10:04.281433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:10:03.455918Z","time spent":"825.461378ms","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:10:04.285419Z","caller":"traceutil/trace.go:171","msg":"trace[139270221] linearizableReadLoop","detail":"{readStateIndex:1017; appliedIndex:1016; }","duration":"572.339511ms","start":"2026-04-28T06:10:03.713053Z","end":"2026-04-28T06:10:04.285393Z","steps":["trace[139270221] 'read index received' (duration: 568.313799ms)","trace[139270221] 'applied index is now lower than readState.Index' (duration: 4.024842ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:10:04.285524Z","caller":"traceutil/trace.go:171","msg":"trace[555437745] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"567.989832ms","start":"2026-04-28T06:10:03.717522Z","end":"2026-04-28T06:10:04.285512Z","steps":["trace[555437745] 'process raft request' (duration: 567.751288ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:10:04.285635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:10:03.717514Z","time spent":"568.046093ms","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:10:04.28566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.047201267s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-28T06:10:04.285705Z","caller":"traceutil/trace.go:171","msg":"trace[1654654383] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:982; }","duration":"1.047272159s","start":"2026-04-28T06:10:03.23842Z","end":"2026-04-28T06:10:04.285692Z","steps":["trace[1654654383] 'agreement among raft nodes before linearized reading' (duration: 1.047136395s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:10:04.285736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:10:03.238406Z","time spent":"1.04732277s","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T06:10:04.285858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"987.477581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-28T06:10:04.285891Z","caller":"traceutil/trace.go:171","msg":"trace[1987754205] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:982; }","duration":"987.540903ms","start":"2026-04-28T06:10:03.298342Z","end":"2026-04-28T06:10:04.285883Z","steps":["trace[1987754205] 'agreement among raft nodes before linearized reading' (duration: 987.457861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:10:04.285903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.556056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:10:04.285968Z","caller":"traceutil/trace.go:171","msg":"trace[1999497989] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:982; }","duration":"570.634967ms","start":"2026-04-28T06:10:03.715316Z","end":"2026-04-28T06:10:04.285951Z","steps":["trace[1999497989] 'agreement among raft nodes before linearized reading' (duration: 570.549655ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:10:04.28601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:10:03.715306Z","time spent":"570.694269ms","remote":"127.0.0.1:59078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T06:10:04.285917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:10:03.298322Z","time spent":"987.589523ms","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T06:10:04.2861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.544841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T06:10:04.286177Z","caller":"traceutil/trace.go:171","msg":"trace[413868892] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:982; }","duration":"453.679374ms","start":"2026-04-28T06:10:03.832479Z","end":"2026-04-28T06:10:04.286159Z","steps":["trace[413868892] 'agreement among raft nodes before linearized reading' (duration: 453.521131ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:10:04.286222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:10:03.832455Z","time spent":"453.758075ms","remote":"127.0.0.1:59274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"info","ts":"2026-04-28T06:10:25.374924Z","caller":"traceutil/trace.go:171","msg":"trace[1298547226] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"203.61946ms","start":"2026-04-28T06:10:25.171274Z","end":"2026-04-28T06:10:25.374894Z","steps":["trace[1298547226] 'process raft request' (duration: 203.458437ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:10:28.064708Z","caller":"traceutil/trace.go:171","msg":"trace[781061908] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"680.995755ms","start":"2026-04-28T06:10:27.383604Z","end":"2026-04-28T06:10:28.0646Z","steps":["trace[781061908] 'process raft request' (duration: 680.834112ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:10:28.064751Z","caller":"traceutil/trace.go:171","msg":"trace[2034652583] linearizableReadLoop","detail":"{readStateIndex:1097; appliedIndex:1097; }","duration":"248.13262ms","start":"2026-04-28T06:10:27.816589Z","end":"2026-04-28T06:10:28.064721Z","steps":["trace[2034652583] 'read index received' (duration: 248.12003ms)","trace[2034652583] 'applied index is now lower than readState.Index' (duration: 10.26µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:10:28.064872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:10:27.383578Z","time spent":"681.217119ms","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:10:28.065139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.381736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:10:28.065625Z","caller":"traceutil/trace.go:171","msg":"trace[375758252] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1057; }","duration":"249.061338ms","start":"2026-04-28T06:10:27.816537Z","end":"2026-04-28T06:10:28.065598Z","steps":["trace[375758252] 'agreement among raft nodes before linearized reading' (duration: 248.344194ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:10:28.170157Z","caller":"traceutil/trace.go:171","msg":"trace[1615187641] linearizableReadLoop","detail":"{readStateIndex:1098; appliedIndex:1097; }","duration":"105.306558ms","start":"2026-04-28T06:10:28.064834Z","end":"2026-04-28T06:10:28.17014Z","steps":["trace[1615187641] 'read index received' (duration: 60.129187ms)","trace[1615187641] 'applied index is now lower than readState.Index' (duration: 45.174081ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:10:28.170248Z","caller":"traceutil/trace.go:171","msg":"trace[1391737304] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"321.889017ms","start":"2026-04-28T06:10:27.848316Z","end":"2026-04-28T06:10:28.170205Z","steps":["trace[1391737304] 'process raft request' (duration: 276.745337ms)","trace[1391737304] 'compare' (duration: 44.977547ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:10:28.170438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:10:27.848299Z","time spent":"322.03681ms","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:10:28.170721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.836488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-28T06:10:28.170766Z","caller":"traceutil/trace.go:171","msg":"trace[1530071209] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1058; }","duration":"288.930279ms","start":"2026-04-28T06:10:27.881824Z","end":"2026-04-28T06:10:28.170755Z","steps":["trace[1530071209] 'agreement among raft nodes before linearized reading' (duration: 288.395519ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:10:28.625523Z","caller":"traceutil/trace.go:171","msg":"trace[430839300] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"151.695703ms","start":"2026-04-28T06:10:28.473784Z","end":"2026-04-28T06:10:28.62548Z","steps":["trace[430839300] 'process raft request' (duration: 75.230705ms)","trace[430839300] 'compare' (duration: 76.304615ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:10:28.669025Z","caller":"traceutil/trace.go:171","msg":"trace[476499310] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"194.478818ms","start":"2026-04-28T06:10:28.47451Z","end":"2026-04-28T06:10:28.668989Z","steps":["trace[476499310] 'process raft request' (duration: 194.271123ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:10:29.912572Z","caller":"traceutil/trace.go:171","msg":"trace[1070980606] linearizableReadLoop","detail":"{readStateIndex:1104; appliedIndex:1103; }","duration":"107.808455ms","start":"2026-04-28T06:10:29.804739Z","end":"2026-04-28T06:10:29.912548Z","steps":["trace[1070980606] 'read index received' (duration: 69.550105ms)","trace[1070980606] 'applied index is now lower than readState.Index' (duration: 38.25732ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:10:29.91278Z","caller":"traceutil/trace.go:171","msg":"trace[639323409] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"187.282811ms","start":"2026-04-28T06:10:29.725378Z","end":"2026-04-28T06:10:29.91266Z","steps":["trace[639323409] 'process raft request' (duration: 149.000061ms)","trace[639323409] 'compare' (duration: 38.059006ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:10:29.913312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.968068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:10:29.913382Z","caller":"traceutil/trace.go:171","msg":"trace[628839978] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1063; }","duration":"108.704512ms","start":"2026-04-28T06:10:29.804664Z","end":"2026-04-28T06:10:29.913368Z","steps":["trace[628839978] 'agreement among raft nodes before linearized reading' (duration: 107.972449ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:10:30.35389Z","caller":"traceutil/trace.go:171","msg":"trace[708701420] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"106.845427ms","start":"2026-04-28T06:10:30.247017Z","end":"2026-04-28T06:10:30.353863Z","steps":["trace[708701420] 'process raft request' (duration: 51.543313ms)","trace[708701420] 'compare' (duration: 55.191072ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:10:41.353198Z","caller":"traceutil/trace.go:171","msg":"trace[1199612384] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"198.666458ms","start":"2026-04-28T06:10:41.154487Z","end":"2026-04-28T06:10:41.353154Z","steps":["trace[1199612384] 'process raft request' (duration: 198.435824ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:10:42.356777Z","caller":"traceutil/trace.go:171","msg":"trace[967966580] transaction","detail":"{read_only:false; response_revision:1156; number_of_response:1; }","duration":"105.756127ms","start":"2026-04-28T06:10:42.250997Z","end":"2026-04-28T06:10:42.356753Z","steps":["trace[967966580] 'process raft request' (duration: 105.594943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:10:44.86294Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988150696542998527,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:10:45.363838Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988150696542998527,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:10:45.442532Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.109057946s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-28T06:10:45.474822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.074700803s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-28T06:10:45.474884Z","caller":"traceutil/trace.go:171","msg":"trace[1020237985] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1156; }","duration":"3.074812257s","start":"2026-04-28T06:10:42.400053Z","end":"2026-04-28T06:10:45.474865Z","steps":["trace[1020237985] 'range keys from in-memory index tree' (duration: 3.074117242s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:10:45.474919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:10:42.400025Z","time spent":"3.074885729s","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T06:10:45.475223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.670993777s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:10:45.475287Z","caller":"traceutil/trace.go:171","msg":"trace[67574568] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1156; }","duration":"2.671107179s","start":"2026-04-28T06:10:42.804163Z","end":"2026-04-28T06:10:45.47527Z","steps":["trace[67574568] 'range keys from in-memory index tree' (duration: 2.670830634s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:10:45.47532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:10:42.804142Z","time spent":"2.67117153s","remote":"127.0.0.1:59078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T06:10:45.475452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.77867327s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-28T06:10:45.475474Z","caller":"traceutil/trace.go:171","msg":"trace[869775048] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1156; }","duration":"2.778718961s","start":"2026-04-28T06:10:42.696748Z","end":"2026-04-28T06:10:45.475467Z","steps":["trace[869775048] 'range keys from in-memory index tree' (duration: 2.778542297s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:10:45.475494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:10:42.696731Z","time spent":"2.778758022s","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-28T06:10:45.475781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.97796173s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-28T06:10:45.475914Z","caller":"traceutil/trace.go:171","msg":"trace[1064819341] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1156; }","duration":"2.978143382s","start":"2026-04-28T06:10:42.497733Z","end":"2026-04-28T06:10:45.475877Z","steps":["trace[1064819341] 'range keys from in-memory index tree' (duration: 2.977683615s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:10:45.47599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:10:42.497711Z","time spent":"2.978263775s","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T06:10:45.476253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.754876706s","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-04-28T06:10:45.476298Z","caller":"traceutil/trace.go:171","msg":"trace[287220661] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1156; }","duration":"2.754965478s","start":"2026-04-28T06:10:42.72132Z","end":"2026-04-28T06:10:45.476285Z","steps":["trace[287220661] 'range keys from in-memory index tree' (duration: 2.754727494s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:10:45.476337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:10:42.721296Z","time spent":"2.75503028s","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T06:10:45.476306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.058802634s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:10:45.476464Z","caller":"traceutil/trace.go:171","msg":"trace[1293887541] transaction","detail":"{read_only:false; response_revision:1157; number_of_response:1; }","duration":"1.143081725s","start":"2026-04-28T06:10:44.333369Z","end":"2026-04-28T06:10:45.476451Z","steps":["trace[1293887541] 'process raft request' (duration: 1.109387202s)","trace[1293887541] 'compare' (duration: 31.261616ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:10:45.47654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:10:44.333345Z","time spent":"1.143147217s","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:10:45.476673Z","caller":"traceutil/trace.go:171","msg":"trace[1986600704] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"748.007892ms","start":"2026-04-28T06:10:44.728658Z","end":"2026-04-28T06:10:45.476666Z","steps":["trace[1986600704] 'process raft request' (duration: 747.718517ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:10:45.476731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:10:44.728629Z","time spent":"748.081954ms","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:10:45.480403Z","caller":"traceutil/trace.go:171","msg":"trace[1773272871] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1156; }","duration":"2.05963519s","start":"2026-04-28T06:10:43.416748Z","end":"2026-04-28T06:10:45.476383Z","steps":["trace[1773272871] 'range keys from in-memory index tree' (duration: 2.058234004s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:10:45.480464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:10:43.416728Z","time spent":"2.063725318s","remote":"127.0.0.1:59088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T06:10:45.592439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.94ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-28T06:10:45.592903Z","caller":"traceutil/trace.go:171","msg":"trace[1707501981] linearizableReadLoop","detail":"{readStateIndex:1205; appliedIndex:1201; }","duration":"1.230572652s","start":"2026-04-28T06:10:44.362311Z","end":"2026-04-28T06:10:45.592883Z","steps":["trace[1707501981] 'read index received' (duration: 1.080459561s)","trace[1707501981] 'applied index is now lower than readState.Index' (duration: 150.111751ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:10:45.592959Z","caller":"traceutil/trace.go:171","msg":"trace[1760360171] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"783.866166ms","start":"2026-04-28T06:10:44.809061Z","end":"2026-04-28T06:10:45.592927Z","steps":["trace[1760360171] 'process raft request' (duration: 783.699383ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:10:45.593234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.230909208s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-28T06:10:45.59329Z","caller":"traceutil/trace.go:171","msg":"trace[680882954] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1159; }","duration":"1.231017101s","start":"2026-04-28T06:10:44.362259Z","end":"2026-04-28T06:10:45.593277Z","steps":["trace[680882954] 'agreement among raft nodes before linearized reading' (duration: 1.230766486s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:10:45.593237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:10:44.809028Z","time spent":"784.135081ms","remote":"127.0.0.1:59192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:10:45.593329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:10:44.362238Z","time spent":"1.231079781s","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-28T06:10:45.611767Z","caller":"traceutil/trace.go:171","msg":"trace[456218630] transaction","detail":"{read_only:false; response_revision:1160; number_of_response:1; }","duration":"128.884807ms","start":"2026-04-28T06:10:45.482863Z","end":"2026-04-28T06:10:45.611747Z","steps":["trace[456218630] 'process raft request' (duration: 128.724244ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:10:45.647114Z","caller":"traceutil/trace.go:171","msg":"trace[1619100463] transaction","detail":"{read_only:false; response_revision:1161; number_of_response:1; }","duration":"163.839734ms","start":"2026-04-28T06:10:45.483252Z","end":"2026-04-28T06:10:45.647092Z","steps":["trace[1619100463] 'process raft request' (duration: 163.672449ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:10:45.647331Z","caller":"traceutil/trace.go:171","msg":"trace[1422270604] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"163.429767ms","start":"2026-04-28T06:10:45.483882Z","end":"2026-04-28T06:10:45.647312Z","steps":["trace[1422270604] 'process raft request' (duration: 163.170672ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:10:45.647534Z","caller":"traceutil/trace.go:171","msg":"trace[1879102403] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"163.442136ms","start":"2026-04-28T06:10:45.484077Z","end":"2026-04-28T06:10:45.64752Z","steps":["trace[1879102403] 'process raft request' (duration: 163.167661ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:10:45.647984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"835.809116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:10:45.648035Z","caller":"traceutil/trace.go:171","msg":"trace[436575496] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1165; }","duration":"835.886858ms","start":"2026-04-28T06:10:44.812133Z","end":"2026-04-28T06:10:45.64802Z","steps":["trace[436575496] 'agreement among raft nodes before linearized reading' (duration: 835.426409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:10:45.648071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:10:44.812126Z","time spent":"835.934998ms","remote":"127.0.0.1:59088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T06:10:45.648811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.753438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T06:10:45.648919Z","caller":"traceutil/trace.go:171","msg":"trace[1045931391] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1165; }","duration":"521.905912ms","start":"2026-04-28T06:10:45.126989Z","end":"2026-04-28T06:10:45.648895Z","steps":["trace[1045931391] 'agreement among raft nodes before linearized reading' (duration: 520.701469ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:10:45.64898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:10:45.126975Z","time spent":"521.988803ms","remote":"127.0.0.1:59368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":5,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"info","ts":"2026-04-28T06:10:49.642367Z","caller":"traceutil/trace.go:171","msg":"trace[965640999] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"151.01642ms","start":"2026-04-28T06:10:49.491302Z","end":"2026-04-28T06:10:49.642319Z","steps":["trace[965640999] 'process raft request' (duration: 103.906331ms)","trace[965640999] 'compare' (duration: 46.851394ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:10:49.642434Z","caller":"traceutil/trace.go:171","msg":"trace[1121104183] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"149.547061ms","start":"2026-04-28T06:10:49.492865Z","end":"2026-04-28T06:10:49.642412Z","steps":["trace[1121104183] 'process raft request' (duration: 149.372728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:11:02.448028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.894192ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:11:02.448295Z","caller":"traceutil/trace.go:171","msg":"trace[1411850960] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"611.767874ms","start":"2026-04-28T06:11:01.83651Z","end":"2026-04-28T06:11:02.448278Z","steps":["trace[1411850960] 'process raft request' (duration: 611.732603ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:11:02.448375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:11:01.836493Z","time spent":"611.841496ms","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:11:02.448567Z","caller":"traceutil/trace.go:171","msg":"trace[291863667] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"727.431189ms","start":"2026-04-28T06:11:01.721124Z","end":"2026-04-28T06:11:02.448555Z","steps":["trace[291863667] 'process raft request' (duration: 420.915456ms)","trace[291863667] 'compare' (duration: 305.298031ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:11:02.448618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:11:01.721099Z","time spent":"727.497521ms","remote":"127.0.0.1:59192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:11:02.448856Z","caller":"traceutil/trace.go:171","msg":"trace[907635159] linearizableReadLoop","detail":"{readStateIndex:1285; appliedIndex:1284; }","duration":"712.720308ms","start":"2026-04-28T06:11:01.736124Z","end":"2026-04-28T06:11:02.448845Z","steps":["trace[907635159] 'read index received' (duration: 405.901709ms)","trace[907635159] 'applied index is now lower than readState.Index' (duration: 306.817309ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:11:02.448936Z","caller":"traceutil/trace.go:171","msg":"trace[548277486] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"710.081388ms","start":"2026-04-28T06:11:01.738847Z","end":"2026-04-28T06:11:02.448929Z","steps":["trace[548277486] 'process raft request' (duration: 709.278052ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:11:02.448979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:11:01.738837Z","time spent":"710.119128ms","remote":"127.0.0.1:59192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:11:02.449252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.969236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:11:02.449291Z","caller":"traceutil/trace.go:171","msg":"trace[1626060779] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1239; }","duration":"646.033487ms","start":"2026-04-28T06:11:01.803248Z","end":"2026-04-28T06:11:02.449281Z","steps":["trace[1626060779] 'agreement among raft nodes before linearized reading' (duration: 645.974866ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:11:02.449453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:11:01.803233Z","time spent":"646.20977ms","remote":"127.0.0.1:59088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-28T06:11:02.449436Z","caller":"traceutil/trace.go:171","msg":"trace[1407711031] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"675.02595ms","start":"2026-04-28T06:11:01.77439Z","end":"2026-04-28T06:11:02.449416Z","steps":["trace[1407711031] 'process raft request' (duration: 673.801596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:11:02.449682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:11:01.774371Z","time spent":"675.151733ms","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:11:02.449699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.930818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:11:02.449748Z","caller":"traceutil/trace.go:171","msg":"trace[1115122093] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1239; }","duration":"118.02444ms","start":"2026-04-28T06:11:02.331704Z","end":"2026-04-28T06:11:02.449728Z","steps":["trace[1115122093] 'agreement among raft nodes before linearized reading' (duration: 117.957638ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:11:02.449462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.933794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-28T06:11:02.449949Z","caller":"traceutil/trace.go:171","msg":"trace[291502357] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1239; }","duration":"605.441913ms","start":"2026-04-28T06:11:01.844494Z","end":"2026-04-28T06:11:02.449936Z","steps":["trace[291502357] 'agreement among raft nodes before linearized reading' (duration: 604.793332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:11:02.449992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:11:01.844489Z","time spent":"605.489015ms","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T06:11:02.449359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.213147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"warn","ts":"2026-04-28T06:11:02.450361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.413002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-28T06:11:02.450364Z","caller":"traceutil/trace.go:171","msg":"trace[1526887562] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1239; }","duration":"714.260208ms","start":"2026-04-28T06:11:01.736091Z","end":"2026-04-28T06:11:02.450351Z","steps":["trace[1526887562] 'agreement among raft nodes before linearized reading' (duration: 713.105526ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:11:02.450406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:11:01.73608Z","time spent":"714.312909ms","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-28T06:11:02.450434Z","caller":"traceutil/trace.go:171","msg":"trace[2007310039] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1239; }","duration":"606.460733ms","start":"2026-04-28T06:11:01.843923Z","end":"2026-04-28T06:11:02.450384Z","steps":["trace[2007310039] 'agreement among raft nodes before linearized reading' (duration: 606.366821ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:11:02.450474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:11:01.843917Z","time spent":"606.548614ms","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-28T06:11:02.450703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.31904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-28T06:11:02.450735Z","caller":"traceutil/trace.go:171","msg":"trace[1539841863] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1239; }","duration":"606.357711ms","start":"2026-04-28T06:11:01.844368Z","end":"2026-04-28T06:11:02.450726Z","steps":["trace[1539841863] 'agreement among raft nodes before linearized reading' (duration: 606.241729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:11:02.450765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:11:01.844365Z","time spent":"606.394062ms","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T06:11:02.450923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.566625ms","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-04-28T06:11:02.451037Z","caller":"traceutil/trace.go:171","msg":"trace[649076093] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1239; }","duration":"606.670757ms","start":"2026-04-28T06:11:01.844332Z","end":"2026-04-28T06:11:02.451003Z","steps":["trace[649076093] 'agreement among raft nodes before linearized reading' (duration: 606.513724ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:11:02.451079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:11:01.844327Z","time spent":"606.742538ms","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T06:11:02.455377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.969179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T06:11:02.455433Z","caller":"traceutil/trace.go:171","msg":"trace[1739425148] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1239; }","duration":"611.03639ms","start":"2026-04-28T06:11:01.844383Z","end":"2026-04-28T06:11:02.455419Z","steps":["trace[1739425148] 'agreement among raft nodes before linearized reading' (duration: 605.391263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:11:02.455469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:11:01.84438Z","time spent":"611.081801ms","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T06:11:03.066272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.897499ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:11:03.066427Z","caller":"traceutil/trace.go:171","msg":"trace[1285945141] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"609.753917ms","start":"2026-04-28T06:11:02.456663Z","end":"2026-04-28T06:11:03.066417Z","steps":["trace[1285945141] 'process raft request' (duration: 609.679255ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:11:03.066463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:11:02.456641Z","time spent":"609.804617ms","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:11:03.066571Z","caller":"traceutil/trace.go:171","msg":"trace[739530893] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"609.812227ms","start":"2026-04-28T06:11:02.456752Z","end":"2026-04-28T06:11:03.066565Z","steps":["trace[739530893] 'process raft request' (duration: 180.558622ms)","trace[739530893] 'compare' (duration: 428.645043ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:11:03.066596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:11:02.456725Z","time spent":"609.857849ms","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:11:03.06664Z","caller":"traceutil/trace.go:171","msg":"trace[748943086] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"608.045903ms","start":"2026-04-28T06:11:02.458569Z","end":"2026-04-28T06:11:03.066615Z","steps":["trace[748943086] 'process raft request' (duration: 608.010562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:11:03.066767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:11:02.458563Z","time spent":"608.136585ms","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:11:03.066815Z","caller":"traceutil/trace.go:171","msg":"trace[1112460165] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"609.950401ms","start":"2026-04-28T06:11:02.456854Z","end":"2026-04-28T06:11:03.066805Z","steps":["trace[1112460165] 'process raft request' (duration: 609.543673ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:11:03.066818Z","caller":"traceutil/trace.go:171","msg":"trace[74372953] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"609.458401ms","start":"2026-04-28T06:11:02.457344Z","end":"2026-04-28T06:11:03.066802Z","steps":["trace[74372953] 'process raft request' (duration: 609.133325ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:11:03.066829Z","caller":"traceutil/trace.go:171","msg":"trace[993111286] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"608.569164ms","start":"2026-04-28T06:11:02.458241Z","end":"2026-04-28T06:11:03.06681Z","steps":["trace[993111286] 'process raft request' (duration: 608.309599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:11:03.066858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:11:02.456235Z","time spent":"610.601042ms","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:11:03.066875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:11:02.457335Z","time spent":"609.512401ms","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:11:03.066912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:11:02.458208Z","time spent":"608.659736ms","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:11:05.547531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.679534ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-28T06:11:05.547605Z","caller":"traceutil/trace.go:171","msg":"trace[890234531] linearizableReadLoop","detail":"{readStateIndex:1296; appliedIndex:1295; }","duration":"743.875323ms","start":"2026-04-28T06:11:04.803717Z","end":"2026-04-28T06:11:05.547593Z","steps":["trace[890234531] 'read index received' (duration: 379.164678ms)","trace[890234531] 'applied index is now lower than readState.Index' (duration: 364.709685ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:11:05.547831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.106467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:11:05.547846Z","caller":"traceutil/trace.go:171","msg":"trace[375097715] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1246; }","duration":"744.155858ms","start":"2026-04-28T06:11:04.803685Z","end":"2026-04-28T06:11:05.547841Z","steps":["trace[375097715] 'agreement among raft nodes before linearized reading' (duration: 744.111588ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:11:05.547866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:11:04.803669Z","time spent":"744.193359ms","remote":"127.0.0.1:59088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-28T06:11:05.605059Z","caller":"traceutil/trace.go:171","msg":"trace[1595312542] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"584.351692ms","start":"2026-04-28T06:11:05.020664Z","end":"2026-04-28T06:11:05.605016Z","steps":["trace[1595312542] 'process raft request' (duration: 583.635207ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:11:05.605256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:11:05.020635Z","time spent":"584.512784ms","remote":"127.0.0.1:59274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6104,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:11:05.605316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.754556ms","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-04-28T06:11:05.605429Z","caller":"traceutil/trace.go:171","msg":"trace[1437852116] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1247; }","duration":"533.88244ms","start":"2026-04-28T06:11:05.071516Z","end":"2026-04-28T06:11:05.605399Z","steps":["trace[1437852116] 'agreement among raft nodes before linearized reading' (duration: 533.623685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:11:05.605489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:11:05.071511Z","time spent":"533.964471ms","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T06:11:05.605666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.178114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-28T06:11:05.605708Z","caller":"traceutil/trace.go:171","msg":"trace[386181379] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1247; }","duration":"534.255966ms","start":"2026-04-28T06:11:05.07144Z","end":"2026-04-28T06:11:05.605696Z","steps":["trace[386181379] 'agreement among raft nodes before linearized reading' (duration: 534.068053ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:11:05.605739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:11:05.071351Z","time spent":"534.381929ms","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T06:11:05.605769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.300187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:11:05.605804Z","caller":"traceutil/trace.go:171","msg":"trace[770711569] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1247; }","duration":"234.380179ms","start":"2026-04-28T06:11:05.371411Z","end":"2026-04-28T06:11:05.605791Z","steps":["trace[770711569] 'agreement among raft nodes before linearized reading' (duration: 234.311628ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:11:05.605948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.632007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:11:05.605986Z","caller":"traceutil/trace.go:171","msg":"trace[792372234] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1247; }","duration":"594.692739ms","start":"2026-04-28T06:11:05.011278Z","end":"2026-04-28T06:11:05.605971Z","steps":["trace[792372234] 'agreement among raft nodes before linearized reading' (duration: 594.637618ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:11:05.606016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:11:05.011239Z","time spent":"594.768661ms","remote":"127.0.0.1:59496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:11:05.60605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.368389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T06:11:05.606086Z","caller":"traceutil/trace.go:171","msg":"trace[1166955431] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1247; }","duration":"533.411401ms","start":"2026-04-28T06:11:05.072661Z","end":"2026-04-28T06:11:05.606073Z","steps":["trace[1166955431] 'agreement among raft nodes before linearized reading' (duration: 533.317929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:11:05.606113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:11:05.072656Z","time spent":"533.451042ms","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T06:11:05.606315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.703205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-28T06:11:05.606357Z","caller":"traceutil/trace.go:171","msg":"trace[231258193] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1247; }","duration":"534.752716ms","start":"2026-04-28T06:11:05.071596Z","end":"2026-04-28T06:11:05.606348Z","steps":["trace[231258193] 'agreement among raft nodes before linearized reading' (duration: 534.659654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:11:05.606385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:11:05.071591Z","time spent":"534.786587ms","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-28T06:11:05.606569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.014821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-28T06:11:05.606595Z","caller":"traceutil/trace.go:171","msg":"trace[590704219] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1247; }","duration":"535.047711ms","start":"2026-04-28T06:11:05.071539Z","end":"2026-04-28T06:11:05.606587Z","steps":["trace[590704219] 'agreement among raft nodes before linearized reading' (duration: 534.96803ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:11:05.606623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:11:05.071535Z","time spent":"535.081762ms","remote":"127.0.0.1:59362","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-04-28T06:11:05.794911Z","caller":"traceutil/trace.go:171","msg":"trace[1886997638] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"180.682865ms","start":"2026-04-28T06:11:05.614199Z","end":"2026-04-28T06:11:05.794881Z","steps":["trace[1886997638] 'process raft request' (duration: 178.359951ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:11:05.795258Z","caller":"traceutil/trace.go:171","msg":"trace[928641118] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"179.736928ms","start":"2026-04-28T06:11:05.615494Z","end":"2026-04-28T06:11:05.795231Z","steps":["trace[928641118] 'process raft request' (duration: 179.286178ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:11:05.795499Z","caller":"traceutil/trace.go:171","msg":"trace[1737010436] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"179.85534ms","start":"2026-04-28T06:11:05.61563Z","end":"2026-04-28T06:11:05.795485Z","steps":["trace[1737010436] 'process raft request' (duration: 179.474113ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:11:05.795609Z","caller":"traceutil/trace.go:171","msg":"trace[1800119447] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"179.864069ms","start":"2026-04-28T06:11:05.615731Z","end":"2026-04-28T06:11:05.795595Z","steps":["trace[1800119447] 'process raft request' (duration: 179.702826ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:11:12.000411Z","caller":"traceutil/trace.go:171","msg":"trace[2000254763] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"120.995818ms","start":"2026-04-28T06:11:11.879386Z","end":"2026-04-28T06:11:12.000382Z","steps":["trace[2000254763] 'process raft request' (duration: 120.906746ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:11:12.000874Z","caller":"traceutil/trace.go:171","msg":"trace[734038330] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"121.484786ms","start":"2026-04-28T06:11:11.87936Z","end":"2026-04-28T06:11:12.000845Z","steps":["trace[734038330] 'process raft request' (duration: 81.651386ms)","trace[734038330] 'compare' (duration: 39.106936ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:11:12.001143Z","caller":"traceutil/trace.go:171","msg":"trace[271233899] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"121.064868ms","start":"2026-04-28T06:11:11.880042Z","end":"2026-04-28T06:11:12.001107Z","steps":["trace[271233899] 'process raft request' (duration: 120.299584ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:11:14.366189Z","caller":"traceutil/trace.go:171","msg":"trace[1555203361] linearizableReadLoop","detail":"{readStateIndex:1365; appliedIndex:1364; }","duration":"360.492052ms","start":"2026-04-28T06:11:14.00567Z","end":"2026-04-28T06:11:14.366162Z","steps":["trace[1555203361] 'read index received' (duration: 360.295709ms)","trace[1555203361] 'applied index is now lower than readState.Index' (duration: 195.543µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:11:14.366303Z","caller":"traceutil/trace.go:171","msg":"trace[1875356402] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"482.319486ms","start":"2026-04-28T06:11:13.883969Z","end":"2026-04-28T06:11:14.366289Z","steps":["trace[1875356402] 'process raft request' (duration: 482.054141ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:11:14.366402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:11:13.883934Z","time spent":"482.394867ms","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:11:14.366517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.168967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-28T06:11:14.366563Z","caller":"traceutil/trace.go:171","msg":"trace[1607769051] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1314; }","duration":"360.222718ms","start":"2026-04-28T06:11:14.006328Z","end":"2026-04-28T06:11:14.36655Z","steps":["trace[1607769051] 'agreement among raft nodes before linearized reading' (duration: 360.096175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:11:14.366594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:11:14.006324Z","time spent":"360.261989ms","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T06:11:14.366639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.265575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-28T06:11:14.366703Z","caller":"traceutil/trace.go:171","msg":"trace[1729166814] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1314; }","duration":"280.340706ms","start":"2026-04-28T06:11:14.086345Z","end":"2026-04-28T06:11:14.366686Z","steps":["trace[1729166814] 'agreement among raft nodes before linearized reading' (duration: 280.170603ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:11:14.366728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.555296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T06:11:14.366762Z","caller":"traceutil/trace.go:171","msg":"trace[279400522] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1314; }","duration":"359.595966ms","start":"2026-04-28T06:11:14.007155Z","end":"2026-04-28T06:11:14.366751Z","steps":["trace[279400522] 'agreement among raft nodes before linearized reading' (duration: 359.501794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:11:14.366791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:11:14.007151Z","time spent":"359.633207ms","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T06:11:14.366872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.198396ms","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-04-28T06:11:14.36693Z","caller":"traceutil/trace.go:171","msg":"trace[111313289] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1314; }","duration":"361.264488ms","start":"2026-04-28T06:11:14.005636Z","end":"2026-04-28T06:11:14.3669Z","steps":["trace[111313289] 'agreement among raft nodes before linearized reading' (duration: 361.174886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:11:14.366957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:11:14.005621Z","time spent":"361.328919ms","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T06:11:14.807567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.865479ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:11:14.808Z","caller":"traceutil/trace.go:171","msg":"trace[1117336561] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"437.181095ms","start":"2026-04-28T06:11:14.370793Z","end":"2026-04-28T06:11:14.807974Z","steps":["trace[1117336561] 'process raft request' (duration: 437.119474ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:11:14.808092Z","caller":"traceutil/trace.go:171","msg":"trace[1806669739] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"437.262107ms","start":"2026-04-28T06:11:14.370775Z","end":"2026-04-28T06:11:14.808037Z","steps":["trace[1806669739] 'process raft request' (duration: 436.985362ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:11:14.808092Z","caller":"traceutil/trace.go:171","msg":"trace[119298807] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"437.953681ms","start":"2026-04-28T06:11:14.370088Z","end":"2026-04-28T06:11:14.808041Z","steps":["trace[119298807] 'process raft request' (duration: 96.46211ms)","trace[119298807] 'compare' (duration: 340.682815ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:11:14.808092Z","caller":"traceutil/trace.go:171","msg":"trace[588808792] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"437.273927ms","start":"2026-04-28T06:11:14.370788Z","end":"2026-04-28T06:11:14.808062Z","steps":["trace[588808792] 'process raft request' (duration: 437.045653ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:11:14.808264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:11:14.370765Z","time spent":"437.404329ms","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:11:14.808264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:11:14.370778Z","time spent":"437.392719ms","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:11:14.808264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:11:14.370072Z","time spent":"438.097533ms","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:11:14.80829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:11:14.370781Z","time spent":"437.278007ms","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:11:15.175421Z","caller":"traceutil/trace.go:171","msg":"trace[1130866439] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"649.944751ms","start":"2026-04-28T06:11:14.525441Z","end":"2026-04-28T06:11:15.175386Z","steps":["trace[1130866439] 'process raft request' (duration: 649.598225ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:11:15.175588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:11:14.525408Z","time spent":"650.107635ms","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:11:15.183456Z","caller":"traceutil/trace.go:171","msg":"trace[1719575007] linearizableReadLoop","detail":"{readStateIndex:1371; appliedIndex:1369; }","duration":"311.983018ms","start":"2026-04-28T06:11:14.871439Z","end":"2026-04-28T06:11:15.183422Z","steps":["trace[1719575007] 'read index received' (duration: 303.519687ms)","trace[1719575007] 'applied index is now lower than readState.Index' (duration: 8.462281ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:11:15.183713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.270414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-28T06:11:15.183786Z","caller":"traceutil/trace.go:171","msg":"trace[1615186130] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1319; }","duration":"312.412536ms","start":"2026-04-28T06:11:14.871342Z","end":"2026-04-28T06:11:15.183755Z","steps":["trace[1615186130] 'agreement among raft nodes before linearized reading' (duration: 312.197952ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:11:15.183943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:11:14.871274Z","time spent":"312.59884ms","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-28T06:13:13.953723Z","caller":"traceutil/trace.go:171","msg":"trace[1673932355] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"107.865954ms","start":"2026-04-28T06:13:13.845825Z","end":"2026-04-28T06:13:13.953691Z","steps":["trace[1673932355] 'process raft request' (duration: 30.272941ms)","trace[1673932355] 'compare' (duration: 77.46302ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:13:14.5555Z","caller":"traceutil/trace.go:171","msg":"trace[446513083] linearizableReadLoop","detail":"{readStateIndex:1940; appliedIndex:1939; }","duration":"117.496909ms","start":"2026-04-28T06:13:14.437981Z","end":"2026-04-28T06:13:14.555478Z","steps":["trace[446513083] 'read index received' (duration: 117.343206ms)","trace[446513083] 'applied index is now lower than readState.Index' (duration: 152.713µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:13:14.555615Z","caller":"traceutil/trace.go:171","msg":"trace[1338889756] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"122.361822ms","start":"2026-04-28T06:13:14.433243Z","end":"2026-04-28T06:13:14.555605Z","steps":["trace[1338889756] 'process raft request' (duration: 122.089137ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:13:14.555774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.779725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T06:13:14.555814Z","caller":"traceutil/trace.go:171","msg":"trace[1442768519] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1865; }","duration":"117.865057ms","start":"2026-04-28T06:13:14.437938Z","end":"2026-04-28T06:13:14.555803Z","steps":["trace[1442768519] 'agreement among raft nodes before linearized reading' (duration: 117.775645ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:13:43.561806Z","caller":"traceutil/trace.go:171","msg":"trace[1425849590] transaction","detail":"{read_only:false; response_revision:1996; number_of_response:1; }","duration":"124.187632ms","start":"2026-04-28T06:13:43.43758Z","end":"2026-04-28T06:13:43.561768Z","steps":["trace[1425849590] 'process raft request' (duration: 124.002639ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:13:44.082741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.928391ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:13:44.082857Z","caller":"traceutil/trace.go:171","msg":"trace[413215963] transaction","detail":"{read_only:false; response_revision:2000; number_of_response:1; }","duration":"240.690124ms","start":"2026-04-28T06:13:43.842148Z","end":"2026-04-28T06:13:44.082838Z","steps":["trace[413215963] 'process raft request' (duration: 81.58514ms)","trace[413215963] 'compare' (duration: 158.724478ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:13:44.169475Z","caller":"traceutil/trace.go:171","msg":"trace[2029824489] linearizableReadLoop","detail":"{readStateIndex:2082; appliedIndex:2081; }","duration":"241.165551ms","start":"2026-04-28T06:13:43.928288Z","end":"2026-04-28T06:13:44.169453Z","steps":["trace[2029824489] 'read index received' (duration: 240.962408ms)","trace[2029824489] 'applied index is now lower than readState.Index' (duration: 202.423µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:13:44.169775Z","caller":"traceutil/trace.go:171","msg":"trace[161796043] transaction","detail":"{read_only:false; response_revision:2001; number_of_response:1; }","duration":"243.341355ms","start":"2026-04-28T06:13:43.926425Z","end":"2026-04-28T06:13:44.169766Z","steps":["trace[161796043] 'process raft request' (duration: 242.919759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:13:44.170077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.7548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-04-28T06:13:44.170166Z","caller":"traceutil/trace.go:171","msg":"trace[1254460966] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2001; }","duration":"127.895102ms","start":"2026-04-28T06:13:44.042244Z","end":"2026-04-28T06:13:44.170139Z","steps":["trace[1254460966] 'agreement among raft nodes before linearized reading' (duration: 127.630658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:13:44.170157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.844162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-28T06:13:44.170239Z","caller":"traceutil/trace.go:171","msg":"trace[926184482] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2001; }","duration":"241.935243ms","start":"2026-04-28T06:13:43.928281Z","end":"2026-04-28T06:13:44.170216Z","steps":["trace[926184482] 'agreement among raft nodes before linearized reading' (duration: 241.69108ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:13:44.369744Z","caller":"traceutil/trace.go:171","msg":"trace[286549027] transaction","detail":"{read_only:false; response_revision:2002; number_of_response:1; }","duration":"195.325177ms","start":"2026-04-28T06:13:44.174398Z","end":"2026-04-28T06:13:44.369723Z","steps":["trace[286549027] 'process raft request' (duration: 162.383077ms)","trace[286549027] 'compare' (duration: 32.806188ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:13:44.369943Z","caller":"traceutil/trace.go:171","msg":"trace[670946426] transaction","detail":"{read_only:false; response_revision:2003; number_of_response:1; }","duration":"194.650866ms","start":"2026-04-28T06:13:44.175247Z","end":"2026-04-28T06:13:44.369898Z","steps":["trace[670946426] 'process raft request' (duration: 194.445063ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:13:56.596345Z","caller":"traceutil/trace.go:171","msg":"trace[1599608404] transaction","detail":"{read_only:false; response_revision:2057; number_of_response:1; }","duration":"130.720617ms","start":"2026-04-28T06:13:56.465599Z","end":"2026-04-28T06:13:56.59632Z","steps":["trace[1599608404] 'process raft request' (duration: 130.549974ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:13:56.81325Z","caller":"traceutil/trace.go:171","msg":"trace[1771748569] transaction","detail":"{read_only:false; response_revision:2058; number_of_response:1; }","duration":"106.639586ms","start":"2026-04-28T06:13:56.706588Z","end":"2026-04-28T06:13:56.813228Z","steps":["trace[1771748569] 'process raft request' (duration: 106.515674ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:14:24.678856Z","caller":"traceutil/trace.go:171","msg":"trace[1769497247] transaction","detail":"{read_only:false; response_revision:2211; number_of_response:1; }","duration":"189.950603ms","start":"2026-04-28T06:14:24.488881Z","end":"2026-04-28T06:14:24.678832Z","steps":["trace[1769497247] 'process raft request' (duration: 134.08936ms)","trace[1769497247] 'compare' (duration: 55.774911ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:14:29.853432Z","caller":"traceutil/trace.go:171","msg":"trace[904952627] linearizableReadLoop","detail":"{readStateIndex:2329; appliedIndex:2328; }","duration":"170.760956ms","start":"2026-04-28T06:14:29.682649Z","end":"2026-04-28T06:14:29.85341Z","steps":["trace[904952627] 'read index received' (duration: 170.591002ms)","trace[904952627] 'applied index is now lower than readState.Index' (duration: 168.634µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:14:29.853598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:14:29.488128Z","time spent":"365.463205ms","remote":"127.0.0.1:59138","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-28T06:14:29.853696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-04-28T06:14:29.853788Z","caller":"traceutil/trace.go:171","msg":"trace[1384102069] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2237; }","duration":"171.178503ms","start":"2026-04-28T06:14:29.682587Z","end":"2026-04-28T06:14:29.853765Z","steps":["trace[1384102069] 'agreement among raft nodes before linearized reading' (duration: 170.920798ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:14:45.462776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.229178ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-28T06:14:45.462911Z","caller":"traceutil/trace.go:171","msg":"trace[173525053] linearizableReadLoop","detail":"{readStateIndex:2413; appliedIndex:2412; }","duration":"121.00156ms","start":"2026-04-28T06:14:45.341882Z","end":"2026-04-28T06:14:45.462883Z","steps":["trace[173525053] 'read index received' (duration: 33.101µs)","trace[173525053] 'applied index is now lower than readState.Index' (duration: 120.964209ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:14:45.463074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.178123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:persistent-volume-provisioner\" ","response":"range_response_count:1 size:812"} {"level":"info","ts":"2026-04-28T06:14:45.46311Z","caller":"traceutil/trace.go:171","msg":"trace[1229937090] range","detail":"{range_begin:/registry/clusterroles/system:persistent-volume-provisioner; range_end:; response_count:1; response_revision:2318; }","duration":"121.253034ms","start":"2026-04-28T06:14:45.341842Z","end":"2026-04-28T06:14:45.463095Z","steps":["trace[1229937090] 'agreement among raft nodes before linearized reading' (duration: 121.148812ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:14:50.769221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.247336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:1 size:833"} {"level":"warn","ts":"2026-04-28T06:14:50.769348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.294473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/kube-node-lease/\" range_end:\"/registry/persistentvolumeclaims/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:14:50.769396Z","caller":"traceutil/trace.go:171","msg":"trace[698054845] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:1; response_revision:2344; }","duration":"101.45852ms","start":"2026-04-28T06:14:50.667918Z","end":"2026-04-28T06:14:50.769376Z","steps":["trace[698054845] 'range keys from in-memory index tree' (duration: 101.123253ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:14:50.76946Z","caller":"traceutil/trace.go:171","msg":"trace[2049772258] range","detail":"{range_begin:/registry/persistentvolumeclaims/kube-node-lease/; range_end:/registry/persistentvolumeclaims/kube-node-lease0; response_count:0; response_revision:2344; }","duration":"104.470097ms","start":"2026-04-28T06:14:50.664969Z","end":"2026-04-28T06:14:50.769439Z","steps":["trace[2049772258] 'range keys from in-memory index tree' (duration: 104.223942ms)"],"step_count":1}