{"level":"warn","ts":"2026-05-05T02:29:09.694053Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T02:29:09.694183Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.15: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.15:2380","--initial-cluster=instance=https://199.19.213.15:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.15:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.15: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-05-05T02:29:09.694291Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T02:29:09.694309Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.15:2380"]} {"level":"info","ts":"2026-05-05T02:29:09.694355Z","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-05-05T02:29:09.695171Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.15:2379"]} {"level":"info","ts":"2026-05-05T02:29:09.695277Z","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.15:2380"],"listen-peer-urls":["https://199.19.213.15:2380"],"advertise-client-urls":["https://199.19.213.15:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.15:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.15: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-05-05T02:29:09.69949Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.930648ms"} {"level":"info","ts":"2026-05-05T02:29:09.712607Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"11371113779d2d76","cluster-id":"1921549e667ce7d9"} {"level":"info","ts":"2026-05-05T02:29:09.713024Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11371113779d2d76 switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T02:29:09.713072Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11371113779d2d76 became follower at term 0"} {"level":"info","ts":"2026-05-05T02:29:09.713093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 11371113779d2d76 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T02:29:09.713117Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11371113779d2d76 became follower at term 1"} {"level":"info","ts":"2026-05-05T02:29:09.713168Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11371113779d2d76 switched to configuration voters=(1240478997672701302)"} {"level":"warn","ts":"2026-05-05T02:29:09.720058Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T02:29:09.721205Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T02:29:09.722262Z","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-05-05T02:29:09.724044Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"11371113779d2d76","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T02:29:09.724334Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"11371113779d2d76","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T02:29:09.72442Z","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-05-05T02:29:09.72448Z","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-05-05T02:29:09.72449Z","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-05-05T02:29:09.725614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11371113779d2d76 switched to configuration voters=(1240478997672701302)"} {"level":"info","ts":"2026-05-05T02:29:09.725818Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"1921549e667ce7d9","local-member-id":"11371113779d2d76","added-peer-id":"11371113779d2d76","added-peer-peer-urls":["https://199.19.213.15:2380"]} {"level":"info","ts":"2026-05-05T02:29:09.728169Z","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-05-05T02:29:09.72842Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"11371113779d2d76","initial-advertise-peer-urls":["https://199.19.213.15:2380"],"listen-peer-urls":["https://199.19.213.15:2380"],"advertise-client-urls":["https://199.19.213.15:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.15:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T02:29:09.728459Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T02:29:09.728731Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.15:2380"} {"level":"info","ts":"2026-05-05T02:29:09.728757Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.15:2380"} {"level":"info","ts":"2026-05-05T02:29:10.413796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11371113779d2d76 is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T02:29:10.41387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11371113779d2d76 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T02:29:10.41391Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11371113779d2d76 received MsgPreVoteResp from 11371113779d2d76 at term 1"} {"level":"info","ts":"2026-05-05T02:29:10.413933Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11371113779d2d76 became candidate at term 2"} {"level":"info","ts":"2026-05-05T02:29:10.41395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11371113779d2d76 received MsgVoteResp from 11371113779d2d76 at term 2"} {"level":"info","ts":"2026-05-05T02:29:10.413966Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11371113779d2d76 became leader at term 2"} {"level":"info","ts":"2026-05-05T02:29:10.413984Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 11371113779d2d76 elected leader 11371113779d2d76 at term 2"} {"level":"info","ts":"2026-05-05T02:29:10.415513Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T02:29:10.41706Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"1921549e667ce7d9","local-member-id":"11371113779d2d76","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T02:29:10.41715Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T02:29:10.417178Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T02:29:10.417207Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"11371113779d2d76","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.15:2379]}","request-path":"/0/members/11371113779d2d76/attributes","cluster-id":"1921549e667ce7d9","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T02:29:10.417335Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T02:29:10.41774Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T02:29:10.417911Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T02:29:10.417932Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T02:29:10.420412Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.15:2379"} {"level":"info","ts":"2026-05-05T02:29:10.423032Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-05T02:29:13.057936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.920472ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-05T02:29:13.058152Z","caller":"traceutil/trace.go:171","msg":"trace[231649254] linearizableReadLoop","detail":"{readStateIndex:82; appliedIndex:81; }","duration":"297.567131ms","start":"2026-05-05T02:29:12.760556Z","end":"2026-05-05T02:29:13.058123Z","steps":["trace[231649254] 'read index received' (duration: 16.362212ms)","trace[231649254] 'applied index is now lower than readState.Index' (duration: 281.203229ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:29:13.058367Z","caller":"traceutil/trace.go:171","msg":"trace[1813953341] transaction","detail":"{read_only:false; response_revision:78; number_of_response:1; }","duration":"306.226212ms","start":"2026-05-05T02:29:12.752077Z","end":"2026-05-05T02:29:13.058304Z","steps":["trace[1813953341] 'process raft request' (duration: 24.916782ms)","trace[1813953341] 'compare' (duration: 279.699168ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T02:29:13.058419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.840076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T02:29:13.058491Z","caller":"traceutil/trace.go:171","msg":"trace[85338313] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:78; }","duration":"297.944017ms","start":"2026-05-05T02:29:12.760529Z","end":"2026-05-05T02:29:13.058473Z","steps":["trace[85338313] 'agreement among raft nodes before linearized reading' (duration: 297.666162ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:13.058604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:12.752035Z","time spent":"306.449797ms","remote":"127.0.0.1:55728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":600,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T02:29:13.320003Z","caller":"traceutil/trace.go:171","msg":"trace[596135194] transaction","detail":"{read_only:false; response_revision:79; number_of_response:1; }","duration":"255.476078ms","start":"2026-05-05T02:29:13.064476Z","end":"2026-05-05T02:29:13.319952Z","steps":["trace[596135194] 'process raft request' (duration: 255.362146ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:29:40.183158Z","caller":"traceutil/trace.go:171","msg":"trace[570876153] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"106.687505ms","start":"2026-05-05T02:29:40.076434Z","end":"2026-05-05T02:29:40.183121Z","steps":["trace[570876153] 'process raft request' (duration: 105.777846ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:29:40.183411Z","caller":"traceutil/trace.go:171","msg":"trace[346663968] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"102.471871ms","start":"2026-05-05T02:29:40.080904Z","end":"2026-05-05T02:29:40.183376Z","steps":["trace[346663968] 'process raft request' (duration: 101.492301ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:29:40.183661Z","caller":"traceutil/trace.go:171","msg":"trace[1103034162] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"102.41583ms","start":"2026-05-05T02:29:40.081196Z","end":"2026-05-05T02:29:40.183612Z","steps":["trace[1103034162] 'process raft request' (duration: 101.247016ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:29:46.533407Z","caller":"traceutil/trace.go:171","msg":"trace[1910766918] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:598; }","duration":"376.750232ms","start":"2026-05-05T02:29:46.156629Z","end":"2026-05-05T02:29:46.533379Z","steps":["trace[1910766918] 'read index received' (duration: 37.15777ms)","trace[1910766918] 'applied index is now lower than readState.Index' (duration: 339.591482ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:29:46.533568Z","caller":"traceutil/trace.go:171","msg":"trace[1158019348] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"392.24049ms","start":"2026-05-05T02:29:46.141262Z","end":"2026-05-05T02:29:46.533503Z","steps":["trace[1158019348] 'process raft request' (duration: 391.500766ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:46.533659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.012166ms","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-05-05T02:29:46.533703Z","caller":"traceutil/trace.go:171","msg":"trace[1826510121] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:575; }","duration":"377.113829ms","start":"2026-05-05T02:29:46.156575Z","end":"2026-05-05T02:29:46.533689Z","steps":["trace[1826510121] 'agreement among raft nodes before linearized reading' (duration: 376.948065ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:46.533751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:46.156555Z","time spent":"377.187431ms","remote":"127.0.0.1:38142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T02:29:46.534156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:46.141207Z","time spent":"392.486385ms","remote":"127.0.0.1:38014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T02:29:46.637972Z","caller":"traceutil/trace.go:171","msg":"trace[1172015932] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"376.490957ms","start":"2026-05-05T02:29:46.261454Z","end":"2026-05-05T02:29:46.637945Z","steps":["trace[1172015932] 'process raft request' (duration: 298.973083ms)","trace[1172015932] 'compare' (duration: 77.255069ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T02:29:46.638221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:46.261423Z","time spent":"376.67457ms","remote":"127.0.0.1:38142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T02:29:46.638244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.932797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T02:29:46.638336Z","caller":"traceutil/trace.go:171","msg":"trace[1314961767] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:576; }","duration":"241.025879ms","start":"2026-05-05T02:29:46.397261Z","end":"2026-05-05T02:29:46.638287Z","steps":["trace[1314961767] 'agreement among raft nodes before linearized reading' (duration: 240.778544ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:29:46.637955Z","caller":"traceutil/trace.go:171","msg":"trace[2145073336] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:600; }","duration":"104.396459ms","start":"2026-05-05T02:29:46.533494Z","end":"2026-05-05T02:29:46.63789Z","steps":["trace[2145073336] 'read index received' (duration: 26.926786ms)","trace[2145073336] 'applied index is now lower than readState.Index' (duration: 77.466313ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T02:29:46.638489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.66399ms","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-05-05T02:29:46.638612Z","caller":"traceutil/trace.go:171","msg":"trace[2102525048] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:576; }","duration":"145.853584ms","start":"2026-05-05T02:29:46.492736Z","end":"2026-05-05T02:29:46.638589Z","steps":["trace[2102525048] 'agreement among raft nodes before linearized reading' (duration: 145.481787ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:29:46.656411Z","caller":"traceutil/trace.go:171","msg":"trace[1629552751] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"117.213174ms","start":"2026-05-05T02:29:46.539176Z","end":"2026-05-05T02:29:46.656389Z","steps":["trace[1629552751] 'process raft request' (duration: 117.106562ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:29:47.150603Z","caller":"traceutil/trace.go:171","msg":"trace[1108148876] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"392.810242ms","start":"2026-05-05T02:29:46.757756Z","end":"2026-05-05T02:29:47.150566Z","steps":["trace[1108148876] 'process raft request' (duration: 380.14956ms)","trace[1108148876] 'compare' (duration: 12.318226ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:29:47.150649Z","caller":"traceutil/trace.go:171","msg":"trace[993890071] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:604; }","duration":"133.234174ms","start":"2026-05-05T02:29:47.017396Z","end":"2026-05-05T02:29:47.15063Z","steps":["trace[993890071] 'read index received' (duration: 120.670473ms)","trace[993890071] 'applied index is now lower than readState.Index' (duration: 12.561841ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T02:29:47.150792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.381817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-05T02:29:47.150817Z","caller":"traceutil/trace.go:171","msg":"trace[1194686947] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:580; }","duration":"133.443517ms","start":"2026-05-05T02:29:47.017366Z","end":"2026-05-05T02:29:47.15081Z","steps":["trace[1194686947] 'agreement among raft nodes before linearized reading' (duration: 133.335355ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:47.150875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:46.75773Z","time spent":"392.954615ms","remote":"127.0.0.1:38066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4538,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T02:30:13.045363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.693952ms","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-05-05T02:30:13.045465Z","caller":"traceutil/trace.go:171","msg":"trace[1068903193] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:768; }","duration":"233.854536ms","start":"2026-05-05T02:30:12.81159Z","end":"2026-05-05T02:30:13.045444Z","steps":["trace[1068903193] 'range keys from in-memory index tree' (duration: 233.460178ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:30:13.045371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.562132ms","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-05-05T02:30:13.045581Z","caller":"traceutil/trace.go:171","msg":"trace[568643300] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:768; }","duration":"205.818088ms","start":"2026-05-05T02:30:12.83972Z","end":"2026-05-05T02:30:13.045538Z","steps":["trace[568643300] 'range keys from in-memory index tree' (duration: 205.40807ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:30:47.485489Z","caller":"traceutil/trace.go:171","msg":"trace[1676230121] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"212.675504ms","start":"2026-05-05T02:30:47.272792Z","end":"2026-05-05T02:30:47.485468Z","steps":["trace[1676230121] 'process raft request' (duration: 212.556171ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:30:47.487916Z","caller":"traceutil/trace.go:171","msg":"trace[259364716] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"102.105821ms","start":"2026-05-05T02:30:47.385779Z","end":"2026-05-05T02:30:47.487884Z","steps":["trace[259364716] 'process raft request' (duration: 102.05165ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:30:47.487967Z","caller":"traceutil/trace.go:171","msg":"trace[1514408301] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"212.726504ms","start":"2026-05-05T02:30:47.275196Z","end":"2026-05-05T02:30:47.487922Z","steps":["trace[1514408301] 'process raft request' (duration: 212.51577ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:31:12.448394Z","caller":"traceutil/trace.go:171","msg":"trace[1803549912] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"157.450603ms","start":"2026-05-05T02:31:12.290904Z","end":"2026-05-05T02:31:12.448355Z","steps":["trace[1803549912] 'process raft request' (duration: 157.223229ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:31:14.038935Z","caller":"traceutil/trace.go:171","msg":"trace[530761651] linearizableReadLoop","detail":"{readStateIndex:1112; appliedIndex:1111; }","duration":"366.017015ms","start":"2026-05-05T02:31:13.672868Z","end":"2026-05-05T02:31:14.038885Z","steps":["trace[530761651] 'read index received' (duration: 365.812001ms)","trace[530761651] 'applied index is now lower than readState.Index' (duration: 203.914µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:31:14.039059Z","caller":"traceutil/trace.go:171","msg":"trace[1802222176] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"399.844324ms","start":"2026-05-05T02:31:13.63911Z","end":"2026-05-05T02:31:14.038989Z","steps":["trace[1802222176] 'process raft request' (duration: 399.534718ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:14.039278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:13.639058Z","time spent":"400.095919ms","remote":"127.0.0.1:38142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T02:31:14.039373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.279438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T02:31:14.039404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.176424ms","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-05-05T02:31:14.03948Z","caller":"traceutil/trace.go:171","msg":"trace[740577458] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1066; }","duration":"249.419901ms","start":"2026-05-05T02:31:13.790033Z","end":"2026-05-05T02:31:14.039453Z","steps":["trace[740577458] 'agreement among raft nodes before linearized reading' (duration: 249.182626ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:31:14.039496Z","caller":"traceutil/trace.go:171","msg":"trace[2050446761] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1066; }","duration":"343.337037ms","start":"2026-05-05T02:31:13.696142Z","end":"2026-05-05T02:31:14.039479Z","steps":["trace[2050446761] 'agreement among raft nodes before linearized reading' (duration: 343.099783ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:14.039541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:13.696116Z","time spent":"343.414979ms","remote":"127.0.0.1:38142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T02:31:14.039686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.333491ms","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-05-05T02:31:14.039718Z","caller":"traceutil/trace.go:171","msg":"trace[1066624391] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1066; }","duration":"366.903482ms","start":"2026-05-05T02:31:13.672804Z","end":"2026-05-05T02:31:14.039707Z","steps":["trace[1066624391] 'agreement among raft nodes before linearized reading' (duration: 366.266499ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:14.039766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.522372ms","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-05-05T02:31:14.039933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:13.672777Z","time spent":"367.147286ms","remote":"127.0.0.1:38142","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-05-05T02:31:14.03997Z","caller":"traceutil/trace.go:171","msg":"trace[1687958470] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1066; }","duration":"345.748756ms","start":"2026-05-05T02:31:13.694206Z","end":"2026-05-05T02:31:14.039955Z","steps":["trace[1687958470] 'agreement among raft nodes before linearized reading' (duration: 344.904689ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:14.040091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:13.694196Z","time spent":"345.883129ms","remote":"127.0.0.1:38142","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-05-05T02:31:14.184678Z","caller":"traceutil/trace.go:171","msg":"trace[1409431380] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"141.000463ms","start":"2026-05-05T02:31:14.04363Z","end":"2026-05-05T02:31:14.18463Z","steps":["trace[1409431380] 'process raft request' (duration: 120.068503ms)","trace[1409431380] 'compare' (duration: 20.802537ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:31:14.318411Z","caller":"traceutil/trace.go:171","msg":"trace[1179566368] linearizableReadLoop","detail":"{readStateIndex:1115; appliedIndex:1112; }","duration":"248.940251ms","start":"2026-05-05T02:31:14.069439Z","end":"2026-05-05T02:31:14.318379Z","steps":["trace[1179566368] 'read index received' (duration: 94.273354ms)","trace[1179566368] 'applied index is now lower than readState.Index' (duration: 154.665637ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:31:14.318468Z","caller":"traceutil/trace.go:171","msg":"trace[596723670] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"273.202979ms","start":"2026-05-05T02:31:14.045239Z","end":"2026-05-05T02:31:14.318442Z","steps":["trace[596723670] 'process raft request' (duration: 273.046786ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:31:14.318446Z","caller":"traceutil/trace.go:171","msg":"trace[52061030] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"273.880962ms","start":"2026-05-05T02:31:14.044515Z","end":"2026-05-05T02:31:14.318396Z","steps":["trace[52061030] 'process raft request' (duration: 244.361119ms)","trace[52061030] 'compare' (duration: 29.181847ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:31:14.318627Z","caller":"traceutil/trace.go:171","msg":"trace[1974955752] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"130.592934ms","start":"2026-05-05T02:31:14.188023Z","end":"2026-05-05T02:31:14.318615Z","steps":["trace[1974955752] 'process raft request' (duration: 130.318468ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:14.318893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.434531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.15\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-05T02:31:14.318966Z","caller":"traceutil/trace.go:171","msg":"trace[1275630078] range","detail":"{range_begin:/registry/masterleases/199.19.213.15; range_end:; response_count:1; response_revision:1070; }","duration":"249.564683ms","start":"2026-05-05T02:31:14.06939Z","end":"2026-05-05T02:31:14.318955Z","steps":["trace[1275630078] 'agreement among raft nodes before linearized reading' (duration: 249.125665ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:15.122094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.298876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T02:31:15.122236Z","caller":"traceutil/trace.go:171","msg":"trace[1228464144] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1073; }","duration":"109.550821ms","start":"2026-05-05T02:31:15.012654Z","end":"2026-05-05T02:31:15.122205Z","steps":["trace[1228464144] 'count revisions from in-memory index tree' (duration: 109.164213ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:31:27.143284Z","caller":"traceutil/trace.go:171","msg":"trace[607506853] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"217.47954ms","start":"2026-05-05T02:31:26.925774Z","end":"2026-05-05T02:31:27.143253Z","steps":["trace[607506853] 'process raft request' (duration: 217.333057ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:43.595263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.805724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T02:31:43.595355Z","caller":"traceutil/trace.go:171","msg":"trace[547506200] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1231; }","duration":"196.912816ms","start":"2026-05-05T02:31:43.398422Z","end":"2026-05-05T02:31:43.595335Z","steps":["trace[547506200] 'range keys from in-memory index tree' (duration: 196.720803ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:31:43.762546Z","caller":"traceutil/trace.go:171","msg":"trace[38303651] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"155.384241ms","start":"2026-05-05T02:31:43.607116Z","end":"2026-05-05T02:31:43.762501Z","steps":["trace[38303651] 'process raft request' (duration: 155.194426ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:44.437382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.72924ms","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-05-05T02:31:44.437485Z","caller":"traceutil/trace.go:171","msg":"trace[347211376] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1238; }","duration":"111.910254ms","start":"2026-05-05T02:31:44.32555Z","end":"2026-05-05T02:31:44.437461Z","steps":["trace[347211376] 'range keys from in-memory index tree' (duration: 111.453974ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:31:44.588659Z","caller":"traceutil/trace.go:171","msg":"trace[1312340310] linearizableReadLoop","detail":"{readStateIndex:1294; appliedIndex:1293; }","duration":"106.071616ms","start":"2026-05-05T02:31:44.482557Z","end":"2026-05-05T02:31:44.588629Z","steps":["trace[1312340310] 'read index received' (duration: 105.78594ms)","trace[1312340310] 'applied index is now lower than readState.Index' (duration: 284.066µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:31:44.588726Z","caller":"traceutil/trace.go:171","msg":"trace[1703180851] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"147.057042ms","start":"2026-05-05T02:31:44.44162Z","end":"2026-05-05T02:31:44.588677Z","steps":["trace[1703180851] 'process raft request' (duration: 146.792156ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:44.589101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.500256ms","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-05-05T02:31:44.589159Z","caller":"traceutil/trace.go:171","msg":"trace[339516146] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1239; }","duration":"106.649689ms","start":"2026-05-05T02:31:44.482498Z","end":"2026-05-05T02:31:44.589147Z","steps":["trace[339516146] 'agreement among raft nodes before linearized reading' (duration: 106.281231ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:44.602097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.542138ms","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-05-05T02:31:44.602185Z","caller":"traceutil/trace.go:171","msg":"trace[1390970090] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1240; }","duration":"117.702981ms","start":"2026-05-05T02:31:44.484463Z","end":"2026-05-05T02:31:44.602166Z","steps":["trace[1390970090] 'agreement among raft nodes before linearized reading' (duration: 117.470937ms)"],"step_count":1}