{"level":"warn","ts":"2026-10-13T09:44:10.459784Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-13T09:44:10.459875Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.80:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.80:2380","--initial-cluster=instance=https://162.253.55.80:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.80:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.80:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-13T09:44:10.459935Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-13T09:44:10.459943Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.80:2380"]} {"level":"info","ts":"2026-10-13T09:44:10.459961Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-13T09:44:10.461046Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.80:2379"]} {"level":"info","ts":"2026-10-13T09:44:10.461198Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.80:2380"],"listen-peer-urls":["https://162.253.55.80:2380"],"advertise-client-urls":["https://162.253.55.80:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.80:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.80:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-13T09:44:10.4668Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.40188ms"} {"level":"info","ts":"2026-10-13T09:44:10.47561Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a87e3d9cf178847b","cluster-id":"df2e96c7e71d56b9"} {"level":"info","ts":"2026-10-13T09:44:10.475713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a87e3d9cf178847b switched to configuration voters=()"} {"level":"info","ts":"2026-10-13T09:44:10.475743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a87e3d9cf178847b became follower at term 0"} {"level":"info","ts":"2026-10-13T09:44:10.475758Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a87e3d9cf178847b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-13T09:44:10.475773Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a87e3d9cf178847b became follower at term 1"} {"level":"info","ts":"2026-10-13T09:44:10.475812Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a87e3d9cf178847b switched to configuration voters=(12141209389712835707)"} {"level":"warn","ts":"2026-10-13T09:44:10.485601Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-13T09:44:10.488181Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-13T09:44:10.489586Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-13T09:44:10.491545Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a87e3d9cf178847b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-13T09:44:10.492132Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a87e3d9cf178847b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-13T09:44:10.498878Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a87e3d9cf178847b switched to configuration voters=(12141209389712835707)"} {"level":"info","ts":"2026-10-13T09:44:10.49898Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"df2e96c7e71d56b9","local-member-id":"a87e3d9cf178847b","added-peer-id":"a87e3d9cf178847b","added-peer-peer-urls":["https://162.253.55.80:2380"]} {"level":"info","ts":"2026-10-13T09:44:10.492257Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-13T09:44:10.499093Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-13T09:44:10.499101Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-13T09:44:10.5018Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-13T09:44:10.501943Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.80:2380"} {"level":"info","ts":"2026-10-13T09:44:10.501966Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.80:2380"} {"level":"info","ts":"2026-10-13T09:44:10.503006Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a87e3d9cf178847b","initial-advertise-peer-urls":["https://162.253.55.80:2380"],"listen-peer-urls":["https://162.253.55.80:2380"],"advertise-client-urls":["https://162.253.55.80:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.80:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-13T09:44:10.503038Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-13T09:44:11.176401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a87e3d9cf178847b is starting a new election at term 1"} {"level":"info","ts":"2026-10-13T09:44:11.176459Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a87e3d9cf178847b became pre-candidate at term 1"} {"level":"info","ts":"2026-10-13T09:44:11.176489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a87e3d9cf178847b received MsgPreVoteResp from a87e3d9cf178847b at term 1"} {"level":"info","ts":"2026-10-13T09:44:11.176503Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a87e3d9cf178847b became candidate at term 2"} {"level":"info","ts":"2026-10-13T09:44:11.17651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a87e3d9cf178847b received MsgVoteResp from a87e3d9cf178847b at term 2"} {"level":"info","ts":"2026-10-13T09:44:11.176521Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a87e3d9cf178847b became leader at term 2"} {"level":"info","ts":"2026-10-13T09:44:11.176533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a87e3d9cf178847b elected leader a87e3d9cf178847b at term 2"} {"level":"info","ts":"2026-10-13T09:44:11.178454Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a87e3d9cf178847b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.80:2379]}","request-path":"/0/members/a87e3d9cf178847b/attributes","cluster-id":"df2e96c7e71d56b9","publish-timeout":"7s"} {"level":"info","ts":"2026-10-13T09:44:11.178486Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-13T09:44:11.178706Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-13T09:44:11.178883Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T09:44:11.178941Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-13T09:44:11.179028Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-13T09:44:11.179951Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"df2e96c7e71d56b9","local-member-id":"a87e3d9cf178847b","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T09:44:11.180077Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T09:44:11.180118Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T09:44:11.180511Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-13T09:44:11.181207Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.80:2379"} {"level":"warn","ts":"2026-10-13T09:44:12.833295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.934475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-13T09:44:12.833622Z","caller":"traceutil/trace.go:171","msg":"trace[984428058] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:2; }","duration":"251.296685ms","start":"2026-10-13T09:44:12.58228Z","end":"2026-10-13T09:44:12.833577Z","steps":["trace[984428058] 'range keys from in-memory index tree' (duration: 246.005317ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T09:44:12.834178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.06232ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-10-13T09:44:12.834311Z","caller":"traceutil/trace.go:171","msg":"trace[932105841] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:5; }","duration":"248.086394ms","start":"2026-10-13T09:44:12.586178Z","end":"2026-10-13T09:44:12.834264Z","steps":["trace[932105841] 'read index received' (duration: 103.652064ms)","trace[932105841] 'applied index is now lower than readState.Index' (duration: 144.43283ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T09:44:12.834406Z","caller":"traceutil/trace.go:171","msg":"trace[787193448] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"252.038911ms","start":"2026-10-13T09:44:12.582354Z","end":"2026-10-13T09:44:12.834393Z","steps":["trace[787193448] 'process raft request' (duration: 107.501588ms)","trace[787193448] 'compare' (duration: 142.302513ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T09:44:12.835049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.237336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-13T09:44:12.835103Z","caller":"traceutil/trace.go:171","msg":"trace[1419565596] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:3; }","duration":"252.301737ms","start":"2026-10-13T09:44:12.582788Z","end":"2026-10-13T09:44:12.835089Z","steps":["trace[1419565596] 'agreement among raft nodes before linearized reading' (duration: 251.95898ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T09:44:12.835321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.857989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-13T09:44:12.835368Z","caller":"traceutil/trace.go:171","msg":"trace[258914054] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:3; }","duration":"252.90026ms","start":"2026-10-13T09:44:12.582448Z","end":"2026-10-13T09:44:12.835348Z","steps":["trace[258914054] 'agreement among raft nodes before linearized reading' (duration: 252.840569ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T09:44:12.835578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.253428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-13T09:44:12.835622Z","caller":"traceutil/trace.go:171","msg":"trace[692000673] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:3; }","duration":"253.296429ms","start":"2026-10-13T09:44:12.582309Z","end":"2026-10-13T09:44:12.835605Z","steps":["trace[692000673] 'agreement among raft nodes before linearized reading' (duration: 253.234457ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T09:44:13.222107Z","caller":"traceutil/trace.go:171","msg":"trace[1700737550] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"558.505352ms","start":"2026-10-13T09:44:12.663575Z","end":"2026-10-13T09:44:13.222081Z","steps":["trace[1700737550] 'process raft request' (duration: 558.346989ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T09:44:13.222199Z","caller":"traceutil/trace.go:171","msg":"trace[1077288898] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"558.111604ms","start":"2026-10-13T09:44:12.664049Z","end":"2026-10-13T09:44:13.222161Z","steps":["trace[1077288898] 'process raft request' (duration: 557.992911ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T09:44:13.222214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T09:44:12.663566Z","time spent":"558.607534ms","remote":"127.0.0.1:52532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-13T09:44:13.222275Z","caller":"traceutil/trace.go:171","msg":"trace[194259835] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"543.284614ms","start":"2026-10-13T09:44:12.678974Z","end":"2026-10-13T09:44:13.222259Z","steps":["trace[194259835] 'process raft request' (duration: 543.237683ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T09:44:13.222321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T09:44:12.664041Z","time spent":"558.230666ms","remote":"127.0.0.1:52532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-13T09:44:13.22235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T09:44:12.678958Z","time spent":"543.360997ms","remote":"127.0.0.1:52160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-13T09:44:13.222369Z","caller":"traceutil/trace.go:171","msg":"trace[1563194000] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"558.157755ms","start":"2026-10-13T09:44:12.664181Z","end":"2026-10-13T09:44:13.222339Z","steps":["trace[1563194000] 'process raft request' (duration: 557.983481ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T09:44:13.222419Z","caller":"traceutil/trace.go:171","msg":"trace[1485711957] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"558.35892ms","start":"2026-10-13T09:44:12.664054Z","end":"2026-10-13T09:44:13.222413Z","steps":["trace[1485711957] 'process raft request' (duration: 558.011132ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T09:44:13.22243Z","caller":"traceutil/trace.go:171","msg":"trace[235613865] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"544.037751ms","start":"2026-10-13T09:44:12.678385Z","end":"2026-10-13T09:44:13.222423Z","steps":["trace[235613865] 'process raft request' (duration: 543.798856ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T09:44:13.222443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T09:44:12.66403Z","time spent":"558.40109ms","remote":"127.0.0.1:52440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-13T09:44:13.222465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T09:44:12.678368Z","time spent":"544.077702ms","remote":"127.0.0.1:52266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-13T09:44:13.222468Z","caller":"traceutil/trace.go:171","msg":"trace[1229960432] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"558.34532ms","start":"2026-10-13T09:44:12.664114Z","end":"2026-10-13T09:44:13.22246Z","steps":["trace[1229960432] 'process raft request' (duration: 558.002572ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T09:44:13.222511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T09:44:12.66411Z","time spent":"558.38099ms","remote":"127.0.0.1:52532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-13T09:44:13.222437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T09:44:12.664173Z","time spent":"558.231446ms","remote":"127.0.0.1:52532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-13T09:44:13.22247Z","caller":"traceutil/trace.go:171","msg":"trace[407786725] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"558.335469ms","start":"2026-10-13T09:44:12.664007Z","end":"2026-10-13T09:44:13.222342Z","steps":["trace[407786725] 'process raft request' (duration: 558.007902ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T09:44:13.222698Z","caller":"traceutil/trace.go:171","msg":"trace[1104297240] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:14; }","duration":"388.3271ms","start":"2026-10-13T09:44:12.834349Z","end":"2026-10-13T09:44:13.222676Z","steps":["trace[1104297240] 'read index received' (duration: 387.466641ms)","trace[1104297240] 'applied index is now lower than readState.Index' (duration: 858.649µs)"],"step_count":2} {"level":"warn","ts":"2026-10-13T09:44:13.222707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T09:44:12.663999Z","time spent":"558.610555ms","remote":"127.0.0.1:52532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-13T09:44:13.222892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.581917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-13T09:44:13.222931Z","caller":"traceutil/trace.go:171","msg":"trace[2113034742] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"559.667079ms","start":"2026-10-13T09:44:12.663253Z","end":"2026-10-13T09:44:13.22292Z","steps":["trace[2113034742] 'agreement among raft nodes before linearized reading' (duration: 559.504234ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T09:44:13.222958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T09:44:12.663238Z","time spent":"559.71396ms","remote":"127.0.0.1:52100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-10-13T09:44:13.223215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.410778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.80\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-13T09:44:13.223249Z","caller":"traceutil/trace.go:171","msg":"trace[1030015268] range","detail":"{range_begin:/registry/masterleases/162.253.55.80; range_end:; response_count:0; response_revision:11; }","duration":"561.47312ms","start":"2026-10-13T09:44:12.661767Z","end":"2026-10-13T09:44:13.22324Z","steps":["trace[1030015268] 'agreement among raft nodes before linearized reading' (duration: 561.423499ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T09:44:13.223272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T09:44:12.661724Z","time spent":"561.542651ms","remote":"127.0.0.1:52002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/162.253.55.80\" "} {"level":"warn","ts":"2026-10-13T09:44:13.223547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.45521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-13T09:44:13.224236Z","caller":"traceutil/trace.go:171","msg":"trace[116757542] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:11; }","duration":"495.189146ms","start":"2026-10-13T09:44:12.729031Z","end":"2026-10-13T09:44:13.22422Z","steps":["trace[116757542] 'agreement among raft nodes before linearized reading' (duration: 494.46512ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T09:44:13.224287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T09:44:12.729003Z","time spent":"495.269817ms","remote":"127.0.0.1:52100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/default\" "} {"level":"warn","ts":"2026-10-13T09:44:13.223597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.915472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-13T09:44:13.224511Z","caller":"traceutil/trace.go:171","msg":"trace[786578165] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"559.839783ms","start":"2026-10-13T09:44:12.664654Z","end":"2026-10-13T09:44:13.224494Z","steps":["trace[786578165] 'agreement among raft nodes before linearized reading' (duration: 558.042073ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T09:44:13.224571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T09:44:12.664649Z","time spent":"559.900634ms","remote":"127.0.0.1:52060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-10-13T09:44:13.223631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.826019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-13T09:44:13.224804Z","caller":"traceutil/trace.go:171","msg":"trace[398906440] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"510.024706ms","start":"2026-10-13T09:44:12.714766Z","end":"2026-10-13T09:44:13.224791Z","steps":["trace[398906440] 'agreement among raft nodes before linearized reading' (duration: 508.84815ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T09:44:13.224847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T09:44:12.714751Z","time spent":"510.083917ms","remote":"127.0.0.1:52100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-10-13T09:44:13.223662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.717914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-13T09:44:13.225112Z","caller":"traceutil/trace.go:171","msg":"trace[906283413] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"374.252257ms","start":"2026-10-13T09:44:12.850845Z","end":"2026-10-13T09:44:13.225098Z","steps":["trace[906283413] 'agreement among raft nodes before linearized reading' (duration: 372.788535ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T09:44:13.225198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T09:44:12.850828Z","time spent":"374.347829ms","remote":"127.0.0.1:52396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-10-13T09:44:13.223694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.383014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-13T09:44:13.225421Z","caller":"traceutil/trace.go:171","msg":"trace[1014483568] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"332.126881ms","start":"2026-10-13T09:44:12.893282Z","end":"2026-10-13T09:44:13.225409Z","steps":["trace[1014483568] 'agreement among raft nodes before linearized reading' (duration: 330.398303ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T09:44:13.225466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T09:44:12.893263Z","time spent":"332.193483ms","remote":"127.0.0.1:52100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-10-13T09:44:13.2237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.322011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-13T09:44:13.225573Z","caller":"traceutil/trace.go:171","msg":"trace[976707845] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"332.202232ms","start":"2026-10-13T09:44:12.893362Z","end":"2026-10-13T09:44:13.225565Z","steps":["trace[976707845] 'agreement among raft nodes before linearized reading' (duration: 330.294941ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T09:44:13.225596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T09:44:12.893338Z","time spent":"332.251304ms","remote":"127.0.0.1:52100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-10-13T09:44:13.223739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.49665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-13T09:44:13.225643Z","caller":"traceutil/trace.go:171","msg":"trace[1504144081] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"595.421074ms","start":"2026-10-13T09:44:12.630207Z","end":"2026-10-13T09:44:13.225628Z","steps":["trace[1504144081] 'agreement among raft nodes before linearized reading' (duration: 593.505421ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T09:44:13.22571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T09:44:12.630192Z","time spent":"595.504965ms","remote":"127.0.0.1:52100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-10-13T09:44:54.358487Z","caller":"traceutil/trace.go:171","msg":"trace[401736289] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"121.589913ms","start":"2026-10-13T09:44:54.236875Z","end":"2026-10-13T09:44:54.358465Z","steps":["trace[401736289] 'process raft request' (duration: 121.203923ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T09:45:00.7495Z","caller":"traceutil/trace.go:171","msg":"trace[1734583354] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"224.560591ms","start":"2026-10-13T09:45:00.524917Z","end":"2026-10-13T09:45:00.749478Z","steps":["trace[1734583354] 'process raft request' (duration: 223.835195ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T09:45:01.360335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.988361ms","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-10-13T09:45:01.360427Z","caller":"traceutil/trace.go:171","msg":"trace[1578975776] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:643; }","duration":"117.162375ms","start":"2026-10-13T09:45:01.243244Z","end":"2026-10-13T09:45:01.360407Z","steps":["trace[1578975776] 'range keys from in-memory index tree' (duration: 116.741266ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T09:45:01.952966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.105656ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-13T09:45:01.953133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.382106ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-13T09:45:01.953161Z","caller":"traceutil/trace.go:171","msg":"trace[1880454046] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:644; }","duration":"420.310651ms","start":"2026-10-13T09:45:01.532828Z","end":"2026-10-13T09:45:01.953138Z","steps":["trace[1880454046] 'range keys from in-memory index tree' (duration: 420.089376ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T09:45:01.953346Z","caller":"traceutil/trace.go:171","msg":"trace[522154208] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:671; }","duration":"534.029573ms","start":"2026-10-13T09:45:01.419299Z","end":"2026-10-13T09:45:01.953329Z","steps":["trace[522154208] 'read index received' (duration: 395.351901ms)","trace[522154208] 'applied index is now lower than readState.Index' (duration: 138.676672ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T09:45:01.953406Z","caller":"traceutil/trace.go:171","msg":"trace[680642541] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"588.083585ms","start":"2026-10-13T09:45:01.365257Z","end":"2026-10-13T09:45:01.953341Z","steps":["trace[680642541] 'process raft request' (duration: 449.379323ms)","trace[680642541] 'compare' (duration: 138.216242ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T09:45:01.953449Z","caller":"traceutil/trace.go:171","msg":"trace[1709554505] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"519.750622ms","start":"2026-10-13T09:45:01.433685Z","end":"2026-10-13T09:45:01.953435Z","steps":["trace[1709554505] 'process raft request' (duration: 519.598269ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T09:45:01.953517Z","caller":"traceutil/trace.go:171","msg":"trace[1264907604] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"587.101784ms","start":"2026-10-13T09:45:01.366397Z","end":"2026-10-13T09:45:01.953499Z","steps":["trace[1264907604] 'process raft request' (duration: 586.817207ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T09:45:01.953526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T09:45:01.433663Z","time spent":"519.823655ms","remote":"127.0.0.1:52248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-13T09:45:01.953565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.567042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-13T09:45:01.9536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T09:45:01.366359Z","time spent":"587.196706ms","remote":"127.0.0.1:52282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T09:45:01.953604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T09:45:01.365206Z","time spent":"588.29361ms","remote":"127.0.0.1:52282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T09:45:01.953607Z","caller":"traceutil/trace.go:171","msg":"trace[597756767] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:647; }","duration":"176.652564ms","start":"2026-10-13T09:45:01.776941Z","end":"2026-10-13T09:45:01.953594Z","steps":["trace[597756767] 'agreement among raft nodes before linearized reading' (duration: 176.563772ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T09:45:01.954144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.843151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-7n5rn\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-10-13T09:45:01.954194Z","caller":"traceutil/trace.go:171","msg":"trace[873477567] range","detail":"{range_begin:/registry/pods/kube-system/cilium-7n5rn; range_end:; response_count:1; response_revision:647; }","duration":"534.966664ms","start":"2026-10-13T09:45:01.419215Z","end":"2026-10-13T09:45:01.954182Z","steps":["trace[873477567] 'agreement among raft nodes before linearized reading' (duration: 534.472383ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T09:45:01.954222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T09:45:01.41919Z","time spent":"535.024215ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-7n5rn\" "} {"level":"warn","ts":"2026-10-13T09:45:17.90733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.792767ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-13T09:45:17.907563Z","caller":"traceutil/trace.go:171","msg":"trace[1792207096] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"387.208169ms","start":"2026-10-13T09:45:17.520338Z","end":"2026-10-13T09:45:17.907546Z","steps":["trace[1792207096] 'process raft request' (duration: 387.143767ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T09:45:17.907588Z","caller":"traceutil/trace.go:171","msg":"trace[673219322] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"388.414326ms","start":"2026-10-13T09:45:17.519136Z","end":"2026-10-13T09:45:17.90755Z","steps":["trace[673219322] 'process raft request' (duration: 47.326902ms)","trace[673219322] 'compare' (duration: 340.628704ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T09:45:17.907625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T09:45:17.520321Z","time spent":"387.27485ms","remote":"127.0.0.1:52282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T09:45:17.907774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T09:45:17.519074Z","time spent":"388.60704ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T09:45:57.864122Z","caller":"traceutil/trace.go:171","msg":"trace[207658536] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"279.296968ms","start":"2026-10-13T09:45:57.584778Z","end":"2026-10-13T09:45:57.864075Z","steps":["trace[207658536] 'process raft request' (duration: 279.160155ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T09:46:15.026476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.841673ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-13T09:46:15.026604Z","caller":"traceutil/trace.go:171","msg":"trace[138077975] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"178.668612ms","start":"2026-10-13T09:46:14.847916Z","end":"2026-10-13T09:46:15.026584Z","steps":["trace[138077975] 'process raft request' (duration: 39.634424ms)","trace[138077975] 'compare' (duration: 138.729631ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T09:46:16.190557Z","caller":"traceutil/trace.go:171","msg":"trace[1590769190] linearizableReadLoop","detail":"{readStateIndex:1218; appliedIndex:1217; }","duration":"212.892265ms","start":"2026-10-13T09:46:15.977644Z","end":"2026-10-13T09:46:16.190536Z","steps":["trace[1590769190] 'read index received' (duration: 212.69409ms)","trace[1590769190] 'applied index is now lower than readState.Index' (duration: 197.395µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T09:46:16.190674Z","caller":"traceutil/trace.go:171","msg":"trace[1020283446] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"226.000157ms","start":"2026-10-13T09:46:15.964648Z","end":"2026-10-13T09:46:16.190648Z","steps":["trace[1020283446] 'process raft request' (duration: 225.764382ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T09:46:16.190763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.09064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-13T09:46:16.190799Z","caller":"traceutil/trace.go:171","msg":"trace[1414341949] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1170; }","duration":"213.153171ms","start":"2026-10-13T09:46:15.977637Z","end":"2026-10-13T09:46:16.19079Z","steps":["trace[1414341949] 'agreement among raft nodes before linearized reading' (duration: 212.977937ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T09:46:16.952981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.480803ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-13T09:46:16.95313Z","caller":"traceutil/trace.go:171","msg":"trace[94724804] linearizableReadLoop","detail":"{readStateIndex:1227; appliedIndex:1226; }","duration":"175.174084ms","start":"2026-10-13T09:46:16.777938Z","end":"2026-10-13T09:46:16.953112Z","steps":["trace[94724804] 'read index received' (duration: 41.551µs)","trace[94724804] 'applied index is now lower than readState.Index' (duration: 175.131243ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T09:46:16.95319Z","caller":"traceutil/trace.go:171","msg":"trace[614045675] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"332.732085ms","start":"2026-10-13T09:46:16.620425Z","end":"2026-10-13T09:46:16.953157Z","steps":["trace[614045675] 'process raft request' (duration: 155.992746ms)","trace[614045675] 'compare' (duration: 176.343641ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T09:46:16.953232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.267566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T09:46:16.953289Z","caller":"traceutil/trace.go:171","msg":"trace[258934078] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1179; }","duration":"175.374168ms","start":"2026-10-13T09:46:16.777899Z","end":"2026-10-13T09:46:16.953273Z","steps":["trace[258934078] 'agreement among raft nodes before linearized reading' (duration: 175.255425ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T09:46:16.953336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T09:46:16.620404Z","time spent":"332.845868ms","remote":"127.0.0.1:52282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T09:46:17.001682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.083177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-13T09:46:17.001728Z","caller":"traceutil/trace.go:171","msg":"trace[1484378167] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1180; }","duration":"146.167268ms","start":"2026-10-13T09:46:16.85555Z","end":"2026-10-13T09:46:17.001718Z","steps":["trace[1484378167] 'agreement among raft nodes before linearized reading' (duration: 146.020465ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T09:46:17.001716Z","caller":"traceutil/trace.go:171","msg":"trace[1649841173] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"154.966675ms","start":"2026-10-13T09:46:16.846716Z","end":"2026-10-13T09:46:17.001682Z","steps":["trace[1649841173] 'process raft request' (duration: 154.634056ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T09:46:17.001882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.273223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-13T09:46:17.0019Z","caller":"traceutil/trace.go:171","msg":"trace[1691560927] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1180; }","duration":"143.329574ms","start":"2026-10-13T09:46:16.858567Z","end":"2026-10-13T09:46:17.001897Z","steps":["trace[1691560927] 'agreement among raft nodes before linearized reading' (duration: 143.280213ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T09:46:29.398155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.459415ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-13T09:46:29.398273Z","caller":"traceutil/trace.go:171","msg":"trace[360898751] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"315.013301ms","start":"2026-10-13T09:46:29.083232Z","end":"2026-10-13T09:46:29.398246Z","steps":["trace[360898751] 'process raft request' (duration: 98.372602ms)","trace[360898751] 'compare' (duration: 216.290402ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T09:46:29.398361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T09:46:29.083221Z","time spent":"315.097224ms","remote":"127.0.0.1:52282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T09:50:32.32074Z","caller":"traceutil/trace.go:171","msg":"trace[449609903] transaction","detail":"{read_only:false; response_revision:2377; number_of_response:1; }","duration":"169.070632ms","start":"2026-10-13T09:50:32.151644Z","end":"2026-10-13T09:50:32.320715Z","steps":["trace[449609903] 'process raft request' (duration: 168.928249ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T09:51:03.984714Z","caller":"traceutil/trace.go:171","msg":"trace[1392462989] transaction","detail":"{read_only:false; response_revision:2548; number_of_response:1; }","duration":"122.105693ms","start":"2026-10-13T09:51:03.862585Z","end":"2026-10-13T09:51:03.984691Z","steps":["trace[1392462989] 'process raft request' (duration: 121.95854ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T09:51:04.276422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.203999ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-13T09:51:04.276532Z","caller":"traceutil/trace.go:171","msg":"trace[209808547] linearizableReadLoop","detail":"{readStateIndex:2655; appliedIndex:2654; }","duration":"236.808513ms","start":"2026-10-13T09:51:04.039705Z","end":"2026-10-13T09:51:04.276513Z","steps":["trace[209808547] 'read index received' (duration: 65.445579ms)","trace[209808547] 'applied index is now lower than readState.Index' (duration: 171.361544ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T09:51:04.276771Z","caller":"traceutil/trace.go:171","msg":"trace[297597475] transaction","detail":"{read_only:false; response_revision:2549; number_of_response:1; }","duration":"413.391862ms","start":"2026-10-13T09:51:03.863366Z","end":"2026-10-13T09:51:04.276757Z","steps":["trace[297597475] 'process raft request' (duration: 241.773543ms)","trace[297597475] 'compare' (duration: 171.112097ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T09:51:04.276843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T09:51:03.86335Z","time spent":"413.455023ms","remote":"127.0.0.1:52282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T09:51:04.277444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.710113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-13T09:51:04.277624Z","caller":"traceutil/trace.go:171","msg":"trace[1460012519] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2549; }","duration":"237.854956ms","start":"2026-10-13T09:51:04.039664Z","end":"2026-10-13T09:51:04.277519Z","steps":["trace[1460012519] 'agreement among raft nodes before linearized reading' (duration: 237.536479ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T09:51:04.415342Z","caller":"traceutil/trace.go:171","msg":"trace[942496825] linearizableReadLoop","detail":"{readStateIndex:2656; appliedIndex:2655; }","duration":"138.756765ms","start":"2026-10-13T09:51:04.276555Z","end":"2026-10-13T09:51:04.415312Z","steps":["trace[942496825] 'read index received' (duration: 128.028955ms)","trace[942496825] 'applied index is now lower than readState.Index' (duration: 10.72663ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T09:51:04.415448Z","caller":"traceutil/trace.go:171","msg":"trace[1733443805] transaction","detail":"{read_only:false; response_revision:2550; number_of_response:1; }","duration":"328.031968ms","start":"2026-10-13T09:51:04.087332Z","end":"2026-10-13T09:51:04.415364Z","steps":["trace[1733443805] 'process raft request' (duration: 317.034912ms)","trace[1733443805] 'compare' (duration: 10.808282ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T09:51:04.415574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.658493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-13T09:51:04.415576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.013138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T09:51:04.415638Z","caller":"traceutil/trace.go:171","msg":"trace[1030116472] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:2550; }","duration":"372.763236ms","start":"2026-10-13T09:51:04.042858Z","end":"2026-10-13T09:51:04.415622Z","steps":["trace[1030116472] 'agreement among raft nodes before linearized reading' (duration: 372.635943ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T09:51:04.415645Z","caller":"traceutil/trace.go:171","msg":"trace[1205314821] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:2550; }","duration":"337.125481ms","start":"2026-10-13T09:51:04.078502Z","end":"2026-10-13T09:51:04.415628Z","steps":["trace[1205314821] 'agreement among raft nodes before linearized reading' (duration: 336.958487ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T09:51:04.415689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T09:51:04.078492Z","time spent":"337.183202ms","remote":"127.0.0.1:38964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-10-13T09:51:04.415687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T09:51:04.042842Z","time spent":"372.832957ms","remote":"127.0.0.1:52134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-10-13T09:51:04.416603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T09:51:04.087288Z","time spent":"328.525129ms","remote":"127.0.0.1:52282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T09:51:04.485584Z","caller":"traceutil/trace.go:171","msg":"trace[1931909753] transaction","detail":"{read_only:false; response_revision:2551; number_of_response:1; }","duration":"203.707324ms","start":"2026-10-13T09:51:04.281847Z","end":"2026-10-13T09:51:04.485555Z","steps":["trace[1931909753] 'process raft request' (duration: 203.547811ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T09:51:04.485868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.668097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.80\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-10-13T09:51:04.486038Z","caller":"traceutil/trace.go:171","msg":"trace[1889994907] range","detail":"{range_begin:/registry/masterleases/162.253.55.80; range_end:; response_count:1; response_revision:2551; }","duration":"132.029325ms","start":"2026-10-13T09:51:04.35399Z","end":"2026-10-13T09:51:04.486019Z","steps":["trace[1889994907] 'agreement among raft nodes before linearized reading' (duration: 131.566904ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T09:51:04.757388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.171027ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-10-13T09:51:04.757516Z","caller":"traceutil/trace.go:171","msg":"trace[1414667520] linearizableReadLoop","detail":"{readStateIndex:2658; appliedIndex:2657; }","duration":"163.16871ms","start":"2026-10-13T09:51:04.594325Z","end":"2026-10-13T09:51:04.757494Z","steps":["trace[1414667520] 'read index received' (duration: 45.361µs)","trace[1414667520] 'applied index is now lower than readState.Index' (duration: 163.121639ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T09:51:04.757887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.558878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-13T09:51:04.757989Z","caller":"traceutil/trace.go:171","msg":"trace[1781122693] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2551; }","duration":"163.697481ms","start":"2026-10-13T09:51:04.594261Z","end":"2026-10-13T09:51:04.757958Z","steps":["trace[1781122693] 'agreement among raft nodes before linearized reading' (duration: 163.438175ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T09:51:05.243982Z","caller":"traceutil/trace.go:171","msg":"trace[1999450026] transaction","detail":"{read_only:false; response_revision:2552; number_of_response:1; }","duration":"485.434448ms","start":"2026-10-13T09:51:04.758514Z","end":"2026-10-13T09:51:05.243948Z","steps":["trace[1999450026] 'process raft request' (duration: 427.373223ms)","trace[1999450026] 'compare' (duration: 57.8229ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T09:51:05.244082Z","caller":"traceutil/trace.go:171","msg":"trace[1144188688] linearizableReadLoop","detail":"{readStateIndex:2659; appliedIndex:2658; }","duration":"485.239914ms","start":"2026-10-13T09:51:04.75883Z","end":"2026-10-13T09:51:05.244069Z","steps":["trace[1144188688] 'read index received' (duration: 427.110337ms)","trace[1144188688] 'applied index is now lower than readState.Index' (duration: 58.128317ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T09:51:05.244158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T09:51:04.758494Z","time spent":"485.584601ms","remote":"127.0.0.1:52002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T09:51:05.244028Z","caller":"traceutil/trace.go:171","msg":"trace[228232157] transaction","detail":"{read_only:false; response_revision:2553; number_of_response:1; }","duration":"481.941511ms","start":"2026-10-13T09:51:04.762065Z","end":"2026-10-13T09:51:05.244007Z","steps":["trace[228232157] 'process raft request' (duration: 481.829298ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T09:51:05.244436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T09:51:04.76205Z","time spent":"482.338909ms","remote":"127.0.0.1:52282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T09:51:05.244594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.751785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-10-13T09:51:05.244663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.396997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T09:51:05.244724Z","caller":"traceutil/trace.go:171","msg":"trace[858892284] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2553; }","duration":"467.495219ms","start":"2026-10-13T09:51:04.777215Z","end":"2026-10-13T09:51:05.24471Z","steps":["trace[858892284] 'agreement among raft nodes before linearized reading' (duration: 467.395567ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T09:51:05.244749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T09:51:04.777198Z","time spent":"467.54612ms","remote":"127.0.0.1:51972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-13T09:51:05.244652Z","caller":"traceutil/trace.go:171","msg":"trace[1208426797] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2553; }","duration":"485.856618ms","start":"2026-10-13T09:51:04.75878Z","end":"2026-10-13T09:51:05.244637Z","steps":["trace[1208426797] 'agreement among raft nodes before linearized reading' (duration: 485.623182ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T09:51:05.244872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.639067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-10-13T09:51:05.244899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T09:51:04.758764Z","time spent":"486.115653ms","remote":"127.0.0.1:52282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-13T09:51:05.244949Z","caller":"traceutil/trace.go:171","msg":"trace[1389933483] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2553; }","duration":"110.725039ms","start":"2026-10-13T09:51:05.13418Z","end":"2026-10-13T09:51:05.244905Z","steps":["trace[1389933483] 'agreement among raft nodes before linearized reading' (duration: 110.590966ms)"],"step_count":1}