{"level":"warn","ts":"2026-05-19T13:29:59.300103Z","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-19T13:29:59.300191Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.109:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.109:2380","--initial-cluster=instance=https://199.204.45.109:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.109:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.109: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-19T13:29:59.300251Z","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-19T13:29:59.300265Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-05-19T13:29:59.300288Z","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-19T13:29:59.300895Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"]} {"level":"info","ts":"2026-05-19T13:29:59.300962Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.109: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-19T13:29:59.306651Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.480516ms"} {"level":"info","ts":"2026-05-19T13:29:59.317974Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d71a1778b31d4ce","cluster-id":"aaca35652fabf9b2"} {"level":"info","ts":"2026-05-19T13:29:59.318043Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=()"} {"level":"info","ts":"2026-05-19T13:29:59.318066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 0"} {"level":"info","ts":"2026-05-19T13:29:59.318074Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d71a1778b31d4ce [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-19T13:29:59.318086Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 1"} {"level":"info","ts":"2026-05-19T13:29:59.318114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"warn","ts":"2026-05-19T13:29:59.325333Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-19T13:29:59.327199Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-19T13:29:59.328567Z","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-19T13:29:59.33074Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d71a1778b31d4ce","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-19T13:29:59.331012Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d71a1778b31d4ce","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-19T13:29:59.331194Z","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-19T13:29:59.331221Z","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-19T13:29:59.331228Z","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-19T13:29:59.332437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"info","ts":"2026-05-19T13:29:59.336072Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","added-peer-id":"d71a1778b31d4ce","added-peer-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-05-19T13:29:59.336894Z","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-19T13:29:59.336982Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-05-19T13:29:59.337755Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d71a1778b31d4ce","initial-advertise-peer-urls":["https://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-19T13:29:59.337737Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-05-19T13:29:59.337815Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-19T13:29:59.418539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce is starting a new election at term 1"} {"level":"info","ts":"2026-05-19T13:29:59.418591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became pre-candidate at term 1"} {"level":"info","ts":"2026-05-19T13:29:59.418647Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgPreVoteResp from d71a1778b31d4ce at term 1"} {"level":"info","ts":"2026-05-19T13:29:59.418668Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became candidate at term 2"} {"level":"info","ts":"2026-05-19T13:29:59.418736Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgVoteResp from d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-05-19T13:29:59.418752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became leader at term 2"} {"level":"info","ts":"2026-05-19T13:29:59.418765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d71a1778b31d4ce elected leader d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-05-19T13:29:59.426628Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T13:29:59.434763Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d71a1778b31d4ce","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.109:2379]}","request-path":"/0/members/d71a1778b31d4ce/attributes","cluster-id":"aaca35652fabf9b2","publish-timeout":"7s"} {"level":"info","ts":"2026-05-19T13:29:59.434875Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T13:29:59.435035Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T13:29:59.435662Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T13:29:59.435871Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T13:29:59.436009Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T13:29:59.43851Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-19T13:29:59.438584Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-19T13:29:59.438856Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.109:2379"} {"level":"info","ts":"2026-05-19T13:29:59.439481Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-19T13:30:03.680542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"856.198717ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-19T13:30:03.68064Z","caller":"traceutil/trace.go:171","msg":"trace[775008150] linearizableReadLoop","detail":"{readStateIndex:196; appliedIndex:195; }","duration":"664.579894ms","start":"2026-05-19T13:30:03.016047Z","end":"2026-05-19T13:30:03.680627Z","steps":["trace[775008150] 'read index received' (duration: 51.871µs)","trace[775008150] 'applied index is now lower than readState.Index' (duration: 664.527243ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:30:03.680709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.671896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:30:03.680732Z","caller":"traceutil/trace.go:171","msg":"trace[735270277] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:192; }","duration":"664.733466ms","start":"2026-05-19T13:30:03.015992Z","end":"2026-05-19T13:30:03.680725Z","steps":["trace[735270277] 'agreement among raft nodes before linearized reading' (duration: 664.676475ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:30:03.680713Z","caller":"traceutil/trace.go:171","msg":"trace[1252378484] transaction","detail":"{read_only:false; response_revision:192; number_of_response:1; }","duration":"1.134796871s","start":"2026-05-19T13:30:02.545876Z","end":"2026-05-19T13:30:03.680673Z","steps":["trace[1252378484] 'process raft request' (duration: 277.734888ms)","trace[1252378484] 'compare' (duration: 856.070926ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:30:03.680756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:30:03.015971Z","time spent":"664.779736ms","remote":"127.0.0.1:34058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T13:30:03.680923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:30:02.545865Z","time spent":"1.134933762s","remote":"127.0.0.1:34406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":726,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T13:30:04.54427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.950886ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T13:30:04.544427Z","caller":"traceutil/trace.go:171","msg":"trace[352929918] linearizableReadLoop","detail":"{readStateIndex:197; appliedIndex:196; }","duration":"500.177284ms","start":"2026-05-19T13:30:04.044194Z","end":"2026-05-19T13:30:04.544371Z","steps":["trace[352929918] 'read index received' (duration: 30.84µs)","trace[352929918] 'applied index is now lower than readState.Index' (duration: 500.145274ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:30:04.5447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.933022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:30:04.544739Z","caller":"traceutil/trace.go:171","msg":"trace[571724952] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:193; }","duration":"159.004982ms","start":"2026-05-19T13:30:04.385721Z","end":"2026-05-19T13:30:04.544726Z","steps":["trace[571724952] 'agreement among raft nodes before linearized reading' (duration: 158.958672ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:30:04.544434Z","caller":"traceutil/trace.go:171","msg":"trace[1643864169] transaction","detail":"{read_only:false; response_revision:193; number_of_response:1; }","duration":"859.036049ms","start":"2026-05-19T13:30:03.685366Z","end":"2026-05-19T13:30:04.544402Z","steps":["trace[1643864169] 'process raft request' (duration: 262.756921ms)","trace[1643864169] 'compare' (duration: 595.837265ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:30:04.544549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.362676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T13:30:04.544948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:30:03.685346Z","time spent":"859.436622ms","remote":"127.0.0.1:34106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":101,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T13:30:04.544958Z","caller":"traceutil/trace.go:171","msg":"trace[399233458] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:193; }","duration":"500.712078ms","start":"2026-05-19T13:30:04.044176Z","end":"2026-05-19T13:30:04.544888Z","steps":["trace[399233458] 'agreement among raft nodes before linearized reading' (duration: 500.319275ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:30:04.545059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:30:04.04412Z","time spent":"500.905539ms","remote":"127.0.0.1:34058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-19T13:30:05.25951Z","caller":"traceutil/trace.go:171","msg":"trace[300049563] transaction","detail":"{read_only:false; response_revision:206; number_of_response:1; }","duration":"109.121371ms","start":"2026-05-19T13:30:05.150357Z","end":"2026-05-19T13:30:05.259478Z","steps":["trace[300049563] 'process raft request' (duration: 108.947ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:30:05.493487Z","caller":"traceutil/trace.go:171","msg":"trace[562787886] transaction","detail":"{read_only:false; response_revision:217; number_of_response:1; }","duration":"152.148844ms","start":"2026-05-19T13:30:05.341316Z","end":"2026-05-19T13:30:05.493465Z","steps":["trace[562787886] 'process raft request' (duration: 152.114874ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:30:05.493673Z","caller":"traceutil/trace.go:171","msg":"trace[1326020486] transaction","detail":"{read_only:false; response_revision:215; number_of_response:1; }","duration":"154.794631ms","start":"2026-05-19T13:30:05.338858Z","end":"2026-05-19T13:30:05.493653Z","steps":["trace[1326020486] 'process raft request' (duration: 91.984533ms)","trace[1326020486] 'compare' (duration: 62.253355ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:30:05.493841Z","caller":"traceutil/trace.go:171","msg":"trace[177838803] transaction","detail":"{read_only:false; response_revision:216; number_of_response:1; }","duration":"152.871038ms","start":"2026-05-19T13:30:05.340962Z","end":"2026-05-19T13:30:05.493833Z","steps":["trace[177838803] 'process raft request' (duration: 152.438186ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:30:29.797833Z","caller":"traceutil/trace.go:171","msg":"trace[1212689581] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"133.926003ms","start":"2026-05-19T13:30:29.663883Z","end":"2026-05-19T13:30:29.797809Z","steps":["trace[1212689581] 'process raft request' (duration: 133.828628ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:30:35.295712Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367780800633415,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-19T13:30:35.403898Z","caller":"traceutil/trace.go:171","msg":"trace[897376524] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"690.937375ms","start":"2026-05-19T13:30:34.712932Z","end":"2026-05-19T13:30:35.403869Z","steps":["trace[897376524] 'process raft request' (duration: 690.746848ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:30:35.404027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:30:34.712914Z","time spent":"691.047438ms","remote":"127.0.0.1:34096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T13:30:35.444218Z","caller":"traceutil/trace.go:171","msg":"trace[854958243] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"714.683254ms","start":"2026-05-19T13:30:34.729506Z","end":"2026-05-19T13:30:35.444189Z","steps":["trace[854958243] 'process raft request' (duration: 714.365543ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:30:35.444312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:30:34.729488Z","time spent":"714.784567ms","remote":"127.0.0.1:34096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T13:30:35.444379Z","caller":"traceutil/trace.go:171","msg":"trace[1234632912] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"714.421164ms","start":"2026-05-19T13:30:34.729903Z","end":"2026-05-19T13:30:35.444324Z","steps":["trace[1234632912] 'process raft request' (duration: 714.192997ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:30:35.444377Z","caller":"traceutil/trace.go:171","msg":"trace[1445683902] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"674.988568ms","start":"2026-05-19T13:30:34.76935Z","end":"2026-05-19T13:30:35.444339Z","steps":["trace[1445683902] 'process raft request' (duration: 674.815482ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:30:35.444471Z","caller":"traceutil/trace.go:171","msg":"trace[298330094] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"673.556168ms","start":"2026-05-19T13:30:34.770857Z","end":"2026-05-19T13:30:35.444413Z","steps":["trace[298330094] 'process raft request' (duration: 673.345401ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:30:35.444344Z","caller":"traceutil/trace.go:171","msg":"trace[653354357] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"677.925178ms","start":"2026-05-19T13:30:34.766372Z","end":"2026-05-19T13:30:35.444298Z","steps":["trace[653354357] 'process raft request' (duration: 677.762722ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:30:35.444584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:30:34.729898Z","time spent":"714.60025ms","remote":"127.0.0.1:34096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T13:30:35.444619Z","caller":"traceutil/trace.go:171","msg":"trace[1988550108] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"669.729509ms","start":"2026-05-19T13:30:34.774875Z","end":"2026-05-19T13:30:35.444605Z","steps":["trace[1988550108] 'process raft request' (duration: 669.430449ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:30:35.444715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:30:34.774872Z","time spent":"669.817743ms","remote":"127.0.0.1:34096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T13:30:35.444757Z","caller":"traceutil/trace.go:171","msg":"trace[712223101] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"657.446722ms","start":"2026-05-19T13:30:34.7873Z","end":"2026-05-19T13:30:35.444746Z","steps":["trace[712223101] 'process raft request' (duration: 657.181213ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:30:35.444751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:30:34.766355Z","time spent":"678.296359ms","remote":"127.0.0.1:34096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T13:30:35.444577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:30:34.770839Z","time spent":"673.689843ms","remote":"127.0.0.1:34096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T13:30:35.444822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:30:34.787285Z","time spent":"657.511464ms","remote":"127.0.0.1:34096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T13:30:35.444854Z","caller":"traceutil/trace.go:171","msg":"trace[1426973686] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"650.902561ms","start":"2026-05-19T13:30:34.793938Z","end":"2026-05-19T13:30:35.444841Z","steps":["trace[1426973686] 'process raft request' (duration: 650.724485ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:30:35.444854Z","caller":"traceutil/trace.go:171","msg":"trace[2080441168] linearizableReadLoop","detail":"{readStateIndex:510; appliedIndex:498; }","duration":"649.768722ms","start":"2026-05-19T13:30:34.795056Z","end":"2026-05-19T13:30:35.444824Z","steps":["trace[2080441168] 'read index received' (duration: 608.711056ms)","trace[2080441168] 'applied index is now lower than readState.Index' (duration: 41.056606ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:30:35.444917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:30:34.793931Z","time spent":"650.958082ms","remote":"127.0.0.1:34096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T13:30:35.444624Z","caller":"traceutil/trace.go:171","msg":"trace[705564665] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"669.792101ms","start":"2026-05-19T13:30:34.77482Z","end":"2026-05-19T13:30:35.444612Z","steps":["trace[705564665] 'process raft request' (duration: 669.417709ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:30:35.445029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:30:34.774809Z","time spent":"670.189585ms","remote":"127.0.0.1:34096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T13:30:35.445044Z","caller":"traceutil/trace.go:171","msg":"trace[1837604563] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"562.887646ms","start":"2026-05-19T13:30:34.882147Z","end":"2026-05-19T13:30:35.445035Z","steps":["trace[1837604563] 'process raft request' (duration: 562.580206ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:30:35.445114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:30:34.882128Z","time spent":"562.966649ms","remote":"127.0.0.1:34096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T13:30:35.444566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:30:34.769333Z","time spent":"675.151203ms","remote":"127.0.0.1:34096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T13:30:35.444631Z","caller":"traceutil/trace.go:171","msg":"trace[241049063] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"669.701778ms","start":"2026-05-19T13:30:34.774919Z","end":"2026-05-19T13:30:35.44462Z","steps":["trace[241049063] 'process raft request' (duration: 669.517442ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:30:35.445207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:30:34.774908Z","time spent":"670.281607ms","remote":"127.0.0.1:34096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T13:30:35.44528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.198406ms","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-19T13:30:35.44533Z","caller":"traceutil/trace.go:171","msg":"trace[400954562] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:490; }","duration":"650.28996ms","start":"2026-05-19T13:30:34.795027Z","end":"2026-05-19T13:30:35.445317Z","steps":["trace[400954562] 'agreement among raft nodes before linearized reading' (duration: 649.893276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:30:35.445372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:30:34.79502Z","time spent":"650.343181ms","remote":"127.0.0.1:34326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-19T13:30:41.688284Z","caller":"traceutil/trace.go:171","msg":"trace[1898046447] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:609; }","duration":"115.161368ms","start":"2026-05-19T13:30:41.573028Z","end":"2026-05-19T13:30:41.688189Z","steps":["trace[1898046447] 'read index received' (duration: 76.898889ms)","trace[1898046447] 'applied index is now lower than readState.Index' (duration: 38.259889ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:30:41.688296Z","caller":"traceutil/trace.go:171","msg":"trace[628882865] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"168.265041ms","start":"2026-05-19T13:30:41.519991Z","end":"2026-05-19T13:30:41.688256Z","steps":["trace[628882865] 'process raft request' (duration: 129.731894ms)","trace[628882865] 'compare' (duration: 38.332161ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:30:41.688618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.54867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-19T13:30:41.688707Z","caller":"traceutil/trace.go:171","msg":"trace[220542599] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:587; }","duration":"115.704124ms","start":"2026-05-19T13:30:41.572976Z","end":"2026-05-19T13:30:41.68868Z","steps":["trace[220542599] 'agreement among raft nodes before linearized reading' (duration: 115.417975ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:30:42.072869Z","caller":"traceutil/trace.go:171","msg":"trace[854839410] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"471.980142ms","start":"2026-05-19T13:30:41.600862Z","end":"2026-05-19T13:30:42.072843Z","steps":["trace[854839410] 'process raft request' (duration: 471.317412ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:30:42.07303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:30:41.600831Z","time spent":"472.108165ms","remote":"127.0.0.1:34132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T13:30:42.154116Z","caller":"traceutil/trace.go:171","msg":"trace[600861770] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:610; }","duration":"404.608443ms","start":"2026-05-19T13:30:41.749477Z","end":"2026-05-19T13:30:42.154085Z","steps":["trace[600861770] 'read index received' (duration: 322.470359ms)","trace[600861770] 'applied index is now lower than readState.Index' (duration: 82.137144ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:30:42.154207Z","caller":"traceutil/trace.go:171","msg":"trace[1800102831] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"460.169044ms","start":"2026-05-19T13:30:41.694007Z","end":"2026-05-19T13:30:42.154176Z","steps":["trace[1800102831] 'process raft request' (duration: 459.943977ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:30:42.154368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.879151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-hdrlt\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-05-19T13:30:42.154384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:30:41.693974Z","time spent":"460.294518ms","remote":"127.0.0.1:34326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T13:30:42.154412Z","caller":"traceutil/trace.go:171","msg":"trace[1271395785] range","detail":"{range_begin:/registry/pods/kube-system/cilium-hdrlt; range_end:; response_count:1; response_revision:589; }","duration":"404.981255ms","start":"2026-05-19T13:30:41.749419Z","end":"2026-05-19T13:30:42.154401Z","steps":["trace[1271395785] 'agreement among raft nodes before linearized reading' (duration: 404.787579ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:30:42.154491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:30:41.749398Z","time spent":"405.048946ms","remote":"127.0.0.1:34236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-hdrlt\" "} {"level":"warn","ts":"2026-05-19T13:30:42.654713Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367780800633660,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-19T13:30:42.952196Z","caller":"traceutil/trace.go:171","msg":"trace[1957104492] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"818.688275ms","start":"2026-05-19T13:30:42.13335Z","end":"2026-05-19T13:30:42.952038Z","steps":["trace[1957104492] 'process raft request' (duration: 818.535691ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:30:42.952322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:30:42.133328Z","time spent":"818.937082ms","remote":"127.0.0.1:34292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T13:30:42.972251Z","caller":"traceutil/trace.go:171","msg":"trace[1800547659] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"804.277969ms","start":"2026-05-19T13:30:42.167935Z","end":"2026-05-19T13:30:42.972213Z","steps":["trace[1800547659] 'process raft request' (duration: 804.024581ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:30:42.972372Z","caller":"traceutil/trace.go:171","msg":"trace[1843906134] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:613; }","duration":"818.163339ms","start":"2026-05-19T13:30:42.154184Z","end":"2026-05-19T13:30:42.972348Z","steps":["trace[1843906134] 'read index received' (duration: 797.83185ms)","trace[1843906134] 'applied index is now lower than readState.Index' (duration: 20.329939ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:30:42.972455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:30:42.167913Z","time spent":"804.434803ms","remote":"127.0.0.1:34236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T13:30:42.972541Z","caller":"traceutil/trace.go:171","msg":"trace[832627428] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"651.413313ms","start":"2026-05-19T13:30:42.321044Z","end":"2026-05-19T13:30:42.972457Z","steps":["trace[832627428] 'process raft request' (duration: 651.099693ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:30:42.97259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.096249838s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-19T13:30:42.972747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:30:42.321024Z","time spent":"651.608748ms","remote":"127.0.0.1:34326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T13:30:42.972765Z","caller":"traceutil/trace.go:171","msg":"trace[1179945855] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:592; }","duration":"1.096488837s","start":"2026-05-19T13:30:41.876266Z","end":"2026-05-19T13:30:42.972755Z","steps":["trace[1179945855] 'agreement among raft nodes before linearized reading' (duration: 1.096197557s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:30:42.972823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:30:41.876245Z","time spent":"1.096568928s","remote":"127.0.0.1:34326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T13:30:42.972726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.117214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:30:42.972969Z","caller":"traceutil/trace.go:171","msg":"trace[1159365824] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"490.397073ms","start":"2026-05-19T13:30:42.482547Z","end":"2026-05-19T13:30:42.972944Z","steps":["trace[1159365824] 'agreement among raft nodes before linearized reading' (duration: 490.111894ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:30:42.973078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:30:42.482528Z","time spent":"490.534248ms","remote":"127.0.0.1:34058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T13:30:44.142092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.954912ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-19T13:30:44.142191Z","caller":"traceutil/trace.go:171","msg":"trace[409456913] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:616; }","duration":"629.207504ms","start":"2026-05-19T13:30:43.512966Z","end":"2026-05-19T13:30:44.142173Z","steps":["trace[409456913] 'read index received' (duration: 130.057676ms)","trace[409456913] 'applied index is now lower than readState.Index' (duration: 499.148698ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:30:44.142251Z","caller":"traceutil/trace.go:171","msg":"trace[1632859006] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"1.124699907s","start":"2026-05-19T13:30:43.017525Z","end":"2026-05-19T13:30:44.142225Z","steps":["trace[1632859006] 'process raft request' (duration: 625.537539ms)","trace[1632859006] 'compare' (duration: 498.421936ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:30:44.14234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.883953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-19T13:30:44.142348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:30:43.01751Z","time spent":"1.12479741s","remote":"127.0.0.1:34236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T13:30:44.142368Z","caller":"traceutil/trace.go:171","msg":"trace[1793450833] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:594; }","duration":"456.954285ms","start":"2026-05-19T13:30:43.685404Z","end":"2026-05-19T13:30:44.142359Z","steps":["trace[1793450833] 'agreement among raft nodes before linearized reading' (duration: 456.854572ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:30:44.142397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:30:43.685381Z","time spent":"457.007536ms","remote":"127.0.0.1:34064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.109\" "} {"level":"warn","ts":"2026-05-19T13:30:44.142436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.473192ms","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-19T13:30:44.142519Z","caller":"traceutil/trace.go:171","msg":"trace[1102536393] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:594; }","duration":"629.600226ms","start":"2026-05-19T13:30:43.512904Z","end":"2026-05-19T13:30:44.142504Z","steps":["trace[1102536393] 'agreement among raft nodes before linearized reading' (duration: 629.330458ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:30:44.142563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:30:43.512863Z","time spent":"629.687528ms","remote":"127.0.0.1:34326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T13:30:44.142631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.528298ms","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-05-19T13:30:44.142724Z","caller":"traceutil/trace.go:171","msg":"trace[1984715982] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:594; }","duration":"450.639652ms","start":"2026-05-19T13:30:43.692075Z","end":"2026-05-19T13:30:44.142714Z","steps":["trace[1984715982] 'agreement among raft nodes before linearized reading' (duration: 450.494838ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:30:44.142754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:30:43.692063Z","time spent":"450.681703ms","remote":"127.0.0.1:34326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-19T13:30:49.076321Z","caller":"traceutil/trace.go:171","msg":"trace[1654398383] linearizableReadLoop","detail":"{readStateIndex:699; appliedIndex:698; }","duration":"121.578045ms","start":"2026-05-19T13:30:48.954714Z","end":"2026-05-19T13:30:49.076292Z","steps":["trace[1654398383] 'read index received' (duration: 121.219585ms)","trace[1654398383] 'applied index is now lower than readState.Index' (duration: 357.44µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:30:49.076376Z","caller":"traceutil/trace.go:171","msg":"trace[1831088093] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"141.131872ms","start":"2026-05-19T13:30:48.935214Z","end":"2026-05-19T13:30:49.076346Z","steps":["trace[1831088093] 'process raft request' (duration: 140.778582ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:30:49.076604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.862813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-05-19T13:30:49.076703Z","caller":"traceutil/trace.go:171","msg":"trace[994581581] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:673; }","duration":"121.995386ms","start":"2026-05-19T13:30:48.954688Z","end":"2026-05-19T13:30:49.076683Z","steps":["trace[994581581] 'agreement among raft nodes before linearized reading' (duration: 121.734118ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:30:57.79609Z","caller":"traceutil/trace.go:171","msg":"trace[1348413586] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"143.706123ms","start":"2026-05-19T13:30:57.652089Z","end":"2026-05-19T13:30:57.795795Z","steps":["trace[1348413586] 'process raft request' (duration: 143.56699ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:31:00.827192Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367780800633982,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T13:31:00.917872Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.05583092s","expected-duration":"1s"} {"level":"info","ts":"2026-05-19T13:31:00.918328Z","caller":"traceutil/trace.go:171","msg":"trace[283791434] linearizableReadLoop","detail":"{readStateIndex:785; appliedIndex:784; }","duration":"591.792752ms","start":"2026-05-19T13:31:00.326511Z","end":"2026-05-19T13:31:00.918304Z","steps":["trace[283791434] 'read index received' (duration: 591.517975ms)","trace[283791434] 'applied index is now lower than readState.Index' (duration: 274.087µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:31:00.918398Z","caller":"traceutil/trace.go:171","msg":"trace[718897840] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"1.056435336s","start":"2026-05-19T13:30:59.861927Z","end":"2026-05-19T13:31:00.918362Z","steps":["trace[718897840] 'process raft request' (duration: 1.05621297s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:31:00.918672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:30:59.861907Z","time spent":"1.056640511s","remote":"127.0.0.1:34236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T13:31:00.918698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.156672ms","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-05-19T13:31:00.918816Z","caller":"traceutil/trace.go:171","msg":"trace[1373776782] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:756; }","duration":"592.387398ms","start":"2026-05-19T13:31:00.326401Z","end":"2026-05-19T13:31:00.918789Z","steps":["trace[1373776782] 'agreement among raft nodes before linearized reading' (duration: 592.011158ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:31:00.919055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:31:00.326381Z","time spent":"592.659875ms","remote":"127.0.0.1:34326","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-19T13:31:00.919571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.675516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-19T13:31:00.91962Z","caller":"traceutil/trace.go:171","msg":"trace[504833075] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:756; }","duration":"592.755787ms","start":"2026-05-19T13:31:00.326852Z","end":"2026-05-19T13:31:00.919608Z","steps":["trace[504833075] 'agreement among raft nodes before linearized reading' (duration: 592.626514ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:31:00.919655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:31:00.326835Z","time spent":"592.813789ms","remote":"127.0.0.1:34326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-19T13:31:00.919761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.755492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T13:31:00.91991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.202221ms","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-19T13:31:00.919954Z","caller":"traceutil/trace.go:171","msg":"trace[2019899808] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:756; }","duration":"590.282934ms","start":"2026-05-19T13:31:00.32966Z","end":"2026-05-19T13:31:00.919943Z","steps":["trace[2019899808] 'agreement among raft nodes before linearized reading' (duration: 590.163551ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:31:00.919984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:31:00.329649Z","time spent":"590.328145ms","remote":"127.0.0.1:34326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-19T13:31:00.920043Z","caller":"traceutil/trace.go:171","msg":"trace[311954022] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:756; }","duration":"427.988458ms","start":"2026-05-19T13:31:00.491931Z","end":"2026-05-19T13:31:00.919919Z","steps":["trace[311954022] 'agreement among raft nodes before linearized reading' (duration: 427.66818ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:31:00.92015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:31:00.491903Z","time spent":"428.229394ms","remote":"127.0.0.1:34058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T13:31:00.920493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.163529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:31:00.920562Z","caller":"traceutil/trace.go:171","msg":"trace[83702200] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:756; }","duration":"217.317374ms","start":"2026-05-19T13:31:00.703229Z","end":"2026-05-19T13:31:00.920547Z","steps":["trace[83702200] 'agreement among raft nodes before linearized reading' (duration: 217.17896ms)"],"step_count":1}