{"level":"warn","ts":"2026-07-17T10:48:38.33431Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-17T10:48:38.334493Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.115: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.115:2380","--initial-cluster=instance=https://199.204.45.115:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.115:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.115:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-17T10:48:38.334601Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-17T10:48:38.334641Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-07-17T10:48:38.334681Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-17T10:48:38.335504Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"]} {"level":"info","ts":"2026-07-17T10:48:38.335639Z","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.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.115:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-17T10:48:38.340203Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.195952ms"} {"level":"info","ts":"2026-07-17T10:48:38.350211Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e3aaa5c123e150e0","cluster-id":"61ec519698823541"} {"level":"info","ts":"2026-07-17T10:48:38.350317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=()"} {"level":"info","ts":"2026-07-17T10:48:38.350354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 0"} {"level":"info","ts":"2026-07-17T10:48:38.350379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e3aaa5c123e150e0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-17T10:48:38.350405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 1"} {"level":"info","ts":"2026-07-17T10:48:38.350463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"warn","ts":"2026-07-17T10:48:38.360953Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-17T10:48:38.367516Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-17T10:48:38.36947Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-17T10:48:38.372189Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e3aaa5c123e150e0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-17T10:48:38.372774Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-17T10:48:38.372875Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-17T10:48:38.372886Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-17T10:48:38.372756Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e3aaa5c123e150e0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-17T10:48:38.377425Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-17T10:48:38.377681Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e3aaa5c123e150e0","initial-advertise-peer-urls":["https://199.204.45.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-17T10:48:38.37771Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-17T10:48:38.377755Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-07-17T10:48:38.37777Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-07-17T10:48:38.379048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"info","ts":"2026-07-17T10:48:38.379186Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","added-peer-id":"e3aaa5c123e150e0","added-peer-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-07-17T10:48:38.751861Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 is starting a new election at term 1"} {"level":"info","ts":"2026-07-17T10:48:38.751919Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-17T10:48:38.751938Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgPreVoteResp from e3aaa5c123e150e0 at term 1"} {"level":"info","ts":"2026-07-17T10:48:38.751949Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became candidate at term 2"} {"level":"info","ts":"2026-07-17T10:48:38.751955Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgVoteResp from e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-07-17T10:48:38.751963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became leader at term 2"} {"level":"info","ts":"2026-07-17T10:48:38.751973Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e3aaa5c123e150e0 elected leader e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-07-17T10:48:38.824074Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e3aaa5c123e150e0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.115:2379]}","request-path":"/0/members/e3aaa5c123e150e0/attributes","cluster-id":"61ec519698823541","publish-timeout":"7s"} {"level":"info","ts":"2026-07-17T10:48:38.824088Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-17T10:48:38.824259Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T10:48:38.824329Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-17T10:48:38.824456Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-17T10:48:38.824704Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-17T10:48:38.82741Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-17T10:48:38.827692Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.115:2379"} {"level":"info","ts":"2026-07-17T10:48:38.833017Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T10:48:38.833165Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T10:48:38.833209Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T10:48:41.219328Z","caller":"traceutil/trace.go:171","msg":"trace[1470720908] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"153.746949ms","start":"2026-07-17T10:48:41.065553Z","end":"2026-07-17T10:48:41.2193Z","steps":["trace[1470720908] 'read index received' (duration: 153.579086ms)","trace[1470720908] 'applied index is now lower than readState.Index' (duration: 166.843µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:48:41.219388Z","caller":"traceutil/trace.go:171","msg":"trace[163161066] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"154.043664ms","start":"2026-07-17T10:48:41.065308Z","end":"2026-07-17T10:48:41.219352Z","steps":["trace[163161066] 'process raft request' (duration: 153.83355ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:48:41.219639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.056304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-17T10:48:41.219719Z","caller":"traceutil/trace.go:171","msg":"trace[1499864097] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:72; }","duration":"154.191677ms","start":"2026-07-17T10:48:41.06551Z","end":"2026-07-17T10:48:41.219702Z","steps":["trace[1499864097] 'agreement among raft nodes before linearized reading' (duration: 153.960943ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:48:46.620211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.270213ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-17T10:48:46.620362Z","caller":"traceutil/trace.go:171","msg":"trace[60081918] linearizableReadLoop","detail":"{readStateIndex:299; appliedIndex:298; }","duration":"384.493077ms","start":"2026-07-17T10:48:46.23585Z","end":"2026-07-17T10:48:46.620343Z","steps":["trace[60081918] 'read index received' (duration: 235.307847ms)","trace[60081918] 'applied index is now lower than readState.Index' (duration: 149.18383ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:48:46.620433Z","caller":"traceutil/trace.go:171","msg":"trace[267676945] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"412.884486ms","start":"2026-07-17T10:48:46.207475Z","end":"2026-07-17T10:48:46.620359Z","steps":["trace[267676945] 'process raft request' (duration: 263.795198ms)","trace[267676945] 'compare' (duration: 148.08983ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:48:46.620596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.761782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-17T10:48:46.620636Z","caller":"traceutil/trace.go:171","msg":"trace[1937537111] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:288; }","duration":"384.864975ms","start":"2026-07-17T10:48:46.23576Z","end":"2026-07-17T10:48:46.620625Z","steps":["trace[1937537111] 'agreement among raft nodes before linearized reading' (duration: 384.685302ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:48:46.620788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:48:46.207452Z","time spent":"413.217062ms","remote":"127.0.0.1:42388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":180,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T10:48:46.620793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:48:46.235729Z","time spent":"384.991826ms","remote":"127.0.0.1:42436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-17T10:48:47.31226Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833219882209470,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T10:48:47.812668Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833219882209470,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T10:48:47.826334Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.141085679s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-17T10:48:47.960927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.238178ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-17T10:48:47.961076Z","caller":"traceutil/trace.go:171","msg":"trace[1614949917] linearizableReadLoop","detail":"{readStateIndex:302; appliedIndex:301; }","duration":"1.149736228s","start":"2026-07-17T10:48:46.811323Z","end":"2026-07-17T10:48:47.96106Z","steps":["trace[1614949917] 'read index received' (duration: 1.015205935s)","trace[1614949917] 'applied index is now lower than readState.Index' (duration: 134.512172ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:48:47.96109Z","caller":"traceutil/trace.go:171","msg":"trace[1105204564] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"1.275953398s","start":"2026-07-17T10:48:46.685101Z","end":"2026-07-17T10:48:47.961054Z","steps":["trace[1105204564] 'process raft request' (duration: 1.141492616s)","trace[1105204564] 'compare' (duration: 134.078965ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:48:47.961175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:48:46.685076Z","time spent":"1.27607244s","remote":"127.0.0.1:42388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T10:48:47.961262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.149932671s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-17T10:48:47.961332Z","caller":"traceutil/trace.go:171","msg":"trace[1650885493] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:291; }","duration":"1.150044203s","start":"2026-07-17T10:48:46.811267Z","end":"2026-07-17T10:48:47.961311Z","steps":["trace[1650885493] 'agreement among raft nodes before linearized reading' (duration: 1.14988311s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:48:47.96138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:48:46.811242Z","time spent":"1.150128784s","remote":"127.0.0.1:42436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T10:48:47.96227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.522706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-17T10:48:47.962371Z","caller":"traceutil/trace.go:171","msg":"trace[1394706317] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:291; }","duration":"785.685758ms","start":"2026-07-17T10:48:47.176662Z","end":"2026-07-17T10:48:47.962348Z","steps":["trace[1394706317] 'agreement among raft nodes before linearized reading' (duration: 784.876483ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:48:47.962435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:48:47.17664Z","time spent":"785.782251ms","remote":"127.0.0.1:42436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-17T10:48:49.041875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.838575ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T10:48:49.042117Z","caller":"traceutil/trace.go:171","msg":"trace[2001500124] linearizableReadLoop","detail":"{readStateIndex:304; appliedIndex:302; }","duration":"1.07319936s","start":"2026-07-17T10:48:47.968893Z","end":"2026-07-17T10:48:49.042092Z","steps":["trace[2001500124] 'read index received' (duration: 199.748454ms)","trace[2001500124] 'applied index is now lower than readState.Index' (duration: 873.446966ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:48:49.042311Z","caller":"traceutil/trace.go:171","msg":"trace[387440153] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"1.075608003s","start":"2026-07-17T10:48:47.96668Z","end":"2026-07-17T10:48:49.042288Z","steps":["trace[387440153] 'process raft request' (duration: 1.075335639s)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:48:49.042362Z","caller":"traceutil/trace.go:171","msg":"trace[952489819] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"1.07601759s","start":"2026-07-17T10:48:47.966319Z","end":"2026-07-17T10:48:49.042336Z","steps":["trace[952489819] 'process raft request' (duration: 202.298ms)","trace[952489819] 'compare' (duration: 872.661802ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:48:49.042489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.073587937s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-07-17T10:48:49.04267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:48:47.9663Z","time spent":"1.076270115s","remote":"127.0.0.1:42436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T10:48:49.042684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"873.17005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-17T10:48:49.042802Z","caller":"traceutil/trace.go:171","msg":"trace[573643835] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:293; }","duration":"873.309483ms","start":"2026-07-17T10:48:48.169461Z","end":"2026-07-17T10:48:49.042771Z","steps":["trace[573643835] 'agreement among raft nodes before linearized reading' (duration: 873.082578ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:48:49.042479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:48:47.966664Z","time spent":"1.075706255s","remote":"127.0.0.1:42436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T10:48:49.042816Z","caller":"traceutil/trace.go:171","msg":"trace[1006350196] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:293; }","duration":"1.073870542s","start":"2026-07-17T10:48:47.968873Z","end":"2026-07-17T10:48:49.042744Z","steps":["trace[1006350196] 'agreement among raft nodes before linearized reading' (duration: 1.073449934s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:48:49.042687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.642396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-07-17T10:48:49.043009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:48:47.968867Z","time spent":"1.074120466s","remote":"127.0.0.1:42282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-07-17T10:48:49.043055Z","caller":"traceutil/trace.go:171","msg":"trace[112505187] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:293; }","duration":"385.092254ms","start":"2026-07-17T10:48:48.657944Z","end":"2026-07-17T10:48:49.043036Z","steps":["trace[112505187] 'agreement among raft nodes before linearized reading' (duration: 384.600905ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:48:49.043271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:48:48.65792Z","time spent":"385.263848ms","remote":"127.0.0.1:42436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-17T10:48:49.043802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:48:48.169389Z","time spent":"873.611259ms","remote":"127.0.0.1:42436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T10:48:49.742357Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833219882209478,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-17T10:48:49.849577Z","caller":"traceutil/trace.go:171","msg":"trace[950708814] linearizableReadLoop","detail":"{readStateIndex:307; appliedIndex:304; }","duration":"607.628266ms","start":"2026-07-17T10:48:49.241916Z","end":"2026-07-17T10:48:49.849545Z","steps":["trace[950708814] 'read index received' (duration: 546.132016ms)","trace[950708814] 'applied index is now lower than readState.Index' (duration: 61.49542ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:48:49.849715Z","caller":"traceutil/trace.go:171","msg":"trace[1624341027] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"803.099873ms","start":"2026-07-17T10:48:49.046603Z","end":"2026-07-17T10:48:49.849703Z","steps":["trace[1624341027] 'process raft request' (duration: 802.840198ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:48:49.84984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:48:49.046567Z","time spent":"803.202414ms","remote":"127.0.0.1:42388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":194,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T10:48:49.849847Z","caller":"traceutil/trace.go:171","msg":"trace[1518390579] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"803.269425ms","start":"2026-07-17T10:48:49.046564Z","end":"2026-07-17T10:48:49.849834Z","steps":["trace[1518390579] 'process raft request' (duration: 741.46893ms)","trace[1518390579] 'compare' (duration: 60.98027ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:48:49.849891Z","caller":"traceutil/trace.go:171","msg":"trace[459991575] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"802.234757ms","start":"2026-07-17T10:48:49.047639Z","end":"2026-07-17T10:48:49.849873Z","steps":["trace[459991575] 'process raft request' (duration: 801.86669ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:48:49.849935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.7748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T10:48:49.849971Z","caller":"traceutil/trace.go:171","msg":"trace[1501612421] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:296; }","duration":"167.862922ms","start":"2026-07-17T10:48:49.6821Z","end":"2026-07-17T10:48:49.849963Z","steps":["trace[1501612421] 'agreement among raft nodes before linearized reading' (duration: 167.803491ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:48:49.849904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.953952ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T10:48:49.849997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:48:49.04763Z","time spent":"802.304818ms","remote":"127.0.0.1:42436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T10:48:49.850043Z","caller":"traceutil/trace.go:171","msg":"trace[442632991] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:296; }","duration":"608.113855ms","start":"2026-07-17T10:48:49.241908Z","end":"2026-07-17T10:48:49.850022Z","steps":["trace[442632991] 'agreement among raft nodes before linearized reading' (duration: 607.916582ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:48:49.850067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:48:49.046537Z","time spent":"803.406618ms","remote":"127.0.0.1:42436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T10:48:50.045852Z","caller":"traceutil/trace.go:171","msg":"trace[319465803] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"107.269373ms","start":"2026-07-17T10:48:49.938503Z","end":"2026-07-17T10:48:50.045772Z","steps":["trace[319465803] 'process raft request' (duration: 74.618844ms)","trace[319465803] 'compare' (duration: 32.232922ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:49:20.194123Z","caller":"traceutil/trace.go:171","msg":"trace[762551176] linearizableReadLoop","detail":"{readStateIndex:517; appliedIndex:516; }","duration":"155.622542ms","start":"2026-07-17T10:49:20.038475Z","end":"2026-07-17T10:49:20.194098Z","steps":["trace[762551176] 'read index received' (duration: 155.451808ms)","trace[762551176] 'applied index is now lower than readState.Index' (duration: 169.743µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:49:20.194179Z","caller":"traceutil/trace.go:171","msg":"trace[1985018665] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"183.250412ms","start":"2026-07-17T10:49:20.010902Z","end":"2026-07-17T10:49:20.194152Z","steps":["trace[1985018665] 'process raft request' (duration: 183.053139ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:20.194304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.807794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T10:49:20.194381Z","caller":"traceutil/trace.go:171","msg":"trace[277075717] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:498; }","duration":"155.928766ms","start":"2026-07-17T10:49:20.038429Z","end":"2026-07-17T10:49:20.194358Z","steps":["trace[277075717] 'agreement among raft nodes before linearized reading' (duration: 155.812655ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:20.194413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.213321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-17T10:49:20.194449Z","caller":"traceutil/trace.go:171","msg":"trace[1855589636] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:498; }","duration":"145.294611ms","start":"2026-07-17T10:49:20.049145Z","end":"2026-07-17T10:49:20.194439Z","steps":["trace[1855589636] 'agreement among raft nodes before linearized reading' (duration: 145.20326ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:21.767852Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833219882209907,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T10:49:21.915747Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.353966947s","expected-duration":"1s"} {"level":"info","ts":"2026-07-17T10:49:21.916201Z","caller":"traceutil/trace.go:171","msg":"trace[1092058638] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"1.354486724s","start":"2026-07-17T10:49:20.561683Z","end":"2026-07-17T10:49:21.91617Z","steps":["trace[1092058638] 'process raft request' (duration: 1.354348552s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:21.916362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:49:20.561665Z","time spent":"1.354598806s","remote":"127.0.0.1:42222","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-07-17T10:49:21.977579Z","caller":"traceutil/trace.go:171","msg":"trace[1634811619] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"1.415581256s","start":"2026-07-17T10:49:20.561972Z","end":"2026-07-17T10:49:21.977554Z","steps":["trace[1634811619] 'process raft request' (duration: 1.415311423s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:21.977707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:49:20.561962Z","time spent":"1.415680978s","remote":"127.0.0.1:42222","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-07-17T10:49:21.977816Z","caller":"traceutil/trace.go:171","msg":"trace[1875350083] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"1.414257736s","start":"2026-07-17T10:49:20.563521Z","end":"2026-07-17T10:49:21.977779Z","steps":["trace[1875350083] 'process raft request' (duration: 1.413863471s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:21.977982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:49:20.563512Z","time spent":"1.414394919s","remote":"127.0.0.1:42222","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-07-17T10:49:21.977996Z","caller":"traceutil/trace.go:171","msg":"trace[940954742] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"1.393832301s","start":"2026-07-17T10:49:20.584144Z","end":"2026-07-17T10:49:21.977976Z","steps":["trace[940954742] 'process raft request' (duration: 1.393501066s)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:49:21.978108Z","caller":"traceutil/trace.go:171","msg":"trace[1526053993] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"1.389847832s","start":"2026-07-17T10:49:20.588229Z","end":"2026-07-17T10:49:21.978077Z","steps":["trace[1526053993] 'process raft request' (duration: 1.38964525s)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:49:21.978187Z","caller":"traceutil/trace.go:171","msg":"trace[1488107180] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"1.406200411s","start":"2026-07-17T10:49:20.571974Z","end":"2026-07-17T10:49:21.978175Z","steps":["trace[1488107180] 'process raft request' (duration: 1.405547991s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:21.978238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:49:20.588226Z","time spent":"1.389959334s","remote":"127.0.0.1:42222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T10:49:21.9783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:49:20.571945Z","time spent":"1.406324922s","remote":"127.0.0.1:42222","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-07-17T10:49:21.978356Z","caller":"traceutil/trace.go:171","msg":"trace[1051038333] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"1.36422209s","start":"2026-07-17T10:49:20.614123Z","end":"2026-07-17T10:49:21.978345Z","steps":["trace[1051038333] 'process raft request' (duration: 1.363850795s)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:49:21.978305Z","caller":"traceutil/trace.go:171","msg":"trace[1625050127] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"1.374094183s","start":"2026-07-17T10:49:20.604198Z","end":"2026-07-17T10:49:21.978292Z","steps":["trace[1625050127] 'process raft request' (duration: 1.373722968s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:21.978418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:49:20.614093Z","time spent":"1.364297912s","remote":"127.0.0.1:42222","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-07-17T10:49:21.978476Z","caller":"traceutil/trace.go:171","msg":"trace[830334486] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"1.390255618s","start":"2026-07-17T10:49:20.588181Z","end":"2026-07-17T10:49:21.978437Z","steps":["trace[830334486] 'process raft request' (duration: 1.389597218s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:21.978474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:49:20.60418Z","time spent":"1.374270957s","remote":"127.0.0.1:42222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T10:49:21.978278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:49:20.584126Z","time spent":"1.393997863s","remote":"127.0.0.1:42222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T10:49:21.978383Z","caller":"traceutil/trace.go:171","msg":"trace[1258276729] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"1.403053455s","start":"2026-07-17T10:49:20.57532Z","end":"2026-07-17T10:49:21.978374Z","steps":["trace[1258276729] 'process raft request' (duration: 1.402280733s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:21.978679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:49:20.5753Z","time spent":"1.403348248s","remote":"127.0.0.1:42222","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-07-17T10:49:21.978704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:49:20.588168Z","time spent":"1.390336359s","remote":"127.0.0.1:42222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T10:49:21.978997Z","caller":"traceutil/trace.go:171","msg":"trace[892279170] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"1.348751474s","start":"2026-07-17T10:49:20.630197Z","end":"2026-07-17T10:49:21.978948Z","steps":["trace[892279170] 'process raft request' (duration: 1.347840342s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:21.979151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:49:20.63017Z","time spent":"1.348907847s","remote":"127.0.0.1:42222","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-07-17T10:49:21.979164Z","caller":"traceutil/trace.go:171","msg":"trace[325301045] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"1.248499878s","start":"2026-07-17T10:49:20.730652Z","end":"2026-07-17T10:49:21.979152Z","steps":["trace[325301045] 'process raft request' (duration: 1.247498094s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:21.979284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:49:20.730622Z","time spent":"1.248634262s","remote":"127.0.0.1:42222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T10:49:22.026619Z","caller":"traceutil/trace.go:171","msg":"trace[232140962] transaction","detail":"{read_only:false; number_of_response:1; response_revision:514; }","duration":"102.161804ms","start":"2026-07-17T10:49:21.92441Z","end":"2026-07-17T10:49:22.026571Z","steps":["trace[232140962] 'process raft request' (duration: 102.103603ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:49:22.027303Z","caller":"traceutil/trace.go:171","msg":"trace[1199620471] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"102.989916ms","start":"2026-07-17T10:49:21.924282Z","end":"2026-07-17T10:49:22.027272Z","steps":["trace[1199620471] 'process raft request' (duration: 102.146214ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:49:22.027882Z","caller":"traceutil/trace.go:171","msg":"trace[366943585] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"582.92996ms","start":"2026-07-17T10:49:21.444868Z","end":"2026-07-17T10:49:22.027798Z","steps":["trace[366943585] 'process raft request' (duration: 534.144366ms)","trace[366943585] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; req_size:670; } (duration: 45.76871ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:49:22.028619Z","caller":"traceutil/trace.go:171","msg":"trace[1273508283] linearizableReadLoop","detail":"{readStateIndex:531; appliedIndex:518; }","duration":"760.720178ms","start":"2026-07-17T10:49:21.267249Z","end":"2026-07-17T10:49:22.027969Z","steps":["trace[1273508283] 'read index received' (duration: 648.846564ms)","trace[1273508283] 'applied index is now lower than readState.Index' (duration: 111.629671ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:49:22.028314Z","caller":"traceutil/trace.go:171","msg":"trace[437188643] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"104.139993ms","start":"2026-07-17T10:49:21.922965Z","end":"2026-07-17T10:49:22.027105Z","steps":["trace[437188643] 'process raft request' (duration: 103.356291ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:22.031176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:49:21.444831Z","time spent":"583.587029ms","remote":"127.0.0.1:42436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T10:49:22.031312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.469309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-17T10:49:22.032664Z","caller":"traceutil/trace.go:171","msg":"trace[11583899] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:514; }","duration":"765.455716ms","start":"2026-07-17T10:49:21.267196Z","end":"2026-07-17T10:49:22.032652Z","steps":["trace[11583899] 'agreement among raft nodes before linearized reading' (duration: 761.374327ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:22.032727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:49:21.267169Z","time spent":"765.549028ms","remote":"127.0.0.1:42436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T10:49:22.046353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.582979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-17T10:49:22.046412Z","caller":"traceutil/trace.go:171","msg":"trace[653594153] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:522; }","duration":"743.68838ms","start":"2026-07-17T10:49:21.302711Z","end":"2026-07-17T10:49:22.0464Z","steps":["trace[653594153] 'agreement among raft nodes before linearized reading' (duration: 743.559729ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:22.046437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:49:21.30269Z","time spent":"743.741161ms","remote":"127.0.0.1:42436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-17T10:49:22.761276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.840946ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T10:49:22.761626Z","caller":"traceutil/trace.go:171","msg":"trace[469656311] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"441.596423ms","start":"2026-07-17T10:49:22.320014Z","end":"2026-07-17T10:49:22.76161Z","steps":["trace[469656311] 'process raft request' (duration: 441.551232ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:22.761691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:49:22.319991Z","time spent":"441.674154ms","remote":"127.0.0.1:42222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":73770,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T10:49:22.761787Z","caller":"traceutil/trace.go:171","msg":"trace[1828621707] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"496.42819ms","start":"2026-07-17T10:49:22.265331Z","end":"2026-07-17T10:49:22.761759Z","steps":["trace[1828621707] 'process raft request' (duration: 171.030846ms)","trace[1828621707] 'compare' (duration: 324.727965ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:49:22.761835Z","caller":"traceutil/trace.go:171","msg":"trace[351682442] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"455.401501ms","start":"2026-07-17T10:49:22.306395Z","end":"2026-07-17T10:49:22.761797Z","steps":["trace[351682442] 'process raft request' (duration: 455.016466ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:22.761921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:49:22.265301Z","time spent":"496.548611ms","remote":"127.0.0.1:42222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T10:49:22.762019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:49:22.306365Z","time spent":"455.552814ms","remote":"127.0.0.1:42222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65190,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T10:49:24.242834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.347470444s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T10:49:24.242956Z","caller":"traceutil/trace.go:171","msg":"trace[909157270] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:552; }","duration":"1.347606326s","start":"2026-07-17T10:49:22.895333Z","end":"2026-07-17T10:49:24.242939Z","steps":["trace[909157270] 'range keys from in-memory index tree' (duration: 1.347455264s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:24.243369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.346460241s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-17T10:49:24.243785Z","caller":"traceutil/trace.go:171","msg":"trace[1077690816] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"1.453052632s","start":"2026-07-17T10:49:22.790707Z","end":"2026-07-17T10:49:24.243759Z","steps":["trace[1077690816] 'process raft request' (duration: 106.137135ms)","trace[1077690816] 'compare' (duration: 1.346276287s)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:49:24.243905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:49:22.790689Z","time spent":"1.453162353s","remote":"127.0.0.1:42222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19834,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T10:49:24.244014Z","caller":"traceutil/trace.go:171","msg":"trace[549969617] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:577; }","duration":"207.050661ms","start":"2026-07-17T10:49:24.036947Z","end":"2026-07-17T10:49:24.243997Z","steps":["trace[549969617] 'read index received' (duration: 205.934165ms)","trace[549969617] 'applied index is now lower than readState.Index' (duration: 1.115696ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:49:24.244195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.244703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T10:49:24.244225Z","caller":"traceutil/trace.go:171","msg":"trace[543774270] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:555; }","duration":"207.329475ms","start":"2026-07-17T10:49:24.036887Z","end":"2026-07-17T10:49:24.244217Z","steps":["trace[543774270] 'agreement among raft nodes before linearized reading' (duration: 207.242764ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:49:24.244324Z","caller":"traceutil/trace.go:171","msg":"trace[188740212] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"1.451974396s","start":"2026-07-17T10:49:22.792343Z","end":"2026-07-17T10:49:24.244317Z","steps":["trace[188740212] 'process raft request' (duration: 1.451209676s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:24.244359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:49:22.792319Z","time spent":"1.452016487s","remote":"127.0.0.1:42222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59616,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T10:49:24.244417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.162086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-17T10:49:24.244446Z","caller":"traceutil/trace.go:171","msg":"trace[534997067] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"1.448015059s","start":"2026-07-17T10:49:22.796424Z","end":"2026-07-17T10:49:24.244439Z","steps":["trace[534997067] 'process raft request' (duration: 1.447505112s)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:49:24.244455Z","caller":"traceutil/trace.go:171","msg":"trace[1568180804] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:555; }","duration":"165.231677ms","start":"2026-07-17T10:49:24.079211Z","end":"2026-07-17T10:49:24.244443Z","steps":["trace[1568180804] 'agreement among raft nodes before linearized reading' (duration: 165.111635ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:24.244484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:49:22.796406Z","time spent":"1.448057419s","remote":"127.0.0.1:42222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80679,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T10:49:24.244904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.686132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-17T10:49:24.244923Z","caller":"traceutil/trace.go:171","msg":"trace[1428601751] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:555; }","duration":"155.737562ms","start":"2026-07-17T10:49:24.08918Z","end":"2026-07-17T10:49:24.244917Z","steps":["trace[1428601751] 'agreement among raft nodes before linearized reading' (duration: 155.648931ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:29.397153Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833219882210085,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-17T10:49:29.890388Z","caller":"traceutil/trace.go:171","msg":"trace[860921748] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:632; }","duration":"994.169901ms","start":"2026-07-17T10:49:28.896163Z","end":"2026-07-17T10:49:29.890333Z","steps":["trace[860921748] 'read index received' (duration: 992.905964ms)","trace[860921748] 'applied index is now lower than readState.Index' (duration: 1.262807ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:49:29.890444Z","caller":"traceutil/trace.go:171","msg":"trace[1870209471] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"1.027304163s","start":"2026-07-17T10:49:28.86312Z","end":"2026-07-17T10:49:29.890424Z","steps":["trace[1870209471] 'process raft request' (duration: 1.026307008s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:29.890589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:49:28.863079Z","time spent":"1.027417654s","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036233,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T10:49:29.890585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"850.66499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T10:49:29.890663Z","caller":"traceutil/trace.go:171","msg":"trace[65893404] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:607; }","duration":"850.779981ms","start":"2026-07-17T10:49:29.039868Z","end":"2026-07-17T10:49:29.890648Z","steps":["trace[65893404] 'agreement among raft nodes before linearized reading' (duration: 850.64344ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:29.890682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"994.515447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-ngwvm\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-07-17T10:49:29.89075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:49:29.03985Z","time spent":"850.886344ms","remote":"127.0.0.1:42162","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-07-17T10:49:29.890791Z","caller":"traceutil/trace.go:171","msg":"trace[1455783857] range","detail":"{range_begin:/registry/pods/kube-system/cilium-ngwvm; range_end:; response_count:1; response_revision:607; }","duration":"994.669558ms","start":"2026-07-17T10:49:28.896103Z","end":"2026-07-17T10:49:29.890773Z","steps":["trace[1455783857] 'agreement among raft nodes before linearized reading' (duration: 994.362174ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:29.891047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:49:28.896075Z","time spent":"994.954261ms","remote":"127.0.0.1:42370","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-ngwvm\" "} {"level":"info","ts":"2026-07-17T10:49:30.037761Z","caller":"traceutil/trace.go:171","msg":"trace[1906265810] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"129.661261ms","start":"2026-07-17T10:49:29.908071Z","end":"2026-07-17T10:49:30.037732Z","steps":["trace[1906265810] 'process raft request' (duration: 129.475698ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:49:30.048537Z","caller":"traceutil/trace.go:171","msg":"trace[56676580] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"100.424456ms","start":"2026-07-17T10:49:29.94809Z","end":"2026-07-17T10:49:30.048515Z","steps":["trace[56676580] 'process raft request' (duration: 100.261594ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:32.174952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.900731032s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-17T10:49:32.175056Z","caller":"traceutil/trace.go:171","msg":"trace[1019075855] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:609; }","duration":"1.900881295s","start":"2026-07-17T10:49:30.274148Z","end":"2026-07-17T10:49:32.175029Z","steps":["trace[1019075855] 'range keys from in-memory index tree' (duration: 1.900514149s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:32.175114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:49:30.274131Z","time spent":"1.900967956s","remote":"127.0.0.1:42436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-17T10:49:32.17527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.890919558s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-17T10:49:32.175349Z","caller":"traceutil/trace.go:171","msg":"trace[1878275042] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:609; }","duration":"1.890999039s","start":"2026-07-17T10:49:30.284312Z","end":"2026-07-17T10:49:32.175311Z","steps":["trace[1878275042] 'range keys from in-memory index tree' (duration: 1.890556813s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:32.175395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:49:30.284302Z","time spent":"1.891079311s","remote":"127.0.0.1:42436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T10:49:32.175455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.895297909s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-17T10:49:32.175526Z","caller":"traceutil/trace.go:171","msg":"trace[771570760] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:609; }","duration":"1.895439251s","start":"2026-07-17T10:49:30.280068Z","end":"2026-07-17T10:49:32.175508Z","steps":["trace[771570760] 'range keys from in-memory index tree' (duration: 1.894567158s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:32.175566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:49:30.280037Z","time spent":"1.895520031s","remote":"127.0.0.1:42436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-17T10:49:32.175582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.891630287s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-17T10:49:32.175633Z","caller":"traceutil/trace.go:171","msg":"trace[1605182770] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:609; }","duration":"1.891710489s","start":"2026-07-17T10:49:30.283906Z","end":"2026-07-17T10:49:32.175617Z","steps":["trace[1605182770] 'range keys from in-memory index tree' (duration: 1.890756816s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:32.175681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:49:30.283889Z","time spent":"1.89178078s","remote":"127.0.0.1:42436","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-07-17T10:49:32.429974Z","caller":"traceutil/trace.go:171","msg":"trace[2094012307] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:635; }","duration":"457.451021ms","start":"2026-07-17T10:49:31.972491Z","end":"2026-07-17T10:49:32.429942Z","steps":["trace[2094012307] 'read index received' (duration: 456.199644ms)","trace[2094012307] 'applied index is now lower than readState.Index' (duration: 1.250487ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:49:32.430112Z","caller":"traceutil/trace.go:171","msg":"trace[1723920450] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"1.952655202s","start":"2026-07-17T10:49:30.477441Z","end":"2026-07-17T10:49:32.430096Z","steps":["trace[1723920450] 'process raft request' (duration: 1.951709799s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:32.430267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:49:30.477407Z","time spent":"1.952735234s","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036265,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T10:49:32.430459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.962108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.115\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-17T10:49:32.430534Z","caller":"traceutil/trace.go:171","msg":"trace[1106996996] range","detail":"{range_begin:/registry/masterleases/199.204.45.115; range_end:; response_count:1; response_revision:610; }","duration":"458.11798ms","start":"2026-07-17T10:49:31.972395Z","end":"2026-07-17T10:49:32.430513Z","steps":["trace[1106996996] 'agreement among raft nodes before linearized reading' (duration: 457.832096ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:32.43058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:49:31.972362Z","time spent":"458.208361ms","remote":"127.0.0.1:42210","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.115\" "} {"level":"warn","ts":"2026-07-17T10:49:32.453543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.992649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T10:49:32.453612Z","caller":"traceutil/trace.go:171","msg":"trace[1477824663] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:615; }","duration":"415.132882ms","start":"2026-07-17T10:49:32.038463Z","end":"2026-07-17T10:49:32.453596Z","steps":["trace[1477824663] 'agreement among raft nodes before linearized reading' (duration: 415.01469ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:32.453643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:49:32.038431Z","time spent":"415.206513ms","remote":"127.0.0.1:42152","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-07-17T10:49:32.453867Z","caller":"traceutil/trace.go:171","msg":"trace[520536543] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"360.770463ms","start":"2026-07-17T10:49:32.093089Z","end":"2026-07-17T10:49:32.45386Z","steps":["trace[520536543] 'process raft request' (duration: 359.951821ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:32.453937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:49:32.093063Z","time spent":"360.829123ms","remote":"127.0.0.1:42436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T10:49:32.453986Z","caller":"traceutil/trace.go:171","msg":"trace[1124413331] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"272.234138ms","start":"2026-07-17T10:49:32.181703Z","end":"2026-07-17T10:49:32.453937Z","steps":["trace[1124413331] 'process raft request' (duration: 271.72767ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:49:32.45415Z","caller":"traceutil/trace.go:171","msg":"trace[1938977484] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"272.596682ms","start":"2026-07-17T10:49:32.181463Z","end":"2026-07-17T10:49:32.454059Z","steps":["trace[1938977484] 'process raft request' (duration: 271.914583ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:49:32.454033Z","caller":"traceutil/trace.go:171","msg":"trace[1841166322] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"272.202697ms","start":"2026-07-17T10:49:32.181823Z","end":"2026-07-17T10:49:32.454026Z","steps":["trace[1841166322] 'process raft request' (duration: 271.626648ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:49:32.454296Z","caller":"traceutil/trace.go:171","msg":"trace[2038460951] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"272.671503ms","start":"2026-07-17T10:49:32.181615Z","end":"2026-07-17T10:49:32.454286Z","steps":["trace[2038460951] 'process raft request' (duration: 271.792961ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:49:33.028698Z","caller":"traceutil/trace.go:171","msg":"trace[1667189125] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"104.114135ms","start":"2026-07-17T10:49:32.924522Z","end":"2026-07-17T10:49:33.028636Z","steps":["trace[1667189125] 'process raft request' (duration: 103.826731ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:34.568102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.979089ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T10:49:34.568246Z","caller":"traceutil/trace.go:171","msg":"trace[468810028] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:673; }","duration":"297.655971ms","start":"2026-07-17T10:49:34.270569Z","end":"2026-07-17T10:49:34.568225Z","steps":["trace[468810028] 'read index received' (duration: 40.951µs)","trace[468810028] 'applied index is now lower than readState.Index' (duration: 297.61327ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:49:34.568292Z","caller":"traceutil/trace.go:171","msg":"trace[2093147242] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"461.771933ms","start":"2026-07-17T10:49:34.106494Z","end":"2026-07-17T10:49:34.568266Z","steps":["trace[2093147242] 'process raft request' (duration: 67.273816ms)","trace[2093147242] 'compare' (duration: 393.850007ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:49:34.568407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:49:34.106481Z","time spent":"461.865454ms","remote":"127.0.0.1:42222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10353,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T10:49:34.568396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.809493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T10:49:34.568507Z","caller":"traceutil/trace.go:171","msg":"trace[2032207717] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:647; }","duration":"297.960375ms","start":"2026-07-17T10:49:34.270518Z","end":"2026-07-17T10:49:34.568478Z","steps":["trace[2032207717] 'agreement among raft nodes before linearized reading' (duration: 297.794763ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:34.571634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.524879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-17T10:49:34.571701Z","caller":"traceutil/trace.go:171","msg":"trace[953976552] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:648; }","duration":"113.63518ms","start":"2026-07-17T10:49:34.458047Z","end":"2026-07-17T10:49:34.571682Z","steps":["trace[953976552] 'agreement among raft nodes before linearized reading' (duration: 113.391347ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:34.571926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.152683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-17T10:49:34.572012Z","caller":"traceutil/trace.go:171","msg":"trace[128324999] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:648; }","duration":"113.276136ms","start":"2026-07-17T10:49:34.458717Z","end":"2026-07-17T10:49:34.571993Z","steps":["trace[128324999] 'agreement among raft nodes before linearized reading' (duration: 112.985612ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:34.572059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.959816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-17T10:49:34.572113Z","caller":"traceutil/trace.go:171","msg":"trace[147432089] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:648; }","duration":"114.054537ms","start":"2026-07-17T10:49:34.458047Z","end":"2026-07-17T10:49:34.572101Z","steps":["trace[147432089] 'agreement among raft nodes before linearized reading' (duration: 113.909224ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:34.571938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.136303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-17T10:49:34.572229Z","caller":"traceutil/trace.go:171","msg":"trace[2013882286] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:648; }","duration":"113.465668ms","start":"2026-07-17T10:49:34.45875Z","end":"2026-07-17T10:49:34.572215Z","steps":["trace[2013882286] 'agreement among raft nodes before linearized reading' (duration: 112.764408ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:49:34.572187Z","caller":"traceutil/trace.go:171","msg":"trace[1409282883] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"100.320001ms","start":"2026-07-17T10:49:34.471746Z","end":"2026-07-17T10:49:34.572066Z","steps":["trace[1409282883] 'process raft request' (duration: 99.587111ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:49:35.060042Z","caller":"traceutil/trace.go:171","msg":"trace[1843874111] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"123.072674ms","start":"2026-07-17T10:49:34.936944Z","end":"2026-07-17T10:49:35.060017Z","steps":["trace[1843874111] 'process raft request' (duration: 120.037853ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:49:35.060614Z","caller":"traceutil/trace.go:171","msg":"trace[388270008] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"114.785932ms","start":"2026-07-17T10:49:34.945786Z","end":"2026-07-17T10:49:35.060572Z","steps":["trace[388270008] 'process raft request' (duration: 114.157604ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:49:37.582577Z","caller":"traceutil/trace.go:171","msg":"trace[1083845622] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"509.923218ms","start":"2026-07-17T10:49:37.072617Z","end":"2026-07-17T10:49:37.58254Z","steps":["trace[1083845622] 'process raft request' (duration: 509.821957ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:37.582695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:49:37.072568Z","time spent":"510.076911ms","remote":"127.0.0.1:42222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T10:49:37.582794Z","caller":"traceutil/trace.go:171","msg":"trace[1076837746] linearizableReadLoop","detail":"{readStateIndex:718; appliedIndex:718; }","duration":"312.126163ms","start":"2026-07-17T10:49:37.27066Z","end":"2026-07-17T10:49:37.582786Z","steps":["trace[1076837746] 'read index received' (duration: 312.122913ms)","trace[1076837746] 'applied index is now lower than readState.Index' (duration: 2.72µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:49:37.582912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.253556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-07-17T10:49:37.582948Z","caller":"traceutil/trace.go:171","msg":"trace[784411537] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:691; }","duration":"312.327156ms","start":"2026-07-17T10:49:37.270609Z","end":"2026-07-17T10:49:37.582936Z","steps":["trace[784411537] 'agreement among raft nodes before linearized reading' (duration: 312.205395ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:37.582972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:49:37.270589Z","time spent":"312.376016ms","remote":"127.0.0.1:42222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5200,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"info","ts":"2026-07-17T10:49:37.604912Z","caller":"traceutil/trace.go:171","msg":"trace[1396030106] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"100.409764ms","start":"2026-07-17T10:49:37.504454Z","end":"2026-07-17T10:49:37.604864Z","steps":["trace[1396030106] 'process raft request' (duration: 100.146859ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:49:38.320568Z","caller":"traceutil/trace.go:171","msg":"trace[2023621119] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"141.343928ms","start":"2026-07-17T10:49:38.179208Z","end":"2026-07-17T10:49:38.320552Z","steps":["trace[2023621119] 'process raft request' (duration: 140.76062ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:45.208292Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833219882210378,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-17T10:49:45.338508Z","caller":"traceutil/trace.go:171","msg":"trace[1745843328] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"639.101245ms","start":"2026-07-17T10:49:44.699383Z","end":"2026-07-17T10:49:45.338484Z","steps":["trace[1745843328] 'process raft request' (duration: 638.929653ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:45.338814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:49:44.699353Z","time spent":"639.235617ms","remote":"127.0.0.1:42436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T10:49:45.416643Z","caller":"traceutil/trace.go:171","msg":"trace[918702045] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"576.924763ms","start":"2026-07-17T10:49:44.839697Z","end":"2026-07-17T10:49:45.416622Z","steps":["trace[918702045] 'process raft request' (duration: 576.69639ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:45.41678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:49:44.839645Z","time spent":"577.062415ms","remote":"127.0.0.1:42354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4905,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T10:49:45.416819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.879589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-17T10:49:45.416847Z","caller":"traceutil/trace.go:171","msg":"trace[1322263705] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:762; }","duration":"708.95107ms","start":"2026-07-17T10:49:44.707888Z","end":"2026-07-17T10:49:45.416839Z","steps":["trace[1322263705] 'agreement among raft nodes before linearized reading' (duration: 708.798048ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:49:45.416651Z","caller":"traceutil/trace.go:171","msg":"trace[1961032727] linearizableReadLoop","detail":"{readStateIndex:793; appliedIndex:790; }","duration":"708.687506ms","start":"2026-07-17T10:49:44.707933Z","end":"2026-07-17T10:49:45.416621Z","steps":["trace[1961032727] 'read index received' (duration: 630.430554ms)","trace[1961032727] 'applied index is now lower than readState.Index' (duration: 78.256352ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:49:45.416869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:49:44.707869Z","time spent":"708.99501ms","remote":"127.0.0.1:42436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-17T10:49:45.416934Z","caller":"traceutil/trace.go:171","msg":"trace[113378526] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"337.523841ms","start":"2026-07-17T10:49:45.079402Z","end":"2026-07-17T10:49:45.416926Z","steps":["trace[113378526] 'process raft request' (duration: 337.182107ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:45.416992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.819302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-17T10:49:45.41699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:49:45.079349Z","time spent":"337.601742ms","remote":"127.0.0.1:42436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T10:49:45.417014Z","caller":"traceutil/trace.go:171","msg":"trace[1223613237] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:762; }","duration":"706.873614ms","start":"2026-07-17T10:49:44.710133Z","end":"2026-07-17T10:49:45.417007Z","steps":["trace[1223613237] 'agreement among raft nodes before linearized reading' (duration: 706.789413ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:45.417039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:49:44.710116Z","time spent":"706.918894ms","remote":"127.0.0.1:42436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T10:49:45.417142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.66651ms","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-07-17T10:49:45.417139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.466061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T10:49:45.417177Z","caller":"traceutil/trace.go:171","msg":"trace[27129013] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:762; }","duration":"706.705281ms","start":"2026-07-17T10:49:44.71045Z","end":"2026-07-17T10:49:45.417156Z","steps":["trace[27129013] 'agreement among raft nodes before linearized reading' (duration: 706.64477ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:45.417211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:49:44.710437Z","time spent":"706.767041ms","remote":"127.0.0.1:42436","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-07-17T10:49:45.417219Z","caller":"traceutil/trace.go:171","msg":"trace[1321827881] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:762; }","duration":"377.592943ms","start":"2026-07-17T10:49:45.039607Z","end":"2026-07-17T10:49:45.4172Z","steps":["trace[1321827881] 'agreement among raft nodes before linearized reading' (duration: 377.469422ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:45.417258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:49:45.039513Z","time spent":"377.738155ms","remote":"127.0.0.1:42152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T10:49:49.540427Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833219882210419,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-17T10:49:49.836499Z","caller":"traceutil/trace.go:171","msg":"trace[692283392] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:812; }","duration":"796.841049ms","start":"2026-07-17T10:49:49.039632Z","end":"2026-07-17T10:49:49.836473Z","steps":["trace[692283392] 'read index received' (duration: 796.532786ms)","trace[692283392] 'applied index is now lower than readState.Index' (duration: 307.093µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:49:49.836768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.121933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T10:49:49.836763Z","caller":"traceutil/trace.go:171","msg":"trace[1359209830] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"821.137235ms","start":"2026-07-17T10:49:49.015587Z","end":"2026-07-17T10:49:49.836724Z","steps":["trace[1359209830] 'process raft request' (duration: 820.65929ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:49:49.836857Z","caller":"traceutil/trace.go:171","msg":"trace[1795299227] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:781; }","duration":"797.254674ms","start":"2026-07-17T10:49:49.03957Z","end":"2026-07-17T10:49:49.836824Z","steps":["trace[1795299227] 'agreement among raft nodes before linearized reading' (duration: 797.136482ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:49.836919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:49:49.039546Z","time spent":"797.356436ms","remote":"127.0.0.1:42152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T10:49:49.836963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:49:49.015565Z","time spent":"821.294487ms","remote":"127.0.0.1:42414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T10:49:49.837125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.638675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-17T10:49:49.837776Z","caller":"traceutil/trace.go:171","msg":"trace[758683471] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:781; }","duration":"486.372135ms","start":"2026-07-17T10:49:49.351368Z","end":"2026-07-17T10:49:49.83774Z","steps":["trace[758683471] 'agreement among raft nodes before linearized reading' (duration: 485.396302ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:49.83785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:49:49.351351Z","time spent":"486.479536ms","remote":"127.0.0.1:42436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-17T10:49:49.837155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.545442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-17T10:49:49.837987Z","caller":"traceutil/trace.go:171","msg":"trace[1350767410] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:781; }","duration":"388.329953ms","start":"2026-07-17T10:49:49.449579Z","end":"2026-07-17T10:49:49.837909Z","steps":["trace[1350767410] 'agreement among raft nodes before linearized reading' (duration: 387.34269ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:49.837251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.921152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T10:49:49.838146Z","caller":"traceutil/trace.go:171","msg":"trace[1129577379] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:781; }","duration":"285.843634ms","start":"2026-07-17T10:49:49.552281Z","end":"2026-07-17T10:49:49.838124Z","steps":["trace[1129577379] 'agreement among raft nodes before linearized reading' (duration: 284.935992ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:49.837377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.99206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-17T10:49:49.838927Z","caller":"traceutil/trace.go:171","msg":"trace[259481885] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:781; }","duration":"398.55745ms","start":"2026-07-17T10:49:49.440325Z","end":"2026-07-17T10:49:49.838883Z","steps":["trace[259481885] 'agreement among raft nodes before linearized reading' (duration: 396.98874ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:49.83738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.842716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-17T10:49:49.839127Z","caller":"traceutil/trace.go:171","msg":"trace[275410970] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:781; }","duration":"389.595339ms","start":"2026-07-17T10:49:49.449496Z","end":"2026-07-17T10:49:49.839092Z","steps":["trace[275410970] 'agreement among raft nodes before linearized reading' (duration: 387.739805ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:49:49.839186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:49:49.449484Z","time spent":"389.68411ms","remote":"127.0.0.1:42436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T10:49:49.838048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:49:49.449574Z","time spent":"388.457874ms","remote":"127.0.0.1:42436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T10:49:49.839063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:49:49.440296Z","time spent":"398.723863ms","remote":"127.0.0.1:42436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-17T10:50:01.076864Z","caller":"traceutil/trace.go:171","msg":"trace[746455496] linearizableReadLoop","detail":"{readStateIndex:960; appliedIndex:958; }","duration":"181.452672ms","start":"2026-07-17T10:50:00.895378Z","end":"2026-07-17T10:50:01.07683Z","steps":["trace[746455496] 'read index received' (duration: 12.069676ms)","trace[746455496] 'applied index is now lower than readState.Index' (duration: 169.379276ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:50:01.076939Z","caller":"traceutil/trace.go:171","msg":"trace[1551686378] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"237.738719ms","start":"2026-07-17T10:50:00.839173Z","end":"2026-07-17T10:50:01.076911Z","steps":["trace[1551686378] 'process raft request' (duration: 236.907618ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:50:01.077176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.369078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12755"} {"level":"warn","ts":"2026-07-17T10:50:01.077194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.783605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-07-17T10:50:01.077234Z","caller":"traceutil/trace.go:171","msg":"trace[1891847865] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:926; }","duration":"180.440339ms","start":"2026-07-17T10:50:00.896775Z","end":"2026-07-17T10:50:01.077215Z","steps":["trace[1891847865] 'agreement among raft nodes before linearized reading' (duration: 180.247557ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:50:01.077285Z","caller":"traceutil/trace.go:171","msg":"trace[54182570] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:926; }","duration":"181.941366ms","start":"2026-07-17T10:50:00.895321Z","end":"2026-07-17T10:50:01.077262Z","steps":["trace[54182570] 'agreement among raft nodes before linearized reading' (duration: 181.650983ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:50:02.153373Z","caller":"traceutil/trace.go:171","msg":"trace[1559554176] linearizableReadLoop","detail":"{readStateIndex:970; appliedIndex:969; }","duration":"140.51229ms","start":"2026-07-17T10:50:02.012832Z","end":"2026-07-17T10:50:02.153344Z","steps":["trace[1559554176] 'read index received' (duration: 140.180515ms)","trace[1559554176] 'applied index is now lower than readState.Index' (duration: 329.865µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:50:02.153393Z","caller":"traceutil/trace.go:171","msg":"trace[1800322618] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"142.083578ms","start":"2026-07-17T10:50:02.011284Z","end":"2026-07-17T10:50:02.153367Z","steps":["trace[1800322618] 'process raft request' (duration: 141.794765ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:50:02.15357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.711982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-17T10:50:02.153615Z","caller":"traceutil/trace.go:171","msg":"trace[1920528004] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:935; }","duration":"140.803773ms","start":"2026-07-17T10:50:02.012799Z","end":"2026-07-17T10:50:02.153603Z","steps":["trace[1920528004] 'agreement among raft nodes before linearized reading' (duration: 140.644391ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:51:34.562711Z","caller":"traceutil/trace.go:171","msg":"trace[503962851] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"195.07309ms","start":"2026-07-17T10:51:34.367608Z","end":"2026-07-17T10:51:34.562681Z","steps":["trace[503962851] 'process raft request' (duration: 194.814047ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:51:34.697729Z","caller":"traceutil/trace.go:171","msg":"trace[343088344] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"107.268876ms","start":"2026-07-17T10:51:34.590426Z","end":"2026-07-17T10:51:34.697695Z","steps":["trace[343088344] 'process raft request' (duration: 107.222546ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:51:34.697814Z","caller":"traceutil/trace.go:171","msg":"trace[720968408] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"109.798271ms","start":"2026-07-17T10:51:34.58794Z","end":"2026-07-17T10:51:34.697738Z","steps":["trace[720968408] 'process raft request' (duration: 109.6668ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:51:34.697847Z","caller":"traceutil/trace.go:171","msg":"trace[2117125046] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"111.169484ms","start":"2026-07-17T10:51:34.586626Z","end":"2026-07-17T10:51:34.697795Z","steps":["trace[2117125046] 'process raft request' (duration: 110.808601ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:51:34.918003Z","caller":"traceutil/trace.go:171","msg":"trace[1362638740] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"175.542898ms","start":"2026-07-17T10:51:34.742417Z","end":"2026-07-17T10:51:34.91796Z","steps":["trace[1362638740] 'process raft request' (duration: 144.879237ms)","trace[1362638740] 'compare' (duration: 30.440629ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:51:38.865615Z","caller":"traceutil/trace.go:171","msg":"trace[1127792945] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"100.075362ms","start":"2026-07-17T10:51:38.76552Z","end":"2026-07-17T10:51:38.865595Z","steps":["trace[1127792945] 'process raft request' (duration: 82.419298ms)","trace[1127792945] 'compare' (duration: 17.400121ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:51:41.071131Z","caller":"traceutil/trace.go:171","msg":"trace[475956870] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"101.661503ms","start":"2026-07-17T10:51:40.96945Z","end":"2026-07-17T10:51:41.071112Z","steps":["trace[475956870] 'process raft request' (duration: 101.530582ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:51:41.083205Z","caller":"traceutil/trace.go:171","msg":"trace[1425368447] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"113.593781ms","start":"2026-07-17T10:51:40.96959Z","end":"2026-07-17T10:51:41.083184Z","steps":["trace[1425368447] 'process raft request' (duration: 113.401909ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:51:41.083271Z","caller":"traceutil/trace.go:171","msg":"trace[647246547] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"113.288408ms","start":"2026-07-17T10:51:40.969954Z","end":"2026-07-17T10:51:41.083242Z","steps":["trace[647246547] 'process raft request' (duration: 113.149297ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:51:47.991737Z","caller":"traceutil/trace.go:171","msg":"trace[828891497] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"254.125831ms","start":"2026-07-17T10:51:47.737581Z","end":"2026-07-17T10:51:47.991707Z","steps":["trace[828891497] 'process raft request' (duration: 253.952779ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:52:01.440883Z","caller":"traceutil/trace.go:171","msg":"trace[1480383426] linearizableReadLoop","detail":"{readStateIndex:1449; appliedIndex:1448; }","duration":"140.128103ms","start":"2026-07-17T10:52:01.300725Z","end":"2026-07-17T10:52:01.440853Z","steps":["trace[1480383426] 'read index received' (duration: 139.842415ms)","trace[1480383426] 'applied index is now lower than readState.Index' (duration: 281.988µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:52:01.441017Z","caller":"traceutil/trace.go:171","msg":"trace[727285183] transaction","detail":"{read_only:false; response_revision:1388; number_of_response:1; }","duration":"155.511367ms","start":"2026-07-17T10:52:01.285463Z","end":"2026-07-17T10:52:01.440974Z","steps":["trace[727285183] 'process raft request' (duration: 155.220199ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:52:01.441233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.479962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-17T10:52:01.441479Z","caller":"traceutil/trace.go:171","msg":"trace[2099887659] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1388; }","duration":"140.766379ms","start":"2026-07-17T10:52:01.300695Z","end":"2026-07-17T10:52:01.441462Z","steps":["trace[2099887659] 'agreement among raft nodes before linearized reading' (duration: 140.325407ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:52:01.441584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.035121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:node-controller\" ","response":"range_response_count:1 size:735"} {"level":"info","ts":"2026-07-17T10:52:01.441677Z","caller":"traceutil/trace.go:171","msg":"trace[1292312597] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:node-controller; range_end:; response_count:1; response_revision:1388; }","duration":"121.476313ms","start":"2026-07-17T10:52:01.320182Z","end":"2026-07-17T10:52:01.441659Z","steps":["trace[1292312597] 'agreement among raft nodes before linearized reading' (duration: 120.905698ms)"],"step_count":1}