{"level":"warn","ts":"2026-07-02T10:00:27.555371Z","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-02T10:00:27.555471Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.154: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.19.213.154:2380","--initial-cluster=instance=https://199.19.213.154:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.154:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.154: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-02T10:00:27.555533Z","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-02T10:00:27.555546Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.154:2380"]} {"level":"info","ts":"2026-07-02T10:00:27.555562Z","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-02T10:00:27.556152Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.154:2379"]} {"level":"info","ts":"2026-07-02T10:00:27.55623Z","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.19.213.154:2380"],"listen-peer-urls":["https://199.19.213.154:2380"],"advertise-client-urls":["https://199.19.213.154:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.154:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.154: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-02T10:00:27.612176Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"55.740649ms"} {"level":"info","ts":"2026-07-02T10:00:27.62115Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f4aebb100319a62d","cluster-id":"23a505f69026554e"} {"level":"info","ts":"2026-07-02T10:00:27.621254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d switched to configuration voters=()"} {"level":"info","ts":"2026-07-02T10:00:27.621303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d became follower at term 0"} {"level":"info","ts":"2026-07-02T10:00:27.621319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f4aebb100319a62d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-02T10:00:27.621332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d became follower at term 1"} {"level":"info","ts":"2026-07-02T10:00:27.621391Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d switched to configuration voters=(17631235268647953965)"} {"level":"warn","ts":"2026-07-02T10:00:27.628631Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-02T10:00:27.629849Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-02T10:00:27.631242Z","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-02T10:00:27.633192Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f4aebb100319a62d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-02T10:00:27.633736Z","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-02T10:00:27.63389Z","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-02T10:00:27.633901Z","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-02T10:00:27.63376Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f4aebb100319a62d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-02T10:00:27.637985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d switched to configuration voters=(17631235268647953965)"} {"level":"info","ts":"2026-07-02T10:00:27.638842Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"23a505f69026554e","local-member-id":"f4aebb100319a62d","added-peer-id":"f4aebb100319a62d","added-peer-peer-urls":["https://199.19.213.154:2380"]} {"level":"info","ts":"2026-07-02T10:00:27.640451Z","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-02T10:00:27.640615Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.154:2380"} {"level":"info","ts":"2026-07-02T10:00:27.640659Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.154:2380"} {"level":"info","ts":"2026-07-02T10:00:27.640839Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f4aebb100319a62d","initial-advertise-peer-urls":["https://199.19.213.154:2380"],"listen-peer-urls":["https://199.19.213.154:2380"],"advertise-client-urls":["https://199.19.213.154:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.154:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-02T10:00:27.640982Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-02T10:00:28.222217Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d is starting a new election at term 1"} {"level":"info","ts":"2026-07-02T10:00:28.222434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d became pre-candidate at term 1"} {"level":"info","ts":"2026-07-02T10:00:28.222519Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d received MsgPreVoteResp from f4aebb100319a62d at term 1"} {"level":"info","ts":"2026-07-02T10:00:28.222565Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d became candidate at term 2"} {"level":"info","ts":"2026-07-02T10:00:28.2226Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d received MsgVoteResp from f4aebb100319a62d at term 2"} {"level":"info","ts":"2026-07-02T10:00:28.222639Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d became leader at term 2"} {"level":"info","ts":"2026-07-02T10:00:28.222678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f4aebb100319a62d elected leader f4aebb100319a62d at term 2"} {"level":"info","ts":"2026-07-02T10:00:28.223741Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T10:00:28.224626Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f4aebb100319a62d","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.154:2379]}","request-path":"/0/members/f4aebb100319a62d/attributes","cluster-id":"23a505f69026554e","publish-timeout":"7s"} {"level":"info","ts":"2026-07-02T10:00:28.224643Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T10:00:28.225079Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T10:00:28.225165Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-02T10:00:28.225233Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-02T10:00:28.22535Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"23a505f69026554e","local-member-id":"f4aebb100319a62d","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T10:00:28.22554Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T10:00:28.225748Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T10:00:28.228866Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-02T10:00:28.228876Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.154:2379"} {"level":"info","ts":"2026-07-02T10:00:30.663808Z","caller":"traceutil/trace.go:171","msg":"trace[1331608211] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"192.216853ms","start":"2026-07-02T10:00:30.47156Z","end":"2026-07-02T10:00:30.663777Z","steps":["trace[1331608211] 'read index received' (duration: 188.586727ms)","trace[1331608211] 'applied index is now lower than readState.Index' (duration: 3.629176ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T10:00:30.663952Z","caller":"traceutil/trace.go:171","msg":"trace[875547154] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"192.583968ms","start":"2026-07-02T10:00:30.47133Z","end":"2026-07-02T10:00:30.663914Z","steps":["trace[875547154] 'process raft request' (duration: 188.861811ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:00:30.664046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.430866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-02T10:00:30.664114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.53367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-02T10:00:30.66414Z","caller":"traceutil/trace.go:171","msg":"trace[753958894] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:0; response_revision:72; }","duration":"192.575568ms","start":"2026-07-02T10:00:30.471544Z","end":"2026-07-02T10:00:30.66412Z","steps":["trace[753958894] 'agreement among raft nodes before linearized reading' (duration: 192.396855ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:00:30.664204Z","caller":"traceutil/trace.go:171","msg":"trace[1756807636] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:72; }","duration":"163.721963ms","start":"2026-07-02T10:00:30.500465Z","end":"2026-07-02T10:00:30.664158Z","steps":["trace[1756807636] 'agreement among raft nodes before linearized reading' (duration: 163.52938ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:00:30.664336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.099327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-02T10:00:30.66446Z","caller":"traceutil/trace.go:171","msg":"trace[2137781147] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:72; }","duration":"156.260569ms","start":"2026-07-02T10:00:30.508172Z","end":"2026-07-02T10:00:30.664433Z","steps":["trace[2137781147] 'agreement among raft nodes before linearized reading' (duration: 156.013216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:00:31.77206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.276219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T10:00:31.772161Z","caller":"traceutil/trace.go:171","msg":"trace[1175126914] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:203; }","duration":"117.455752ms","start":"2026-07-02T10:00:31.654684Z","end":"2026-07-02T10:00:31.772139Z","steps":["trace[1175126914] 'range keys from in-memory index tree' (duration: 117.156617ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:00:31.993562Z","caller":"traceutil/trace.go:171","msg":"trace[1980000799] linearizableReadLoop","detail":"{readStateIndex:211; appliedIndex:209; }","duration":"110.631769ms","start":"2026-07-02T10:00:31.882905Z","end":"2026-07-02T10:00:31.993537Z","steps":["trace[1980000799] 'read index received' (duration: 41.978629ms)","trace[1980000799] 'applied index is now lower than readState.Index' (duration: 68.65203ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T10:00:31.993701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.772361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pv-protection-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T10:00:31.993747Z","caller":"traceutil/trace.go:171","msg":"trace[1516269826] transaction","detail":"{read_only:false; response_revision:205; number_of_response:1; }","duration":"118.016079ms","start":"2026-07-02T10:00:31.875693Z","end":"2026-07-02T10:00:31.993709Z","steps":["trace[1516269826] 'process raft request' (duration: 117.742405ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:00:31.993741Z","caller":"traceutil/trace.go:171","msg":"trace[90514689] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pv-protection-controller; range_end:; response_count:0; response_revision:205; }","duration":"110.866942ms","start":"2026-07-02T10:00:31.882862Z","end":"2026-07-02T10:00:31.993729Z","steps":["trace[90514689] 'agreement among raft nodes before linearized reading' (duration: 110.771021ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:00:32.185452Z","caller":"traceutil/trace.go:171","msg":"trace[1954672367] transaction","detail":"{read_only:false; response_revision:207; number_of_response:1; }","duration":"131.238695ms","start":"2026-07-02T10:00:32.054163Z","end":"2026-07-02T10:00:32.185402Z","steps":["trace[1954672367] 'process raft request' (duration: 130.127358ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:00:32.186078Z","caller":"traceutil/trace.go:171","msg":"trace[1643574553] transaction","detail":"{read_only:false; response_revision:208; number_of_response:1; }","duration":"127.62221ms","start":"2026-07-02T10:00:32.058434Z","end":"2026-07-02T10:00:32.186057Z","steps":["trace[1643574553] 'process raft request' (duration: 127.270555ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:00:45.477397Z","caller":"traceutil/trace.go:171","msg":"trace[1429366600] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"168.85333ms","start":"2026-07-02T10:00:45.308511Z","end":"2026-07-02T10:00:45.477364Z","steps":["trace[1429366600] 'process raft request' (duration: 95.585828ms)","trace[1429366600] 'compare' (duration: 73.080029ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T10:00:52.436231Z","caller":"traceutil/trace.go:171","msg":"trace[431190134] linearizableReadLoop","detail":"{readStateIndex:408; appliedIndex:407; }","duration":"323.047201ms","start":"2026-07-02T10:00:52.113163Z","end":"2026-07-02T10:00:52.436211Z","steps":["trace[431190134] 'read index received' (duration: 322.886599ms)","trace[431190134] 'applied index is now lower than readState.Index' (duration: 159.622µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T10:00:52.436437Z","caller":"traceutil/trace.go:171","msg":"trace[609809804] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"334.710665ms","start":"2026-07-02T10:00:52.101697Z","end":"2026-07-02T10:00:52.436408Z","steps":["trace[609809804] 'process raft request' (duration: 334.404361ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:00:52.436516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.333625ms","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-02T10:00:52.436553Z","caller":"traceutil/trace.go:171","msg":"trace[1864807784] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:393; }","duration":"323.412026ms","start":"2026-07-02T10:00:52.113131Z","end":"2026-07-02T10:00:52.436543Z","steps":["trace[1864807784] 'agreement among raft nodes before linearized reading' (duration: 323.264653ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:00:52.436593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:00:52.113123Z","time spent":"323.459307ms","remote":"127.0.0.1:36044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-02T10:00:52.436845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:00:52.101683Z","time spent":"334.832407ms","remote":"127.0.0.1:36044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T10:00:52.994401Z","caller":"traceutil/trace.go:171","msg":"trace[147813805] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"553.782512ms","start":"2026-07-02T10:00:52.440589Z","end":"2026-07-02T10:00:52.994372Z","steps":["trace[147813805] 'process raft request' (duration: 471.813235ms)","trace[147813805] 'compare' (duration: 81.864556ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T10:00:52.994561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:00:52.440565Z","time spent":"553.913763ms","remote":"127.0.0.1:36044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T10:00:52.998482Z","caller":"traceutil/trace.go:171","msg":"trace[1001852693] linearizableReadLoop","detail":"{readStateIndex:410; appliedIndex:408; }","duration":"435.843693ms","start":"2026-07-02T10:00:52.562623Z","end":"2026-07-02T10:00:52.998467Z","steps":["trace[1001852693] 'read index received' (duration: 349.789443ms)","trace[1001852693] 'applied index is now lower than readState.Index' (duration: 86.05327ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T10:00:52.998495Z","caller":"traceutil/trace.go:171","msg":"trace[130963612] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"547.912024ms","start":"2026-07-02T10:00:52.450565Z","end":"2026-07-02T10:00:52.998477Z","steps":["trace[130963612] 'process raft request' (duration: 547.814613ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:00:52.998582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.964574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T10:00:52.998623Z","caller":"traceutil/trace.go:171","msg":"trace[661624415] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:395; }","duration":"436.039875ms","start":"2026-07-02T10:00:52.562573Z","end":"2026-07-02T10:00:52.998613Z","steps":["trace[661624415] 'agreement among raft nodes before linearized reading' (duration: 435.960814ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:00:52.998656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:00:52.562558Z","time spent":"436.091855ms","remote":"127.0.0.1:35784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T10:00:52.998786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:00:52.450542Z","time spent":"548.063366ms","remote":"127.0.0.1:36198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T10:00:57.431359Z","caller":"traceutil/trace.go:171","msg":"trace[624363205] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"419.393466ms","start":"2026-07-02T10:00:57.011933Z","end":"2026-07-02T10:00:57.431326Z","steps":["trace[624363205] 'process raft request' (duration: 419.202393ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:00:57.431568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:00:57.011916Z","time spent":"419.542598ms","remote":"127.0.0.1:36044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T10:00:57.432231Z","caller":"traceutil/trace.go:171","msg":"trace[1326625900] linearizableReadLoop","detail":"{readStateIndex:451; appliedIndex:451; }","duration":"303.420845ms","start":"2026-07-02T10:00:57.128797Z","end":"2026-07-02T10:00:57.432218Z","steps":["trace[1326625900] 'read index received' (duration: 303.415174ms)","trace[1326625900] 'applied index is now lower than readState.Index' (duration: 4.441µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T10:00:57.432331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.247099ms","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-02T10:00:57.432362Z","caller":"traceutil/trace.go:171","msg":"trace[2086071190] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:433; }","duration":"298.301679ms","start":"2026-07-02T10:00:57.13405Z","end":"2026-07-02T10:00:57.432352Z","steps":["trace[2086071190] 'agreement among raft nodes before linearized reading' (duration: 298.238748ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:00:57.432546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.759379ms","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-02T10:00:57.432573Z","caller":"traceutil/trace.go:171","msg":"trace[488119462] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:433; }","duration":"303.8439ms","start":"2026-07-02T10:00:57.128721Z","end":"2026-07-02T10:00:57.432564Z","steps":["trace[488119462] 'agreement among raft nodes before linearized reading' (duration: 303.794059ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:00:57.432598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:00:57.128686Z","time spent":"303.906191ms","remote":"127.0.0.1:36044","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-02T10:00:58.678441Z","caller":"traceutil/trace.go:171","msg":"trace[522543264] linearizableReadLoop","detail":"{readStateIndex:454; appliedIndex:453; }","duration":"115.290024ms","start":"2026-07-02T10:00:58.563124Z","end":"2026-07-02T10:00:58.678414Z","steps":["trace[522543264] 'read index received' (duration: 115.087941ms)","trace[522543264] 'applied index is now lower than readState.Index' (duration: 200.793µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T10:00:58.678529Z","caller":"traceutil/trace.go:171","msg":"trace[728116086] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"196.042479ms","start":"2026-07-02T10:00:58.482452Z","end":"2026-07-02T10:00:58.678495Z","steps":["trace[728116086] 'process raft request' (duration: 195.751065ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:00:58.678576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.436567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T10:00:58.678623Z","caller":"traceutil/trace.go:171","msg":"trace[170705807] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:436; }","duration":"115.561307ms","start":"2026-07-02T10:00:58.563045Z","end":"2026-07-02T10:00:58.678607Z","steps":["trace[170705807] 'agreement among raft nodes before linearized reading' (duration: 115.473716ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:01:07.696438Z","caller":"traceutil/trace.go:171","msg":"trace[1159234533] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"634.495235ms","start":"2026-07-02T10:01:07.061906Z","end":"2026-07-02T10:01:07.696401Z","steps":["trace[1159234533] 'process raft request' (duration: 634.273963ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:01:07.696801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:01:07.061854Z","time spent":"634.708049ms","remote":"127.0.0.1:35842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10846,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T10:01:07.704944Z","caller":"traceutil/trace.go:171","msg":"trace[2090701925] linearizableReadLoop","detail":"{readStateIndex:550; appliedIndex:548; }","duration":"174.360016ms","start":"2026-07-02T10:01:07.530561Z","end":"2026-07-02T10:01:07.704921Z","steps":["trace[2090701925] 'read index received' (duration: 165.892173ms)","trace[2090701925] 'applied index is now lower than readState.Index' (duration: 8.467043ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T10:01:07.70508Z","caller":"traceutil/trace.go:171","msg":"trace[2020150117] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"640.860883ms","start":"2026-07-02T10:01:07.064211Z","end":"2026-07-02T10:01:07.705072Z","steps":["trace[2020150117] 'process raft request' (duration: 640.426487ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:01:07.705188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:01:07.064182Z","time spent":"640.922735ms","remote":"127.0.0.1:35842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T10:01:07.705264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.669359ms","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-02T10:01:07.705353Z","caller":"traceutil/trace.go:171","msg":"trace[31863551] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:525; }","duration":"174.825501ms","start":"2026-07-02T10:01:07.530508Z","end":"2026-07-02T10:01:07.705333Z","steps":["trace[31863551] 'agreement among raft nodes before linearized reading' (duration: 174.582969ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:01:07.705365Z","caller":"traceutil/trace.go:171","msg":"trace[741994514] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"581.712136ms","start":"2026-07-02T10:01:07.123605Z","end":"2026-07-02T10:01:07.705317Z","steps":["trace[741994514] 'process raft request' (duration: 581.236611ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:01:07.705531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:01:07.12357Z","time spent":"581.890038ms","remote":"127.0.0.1:35842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T10:01:07.705536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.930695ms","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-02T10:01:07.705716Z","caller":"traceutil/trace.go:171","msg":"trace[903622435] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:525; }","duration":"148.251368ms","start":"2026-07-02T10:01:07.557441Z","end":"2026-07-02T10:01:07.705693Z","steps":["trace[903622435] 'agreement among raft nodes before linearized reading' (duration: 147.837964ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:01:07.705838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.019048ms","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-02T10:01:07.70588Z","caller":"traceutil/trace.go:171","msg":"trace[41775463] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:525; }","duration":"163.079658ms","start":"2026-07-02T10:01:07.542789Z","end":"2026-07-02T10:01:07.705869Z","steps":["trace[41775463] 'agreement among raft nodes before linearized reading' (duration: 162.403051ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:01:08.130635Z","caller":"traceutil/trace.go:171","msg":"trace[2005638651] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"271.564217ms","start":"2026-07-02T10:01:07.859044Z","end":"2026-07-02T10:01:08.130608Z","steps":["trace[2005638651] 'process raft request' (duration: 261.921561ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:01:08.130967Z","caller":"traceutil/trace.go:171","msg":"trace[302479524] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"266.174402ms","start":"2026-07-02T10:01:07.86477Z","end":"2026-07-02T10:01:08.130944Z","steps":["trace[302479524] 'process raft request' (duration: 265.782978ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:01:08.131077Z","caller":"traceutil/trace.go:171","msg":"trace[871718332] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"262.784571ms","start":"2026-07-02T10:01:07.868258Z","end":"2026-07-02T10:01:08.131042Z","steps":["trace[871718332] 'process raft request' (duration: 262.527938ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:01:14.060756Z","caller":"traceutil/trace.go:171","msg":"trace[66860098] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:622; }","duration":"315.489556ms","start":"2026-07-02T10:01:13.745224Z","end":"2026-07-02T10:01:14.060713Z","steps":["trace[66860098] 'read index received' (duration: 315.353084ms)","trace[66860098] 'applied index is now lower than readState.Index' (duration: 135.002µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T10:01:14.060853Z","caller":"traceutil/trace.go:171","msg":"trace[402001773] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"322.439918ms","start":"2026-07-02T10:01:13.738365Z","end":"2026-07-02T10:01:14.060805Z","steps":["trace[402001773] 'process raft request' (duration: 322.126344ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:01:14.0611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:01:13.738335Z","time spent":"322.62005ms","remote":"127.0.0.1:36044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T10:01:14.061185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.92278ms","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-02T10:01:14.061287Z","caller":"traceutil/trace.go:171","msg":"trace[914111830] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:597; }","duration":"316.084203ms","start":"2026-07-02T10:01:13.74518Z","end":"2026-07-02T10:01:14.061264Z","steps":["trace[914111830] 'agreement among raft nodes before linearized reading' (duration: 315.691868ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:01:14.061345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:01:13.745167Z","time spent":"316.169784ms","remote":"127.0.0.1:36044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-02T10:01:14.561219Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11974401953796778503,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-02T10:01:14.6693Z","caller":"traceutil/trace.go:171","msg":"trace[505624103] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"906.140379ms","start":"2026-07-02T10:01:13.763132Z","end":"2026-07-02T10:01:14.669272Z","steps":["trace[505624103] 'process raft request' (duration: 905.900606ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:01:14.669415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:01:13.763104Z","time spent":"906.239291ms","remote":"127.0.0.1:36044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T10:01:14.670479Z","caller":"traceutil/trace.go:171","msg":"trace[1154512983] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"704.071796ms","start":"2026-07-02T10:01:13.966378Z","end":"2026-07-02T10:01:14.67045Z","steps":["trace[1154512983] 'process raft request' (duration: 703.055814ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:01:14.67066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:01:13.966345Z","time spent":"704.216607ms","remote":"127.0.0.1:35900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T10:01:14.683014Z","caller":"traceutil/trace.go:171","msg":"trace[97739326] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:623; }","duration":"622.096352ms","start":"2026-07-02T10:01:14.060856Z","end":"2026-07-02T10:01:14.682953Z","steps":["trace[97739326] 'read index received' (duration: 608.22345ms)","trace[97739326] 'applied index is now lower than readState.Index' (duration: 13.872292ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T10:01:14.683172Z","caller":"traceutil/trace.go:171","msg":"trace[218723097] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"615.893379ms","start":"2026-07-02T10:01:14.067252Z","end":"2026-07-02T10:01:14.683146Z","steps":["trace[218723097] 'process raft request' (duration: 615.532534ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:01:14.683308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.249995ms","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-02T10:01:14.683361Z","caller":"traceutil/trace.go:171","msg":"trace[1931579803] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:600; }","duration":"932.333606ms","start":"2026-07-02T10:01:13.751016Z","end":"2026-07-02T10:01:14.68335Z","steps":["trace[1931579803] 'agreement among raft nodes before linearized reading' (duration: 932.134514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:01:14.683389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:01:13.751005Z","time spent":"932.377006ms","remote":"127.0.0.1:36044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-02T10:01:14.683329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:01:14.06722Z","time spent":"616.015071ms","remote":"127.0.0.1:36044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T10:01:14.683575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.824406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-cwxj7\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-02T10:01:14.683601Z","caller":"traceutil/trace.go:171","msg":"trace[37835752] range","detail":"{range_begin:/registry/pods/kube-system/cilium-cwxj7; range_end:; response_count:1; response_revision:600; }","duration":"338.896437ms","start":"2026-07-02T10:01:14.344696Z","end":"2026-07-02T10:01:14.683592Z","steps":["trace[37835752] 'agreement among raft nodes before linearized reading' (duration: 338.785296ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:01:14.683613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.1686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T10:01:14.683619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:01:14.344665Z","time spent":"338.950427ms","remote":"127.0.0.1:35976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-cwxj7\" "} {"level":"info","ts":"2026-07-02T10:01:14.683638Z","caller":"traceutil/trace.go:171","msg":"trace[397888761] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:600; }","duration":"120.23393ms","start":"2026-07-02T10:01:14.563398Z","end":"2026-07-02T10:01:14.683631Z","steps":["trace[397888761] 'agreement among raft nodes before linearized reading' (duration: 120.19271ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:01:15.23507Z","caller":"traceutil/trace.go:171","msg":"trace[2048330786] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"538.491608ms","start":"2026-07-02T10:01:14.696478Z","end":"2026-07-02T10:01:15.23497Z","steps":["trace[2048330786] 'process raft request' (duration: 537.916061ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:01:15.235226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:01:14.696439Z","time spent":"538.700131ms","remote":"127.0.0.1:35976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T10:01:15.281369Z","caller":"traceutil/trace.go:171","msg":"trace[104695890] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:627; }","duration":"480.589966ms","start":"2026-07-02T10:01:14.800743Z","end":"2026-07-02T10:01:15.281333Z","steps":["trace[104695890] 'read index received' (duration: 433.792999ms)","trace[104695890] 'applied index is now lower than readState.Index' (duration: 46.794977ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T10:01:15.281469Z","caller":"traceutil/trace.go:171","msg":"trace[900150092] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"554.274571ms","start":"2026-07-02T10:01:14.727163Z","end":"2026-07-02T10:01:15.281438Z","steps":["trace[900150092] 'process raft request' (duration: 553.979588ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:01:15.282644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.117812ms","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-02T10:01:15.282766Z","caller":"traceutil/trace.go:171","msg":"trace[1220540137] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:603; }","duration":"482.071153ms","start":"2026-07-02T10:01:14.800669Z","end":"2026-07-02T10:01:15.28274Z","steps":["trace[1220540137] 'agreement among raft nodes before linearized reading' (duration: 480.799338ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:01:15.282848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:01:14.800643Z","time spent":"482.181444ms","remote":"127.0.0.1:36044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-02T10:01:15.282685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:01:14.727133Z","time spent":"555.484555ms","remote":"127.0.0.1:36246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T10:01:15.670066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.023239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T10:01:15.670138Z","caller":"traceutil/trace.go:171","msg":"trace[1177001787] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:604; }","duration":"107.159632ms","start":"2026-07-02T10:01:15.562963Z","end":"2026-07-02T10:01:15.670123Z","steps":["trace[1177001787] 'range keys from in-memory index tree' (duration: 106.952429ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:01:16.567187Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11974401953796778514,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T10:01:17.067399Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11974401953796778514,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T10:01:17.567696Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11974401953796778514,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T10:01:17.748461Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.045982595s","expected-duration":"1s"} {"level":"info","ts":"2026-07-02T10:01:17.749534Z","caller":"traceutil/trace.go:171","msg":"trace[1066045075] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"2.048428494s","start":"2026-07-02T10:01:15.70108Z","end":"2026-07-02T10:01:17.749508Z","steps":["trace[1066045075] 'process raft request' (duration: 2.047856507s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:01:17.749688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:01:15.701037Z","time spent":"2.048570755s","remote":"127.0.0.1:35900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T10:01:18.42306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.765874ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-02T10:01:18.423296Z","caller":"traceutil/trace.go:171","msg":"trace[362623899] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"612.258498ms","start":"2026-07-02T10:01:17.811027Z","end":"2026-07-02T10:01:18.423286Z","steps":["trace[362623899] 'process raft request' (duration: 612.120127ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:01:18.423366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:01:17.811006Z","time spent":"612.322059ms","remote":"127.0.0.1:36016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T10:01:18.423473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.356686367s","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-02T10:01:18.423514Z","caller":"traceutil/trace.go:171","msg":"trace[1482664283] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:606; }","duration":"2.356783669s","start":"2026-07-02T10:01:16.066719Z","end":"2026-07-02T10:01:18.423503Z","steps":["trace[1482664283] 'agreement among raft nodes before linearized reading' (duration: 2.356602937s)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:01:18.423284Z","caller":"traceutil/trace.go:171","msg":"trace[363241475] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:630; }","duration":"2.356478325s","start":"2026-07-02T10:01:16.066782Z","end":"2026-07-02T10:01:18.42326Z","steps":["trace[363241475] 'read index received' (duration: 1.681962176s)","trace[363241475] 'applied index is now lower than readState.Index' (duration: 674.513359ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T10:01:18.423548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:01:16.066694Z","time spent":"2.356846459s","remote":"127.0.0.1:36044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-02T10:01:18.423638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.860187343s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T10:01:18.423666Z","caller":"traceutil/trace.go:171","msg":"trace[63400043] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:606; }","duration":"1.860262663s","start":"2026-07-02T10:01:16.563397Z","end":"2026-07-02T10:01:18.42366Z","steps":["trace[63400043] 'agreement among raft nodes before linearized reading' (duration: 1.860222103s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:01:18.423684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:01:16.563376Z","time spent":"1.860303426s","remote":"127.0.0.1:35784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T10:01:18.423817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.732064034s","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-02T10:01:18.42384Z","caller":"traceutil/trace.go:171","msg":"trace[484188308] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:606; }","duration":"1.732123134s","start":"2026-07-02T10:01:16.691711Z","end":"2026-07-02T10:01:18.423834Z","steps":["trace[484188308] 'agreement among raft nodes before linearized reading' (duration: 1.732065104s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:01:18.423856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:01:16.691694Z","time spent":"1.732159074s","remote":"127.0.0.1:36044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-02T10:01:18.423986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.117616291s","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-02T10:01:18.424031Z","caller":"traceutil/trace.go:171","msg":"trace[1831347048] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:606; }","duration":"1.117700752s","start":"2026-07-02T10:01:17.30632Z","end":"2026-07-02T10:01:18.424021Z","steps":["trace[1831347048] 'agreement among raft nodes before linearized reading' (duration: 1.117597421s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:01:18.424064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:01:17.306296Z","time spent":"1.117760903s","remote":"127.0.0.1:36044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-02T10:01:18.42556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.714768151s","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-02T10:01:18.425728Z","caller":"traceutil/trace.go:171","msg":"trace[1553268963] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:606; }","duration":"1.715020224s","start":"2026-07-02T10:01:16.71068Z","end":"2026-07-02T10:01:18.4257Z","steps":["trace[1553268963] 'agreement among raft nodes before linearized reading' (duration: 1.713096912s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:01:18.426076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:01:16.71065Z","time spent":"1.715222417s","remote":"127.0.0.1:36044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-02T10:01:18.673721Z","caller":"traceutil/trace.go:171","msg":"trace[1658890979] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:637; }","duration":"201.000258ms","start":"2026-07-02T10:01:18.472693Z","end":"2026-07-02T10:01:18.673693Z","steps":["trace[1658890979] 'read index received' (duration: 200.232498ms)","trace[1658890979] 'applied index is now lower than readState.Index' (duration: 766.84µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T10:01:18.673768Z","caller":"traceutil/trace.go:171","msg":"trace[2026783237] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"203.889931ms","start":"2026-07-02T10:01:18.469837Z","end":"2026-07-02T10:01:18.673727Z","steps":["trace[2026783237] 'process raft request' (duration: 203.100941ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:01:18.67396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.22973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T10:01:18.674059Z","caller":"traceutil/trace.go:171","msg":"trace[549413779] range","detail":"{range_begin:/registry/limitranges/envoy-gateway-system/; range_end:/registry/limitranges/envoy-gateway-system0; response_count:0; response_revision:611; }","duration":"201.397622ms","start":"2026-07-02T10:01:18.472638Z","end":"2026-07-02T10:01:18.674036Z","steps":["trace[549413779] 'agreement among raft nodes before linearized reading' (duration: 201.16814ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:01:18.68016Z","caller":"traceutil/trace.go:171","msg":"trace[1700257002] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"199.018284ms","start":"2026-07-02T10:01:18.48112Z","end":"2026-07-02T10:01:18.680139Z","steps":["trace[1700257002] 'process raft request' (duration: 198.882913ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:01:30.747136Z","caller":"traceutil/trace.go:171","msg":"trace[186966786] linearizableReadLoop","detail":"{readStateIndex:778; appliedIndex:777; }","duration":"227.090885ms","start":"2026-07-02T10:01:30.520019Z","end":"2026-07-02T10:01:30.74711Z","steps":["trace[186966786] 'read index received' (duration: 226.981304ms)","trace[186966786] 'applied index is now lower than readState.Index' (duration: 108.571µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T10:01:30.74718Z","caller":"traceutil/trace.go:171","msg":"trace[1971758946] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"338.203015ms","start":"2026-07-02T10:01:30.40895Z","end":"2026-07-02T10:01:30.747153Z","steps":["trace[1971758946] 'process raft request' (duration: 338.012243ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:01:30.747389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.341667ms","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-02T10:01:30.747432Z","caller":"traceutil/trace.go:171","msg":"trace[1477700036] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:748; }","duration":"227.458108ms","start":"2026-07-02T10:01:30.519963Z","end":"2026-07-02T10:01:30.747421Z","steps":["trace[1477700036] 'agreement among raft nodes before linearized reading' (duration: 227.277946ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:01:30.747585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:01:30.408923Z","time spent":"338.312457ms","remote":"127.0.0.1:36044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T10:01:30.75363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.907607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T10:01:30.753674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.564156ms","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-02T10:01:30.753743Z","caller":"traceutil/trace.go:171","msg":"trace[372509538] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:749; }","duration":"191.08157ms","start":"2026-07-02T10:01:30.562646Z","end":"2026-07-02T10:01:30.753727Z","steps":["trace[372509538] 'agreement among raft nodes before linearized reading' (duration: 190.927247ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:01:30.753947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.908718ms","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-02T10:01:30.753985Z","caller":"traceutil/trace.go:171","msg":"trace[977079182] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:749; }","duration":"231.965419ms","start":"2026-07-02T10:01:30.52201Z","end":"2026-07-02T10:01:30.753976Z","steps":["trace[977079182] 'agreement among raft nodes before linearized reading' (duration: 231.839297ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:01:30.754055Z","caller":"traceutil/trace.go:171","msg":"trace[1755179435] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"183.564697ms","start":"2026-07-02T10:01:30.57045Z","end":"2026-07-02T10:01:30.754015Z","steps":["trace[1755179435] 'process raft request' (duration: 183.00679ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:01:30.753778Z","caller":"traceutil/trace.go:171","msg":"trace[1514685149] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:749; }","duration":"233.717347ms","start":"2026-07-02T10:01:30.520046Z","end":"2026-07-02T10:01:30.753764Z","steps":["trace[1514685149] 'agreement among raft nodes before linearized reading' (duration: 233.503144ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:01:30.754241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.563475ms","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-02T10:01:30.754292Z","caller":"traceutil/trace.go:171","msg":"trace[794756113] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:749; }","duration":"232.655066ms","start":"2026-07-02T10:01:30.521627Z","end":"2026-07-02T10:01:30.754282Z","steps":["trace[794756113] 'agreement among raft nodes before linearized reading' (duration: 232.454304ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:01:34.242327Z","caller":"traceutil/trace.go:171","msg":"trace[395477303] linearizableReadLoop","detail":"{readStateIndex:800; appliedIndex:799; }","duration":"324.427394ms","start":"2026-07-02T10:01:33.91788Z","end":"2026-07-02T10:01:34.242308Z","steps":["trace[395477303] 'read index received' (duration: 324.333753ms)","trace[395477303] 'applied index is now lower than readState.Index' (duration: 92.811µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T10:01:34.242542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.591416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T10:01:34.242472Z","caller":"traceutil/trace.go:171","msg":"trace[2052316367] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"645.663235ms","start":"2026-07-02T10:01:33.596766Z","end":"2026-07-02T10:01:34.242429Z","steps":["trace[2052316367] 'process raft request' (duration: 645.412452ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:01:34.242781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:01:33.596738Z","time spent":"645.919358ms","remote":"127.0.0.1:35976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T10:01:34.242605Z","caller":"traceutil/trace.go:171","msg":"trace[1164406993] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:768; }","duration":"324.752858ms","start":"2026-07-02T10:01:33.917825Z","end":"2026-07-02T10:01:34.242578Z","steps":["trace[1164406993] 'agreement among raft nodes before linearized reading' (duration: 324.568156ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:01:34.243639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:01:33.917807Z","time spent":"325.227724ms","remote":"127.0.0.1:35868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":76,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-07-02T10:01:48.544064Z","caller":"traceutil/trace.go:171","msg":"trace[88809301] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"494.675644ms","start":"2026-07-02T10:01:48.049361Z","end":"2026-07-02T10:01:48.544036Z","steps":["trace[88809301] 'process raft request' (duration: 494.484563ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:01:48.54425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:01:48.04934Z","time spent":"494.816086ms","remote":"127.0.0.1:35976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5472,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T10:02:37.395Z","caller":"traceutil/trace.go:171","msg":"trace[1871906698] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"111.958909ms","start":"2026-07-02T10:02:37.283007Z","end":"2026-07-02T10:02:37.394966Z","steps":["trace[1871906698] 'process raft request' (duration: 111.813877ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:02:39.544309Z","caller":"traceutil/trace.go:171","msg":"trace[424430791] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"140.672711ms","start":"2026-07-02T10:02:39.403592Z","end":"2026-07-02T10:02:39.544265Z","steps":["trace[424430791] 'process raft request' (duration: 140.435589ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:03:21.778652Z","caller":"traceutil/trace.go:171","msg":"trace[1870672011] linearizableReadLoop","detail":"{readStateIndex:1342; appliedIndex:1340; }","duration":"108.730692ms","start":"2026-07-02T10:03:21.669901Z","end":"2026-07-02T10:03:21.778631Z","steps":["trace[1870672011] 'read index received' (duration: 27.096249ms)","trace[1870672011] 'applied index is now lower than readState.Index' (duration: 81.633523ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T10:03:21.778686Z","caller":"traceutil/trace.go:171","msg":"trace[948421899] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"108.823494ms","start":"2026-07-02T10:03:21.669839Z","end":"2026-07-02T10:03:21.778662Z","steps":["trace[948421899] 'process raft request' (duration: 66.782123ms)","trace[948421899] 'compare' (duration: 41.929438ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T10:03:21.778857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.942917ms","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-02T10:03:21.77889Z","caller":"traceutil/trace.go:171","msg":"trace[1593993306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1284; }","duration":"109.005839ms","start":"2026-07-02T10:03:21.669873Z","end":"2026-07-02T10:03:21.778879Z","steps":["trace[1593993306] 'agreement among raft nodes before linearized reading' (duration: 108.855754ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:03:30.419338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.549723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-02T10:03:30.419359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.211451ms","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-02T10:03:30.419402Z","caller":"traceutil/trace.go:171","msg":"trace[38133301] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1315; }","duration":"397.653305ms","start":"2026-07-02T10:03:30.021735Z","end":"2026-07-02T10:03:30.419388Z","steps":["trace[38133301] 'count revisions from in-memory index tree' (duration: 397.515271ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:03:30.419433Z","caller":"traceutil/trace.go:171","msg":"trace[760494358] 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:1315; }","duration":"406.299323ms","start":"2026-07-02T10:03:30.013108Z","end":"2026-07-02T10:03:30.419407Z","steps":["trace[760494358] 'count revisions from in-memory index tree' (duration: 406.117659ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:03:30.419441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:03:30.02173Z","time spent":"397.703386ms","remote":"127.0.0.1:36060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-07-02T10:03:30.419506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:03:30.012938Z","time spent":"406.556841ms","remote":"127.0.0.1:56406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-07-02T10:03:30.41965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.568038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-02T10:03:30.419647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.779489ms","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-02T10:03:30.419817Z","caller":"traceutil/trace.go:171","msg":"trace[1169189152] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1315; }","duration":"578.984464ms","start":"2026-07-02T10:03:29.840749Z","end":"2026-07-02T10:03:30.419733Z","steps":["trace[1169189152] 'range keys from in-memory index tree' (duration: 578.625455ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:03:30.419829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.436293ms","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-02T10:03:30.419893Z","caller":"traceutil/trace.go:171","msg":"trace[1971225134] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1315; }","duration":"577.524286ms","start":"2026-07-02T10:03:29.842349Z","end":"2026-07-02T10:03:30.419873Z","steps":["trace[1971225134] 'range keys from in-memory index tree' (duration: 577.293959ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:03:30.419893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:03:29.840731Z","time spent":"579.141539ms","remote":"127.0.0.1:36044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-02T10:03:30.419678Z","caller":"traceutil/trace.go:171","msg":"trace[1825154817] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1315; }","duration":"404.619589ms","start":"2026-07-02T10:03:30.015051Z","end":"2026-07-02T10:03:30.419671Z","steps":["trace[1825154817] 'range keys from in-memory index tree' (duration: 404.459575ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:03:30.419935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:03:29.842333Z","time spent":"577.594338ms","remote":"127.0.0.1:36044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-02T10:03:30.420003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:03:30.015038Z","time spent":"404.919308ms","remote":"127.0.0.1:36044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-02T10:03:51.908297Z","caller":"traceutil/trace.go:171","msg":"trace[2122582530] transaction","detail":"{read_only:false; response_revision:1392; number_of_response:1; }","duration":"104.138361ms","start":"2026-07-02T10:03:51.804132Z","end":"2026-07-02T10:03:51.908271Z","steps":["trace[2122582530] 'process raft request' (duration: 103.757152ms)"],"step_count":1}