{"level":"warn","ts":"2026-07-02T00:21:23.305434Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-02T00:21:23.305522Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.116:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.116:2380","--initial-cluster=instance=https://199.204.45.116:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.116:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.116:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-02T00:21:23.305588Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-02T00:21:23.305601Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-07-02T00:21:23.305616Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-02T00:21:23.306757Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"]} {"level":"info","ts":"2026-07-02T00:21:23.306872Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.116:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-02T00:21:23.309938Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.827009ms"} {"level":"info","ts":"2026-07-02T00:21:23.315357Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5caa70ef1dd6dc04","cluster-id":"45afccab143d2c82"} {"level":"info","ts":"2026-07-02T00:21:23.315418Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=()"} {"level":"info","ts":"2026-07-02T00:21:23.31544Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 0"} {"level":"info","ts":"2026-07-02T00:21:23.315478Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5caa70ef1dd6dc04 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-02T00:21:23.315492Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 1"} {"level":"info","ts":"2026-07-02T00:21:23.315561Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"warn","ts":"2026-07-02T00:21:23.320666Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-02T00:21:23.322123Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-02T00:21:23.323192Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-02T00:21:23.325658Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5caa70ef1dd6dc04","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-02T00:21:23.325819Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5caa70ef1dd6dc04","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-02T00:21:23.325942Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-02T00:21:23.325991Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-02T00:21:23.326001Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-02T00:21:23.330867Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"info","ts":"2026-07-02T00:21:23.3311Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","added-peer-id":"5caa70ef1dd6dc04","added-peer-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-07-02T00:21:23.333165Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-02T00:21:23.333325Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-07-02T00:21:23.333338Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-07-02T00:21:23.333906Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5caa70ef1dd6dc04","initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-02T00:21:23.333939Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-02T00:21:23.616364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 is starting a new election at term 1"} {"level":"info","ts":"2026-07-02T00:21:23.616425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-02T00:21:23.61645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgPreVoteResp from 5caa70ef1dd6dc04 at term 1"} {"level":"info","ts":"2026-07-02T00:21:23.616572Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became candidate at term 2"} {"level":"info","ts":"2026-07-02T00:21:23.616605Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgVoteResp from 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-07-02T00:21:23.616642Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became leader at term 2"} {"level":"info","ts":"2026-07-02T00:21:23.616657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5caa70ef1dd6dc04 elected leader 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-07-02T00:21:23.617696Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T00:21:23.622829Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5caa70ef1dd6dc04","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.116:2379]}","request-path":"/0/members/5caa70ef1dd6dc04/attributes","cluster-id":"45afccab143d2c82","publish-timeout":"7s"} {"level":"info","ts":"2026-07-02T00:21:23.622864Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T00:21:23.623159Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T00:21:23.625196Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.116:2379"} {"level":"info","ts":"2026-07-02T00:21:23.625306Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-02T00:21:23.626998Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-02T00:21:23.627041Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-02T00:21:23.631466Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T00:21:23.631582Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T00:21:23.631605Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T00:21:27.762011Z","caller":"traceutil/trace.go:171","msg":"trace[993671903] transaction","detail":"{read_only:false; response_revision:203; number_of_response:1; }","duration":"205.893252ms","start":"2026-07-02T00:21:27.556078Z","end":"2026-07-02T00:21:27.761972Z","steps":["trace[993671903] 'process raft request' (duration: 205.736828ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:21:37.221776Z","caller":"traceutil/trace.go:171","msg":"trace[145303121] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"169.903005ms","start":"2026-07-02T00:21:37.051559Z","end":"2026-07-02T00:21:37.221462Z","steps":["trace[145303121] 'process raft request' (duration: 169.741411ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:21:37.64621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.267656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-02T00:21:37.646481Z","caller":"traceutil/trace.go:171","msg":"trace[308688818] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:360; }","duration":"189.602555ms","start":"2026-07-02T00:21:37.456858Z","end":"2026-07-02T00:21:37.64646Z","steps":["trace[308688818] 'range keys from in-memory index tree' (duration: 189.187115ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:21:37.646727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.242219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-02T00:21:37.646828Z","caller":"traceutil/trace.go:171","msg":"trace[1410360198] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:360; }","duration":"142.396733ms","start":"2026-07-02T00:21:37.504405Z","end":"2026-07-02T00:21:37.646801Z","steps":["trace[1410360198] 'range keys from in-memory index tree' (duration: 141.574774ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:21:48.560431Z","caller":"traceutil/trace.go:171","msg":"trace[717616054] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"475.627051ms","start":"2026-07-02T00:21:48.084784Z","end":"2026-07-02T00:21:48.560411Z","steps":["trace[717616054] 'process raft request' (duration: 475.468557ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:21:48.561012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:21:48.084763Z","time spent":"475.732953ms","remote":"127.0.0.1:33688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T00:21:52.819953Z","caller":"traceutil/trace.go:171","msg":"trace[673526153] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"240.781533ms","start":"2026-07-02T00:21:52.579139Z","end":"2026-07-02T00:21:52.819921Z","steps":["trace[673526153] 'process raft request' (duration: 240.607499ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:03.640136Z","caller":"traceutil/trace.go:171","msg":"trace[1193971005] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"324.29855ms","start":"2026-07-02T00:22:03.315813Z","end":"2026-07-02T00:22:03.640112Z","steps":["trace[1193971005] 'process raft request' (duration: 324.124685ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:03.640277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:03.315794Z","time spent":"324.420423ms","remote":"127.0.0.1:33450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T00:22:03.731649Z","caller":"traceutil/trace.go:171","msg":"trace[421303977] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"413.386183ms","start":"2026-07-02T00:22:03.318236Z","end":"2026-07-02T00:22:03.731622Z","steps":["trace[421303977] 'process raft request' (duration: 412.44062ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:03.731778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:03.318215Z","time spent":"413.505265ms","remote":"127.0.0.1:33450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T00:22:03.731772Z","caller":"traceutil/trace.go:171","msg":"trace[2114010131] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"413.440664ms","start":"2026-07-02T00:22:03.318291Z","end":"2026-07-02T00:22:03.731731Z","steps":["trace[2114010131] 'process raft request' (duration: 413.2575ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:03.73191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:03.318286Z","time spent":"413.556687ms","remote":"127.0.0.1:33450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T00:22:03.732043Z","caller":"traceutil/trace.go:171","msg":"trace[1939344247] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"403.4205ms","start":"2026-07-02T00:22:03.328607Z","end":"2026-07-02T00:22:03.732028Z","steps":["trace[1939344247] 'process raft request' (duration: 403.218065ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:03.731921Z","caller":"traceutil/trace.go:171","msg":"trace[1844155471] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"406.021133ms","start":"2026-07-02T00:22:03.325867Z","end":"2026-07-02T00:22:03.731888Z","steps":["trace[1844155471] 'process raft request' (duration: 405.732796ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:03.732134Z","caller":"traceutil/trace.go:171","msg":"trace[270931741] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"406.207138ms","start":"2026-07-02T00:22:03.325918Z","end":"2026-07-02T00:22:03.732126Z","steps":["trace[270931741] 'process raft request' (duration: 405.854059ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:03.732183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:03.325915Z","time spent":"406.247639ms","remote":"127.0.0.1:33450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T00:22:03.732182Z","caller":"traceutil/trace.go:171","msg":"trace[1937940531] linearizableReadLoop","detail":"{readStateIndex:519; appliedIndex:509; }","duration":"373.848728ms","start":"2026-07-02T00:22:03.358315Z","end":"2026-07-02T00:22:03.732163Z","steps":["trace[1937940531] 'read index received' (duration: 281.734041ms)","trace[1937940531] 'applied index is now lower than readState.Index' (duration: 92.113607ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:22:03.732218Z","caller":"traceutil/trace.go:171","msg":"trace[542090215] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"403.556613ms","start":"2026-07-02T00:22:03.328655Z","end":"2026-07-02T00:22:03.732211Z","steps":["trace[542090215] 'process raft request' (duration: 403.210205ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:03.73221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:03.325857Z","time spent":"406.287199ms","remote":"127.0.0.1:33450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T00:22:03.732245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:03.328652Z","time spent":"403.578934ms","remote":"127.0.0.1:33450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T00:22:03.732256Z","caller":"traceutil/trace.go:171","msg":"trace[1475121931] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"390.638828ms","start":"2026-07-02T00:22:03.341609Z","end":"2026-07-02T00:22:03.732247Z","steps":["trace[1475121931] 'process raft request' (duration: 390.387522ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:03.73229Z","caller":"traceutil/trace.go:171","msg":"trace[801609241] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"402.349194ms","start":"2026-07-02T00:22:03.329936Z","end":"2026-07-02T00:22:03.732285Z","steps":["trace[801609241] 'process raft request' (duration: 401.966065ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:03.732312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:03.329928Z","time spent":"402.374134ms","remote":"127.0.0.1:33450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T00:22:03.732099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:03.328601Z","time spent":"403.474331ms","remote":"127.0.0.1:33450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T00:22:03.732196Z","caller":"traceutil/trace.go:171","msg":"trace[20191924] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"395.448396ms","start":"2026-07-02T00:22:03.336742Z","end":"2026-07-02T00:22:03.73219Z","steps":["trace[20191924] 'process raft request' (duration: 395.20951ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:03.732381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:03.336733Z","time spent":"395.63522ms","remote":"127.0.0.1:33450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T00:22:03.732404Z","caller":"traceutil/trace.go:171","msg":"trace[987319084] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"253.228697ms","start":"2026-07-02T00:22:03.479166Z","end":"2026-07-02T00:22:03.732395Z","steps":["trace[987319084] 'process raft request' (duration: 252.94157ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:03.73233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:03.341594Z","time spent":"390.691089ms","remote":"127.0.0.1:33450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T00:22:03.732383Z","caller":"traceutil/trace.go:171","msg":"trace[1322810173] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"355.627524ms","start":"2026-07-02T00:22:03.376742Z","end":"2026-07-02T00:22:03.73237Z","steps":["trace[1322810173] 'process raft request' (duration: 355.302966ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:03.732541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:03.376722Z","time spent":"355.786168ms","remote":"127.0.0.1:33450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T00:22:03.732574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.245259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:22:03.732626Z","caller":"traceutil/trace.go:171","msg":"trace[700314070] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:503; }","duration":"374.3143ms","start":"2026-07-02T00:22:03.358296Z","end":"2026-07-02T00:22:03.732611Z","steps":["trace[700314070] 'agreement among raft nodes before linearized reading' (duration: 374.209437ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:03.732668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:03.358289Z","time spent":"374.370881ms","remote":"127.0.0.1:33414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-02T00:22:03.763801Z","caller":"traceutil/trace.go:171","msg":"trace[1883726881] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"117.211529ms","start":"2026-07-02T00:22:03.646567Z","end":"2026-07-02T00:22:03.763778Z","steps":["trace[1883726881] 'process raft request' (duration: 117.012784ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:03.763808Z","caller":"traceutil/trace.go:171","msg":"trace[98905172] transaction","detail":"{read_only:false; number_of_response:1; response_revision:505; }","duration":"116.145753ms","start":"2026-07-02T00:22:03.647642Z","end":"2026-07-02T00:22:03.763787Z","steps":["trace[98905172] 'process raft request' (duration: 116.097392ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:03.764104Z","caller":"traceutil/trace.go:171","msg":"trace[1502735218] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"116.565883ms","start":"2026-07-02T00:22:03.64753Z","end":"2026-07-02T00:22:03.764096Z","steps":["trace[1502735218] 'process raft request' (duration: 116.154293ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:03.76423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.637414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-02T00:22:03.764252Z","caller":"traceutil/trace.go:171","msg":"trace[1024493894] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:505; }","duration":"405.693876ms","start":"2026-07-02T00:22:03.358553Z","end":"2026-07-02T00:22:03.764246Z","steps":["trace[1024493894] 'agreement among raft nodes before linearized reading' (duration: 405.622754ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:03.764267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:03.358538Z","time spent":"405.725076ms","remote":"127.0.0.1:33688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-02T00:22:04.247953Z","caller":"traceutil/trace.go:171","msg":"trace[468083364] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"191.581003ms","start":"2026-07-02T00:22:04.056358Z","end":"2026-07-02T00:22:04.247939Z","steps":["trace[468083364] 'process raft request' (duration: 186.241923ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:04.248164Z","caller":"traceutil/trace.go:171","msg":"trace[1612211924] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"191.675035ms","start":"2026-07-02T00:22:04.056483Z","end":"2026-07-02T00:22:04.248158Z","steps":["trace[1612211924] 'process raft request' (duration: 191.382788ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:04.248208Z","caller":"traceutil/trace.go:171","msg":"trace[1942335274] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"120.073419ms","start":"2026-07-02T00:22:04.12811Z","end":"2026-07-02T00:22:04.248184Z","steps":["trace[1942335274] 'process raft request' (duration: 119.986327ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:05.849262Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853971548905207763,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T00:22:05.852736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.593234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:570105"} {"level":"info","ts":"2026-07-02T00:22:05.852776Z","caller":"traceutil/trace.go:171","msg":"trace[2145814721] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:569; }","duration":"697.671085ms","start":"2026-07-02T00:22:05.155091Z","end":"2026-07-02T00:22:05.852762Z","steps":["trace[2145814721] 'range keys from in-memory index tree' (duration: 697.335447ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:05.852808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:05.155071Z","time spent":"697.731037ms","remote":"127.0.0.1:33450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":93,"response count":1,"response size":570127,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io\" "} {"level":"info","ts":"2026-07-02T00:22:05.877708Z","caller":"traceutil/trace.go:171","msg":"trace[1288721980] linearizableReadLoop","detail":"{readStateIndex:591; appliedIndex:590; }","duration":"529.226107ms","start":"2026-07-02T00:22:05.348444Z","end":"2026-07-02T00:22:05.87767Z","steps":["trace[1288721980] 'read index received' (duration: 528.842317ms)","trace[1288721980] 'applied index is now lower than readState.Index' (duration: 380.48µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:22:05.877781Z","caller":"traceutil/trace.go:171","msg":"trace[543417828] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"719.076857ms","start":"2026-07-02T00:22:05.158667Z","end":"2026-07-02T00:22:05.877744Z","steps":["trace[543417828] 'process raft request' (duration: 718.745209ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:05.877825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.363781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:22:05.877906Z","caller":"traceutil/trace.go:171","msg":"trace[1430961517] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:570; }","duration":"529.475943ms","start":"2026-07-02T00:22:05.348409Z","end":"2026-07-02T00:22:05.877885Z","steps":["trace[1430961517] 'agreement among raft nodes before linearized reading' (duration: 529.34783ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:05.877946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:05.348393Z","time spent":"529.544835ms","remote":"127.0.0.1:33414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T00:22:05.877955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:05.15864Z","time spent":"719.229231ms","remote":"127.0.0.1:33450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208470,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T00:22:06.439035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:06.096629Z","time spent":"342.401331ms","remote":"127.0.0.1:55420","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-02T00:22:06.439043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.403599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/securitypolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:208485"} {"level":"info","ts":"2026-07-02T00:22:06.439151Z","caller":"traceutil/trace.go:171","msg":"trace[1933894839] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/securitypolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:573; }","duration":"476.559833ms","start":"2026-07-02T00:22:05.962565Z","end":"2026-07-02T00:22:06.439124Z","steps":["trace[1933894839] 'range keys from in-memory index tree' (duration: 476.105813ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:06.439209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:05.962546Z","time spent":"476.646076ms","remote":"127.0.0.1:33450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":208507,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/securitypolicies.gateway.envoyproxy.io\" "} {"level":"info","ts":"2026-07-02T00:22:06.440871Z","caller":"traceutil/trace.go:171","msg":"trace[405506277] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"261.857217ms","start":"2026-07-02T00:22:06.178999Z","end":"2026-07-02T00:22:06.440856Z","steps":["trace[405506277] 'process raft request' (duration: 261.614141ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:10.497694Z","caller":"traceutil/trace.go:171","msg":"trace[963307140] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:618; }","duration":"541.9996ms","start":"2026-07-02T00:22:09.955669Z","end":"2026-07-02T00:22:10.497668Z","steps":["trace[963307140] 'read index received' (duration: 4.340946ms)","trace[963307140] 'applied index is now lower than readState.Index' (duration: 537.657174ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:22:10.497818Z","caller":"traceutil/trace.go:171","msg":"trace[1267996338] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"562.143591ms","start":"2026-07-02T00:22:09.935662Z","end":"2026-07-02T00:22:10.497805Z","steps":["trace[1267996338] 'process raft request' (duration: 501.349198ms)","trace[1267996338] 'compare' (duration: 59.84775ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:22:10.497928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:09.935643Z","time spent":"562.208382ms","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T00:22:10.498134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.44341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-02T00:22:10.498255Z","caller":"traceutil/trace.go:171","msg":"trace[1788922197] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:598; }","duration":"542.609094ms","start":"2026-07-02T00:22:09.955625Z","end":"2026-07-02T00:22:10.498234Z","steps":["trace[1788922197] 'agreement among raft nodes before linearized reading' (duration: 542.368889ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:10.4983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:09.955609Z","time spent":"542.682116ms","remote":"127.0.0.1:33688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-02T00:22:10.50609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.237828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-02T00:22:10.506121Z","caller":"traceutil/trace.go:171","msg":"trace[1629008887] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"181.854975ms","start":"2026-07-02T00:22:10.324243Z","end":"2026-07-02T00:22:10.506098Z","steps":["trace[1629008887] 'process raft request' (duration: 181.278791ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:10.506156Z","caller":"traceutil/trace.go:171","msg":"trace[674043479] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:599; }","duration":"545.336421ms","start":"2026-07-02T00:22:09.960808Z","end":"2026-07-02T00:22:10.506144Z","steps":["trace[674043479] 'agreement among raft nodes before linearized reading' (duration: 545.178937ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:10.50621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:09.960797Z","time spent":"545.403633ms","remote":"127.0.0.1:33688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-02T00:22:11.606712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.532741ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-02T00:22:11.606796Z","caller":"traceutil/trace.go:171","msg":"trace[1608146181] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:623; }","duration":"486.199609ms","start":"2026-07-02T00:22:11.120583Z","end":"2026-07-02T00:22:11.606783Z","steps":["trace[1608146181] 'read index received' (duration: 80.407741ms)","trace[1608146181] 'applied index is now lower than readState.Index' (duration: 405.790938ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:22:11.606867Z","caller":"traceutil/trace.go:171","msg":"trace[1502703672] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"1.042789624s","start":"2026-07-02T00:22:10.564069Z","end":"2026-07-02T00:22:11.606858Z","steps":["trace[1502703672] 'process raft request' (duration: 636.939536ms)","trace[1502703672] 'compare' (duration: 405.135751ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:22:11.606903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:10.564048Z","time spent":"1.042836805s","remote":"127.0.0.1:33776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T00:22:11.607115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.527277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-02T00:22:11.607136Z","caller":"traceutil/trace.go:171","msg":"trace[263206395] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:602; }","duration":"486.584428ms","start":"2026-07-02T00:22:11.120545Z","end":"2026-07-02T00:22:11.607129Z","steps":["trace[263206395] 'agreement among raft nodes before linearized reading' (duration: 486.478616ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:11.607154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:11.120524Z","time spent":"486.625999ms","remote":"127.0.0.1:33688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-02T00:22:11.607143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.880484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:22:11.607238Z","caller":"traceutil/trace.go:171","msg":"trace[1014639296] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:602; }","duration":"259.011577ms","start":"2026-07-02T00:22:11.348198Z","end":"2026-07-02T00:22:11.60721Z","steps":["trace[1014639296] 'agreement among raft nodes before linearized reading' (duration: 258.827813ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:12.919452Z","caller":"traceutil/trace.go:171","msg":"trace[1040212254] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"173.591164ms","start":"2026-07-02T00:22:12.74584Z","end":"2026-07-02T00:22:12.919431Z","steps":["trace[1040212254] 'process raft request' (duration: 173.474951ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:12.919595Z","caller":"traceutil/trace.go:171","msg":"trace[1328327062] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"174.397224ms","start":"2026-07-02T00:22:12.745167Z","end":"2026-07-02T00:22:12.919564Z","steps":["trace[1328327062] 'process raft request' (duration: 134.794368ms)","trace[1328327062] 'compare' (duration: 39.101524ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:22:13.589619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.697055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-02T00:22:13.589708Z","caller":"traceutil/trace.go:171","msg":"trace[294073338] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:625; }","duration":"307.834388ms","start":"2026-07-02T00:22:13.281853Z","end":"2026-07-02T00:22:13.589688Z","steps":["trace[294073338] 'range keys from in-memory index tree' (duration: 307.602372ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:13.589749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:13.281836Z","time spent":"307.903819ms","remote":"127.0.0.1:33518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-07-02T00:22:13.589709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.450549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:22:13.589865Z","caller":"traceutil/trace.go:171","msg":"trace[1416890704] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:625; }","duration":"241.595733ms","start":"2026-07-02T00:22:13.348205Z","end":"2026-07-02T00:22:13.589801Z","steps":["trace[1416890704] 'range keys from in-memory index tree' (duration: 241.376007ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:13.879614Z","caller":"traceutil/trace.go:171","msg":"trace[536241915] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"226.280649ms","start":"2026-07-02T00:22:13.6533Z","end":"2026-07-02T00:22:13.87958Z","steps":["trace[536241915] 'process raft request' (duration: 181.579338ms)","trace[536241915] 'compare' (duration: 44.505246ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:22:13.879787Z","caller":"traceutil/trace.go:171","msg":"trace[1425945203] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"226.363821ms","start":"2026-07-02T00:22:13.653394Z","end":"2026-07-02T00:22:13.879757Z","steps":["trace[1425945203] 'process raft request' (duration: 226.134676ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:14.091023Z","caller":"traceutil/trace.go:171","msg":"trace[41715773] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:652; }","duration":"180.528843ms","start":"2026-07-02T00:22:13.910474Z","end":"2026-07-02T00:22:14.091003Z","steps":["trace[41715773] 'read index received' (duration: 180.513643ms)","trace[41715773] 'applied index is now lower than readState.Index' (duration: 13.99µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:22:14.091058Z","caller":"traceutil/trace.go:171","msg":"trace[992705670] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"195.298354ms","start":"2026-07-02T00:22:13.895723Z","end":"2026-07-02T00:22:14.091022Z","steps":["trace[992705670] 'process raft request' (duration: 195.178321ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:14.09117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.374565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:22:14.091215Z","caller":"traceutil/trace.go:171","msg":"trace[1330255587] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:630; }","duration":"195.429576ms","start":"2026-07-02T00:22:13.895771Z","end":"2026-07-02T00:22:14.091201Z","steps":["trace[1330255587] 'agreement among raft nodes before linearized reading' (duration: 195.333064ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:14.191026Z","caller":"traceutil/trace.go:171","msg":"trace[1518623378] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"239.160533ms","start":"2026-07-02T00:22:13.951833Z","end":"2026-07-02T00:22:14.190994Z","steps":["trace[1518623378] 'process raft request' (duration: 238.930838ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:14.191072Z","caller":"traceutil/trace.go:171","msg":"trace[1725808608] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"102.294636ms","start":"2026-07-02T00:22:14.088749Z","end":"2026-07-02T00:22:14.191044Z","steps":["trace[1725808608] 'process raft request' (duration: 102.110511ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:14.191041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.163392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-pw8r4\" ","response":"range_response_count:1 size:17524"} {"level":"info","ts":"2026-07-02T00:22:14.191175Z","caller":"traceutil/trace.go:171","msg":"trace[376991727] range","detail":"{range_begin:/registry/pods/kube-system/cilium-pw8r4; range_end:; response_count:1; response_revision:632; }","duration":"241.345196ms","start":"2026-07-02T00:22:13.94982Z","end":"2026-07-02T00:22:14.191165Z","steps":["trace[376991727] 'agreement among raft nodes before linearized reading' (duration: 241.08192ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:14.286841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.297807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-02T00:22:14.286915Z","caller":"traceutil/trace.go:171","msg":"trace[1507567984] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:632; }","duration":"264.41593ms","start":"2026-07-02T00:22:14.02248Z","end":"2026-07-02T00:22:14.286896Z","steps":["trace[1507567984] 'agreement among raft nodes before linearized reading' (duration: 168.594572ms)","trace[1507567984] 'range keys from in-memory index tree' (duration: 95.631923ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:22:14.291728Z","caller":"traceutil/trace.go:171","msg":"trace[797760583] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"197.509948ms","start":"2026-07-02T00:22:14.094193Z","end":"2026-07-02T00:22:14.291703Z","steps":["trace[797760583] 'process raft request' (duration: 197.441736ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:14.291755Z","caller":"traceutil/trace.go:171","msg":"trace[1359221092] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"197.828375ms","start":"2026-07-02T00:22:14.093908Z","end":"2026-07-02T00:22:14.291737Z","steps":["trace[1359221092] 'process raft request' (duration: 197.62083ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:14.37403Z","caller":"traceutil/trace.go:171","msg":"trace[1152608166] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"169.017922ms","start":"2026-07-02T00:22:14.204954Z","end":"2026-07-02T00:22:14.373971Z","steps":["trace[1152608166] 'process raft request' (duration: 168.823007ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:14.374202Z","caller":"traceutil/trace.go:171","msg":"trace[1048547866] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"179.124749ms","start":"2026-07-02T00:22:14.195034Z","end":"2026-07-02T00:22:14.374159Z","steps":["trace[1048547866] 'process raft request' (duration: 178.662718ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:14.398908Z","caller":"traceutil/trace.go:171","msg":"trace[1928919965] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"102.589872ms","start":"2026-07-02T00:22:14.296293Z","end":"2026-07-02T00:22:14.398883Z","steps":["trace[1928919965] 'process raft request' (duration: 102.261504ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:14.398682Z","caller":"traceutil/trace.go:171","msg":"trace[2073061105] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"102.412218ms","start":"2026-07-02T00:22:14.29622Z","end":"2026-07-02T00:22:14.398632Z","steps":["trace[2073061105] 'process raft request' (duration: 94.538806ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:14.568974Z","caller":"traceutil/trace.go:171","msg":"trace[770613849] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"100.818679ms","start":"2026-07-02T00:22:14.468122Z","end":"2026-07-02T00:22:14.56894Z","steps":["trace[770613849] 'process raft request' (duration: 68.9009ms)","trace[770613849] 'compare' (duration: 31.719144ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:22:14.851309Z","caller":"traceutil/trace.go:171","msg":"trace[110069685] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:669; }","duration":"323.566302ms","start":"2026-07-02T00:22:14.527725Z","end":"2026-07-02T00:22:14.851291Z","steps":["trace[110069685] 'read index received' (duration: 9.257616ms)","trace[110069685] 'applied index is now lower than readState.Index' (duration: 314.308096ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:22:14.851348Z","caller":"traceutil/trace.go:171","msg":"trace[1320307231] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"362.409889ms","start":"2026-07-02T00:22:14.488913Z","end":"2026-07-02T00:22:14.851323Z","steps":["trace[1320307231] 'process raft request' (duration: 318.087878ms)","trace[1320307231] 'compare' (duration: 44.103886ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:22:14.851452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:14.488898Z","time spent":"362.497031ms","remote":"127.0.0.1:33450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T00:22:14.851517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.729246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-02T00:22:14.851809Z","caller":"traceutil/trace.go:171","msg":"trace[359420159] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:648; }","duration":"323.88716ms","start":"2026-07-02T00:22:14.527673Z","end":"2026-07-02T00:22:14.85156Z","steps":["trace[359420159] 'agreement among raft nodes before linearized reading' (duration: 323.674305ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:14.851846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:14.527651Z","time spent":"324.186637ms","remote":"127.0.0.1:33688","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-07-02T00:22:14.853798Z","caller":"traceutil/trace.go:171","msg":"trace[2121490530] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"245.926228ms","start":"2026-07-02T00:22:14.607845Z","end":"2026-07-02T00:22:14.853772Z","steps":["trace[2121490530] 'process raft request' (duration: 245.797555ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:14.853866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.018929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-02T00:22:14.853897Z","caller":"traceutil/trace.go:171","msg":"trace[1367055638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:650; }","duration":"323.092691ms","start":"2026-07-02T00:22:14.530795Z","end":"2026-07-02T00:22:14.853888Z","steps":["trace[1367055638] 'agreement among raft nodes before linearized reading' (duration: 322.960188ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:14.853908Z","caller":"traceutil/trace.go:171","msg":"trace[1070425593] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"164.269657ms","start":"2026-07-02T00:22:14.689626Z","end":"2026-07-02T00:22:14.853896Z","steps":["trace[1070425593] 'process raft request' (duration: 164.100603ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:14.853917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:14.530745Z","time spent":"323.166532ms","remote":"127.0.0.1:33688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-02T00:22:15.021316Z","caller":"traceutil/trace.go:171","msg":"trace[1310981897] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"131.99083ms","start":"2026-07-02T00:22:14.889301Z","end":"2026-07-02T00:22:15.021292Z","steps":["trace[1310981897] 'process raft request' (duration: 131.787395ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:16.000132Z","caller":"traceutil/trace.go:171","msg":"trace[1542004337] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"108.552937ms","start":"2026-07-02T00:22:15.891553Z","end":"2026-07-02T00:22:16.000106Z","steps":["trace[1542004337] 'process raft request' (duration: 107.797379ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:26.348696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.665714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-wp524\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-07-02T00:22:26.349178Z","caller":"traceutil/trace.go:171","msg":"trace[535643249] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-wp524; range_end:; response_count:1; response_revision:760; }","duration":"324.196237ms","start":"2026-07-02T00:22:26.024954Z","end":"2026-07-02T00:22:26.34915Z","steps":["trace[535643249] 'range keys from in-memory index tree' (duration: 323.46192ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:26.348729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.681231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-02T00:22:26.349349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:26.024931Z","time spent":"324.356272ms","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-wp524\" "} {"level":"info","ts":"2026-07-02T00:22:26.349506Z","caller":"traceutil/trace.go:171","msg":"trace[53587935] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:760; }","duration":"348.433149ms","start":"2026-07-02T00:22:26.000995Z","end":"2026-07-02T00:22:26.349428Z","steps":["trace[53587935] 'range keys from in-memory index tree' (duration: 347.452734ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:26.349757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:26.000966Z","time spent":"348.763716ms","remote":"127.0.0.1:33688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-02T00:22:26.348891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.967108ms","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-07-02T00:22:26.349882Z","caller":"traceutil/trace.go:171","msg":"trace[1718104220] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:760; }","duration":"305.972683ms","start":"2026-07-02T00:22:26.043872Z","end":"2026-07-02T00:22:26.349844Z","steps":["trace[1718104220] 'range keys from in-memory index tree' (duration: 304.787924ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:26.349988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:26.043853Z","time spent":"306.103966ms","remote":"127.0.0.1:33688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-02T00:22:26.495951Z","caller":"traceutil/trace.go:171","msg":"trace[1285924726] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"141.83646ms","start":"2026-07-02T00:22:26.354086Z","end":"2026-07-02T00:22:26.495922Z","steps":["trace[1285924726] 'process raft request' (duration: 141.656405ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:26.527542Z","caller":"traceutil/trace.go:171","msg":"trace[1476951400] linearizableReadLoop","detail":"{readStateIndex:789; appliedIndex:786; }","duration":"110.648748ms","start":"2026-07-02T00:22:26.416874Z","end":"2026-07-02T00:22:26.527523Z","steps":["trace[1476951400] 'read index received' (duration: 78.875023ms)","trace[1476951400] 'applied index is now lower than readState.Index' (duration: 31.772765ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:22:26.527762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.852173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-02T00:22:26.527816Z","caller":"traceutil/trace.go:171","msg":"trace[944273515] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:763; }","duration":"110.948076ms","start":"2026-07-02T00:22:26.416854Z","end":"2026-07-02T00:22:26.527802Z","steps":["trace[944273515] 'agreement among raft nodes before linearized reading' (duration: 110.751931ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:26.527812Z","caller":"traceutil/trace.go:171","msg":"trace[853046812] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"172.63343ms","start":"2026-07-02T00:22:26.355129Z","end":"2026-07-02T00:22:26.527763Z","steps":["trace[853046812] 'process raft request' (duration: 172.279862ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:26.52809Z","caller":"traceutil/trace.go:171","msg":"trace[1487093550] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"171.588365ms","start":"2026-07-02T00:22:26.356488Z","end":"2026-07-02T00:22:26.528076Z","steps":["trace[1487093550] 'process raft request' (duration: 170.99148ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:33.287219Z","caller":"traceutil/trace.go:171","msg":"trace[1758383023] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"143.513862ms","start":"2026-07-02T00:22:33.143678Z","end":"2026-07-02T00:22:33.287192Z","steps":["trace[1758383023] 'process raft request' (duration: 139.813332ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:55.485716Z","caller":"traceutil/trace.go:171","msg":"trace[1827903970] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"103.229822ms","start":"2026-07-02T00:22:55.382459Z","end":"2026-07-02T00:22:55.485689Z","steps":["trace[1827903970] 'process raft request' (duration: 103.088099ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:23:00.744965Z","caller":"traceutil/trace.go:171","msg":"trace[263375926] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"101.368616ms","start":"2026-07-02T00:23:00.643563Z","end":"2026-07-02T00:23:00.744932Z","steps":["trace[263375926] 'process raft request' (duration: 101.091719ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:23:00.951371Z","caller":"traceutil/trace.go:171","msg":"trace[219130208] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"202.415255ms","start":"2026-07-02T00:23:00.748923Z","end":"2026-07-02T00:23:00.951338Z","steps":["trace[219130208] 'process raft request' (duration: 201.79637ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:23:01.416734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.850193ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T00:23:01.41688Z","caller":"traceutil/trace.go:171","msg":"trace[1892559856] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"667.653624ms","start":"2026-07-02T00:23:00.749196Z","end":"2026-07-02T00:23:01.416849Z","steps":["trace[1892559856] 'process raft request' (duration: 439.605506ms)","trace[1892559856] 'compare' (duration: 227.552236ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:23:01.417031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:23:00.749178Z","time spent":"667.774478ms","remote":"127.0.0.1:33688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T00:23:01.417389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.047163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-02T00:23:01.416853Z","caller":"traceutil/trace.go:171","msg":"trace[1340456190] linearizableReadLoop","detail":"{readStateIndex:1010; appliedIndex:1009; }","duration":"184.500379ms","start":"2026-07-02T00:23:01.232325Z","end":"2026-07-02T00:23:01.416825Z","steps":["trace[1340456190] 'read index received' (duration: 58.041µs)","trace[1340456190] 'applied index is now lower than readState.Index' (duration: 184.441338ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:23:01.417474Z","caller":"traceutil/trace.go:171","msg":"trace[1076911092] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:976; }","duration":"185.178776ms","start":"2026-07-02T00:23:01.232274Z","end":"2026-07-02T00:23:01.417453Z","steps":["trace[1076911092] 'agreement among raft nodes before linearized reading' (duration: 184.889529ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:23:01.566147Z","caller":"traceutil/trace.go:171","msg":"trace[938650423] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"144.455564ms","start":"2026-07-02T00:23:01.421541Z","end":"2026-07-02T00:23:01.565996Z","steps":["trace[938650423] 'process raft request' (duration: 126.435036ms)","trace[938650423] 'compare' (duration: 17.585727ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:23:29.274316Z","caller":"traceutil/trace.go:171","msg":"trace[671013790] linearizableReadLoop","detail":"{readStateIndex:1106; appliedIndex:1105; }","duration":"111.79195ms","start":"2026-07-02T00:23:29.162506Z","end":"2026-07-02T00:23:29.274298Z","steps":["trace[671013790] 'read index received' (duration: 111.542383ms)","trace[671013790] 'applied index is now lower than readState.Index' (duration: 248.827µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:23:29.2745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.984314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-02T00:23:29.274533Z","caller":"traceutil/trace.go:171","msg":"trace[1911067904] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1066; }","duration":"112.078506ms","start":"2026-07-02T00:23:29.162447Z","end":"2026-07-02T00:23:29.274526Z","steps":["trace[1911067904] 'agreement among raft nodes before linearized reading' (duration: 111.957123ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:23:29.274697Z","caller":"traceutil/trace.go:171","msg":"trace[232260361] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"341.568531ms","start":"2026-07-02T00:23:28.933122Z","end":"2026-07-02T00:23:29.274691Z","steps":["trace[232260361] 'process raft request' (duration: 341.034248ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:23:29.274776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:23:28.933106Z","time spent":"341.623712ms","remote":"127.0.0.1:33688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T00:23:29.907118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.602578ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T00:23:29.90733Z","caller":"traceutil/trace.go:171","msg":"trace[2120347820] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"200.262493ms","start":"2026-07-02T00:23:29.707049Z","end":"2026-07-02T00:23:29.907312Z","steps":["trace[2120347820] 'process raft request' (duration: 200.181891ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:23:29.907693Z","caller":"traceutil/trace.go:171","msg":"trace[801635543] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"200.453287ms","start":"2026-07-02T00:23:29.707034Z","end":"2026-07-02T00:23:29.907487Z","steps":["trace[801635543] 'process raft request' (duration: 32.399638ms)","trace[801635543] 'compare' (duration: 166.896031ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:23:30.494924Z","caller":"traceutil/trace.go:171","msg":"trace[249474044] linearizableReadLoop","detail":"{readStateIndex:1111; appliedIndex:1110; }","duration":"206.965286ms","start":"2026-07-02T00:23:30.287935Z","end":"2026-07-02T00:23:30.4949Z","steps":["trace[249474044] 'read index received' (duration: 206.829633ms)","trace[249474044] 'applied index is now lower than readState.Index' (duration: 134.613µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:23:30.494999Z","caller":"traceutil/trace.go:171","msg":"trace[104145453] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"503.360478ms","start":"2026-07-02T00:23:29.991606Z","end":"2026-07-02T00:23:30.494967Z","steps":["trace[104145453] 'process raft request' (duration: 503.154793ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:23:30.495103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.152451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:23:30.495167Z","caller":"traceutil/trace.go:171","msg":"trace[2017137157] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1071; }","duration":"207.269314ms","start":"2026-07-02T00:23:30.287884Z","end":"2026-07-02T00:23:30.495153Z","steps":["trace[2017137157] 'agreement among raft nodes before linearized reading' (duration: 207.150261ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:23:30.495166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:23:29.99158Z","time spent":"503.499462ms","remote":"127.0.0.1:33688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T00:23:30.495097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.400293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:23:30.495276Z","caller":"traceutil/trace.go:171","msg":"trace[829442047] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1071; }","duration":"146.575487ms","start":"2026-07-02T00:23:30.34867Z","end":"2026-07-02T00:23:30.495246Z","steps":["trace[829442047] 'agreement among raft nodes before linearized reading' (duration: 146.376622ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:23:31.498486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.346857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-02T00:23:31.49853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.110806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-02T00:23:31.49858Z","caller":"traceutil/trace.go:171","msg":"trace[431338259] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1072; }","duration":"217.509641ms","start":"2026-07-02T00:23:31.281048Z","end":"2026-07-02T00:23:31.498558Z","steps":["trace[431338259] 'range keys from in-memory index tree' (duration: 217.08978ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:23:31.498602Z","caller":"traceutil/trace.go:171","msg":"trace[1889792501] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1072; }","duration":"202.229279ms","start":"2026-07-02T00:23:31.296354Z","end":"2026-07-02T00:23:31.498584Z","steps":["trace[1889792501] 'range keys from in-memory index tree' (duration: 201.955042ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:23:31.623671Z","caller":"traceutil/trace.go:171","msg":"trace[1598897753] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"120.880881ms","start":"2026-07-02T00:23:31.502769Z","end":"2026-07-02T00:23:31.62365Z","steps":["trace[1598897753] 'process raft request' (duration: 120.666396ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:23:31.62367Z","caller":"traceutil/trace.go:171","msg":"trace[1972027296] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"120.84779ms","start":"2026-07-02T00:23:31.502799Z","end":"2026-07-02T00:23:31.623647Z","steps":["trace[1972027296] 'process raft request' (duration: 120.802529ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:23:31.952674Z","caller":"traceutil/trace.go:171","msg":"trace[98579307] linearizableReadLoop","detail":"{readStateIndex:1115; appliedIndex:1114; }","duration":"242.808408ms","start":"2026-07-02T00:23:31.70984Z","end":"2026-07-02T00:23:31.952648Z","steps":["trace[98579307] 'read index received' (duration: 152.46397ms)","trace[98579307] 'applied index is now lower than readState.Index' (duration: 90.343528ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:23:31.953139Z","caller":"traceutil/trace.go:171","msg":"trace[677663719] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"306.782424ms","start":"2026-07-02T00:23:31.646335Z","end":"2026-07-02T00:23:31.953118Z","steps":["trace[677663719] 'process raft request' (duration: 215.966255ms)","trace[677663719] 'compare' (duration: 90.228735ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:23:31.953267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:23:31.64631Z","time spent":"306.870656ms","remote":"127.0.0.1:33688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T00:23:31.953321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.446773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:23:31.953396Z","caller":"traceutil/trace.go:171","msg":"trace[802227033] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1075; }","duration":"243.565827ms","start":"2026-07-02T00:23:31.709809Z","end":"2026-07-02T00:23:31.953375Z","steps":["trace[802227033] 'agreement among raft nodes before linearized reading' (duration: 243.429143ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:23:32.331752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.414447ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T00:23:32.332153Z","caller":"traceutil/trace.go:171","msg":"trace[887652736] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"374.23849ms","start":"2026-07-02T00:23:31.957884Z","end":"2026-07-02T00:23:32.332123Z","steps":["trace[887652736] 'process raft request' (duration: 104.362812ms)","trace[887652736] 'compare' (duration: 269.265433ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:23:32.332373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:23:31.957868Z","time spent":"374.353153ms","remote":"127.0.0.1:33688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T00:23:32.522503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.646639ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-02T00:23:32.522626Z","caller":"traceutil/trace.go:171","msg":"trace[745572573] linearizableReadLoop","detail":"{readStateIndex:1118; appliedIndex:1117; }","duration":"176.883083ms","start":"2026-07-02T00:23:32.345718Z","end":"2026-07-02T00:23:32.522601Z","steps":["trace[745572573] 'read index received' (duration: 41.431µs)","trace[745572573] 'applied index is now lower than readState.Index' (duration: 176.839711ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:23:32.522842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.102048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:23:32.522916Z","caller":"traceutil/trace.go:171","msg":"trace[1809588849] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1077; }","duration":"177.225441ms","start":"2026-07-02T00:23:32.345673Z","end":"2026-07-02T00:23:32.522898Z","steps":["trace[1809588849] 'agreement among raft nodes before linearized reading' (duration: 177.073117ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:23:50.703386Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853971548905209156,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-02T00:23:51.012419Z","caller":"traceutil/trace.go:171","msg":"trace[647001796] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"924.35994ms","start":"2026-07-02T00:23:50.08803Z","end":"2026-07-02T00:23:51.01239Z","steps":["trace[647001796] 'process raft request' (duration: 924.218976ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:23:51.012572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:23:50.087993Z","time spent":"924.502743ms","remote":"127.0.0.1:33688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T00:23:51.012586Z","caller":"traceutil/trace.go:171","msg":"trace[1048246485] linearizableReadLoop","detail":"{readStateIndex:1231; appliedIndex:1231; }","duration":"810.067652ms","start":"2026-07-02T00:23:50.202462Z","end":"2026-07-02T00:23:51.01253Z","steps":["trace[1048246485] 'read index received' (duration: 810.057062ms)","trace[1048246485] 'applied index is now lower than readState.Index' (duration: 8.45µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:23:51.012779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.295538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-02T00:23:51.012851Z","caller":"traceutil/trace.go:171","msg":"trace[2006020596] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1185; }","duration":"810.409611ms","start":"2026-07-02T00:23:50.202424Z","end":"2026-07-02T00:23:51.012834Z","steps":["trace[2006020596] 'agreement among raft nodes before linearized reading' (duration: 810.214676ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:23:51.012881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:23:50.202406Z","time spent":"810.469852ms","remote":"127.0.0.1:33688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-02T00:23:51.016891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.473319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:23:51.017174Z","caller":"traceutil/trace.go:171","msg":"trace[1799963872] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"270.035701ms","start":"2026-07-02T00:23:50.747112Z","end":"2026-07-02T00:23:51.017148Z","steps":["trace[1799963872] 'process raft request' (duration: 269.58941ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:23:51.017212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.583357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T00:23:51.017254Z","caller":"traceutil/trace.go:171","msg":"trace[1255344426] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1186; }","duration":"293.66161ms","start":"2026-07-02T00:23:50.72358Z","end":"2026-07-02T00:23:51.017242Z","steps":["trace[1255344426] 'agreement among raft nodes before linearized reading' (duration: 293.584888ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:23:51.017516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.322957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-02T00:23:51.017575Z","caller":"traceutil/trace.go:171","msg":"trace[606128051] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1186; }","duration":"524.417128ms","start":"2026-07-02T00:23:50.493143Z","end":"2026-07-02T00:23:51.01756Z","steps":["trace[606128051] 'agreement among raft nodes before linearized reading' (duration: 524.137932ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:23:51.016958Z","caller":"traceutil/trace.go:171","msg":"trace[626555789] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1186; }","duration":"669.583342ms","start":"2026-07-02T00:23:50.347359Z","end":"2026-07-02T00:23:51.016943Z","steps":["trace[626555789] 'agreement among raft nodes before linearized reading' (duration: 669.452239ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:23:51.017617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:23:50.493112Z","time spent":"524.496871ms","remote":"127.0.0.1:33688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-02T00:23:51.017653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:23:50.347344Z","time spent":"670.29453ms","remote":"127.0.0.1:33414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-02T00:24:05.297073Z","caller":"traceutil/trace.go:171","msg":"trace[1810340265] linearizableReadLoop","detail":"{readStateIndex:1288; appliedIndex:1287; }","duration":"209.612371ms","start":"2026-07-02T00:24:05.087443Z","end":"2026-07-02T00:24:05.297055Z","steps":["trace[1810340265] 'read index received' (duration: 209.459658ms)","trace[1810340265] 'applied index is now lower than readState.Index' (duration: 151.983µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:24:05.297342Z","caller":"traceutil/trace.go:171","msg":"trace[524081671] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"223.291317ms","start":"2026-07-02T00:24:05.074037Z","end":"2026-07-02T00:24:05.297329Z","steps":["trace[524081671] 'process raft request' (duration: 222.916068ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:05.297562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.104003ms","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-07-02T00:24:05.297589Z","caller":"traceutil/trace.go:171","msg":"trace[1320789256] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1239; }","duration":"210.163875ms","start":"2026-07-02T00:24:05.087419Z","end":"2026-07-02T00:24:05.297583Z","steps":["trace[1320789256] 'agreement among raft nodes before linearized reading' (duration: 210.044802ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:05.297801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.662513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-02T00:24:05.297863Z","caller":"traceutil/trace.go:171","msg":"trace[1251616816] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1239; }","duration":"209.774926ms","start":"2026-07-02T00:24:05.088073Z","end":"2026-07-02T00:24:05.297848Z","steps":["trace[1251616816] 'agreement among raft nodes before linearized reading' (duration: 209.583851ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:05.297901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.959267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-02T00:24:05.297924Z","caller":"traceutil/trace.go:171","msg":"trace[695332416] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1239; }","duration":"206.994237ms","start":"2026-07-02T00:24:05.090924Z","end":"2026-07-02T00:24:05.297918Z","steps":["trace[695332416] 'agreement among raft nodes before linearized reading' (duration: 206.931936ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:24:06.572855Z","caller":"traceutil/trace.go:171","msg":"trace[710040158] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"156.551502ms","start":"2026-07-02T00:24:06.416269Z","end":"2026-07-02T00:24:06.572821Z","steps":["trace[710040158] 'process raft request' (duration: 104.797075ms)","trace[710040158] 'compare' (duration: 51.641665ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:24:06.600768Z","caller":"traceutil/trace.go:171","msg":"trace[52113491] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"181.025712ms","start":"2026-07-02T00:24:06.419712Z","end":"2026-07-02T00:24:06.600738Z","steps":["trace[52113491] 'process raft request' (duration: 180.990871ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:24:06.600809Z","caller":"traceutil/trace.go:171","msg":"trace[2064517178] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"184.1852ms","start":"2026-07-02T00:24:06.416586Z","end":"2026-07-02T00:24:06.600771Z","steps":["trace[2064517178] 'process raft request' (duration: 184.004355ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:07.24951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.911526ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T00:24:07.249634Z","caller":"traceutil/trace.go:171","msg":"trace[2111553084] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"609.789987ms","start":"2026-07-02T00:24:06.639825Z","end":"2026-07-02T00:24:07.249615Z","steps":["trace[2111553084] 'process raft request' (duration: 415.676266ms)","trace[2111553084] 'compare' (duration: 193.732492ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:24:07.249706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:06.639812Z","time spent":"609.85342ms","remote":"127.0.0.1:33440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T00:24:07.53028Z","caller":"traceutil/trace.go:171","msg":"trace[291745125] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"228.943085ms","start":"2026-07-02T00:24:07.301219Z","end":"2026-07-02T00:24:07.530162Z","steps":["trace[291745125] 'process raft request' (duration: 208.838962ms)","trace[291745125] 'compare' (duration: 19.877937ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:24:07.53036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.720168ms","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-07-02T00:24:07.530431Z","caller":"traceutil/trace.go:171","msg":"trace[925895623] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1258; }","duration":"212.833181ms","start":"2026-07-02T00:24:07.317579Z","end":"2026-07-02T00:24:07.530412Z","steps":["trace[925895623] 'agreement among raft nodes before linearized reading' (duration: 212.622266ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:24:07.530866Z","caller":"traceutil/trace.go:171","msg":"trace[284012311] linearizableReadLoop","detail":"{readStateIndex:1308; appliedIndex:1307; }","duration":"212.467551ms","start":"2026-07-02T00:24:07.317647Z","end":"2026-07-02T00:24:07.530114Z","steps":["trace[284012311] 'read index received' (duration: 192.292447ms)","trace[284012311] 'applied index is now lower than readState.Index' (duration: 20.174194ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:24:07.531256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.652989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-02T00:24:07.531279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.763322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-02T00:24:07.531309Z","caller":"traceutil/trace.go:171","msg":"trace[178795175] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1258; }","duration":"177.754281ms","start":"2026-07-02T00:24:07.353539Z","end":"2026-07-02T00:24:07.531293Z","steps":["trace[178795175] 'agreement among raft nodes before linearized reading' (duration: 177.596987ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:24:07.531336Z","caller":"traceutil/trace.go:171","msg":"trace[1529074609] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1258; }","duration":"177.843494ms","start":"2026-07-02T00:24:07.353476Z","end":"2026-07-02T00:24:07.531319Z","steps":["trace[1529074609] 'agreement among raft nodes before linearized reading' (duration: 177.706781ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:07.531521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.329397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:24:07.531707Z","caller":"traceutil/trace.go:171","msg":"trace[1289022857] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1258; }","duration":"185.974043ms","start":"2026-07-02T00:24:07.345718Z","end":"2026-07-02T00:24:07.531692Z","steps":["trace[1289022857] 'agreement among raft nodes before linearized reading' (duration: 185.365378ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:24:08.051716Z","caller":"traceutil/trace.go:171","msg":"trace[1390140671] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"176.139552ms","start":"2026-07-02T00:24:07.875538Z","end":"2026-07-02T00:24:08.051677Z","steps":["trace[1390140671] 'process raft request' (duration: 86.333784ms)","trace[1390140671] 'compare' (duration: 89.668125ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:24:09.359513Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853971548905209329,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T00:24:09.69925Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.178113463s","expected-duration":"1s"} {"level":"info","ts":"2026-07-02T00:24:09.69964Z","caller":"traceutil/trace.go:171","msg":"trace[268283496] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"1.178566364s","start":"2026-07-02T00:24:08.521046Z","end":"2026-07-02T00:24:09.699613Z","steps":["trace[268283496] 'process raft request' (duration: 1.178383949s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:09.699797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:08.521023Z","time spent":"1.178691477s","remote":"127.0.0.1:33472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":892,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T00:24:09.711511Z","caller":"traceutil/trace.go:171","msg":"trace[1638792483] linearizableReadLoop","detail":"{readStateIndex:1315; appliedIndex:1314; }","duration":"852.256425ms","start":"2026-07-02T00:24:08.859225Z","end":"2026-07-02T00:24:09.711481Z","steps":["trace[1638792483] 'read index received' (duration: 840.935698ms)","trace[1638792483] 'applied index is now lower than readState.Index' (duration: 11.319687ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:24:09.711542Z","caller":"traceutil/trace.go:171","msg":"trace[788710637] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"1.183929375s","start":"2026-07-02T00:24:08.527582Z","end":"2026-07-02T00:24:09.711512Z","steps":["trace[788710637] 'process raft request' (duration: 1.183705489s)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:24:09.711598Z","caller":"traceutil/trace.go:171","msg":"trace[1003811634] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"174.84088ms","start":"2026-07-02T00:24:09.536727Z","end":"2026-07-02T00:24:09.711568Z","steps":["trace[1003811634] 'process raft request' (duration: 174.699867ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:09.711674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:08.527572Z","time spent":"1.184029127s","remote":"127.0.0.1:33688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T00:24:09.711696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.432879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:24:09.711787Z","caller":"traceutil/trace.go:171","msg":"trace[1186420377] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1266; }","duration":"852.590753ms","start":"2026-07-02T00:24:08.859175Z","end":"2026-07-02T00:24:09.711766Z","steps":["trace[1186420377] 'agreement among raft nodes before linearized reading' (duration: 852.416299ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:09.711829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:08.859156Z","time spent":"852.663084ms","remote":"127.0.0.1:55334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-07-02T00:24:09.711919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.370491ms","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-07-02T00:24:09.7119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.363886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T00:24:09.711956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.615677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-02T00:24:09.711974Z","caller":"traceutil/trace.go:171","msg":"trace[565334543] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1266; }","duration":"141.519735ms","start":"2026-07-02T00:24:09.570439Z","end":"2026-07-02T00:24:09.711958Z","steps":["trace[565334543] 'agreement among raft nodes before linearized reading' (duration: 141.34115ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:09.711978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.911809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-02T00:24:09.711999Z","caller":"traceutil/trace.go:171","msg":"trace[1355549323] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1266; }","duration":"363.52431ms","start":"2026-07-02T00:24:09.34845Z","end":"2026-07-02T00:24:09.711974Z","steps":["trace[1355549323] 'agreement among raft nodes before linearized reading' (duration: 363.337336ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:24:09.712015Z","caller":"traceutil/trace.go:171","msg":"trace[1387797802] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1266; }","duration":"151.976281ms","start":"2026-07-02T00:24:09.560029Z","end":"2026-07-02T00:24:09.712006Z","steps":["trace[1387797802] 'agreement among raft nodes before linearized reading' (duration: 151.865948ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:24:09.712016Z","caller":"traceutil/trace.go:171","msg":"trace[1216856819] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1266; }","duration":"141.702789ms","start":"2026-07-02T00:24:09.570297Z","end":"2026-07-02T00:24:09.712Z","steps":["trace[1216856819] 'agreement among raft nodes before linearized reading' (duration: 141.529265ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:09.712048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:09.348431Z","time spent":"363.607552ms","remote":"127.0.0.1:33414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T00:24:10.183474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.006697ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T00:24:10.184175Z","caller":"traceutil/trace.go:171","msg":"trace[249377469] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"468.557641ms","start":"2026-07-02T00:24:09.715589Z","end":"2026-07-02T00:24:10.184147Z","steps":["trace[249377469] 'process raft request' (duration: 287.750104ms)","trace[249377469] 'compare' (duration: 179.889354ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:24:10.184288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:09.715578Z","time spent":"468.650924ms","remote":"127.0.0.1:33688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T00:24:10.242416Z","caller":"traceutil/trace.go:171","msg":"trace[419861142] linearizableReadLoop","detail":"{readStateIndex:1319; appliedIndex:1316; }","duration":"366.632855ms","start":"2026-07-02T00:24:09.875718Z","end":"2026-07-02T00:24:10.242351Z","steps":["trace[419861142] 'read index received' (duration: 127.694266ms)","trace[419861142] 'applied index is now lower than readState.Index' (duration: 238.936569ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:24:10.242625Z","caller":"traceutil/trace.go:171","msg":"trace[2091972110] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"526.039999ms","start":"2026-07-02T00:24:09.716557Z","end":"2026-07-02T00:24:10.242597Z","steps":["trace[2091972110] 'process raft request' (duration: 525.69083ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:10.242795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:09.716552Z","time spent":"526.138451ms","remote":"127.0.0.1:33688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T00:24:10.24306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.316742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-02T00:24:10.243132Z","caller":"traceutil/trace.go:171","msg":"trace[1605358687] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1269; }","duration":"367.442315ms","start":"2026-07-02T00:24:09.87567Z","end":"2026-07-02T00:24:10.243112Z","steps":["trace[1605358687] 'agreement among raft nodes before linearized reading' (duration: 366.923192ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:10.243149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.116922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-02T00:24:10.242955Z","caller":"traceutil/trace.go:171","msg":"trace[1844273704] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"527.061593ms","start":"2026-07-02T00:24:09.715882Z","end":"2026-07-02T00:24:10.242943Z","steps":["trace[1844273704] 'process raft request' (duration: 526.182931ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:10.243022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.941963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T00:24:10.243688Z","caller":"traceutil/trace.go:171","msg":"trace[1917586974] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1269; }","duration":"282.64935ms","start":"2026-07-02T00:24:09.961029Z","end":"2026-07-02T00:24:10.243678Z","steps":["trace[1917586974] 'agreement among raft nodes before linearized reading' (duration: 281.958783ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:10.243182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:09.875646Z","time spent":"367.523898ms","remote":"127.0.0.1:33688","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":"info","ts":"2026-07-02T00:24:10.243217Z","caller":"traceutil/trace.go:171","msg":"trace[393550567] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1269; }","duration":"185.210914ms","start":"2026-07-02T00:24:10.057989Z","end":"2026-07-02T00:24:10.2432Z","steps":["trace[393550567] 'agreement among raft nodes before linearized reading' (duration: 185.03476ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:10.243666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:09.71586Z","time spent":"527.72341ms","remote":"127.0.0.1:33688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T00:24:11.099828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.670333ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-02T00:24:11.099959Z","caller":"traceutil/trace.go:171","msg":"trace[301369799] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"481.388004ms","start":"2026-07-02T00:24:10.618552Z","end":"2026-07-02T00:24:11.09994Z","steps":["trace[301369799] 'process raft request' (duration: 81.533786ms)","trace[301369799] 'compare' (duration: 399.561501ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:24:11.100009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:10.618529Z","time spent":"481.459286ms","remote":"127.0.0.1:33688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T00:24:11.139233Z","caller":"traceutil/trace.go:171","msg":"trace[2135618591] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"434.223239ms","start":"2026-07-02T00:24:10.704973Z","end":"2026-07-02T00:24:11.139197Z","steps":["trace[2135618591] 'process raft request' (duration: 434.071545ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:11.139366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:10.704947Z","time spent":"434.354643ms","remote":"127.0.0.1:33472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T00:24:11.536008Z","caller":"traceutil/trace.go:171","msg":"trace[1092917877] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"431.842453ms","start":"2026-07-02T00:24:11.104132Z","end":"2026-07-02T00:24:11.535975Z","steps":["trace[1092917877] 'process raft request' (duration: 404.304599ms)","trace[1092917877] 'compare' (duration: 27.352449ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:24:11.536013Z","caller":"traceutil/trace.go:171","msg":"trace[1640963688] linearizableReadLoop","detail":"{readStateIndex:1327; appliedIndex:1324; }","duration":"186.68175ms","start":"2026-07-02T00:24:11.349312Z","end":"2026-07-02T00:24:11.535994Z","steps":["trace[1640963688] 'read index received' (duration: 159.140116ms)","trace[1640963688] 'applied index is now lower than readState.Index' (duration: 27.540464ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:24:11.536061Z","caller":"traceutil/trace.go:171","msg":"trace[1994252311] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"392.772496ms","start":"2026-07-02T00:24:11.143257Z","end":"2026-07-02T00:24:11.536029Z","steps":["trace[1994252311] 'process raft request' (duration: 392.641933ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:11.536108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:11.104119Z","time spent":"431.943135ms","remote":"127.0.0.1:33472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T00:24:11.536139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.820564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:24:11.536183Z","caller":"traceutil/trace.go:171","msg":"trace[1799332974] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1277; }","duration":"186.913306ms","start":"2026-07-02T00:24:11.349259Z","end":"2026-07-02T00:24:11.536172Z","steps":["trace[1799332974] 'agreement among raft nodes before linearized reading' (duration: 186.810173ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:11.536225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:11.143203Z","time spent":"392.93509ms","remote":"127.0.0.1:33688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T00:24:11.536371Z","caller":"traceutil/trace.go:171","msg":"trace[2111143137] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"392.055119ms","start":"2026-07-02T00:24:11.144278Z","end":"2026-07-02T00:24:11.536333Z","steps":["trace[2111143137] 'process raft request' (duration: 391.6822ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:11.53647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:11.144264Z","time spent":"392.150721ms","remote":"127.0.0.1:33472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T00:24:11.821716Z","caller":"traceutil/trace.go:171","msg":"trace[1499148206] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"101.806183ms","start":"2026-07-02T00:24:11.719887Z","end":"2026-07-02T00:24:11.821694Z","steps":["trace[1499148206] 'process raft request' (duration: 101.647749ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:24:20.214297Z","caller":"traceutil/trace.go:171","msg":"trace[1403211832] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"551.619284ms","start":"2026-07-02T00:24:19.662648Z","end":"2026-07-02T00:24:20.214267Z","steps":["trace[1403211832] 'process raft request' (duration: 551.392449ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:20.214506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:19.662608Z","time spent":"551.769728ms","remote":"127.0.0.1:33688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T00:24:20.610324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.679505ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T00:24:20.610519Z","caller":"traceutil/trace.go:171","msg":"trace[1393761433] linearizableReadLoop","detail":"{readStateIndex:1393; appliedIndex:1390; }","duration":"585.649008ms","start":"2026-07-02T00:24:20.024852Z","end":"2026-07-02T00:24:20.610501Z","steps":["trace[1393761433] 'read index received' (duration: 189.223593ms)","trace[1393761433] 'applied index is now lower than readState.Index' (duration: 396.424595ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:24:20.610675Z","caller":"traceutil/trace.go:171","msg":"trace[1941055073] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"709.608212ms","start":"2026-07-02T00:24:19.901037Z","end":"2026-07-02T00:24:20.610646Z","steps":["trace[1941055073] 'process raft request' (duration: 561.517067ms)","trace[1941055073] 'compare' (duration: 147.420749ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:24:20.611015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.632541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-02T00:24:20.611077Z","caller":"traceutil/trace.go:171","msg":"trace[913178929] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1341; }","duration":"376.756834ms","start":"2026-07-02T00:24:20.234307Z","end":"2026-07-02T00:24:20.611063Z","steps":["trace[913178929] 'agreement among raft nodes before linearized reading' (duration: 376.59186ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:20.61112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:20.234284Z","time spent":"376.827956ms","remote":"127.0.0.1:33688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-02T00:24:20.61116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.234874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-02T00:24:20.611257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.574187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-02T00:24:20.611256Z","caller":"traceutil/trace.go:171","msg":"trace[1092355499] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1341; }","duration":"234.315116ms","start":"2026-07-02T00:24:20.376878Z","end":"2026-07-02T00:24:20.611193Z","steps":["trace[1092355499] 'agreement among raft nodes before linearized reading' (duration: 234.130231ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:24:20.611344Z","caller":"traceutil/trace.go:171","msg":"trace[1499565752] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1341; }","duration":"284.70845ms","start":"2026-07-02T00:24:20.326615Z","end":"2026-07-02T00:24:20.611324Z","steps":["trace[1499565752] 'agreement among raft nodes before linearized reading' (duration: 284.380092ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:20.611538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.042676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-02T00:24:20.61155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.47775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T00:24:20.610942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:19.901Z","time spent":"709.90777ms","remote":"127.0.0.1:33688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T00:24:20.610749Z","caller":"traceutil/trace.go:171","msg":"trace[268667183] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"620.824179ms","start":"2026-07-02T00:24:19.989916Z","end":"2026-07-02T00:24:20.61074Z","steps":["trace[268667183] 'process raft request' (duration: 620.503011ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:24:20.611605Z","caller":"traceutil/trace.go:171","msg":"trace[1428796800] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1341; }","duration":"300.132488ms","start":"2026-07-02T00:24:20.31146Z","end":"2026-07-02T00:24:20.611592Z","steps":["trace[1428796800] 'agreement among raft nodes before linearized reading' (duration: 299.926953ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:24:20.611616Z","caller":"traceutil/trace.go:171","msg":"trace[2050344182] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1341; }","duration":"263.544612ms","start":"2026-07-02T00:24:20.348057Z","end":"2026-07-02T00:24:20.611602Z","steps":["trace[2050344182] 'agreement among raft nodes before linearized reading' (duration: 263.45028ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:20.611615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.212938ms","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-07-02T00:24:20.611645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:20.311448Z","time spent":"300.188289ms","remote":"127.0.0.1:33688","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-07-02T00:24:20.611656Z","caller":"traceutil/trace.go:171","msg":"trace[1950771038] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1341; }","duration":"293.29329ms","start":"2026-07-02T00:24:20.318353Z","end":"2026-07-02T00:24:20.611646Z","steps":["trace[1950771038] 'agreement among raft nodes before linearized reading' (duration: 293.181717ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:20.610794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.943305ms","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":"warn","ts":"2026-07-02T00:24:20.611715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:19.9899Z","time spent":"621.734271ms","remote":"127.0.0.1:33688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T00:24:20.611867Z","caller":"traceutil/trace.go:171","msg":"trace[2034470062] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1341; }","duration":"586.920749ms","start":"2026-07-02T00:24:20.024802Z","end":"2026-07-02T00:24:20.611723Z","steps":["trace[2034470062] 'agreement among raft nodes before linearized reading' (duration: 585.944575ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:20.611973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:20.024786Z","time spent":"587.119773ms","remote":"127.0.0.1:33590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":29,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-07-02T00:24:20.612184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.979824ms","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:533"} {"level":"info","ts":"2026-07-02T00:24:20.612259Z","caller":"traceutil/trace.go:171","msg":"trace[1171742904] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1341; }","duration":"349.094287ms","start":"2026-07-02T00:24:20.263151Z","end":"2026-07-02T00:24:20.612245Z","steps":["trace[1171742904] 'agreement among raft nodes before linearized reading' (duration: 348.357549ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:20.612293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:20.263132Z","time spent":"349.152718ms","remote":"127.0.0.1:33688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-02T00:24:21.723973Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.107765709s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-02T00:24:22.025854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.486751ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T00:24:22.025971Z","caller":"traceutil/trace.go:171","msg":"trace[122708427] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"1.409808334s","start":"2026-07-02T00:24:20.616138Z","end":"2026-07-02T00:24:22.025947Z","steps":["trace[122708427] 'process raft request' (duration: 1.108158049s)","trace[122708427] 'compare' (duration: 301.366778ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:24:22.026054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:20.616122Z","time spent":"1.409888345s","remote":"127.0.0.1:33688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T00:24:22.085425Z","caller":"traceutil/trace.go:171","msg":"trace[1563809352] linearizableReadLoop","detail":"{readStateIndex:1399; appliedIndex:1393; }","duration":"736.410018ms","start":"2026-07-02T00:24:21.348996Z","end":"2026-07-02T00:24:22.085406Z","steps":["trace[1563809352] 'read index received' (duration: 375.181544ms)","trace[1563809352] 'applied index is now lower than readState.Index' (duration: 361.227404ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:24:22.085522Z","caller":"traceutil/trace.go:171","msg":"trace[123898257] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"1.467184219s","start":"2026-07-02T00:24:20.618321Z","end":"2026-07-02T00:24:22.085505Z","steps":["trace[123898257] 'process raft request' (duration: 1.467002165s)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:24:22.085621Z","caller":"traceutil/trace.go:171","msg":"trace[186043203] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"1.468371027s","start":"2026-07-02T00:24:20.617233Z","end":"2026-07-02T00:24:22.085605Z","steps":["trace[186043203] 'process raft request' (duration: 1.468018859s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:22.085881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:20.617209Z","time spent":"1.468572493s","remote":"127.0.0.1:33688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T00:24:22.085383Z","caller":"traceutil/trace.go:171","msg":"trace[1876115064] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"1.469171427s","start":"2026-07-02T00:24:20.616185Z","end":"2026-07-02T00:24:22.085356Z","steps":["trace[1876115064] 'process raft request' (duration: 1.468956102s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:22.085645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:20.618311Z","time spent":"1.467257341s","remote":"127.0.0.1:33688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T00:24:22.08569Z","caller":"traceutil/trace.go:171","msg":"trace[494298933] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"1.466983413s","start":"2026-07-02T00:24:20.618696Z","end":"2026-07-02T00:24:22.085679Z","steps":["trace[494298933] 'process raft request' (duration: 1.466668956s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:22.086125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:20.616163Z","time spent":"1.469863224s","remote":"127.0.0.1:33688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T00:24:22.085735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.747856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:24:22.086315Z","caller":"traceutil/trace.go:171","msg":"trace[1045863068] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1347; }","duration":"737.359452ms","start":"2026-07-02T00:24:21.348942Z","end":"2026-07-02T00:24:22.086301Z","steps":["trace[1045863068] 'agreement among raft nodes before linearized reading' (duration: 736.765857ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:22.086354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:21.348922Z","time spent":"737.423073ms","remote":"127.0.0.1:33414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-02T00:24:22.085442Z","caller":"traceutil/trace.go:171","msg":"trace[70272187] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"1.467761913s","start":"2026-07-02T00:24:20.617659Z","end":"2026-07-02T00:24:22.085421Z","steps":["trace[70272187] 'process raft request' (duration: 1.46762846s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:22.086572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:20.617644Z","time spent":"1.46889048s","remote":"127.0.0.1:33688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T00:24:22.086825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:20.618688Z","time spent":"1.467459846s","remote":"127.0.0.1:33688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T00:24:22.115171Z","caller":"traceutil/trace.go:171","msg":"trace[583518799] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"107.897791ms","start":"2026-07-02T00:24:22.007246Z","end":"2026-07-02T00:24:22.115144Z","steps":["trace[583518799] 'process raft request' (duration: 107.696987ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:22.137176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.624522ms","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":"info","ts":"2026-07-02T00:24:22.137253Z","caller":"traceutil/trace.go:171","msg":"trace[1231799831] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1348; }","duration":"582.746125ms","start":"2026-07-02T00:24:21.554488Z","end":"2026-07-02T00:24:22.137234Z","steps":["trace[1231799831] 'agreement among raft nodes before linearized reading' (duration: 582.51402ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:22.137294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:21.554469Z","time spent":"582.816278ms","remote":"127.0.0.1:33688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-02T00:24:44.44443Z","caller":"traceutil/trace.go:171","msg":"trace[600538246] linearizableReadLoop","detail":"{readStateIndex:1506; appliedIndex:1503; }","duration":"104.897782ms","start":"2026-07-02T00:24:44.339507Z","end":"2026-07-02T00:24:44.444404Z","steps":["trace[600538246] 'read index received' (duration: 23.928325ms)","trace[600538246] 'applied index is now lower than readState.Index' (duration: 80.968447ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:24:44.444788Z","caller":"traceutil/trace.go:171","msg":"trace[679485329] transaction","detail":"{read_only:false; response_revision:1449; number_of_response:1; }","duration":"173.462296ms","start":"2026-07-02T00:24:44.271305Z","end":"2026-07-02T00:24:44.444767Z","steps":["trace[679485329] 'process raft request' (duration: 173.056496ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:24:44.444822Z","caller":"traceutil/trace.go:171","msg":"trace[1339969434] transaction","detail":"{read_only:false; response_revision:1448; number_of_response:1; }","duration":"173.604499ms","start":"2026-07-02T00:24:44.271185Z","end":"2026-07-02T00:24:44.444789Z","steps":["trace[1339969434] 'process raft request' (duration: 173.048966ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:44.445175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.786599ms","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-07-02T00:24:44.445267Z","caller":"traceutil/trace.go:171","msg":"trace[1784157144] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1449; }","duration":"104.930432ms","start":"2026-07-02T00:24:44.340314Z","end":"2026-07-02T00:24:44.445244Z","steps":["trace[1784157144] 'agreement among raft nodes before linearized reading' (duration: 104.554473ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:44.445371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.790653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-02T00:24:44.445561Z","caller":"traceutil/trace.go:171","msg":"trace[1600663039] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1449; }","duration":"106.087681ms","start":"2026-07-02T00:24:44.339454Z","end":"2026-07-02T00:24:44.445542Z","steps":["trace[1600663039] 'agreement among raft nodes before linearized reading' (duration: 105.691051ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:24:50.666138Z","caller":"traceutil/trace.go:171","msg":"trace[637582471] transaction","detail":"{read_only:false; response_revision:1475; number_of_response:1; }","duration":"182.354283ms","start":"2026-07-02T00:24:50.483761Z","end":"2026-07-02T00:24:50.666116Z","steps":["trace[637582471] 'process raft request' (duration: 131.109982ms)","trace[637582471] 'compare' (duration: 51.093297ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:24:50.666284Z","caller":"traceutil/trace.go:171","msg":"trace[534538649] linearizableReadLoop","detail":"{readStateIndex:1534; appliedIndex:1531; }","duration":"175.755191ms","start":"2026-07-02T00:24:50.490497Z","end":"2026-07-02T00:24:50.666252Z","steps":["trace[534538649] 'read index received' (duration: 46.050994ms)","trace[534538649] 'applied index is now lower than readState.Index' (duration: 129.702217ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:24:50.666329Z","caller":"traceutil/trace.go:171","msg":"trace[1069033073] transaction","detail":"{read_only:false; response_revision:1476; number_of_response:1; }","duration":"182.391723ms","start":"2026-07-02T00:24:50.483903Z","end":"2026-07-02T00:24:50.666295Z","steps":["trace[1069033073] 'process raft request' (duration: 182.180248ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:50.666491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.979698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-02T00:24:50.666556Z","caller":"traceutil/trace.go:171","msg":"trace[134403667] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1476; }","duration":"176.069099ms","start":"2026-07-02T00:24:50.49047Z","end":"2026-07-02T00:24:50.666539Z","steps":["trace[134403667] 'agreement among raft nodes before linearized reading' (duration: 175.901645ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:50.667368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.741391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-02T00:24:50.667431Z","caller":"traceutil/trace.go:171","msg":"trace[445091123] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1476; }","duration":"159.856774ms","start":"2026-07-02T00:24:50.507559Z","end":"2026-07-02T00:24:50.667416Z","steps":["trace[445091123] 'agreement among raft nodes before linearized reading' (duration: 158.849759ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:24:50.773595Z","caller":"traceutil/trace.go:171","msg":"trace[1689416256] transaction","detail":"{read_only:false; response_revision:1478; number_of_response:1; }","duration":"101.251892ms","start":"2026-07-02T00:24:50.672317Z","end":"2026-07-02T00:24:50.773569Z","steps":["trace[1689416256] 'process raft request' (duration: 101.056218ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:24:52.591388Z","caller":"traceutil/trace.go:171","msg":"trace[344247661] transaction","detail":"{read_only:false; response_revision:1483; number_of_response:1; }","duration":"102.838951ms","start":"2026-07-02T00:24:52.488514Z","end":"2026-07-02T00:24:52.591353Z","steps":["trace[344247661] 'process raft request' (duration: 102.670277ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:28:12.000197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.420771ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"}