{"level":"warn","ts":"2026-05-14T14:19:09.876075Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-14T14:19:09.876157Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.3: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.3:2380","--initial-cluster=instance=https://199.204.45.3:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.3:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.3:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-14T14:19:09.876219Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-14T14:19:09.876231Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-05-14T14:19:09.876246Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-14T14:19:09.876931Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"]} {"level":"info","ts":"2026-05-14T14:19:09.877009Z","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.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.3:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-14T14:19:09.880586Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.399018ms"} {"level":"info","ts":"2026-05-14T14:19:09.886358Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"597401b06ce1cf3d","cluster-id":"dfc54657ad8a2432"} {"level":"info","ts":"2026-05-14T14:19:09.886457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=()"} {"level":"info","ts":"2026-05-14T14:19:09.886481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 0"} {"level":"info","ts":"2026-05-14T14:19:09.886499Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 597401b06ce1cf3d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-14T14:19:09.886514Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 1"} {"level":"info","ts":"2026-05-14T14:19:09.886557Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"warn","ts":"2026-05-14T14:19:09.891158Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-14T14:19:09.894718Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-14T14:19:09.897242Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-14T14:19:09.900308Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"597401b06ce1cf3d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-14T14:19:09.902837Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"597401b06ce1cf3d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-14T14:19:09.902754Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-14T14:19:09.903394Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-14T14:19:09.903413Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-14T14:19:09.903671Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"info","ts":"2026-05-14T14:19:09.903971Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","added-peer-id":"597401b06ce1cf3d","added-peer-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-05-14T14:19:09.910257Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-14T14:19:09.910369Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-05-14T14:19:09.910402Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-05-14T14:19:09.910528Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"597401b06ce1cf3d","initial-advertise-peer-urls":["https://199.204.45.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-14T14:19:09.910568Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-14T14:19:10.889699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d is starting a new election at term 1"} {"level":"info","ts":"2026-05-14T14:19:10.889759Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became pre-candidate at term 1"} {"level":"info","ts":"2026-05-14T14:19:10.889781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgPreVoteResp from 597401b06ce1cf3d at term 1"} {"level":"info","ts":"2026-05-14T14:19:10.889795Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became candidate at term 2"} {"level":"info","ts":"2026-05-14T14:19:10.889803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgVoteResp from 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-05-14T14:19:10.889814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became leader at term 2"} {"level":"info","ts":"2026-05-14T14:19:10.889823Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 597401b06ce1cf3d elected leader 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-05-14T14:19:10.898865Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T14:19:10.900154Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T14:19:10.900233Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T14:19:10.900258Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T14:19:10.900241Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"597401b06ce1cf3d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.3:2379]}","request-path":"/0/members/597401b06ce1cf3d/attributes","cluster-id":"dfc54657ad8a2432","publish-timeout":"7s"} {"level":"info","ts":"2026-05-14T14:19:10.900276Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-14T14:19:10.900461Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-14T14:19:10.90337Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.3:2379"} {"level":"info","ts":"2026-05-14T14:19:10.903955Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-14T14:19:10.90992Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-14T14:19:10.909945Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-05-14T14:19:15.246607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.597971072s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-14T14:19:15.247188Z","caller":"traceutil/trace.go:171","msg":"trace[1009469712] linearizableReadLoop","detail":"{readStateIndex:78; appliedIndex:77; }","duration":"1.911369614s","start":"2026-05-14T14:19:13.335729Z","end":"2026-05-14T14:19:15.247099Z","steps":["trace[1009469712] 'read index received' (duration: 310.159115ms)","trace[1009469712] 'applied index is now lower than readState.Index' (duration: 1.601190898s)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:19:15.249487Z","caller":"traceutil/trace.go:171","msg":"trace[752434717] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"1.981966611s","start":"2026-05-14T14:19:13.267489Z","end":"2026-05-14T14:19:15.249455Z","steps":["trace[752434717] 'process raft request' (duration: 378.599105ms)","trace[752434717] 'compare' (duration: 1.59763026s)"],"step_count":2} {"level":"warn","ts":"2026-05-14T14:19:15.249641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:13.267461Z","time spent":"1.982103941s","remote":"127.0.0.1:52440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T14:19:15.249949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.914206787s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-14T14:19:15.250049Z","caller":"traceutil/trace.go:171","msg":"trace[478629536] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:74; }","duration":"1.914330007s","start":"2026-05-14T14:19:13.335694Z","end":"2026-05-14T14:19:15.250024Z","steps":["trace[478629536] 'agreement among raft nodes before linearized reading' (duration: 1.914069225s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:15.250131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:13.335679Z","time spent":"1.914439918s","remote":"127.0.0.1:52032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-14T14:19:15.344107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:14.891396Z","time spent":"452.706969ms","remote":"127.0.0.1:52138","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-14T14:19:15.403566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.234747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-14T14:19:15.403612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.972697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:discovery\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-14T14:19:15.403696Z","caller":"traceutil/trace.go:171","msg":"trace[1714031904] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:74; }","duration":"863.429879ms","start":"2026-05-14T14:19:14.540234Z","end":"2026-05-14T14:19:15.403664Z","steps":["trace[1714031904] 'agreement among raft nodes before linearized reading' (duration: 803.977684ms)","trace[1714031904] 'range keys from in-memory index tree' (duration: 59.255913ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:19:15.403733Z","caller":"traceutil/trace.go:171","msg":"trace[1321054490] range","detail":"{range_begin:/registry/clusterroles/system:discovery; range_end:; response_count:0; response_revision:74; }","duration":"150.132069ms","start":"2026-05-14T14:19:15.253562Z","end":"2026-05-14T14:19:15.403694Z","steps":["trace[1321054490] 'agreement among raft nodes before linearized reading' (duration: 90.614234ms)","trace[1321054490] 'range keys from in-memory index tree' (duration: 59.340294ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T14:19:15.403752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:14.540213Z","time spent":"863.528969ms","remote":"127.0.0.1:52024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-14T14:19:15.591102Z","caller":"traceutil/trace.go:171","msg":"trace[1112335248] transaction","detail":"{read_only:false; response_revision:80; number_of_response:1; }","duration":"142.8313ms","start":"2026-05-14T14:19:15.448223Z","end":"2026-05-14T14:19:15.591054Z","steps":["trace[1112335248] 'process raft request' (duration: 101.131227ms)","trace[1112335248] 'compare' (duration: 41.503532ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:19:21.688532Z","caller":"traceutil/trace.go:171","msg":"trace[919624102] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"113.30163ms","start":"2026-05-14T14:19:21.575183Z","end":"2026-05-14T14:19:21.688485Z","steps":["trace[919624102] 'process raft request' (duration: 113.091903ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:24.767169Z","caller":"traceutil/trace.go:171","msg":"trace[1249627810] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"109.542692ms","start":"2026-05-14T14:19:24.657601Z","end":"2026-05-14T14:19:24.767144Z","steps":["trace[1249627810] 'process raft request' (duration: 109.406128ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:44.226352Z","caller":"traceutil/trace.go:171","msg":"trace[602855567] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"152.497602ms","start":"2026-05-14T14:19:44.073833Z","end":"2026-05-14T14:19:44.226331Z","steps":["trace[602855567] 'process raft request' (duration: 152.361238ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:44.655645Z","caller":"traceutil/trace.go:171","msg":"trace[185955535] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"154.962161ms","start":"2026-05-14T14:19:44.500655Z","end":"2026-05-14T14:19:44.655617Z","steps":["trace[185955535] 'process raft request' (duration: 154.840187ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:45.156343Z","caller":"traceutil/trace.go:171","msg":"trace[83718838] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"158.560042ms","start":"2026-05-14T14:19:44.997757Z","end":"2026-05-14T14:19:45.156317Z","steps":["trace[83718838] 'process raft request' (duration: 158.137051ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:45.318722Z","caller":"traceutil/trace.go:171","msg":"trace[788087146] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"319.526219ms","start":"2026-05-14T14:19:44.99916Z","end":"2026-05-14T14:19:45.318686Z","steps":["trace[788087146] 'process raft request' (duration: 315.836415ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:45.31887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:44.999141Z","time spent":"319.663023ms","remote":"127.0.0.1:52104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T14:19:45.31893Z","caller":"traceutil/trace.go:171","msg":"trace[189988911] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"238.759766ms","start":"2026-05-14T14:19:45.080152Z","end":"2026-05-14T14:19:45.318912Z","steps":["trace[189988911] 'process raft request' (duration: 238.725225ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:45.319019Z","caller":"traceutil/trace.go:171","msg":"trace[1555602037] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"301.137218ms","start":"2026-05-14T14:19:45.017855Z","end":"2026-05-14T14:19:45.318992Z","steps":["trace[1555602037] 'process raft request' (duration: 300.85526ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:45.319158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:45.017849Z","time spent":"301.259822ms","remote":"127.0.0.1:52104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T14:19:45.319251Z","caller":"traceutil/trace.go:171","msg":"trace[1449225105] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"296.543119ms","start":"2026-05-14T14:19:45.022696Z","end":"2026-05-14T14:19:45.319239Z","steps":["trace[1449225105] 'process raft request' (duration: 296.041864ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:45.319353Z","caller":"traceutil/trace.go:171","msg":"trace[527585811] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"295.19335ms","start":"2026-05-14T14:19:45.024151Z","end":"2026-05-14T14:19:45.319344Z","steps":["trace[527585811] 'process raft request' (duration: 294.615864ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:45.319357Z","caller":"traceutil/trace.go:171","msg":"trace[333945448] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"319.608443ms","start":"2026-05-14T14:19:44.999665Z","end":"2026-05-14T14:19:45.319274Z","steps":["trace[333945448] 'process raft request' (duration: 318.916353ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:45.319462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:44.999646Z","time spent":"319.751166ms","remote":"127.0.0.1:52104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T14:19:45.319493Z","caller":"traceutil/trace.go:171","msg":"trace[1121146932] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"304.693798ms","start":"2026-05-14T14:19:45.014787Z","end":"2026-05-14T14:19:45.31948Z","steps":["trace[1121146932] 'process raft request' (duration: 303.863405ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:45.319522Z","caller":"traceutil/trace.go:171","msg":"trace[285958037] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"291.345091ms","start":"2026-05-14T14:19:45.028166Z","end":"2026-05-14T14:19:45.319511Z","steps":["trace[285958037] 'process raft request' (duration: 290.643631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:45.319571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:45.014777Z","time spent":"304.76598ms","remote":"127.0.0.1:52104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T14:19:45.31959Z","caller":"traceutil/trace.go:171","msg":"trace[475319451] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"309.838544ms","start":"2026-05-14T14:19:45.009733Z","end":"2026-05-14T14:19:45.319571Z","steps":["trace[475319451] 'process raft request' (duration: 308.884337ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:45.319625Z","caller":"traceutil/trace.go:171","msg":"trace[238140978] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"265.440189ms","start":"2026-05-14T14:19:45.054176Z","end":"2026-05-14T14:19:45.319617Z","steps":["trace[238140978] 'process raft request' (duration: 264.670797ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:45.319681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:45.009714Z","time spent":"309.905875ms","remote":"127.0.0.1:52104","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-05-14T14:19:45.31978Z","caller":"traceutil/trace.go:171","msg":"trace[1561873698] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"301.964232ms","start":"2026-05-14T14:19:45.017804Z","end":"2026-05-14T14:19:45.319769Z","steps":["trace[1561873698] 'process raft request' (duration: 300.878151ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:45.31985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:45.017796Z","time spent":"302.019384ms","remote":"127.0.0.1:52104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T14:19:45.398208Z","caller":"traceutil/trace.go:171","msg":"trace[1346754404] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"236.850392ms","start":"2026-05-14T14:19:45.161311Z","end":"2026-05-14T14:19:45.398161Z","steps":["trace[1346754404] 'process raft request' (duration: 236.483352ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:45.398939Z","caller":"traceutil/trace.go:171","msg":"trace[752660223] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"235.434843ms","start":"2026-05-14T14:19:45.163483Z","end":"2026-05-14T14:19:45.398918Z","steps":["trace[752660223] 'process raft request' (duration: 234.440524ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:45.399074Z","caller":"traceutil/trace.go:171","msg":"trace[897434056] transaction","detail":"{read_only:false; number_of_response:1; response_revision:527; }","duration":"226.698925ms","start":"2026-05-14T14:19:45.172349Z","end":"2026-05-14T14:19:45.399048Z","steps":["trace[897434056] 'process raft request' (duration: 226.422567ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:45.399419Z","caller":"traceutil/trace.go:171","msg":"trace[1303036337] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"226.410906ms","start":"2026-05-14T14:19:45.172924Z","end":"2026-05-14T14:19:45.399334Z","steps":["trace[1303036337] 'process raft request' (duration: 226.044636ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:45.871706Z","caller":"traceutil/trace.go:171","msg":"trace[948440302] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"102.096485ms","start":"2026-05-14T14:19:45.769594Z","end":"2026-05-14T14:19:45.87169Z","steps":["trace[948440302] 'process raft request' (duration: 95.667063ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:46.731374Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933265829151294130,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T14:19:47.04165Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.131000213s","expected-duration":"1s"} {"level":"info","ts":"2026-05-14T14:19:47.044019Z","caller":"traceutil/trace.go:171","msg":"trace[854646440] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"1.133433491s","start":"2026-05-14T14:19:45.910548Z","end":"2026-05-14T14:19:47.043981Z","steps":["trace[854646440] 'process raft request' (duration: 1.131330632s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:47.044389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:45.910522Z","time spent":"1.133570896s","remote":"127.0.0.1:52104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18032,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T14:19:47.051306Z","caller":"traceutil/trace.go:171","msg":"trace[1823334999] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:591; }","duration":"820.601312ms","start":"2026-05-14T14:19:46.230683Z","end":"2026-05-14T14:19:47.051284Z","steps":["trace[1823334999] 'read index received' (duration: 811.288104ms)","trace[1823334999] 'applied index is now lower than readState.Index' (duration: 9.312308ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:19:47.051411Z","caller":"traceutil/trace.go:171","msg":"trace[1281270367] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"1.087732047s","start":"2026-05-14T14:19:45.963663Z","end":"2026-05-14T14:19:47.051395Z","steps":["trace[1281270367] 'process raft request' (duration: 1.087105119s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:47.051496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:45.963622Z","time spent":"1.087809649s","remote":"127.0.0.1:52104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151221,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T14:19:47.0517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.016063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-14T14:19:47.051731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.443083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T14:19:47.051753Z","caller":"traceutil/trace.go:171","msg":"trace[993279690] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:572; }","duration":"821.125546ms","start":"2026-05-14T14:19:46.230613Z","end":"2026-05-14T14:19:47.051739Z","steps":["trace[993279690] 'agreement among raft nodes before linearized reading' (duration: 820.986932ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:47.051775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:46.23058Z","time spent":"821.190318ms","remote":"127.0.0.1:52348","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":"info","ts":"2026-05-14T14:19:47.051779Z","caller":"traceutil/trace.go:171","msg":"trace[2064927556] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:572; }","duration":"664.538957ms","start":"2026-05-14T14:19:46.387225Z","end":"2026-05-14T14:19:47.051764Z","steps":["trace[2064927556] 'agreement among raft nodes before linearized reading' (duration: 664.470755ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:47.051807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:46.387197Z","time spent":"664.603248ms","remote":"127.0.0.1:52024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-14T14:19:47.051891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.343447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-14T14:19:47.051969Z","caller":"traceutil/trace.go:171","msg":"trace[640497939] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:572; }","duration":"389.4402ms","start":"2026-05-14T14:19:46.662513Z","end":"2026-05-14T14:19:47.051953Z","steps":["trace[640497939] 'agreement among raft nodes before linearized reading' (duration: 389.303827ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:47.051916Z","caller":"traceutil/trace.go:171","msg":"trace[1571305247] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"851.355832ms","start":"2026-05-14T14:19:46.200551Z","end":"2026-05-14T14:19:47.051907Z","steps":["trace[1571305247] 'process raft request' (duration: 850.55959ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:47.052025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.014076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-14T14:19:47.052058Z","caller":"traceutil/trace.go:171","msg":"trace[858833329] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:572; }","duration":"233.085768ms","start":"2026-05-14T14:19:46.818963Z","end":"2026-05-14T14:19:47.052049Z","steps":["trace[858833329] 'agreement among raft nodes before linearized reading' (duration: 233.036437ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:47.052079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:46.200512Z","time spent":"851.533427ms","remote":"127.0.0.1:52104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T14:19:47.052028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:46.662498Z","time spent":"389.519802ms","remote":"127.0.0.1:52348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-14T14:19:47.051978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.409503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.3\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-05-14T14:19:47.052243Z","caller":"traceutil/trace.go:171","msg":"trace[18881077] range","detail":"{range_begin:/registry/masterleases/199.204.45.3; range_end:; response_count:1; response_revision:572; }","duration":"412.710231ms","start":"2026-05-14T14:19:46.639522Z","end":"2026-05-14T14:19:47.052232Z","steps":["trace[18881077] 'agreement among raft nodes before linearized reading' (duration: 412.418853ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:47.052312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:46.639501Z","time spent":"412.799502ms","remote":"127.0.0.1:52086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":154,"request content":"key:\"/registry/masterleases/199.204.45.3\" "} {"level":"info","ts":"2026-05-14T14:19:47.266564Z","caller":"traceutil/trace.go:171","msg":"trace[233070866] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"118.493202ms","start":"2026-05-14T14:19:47.148045Z","end":"2026-05-14T14:19:47.266538Z","steps":["trace[233070866] 'process raft request' (duration: 118.245125ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:47.301448Z","caller":"traceutil/trace.go:171","msg":"trace[1788004540] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"119.685155ms","start":"2026-05-14T14:19:47.181737Z","end":"2026-05-14T14:19:47.301422Z","steps":["trace[1788004540] 'process raft request' (duration: 119.559362ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:47.30192Z","caller":"traceutil/trace.go:171","msg":"trace[511695134] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"132.658795ms","start":"2026-05-14T14:19:47.16925Z","end":"2026-05-14T14:19:47.301909Z","steps":["trace[511695134] 'process raft request' (duration: 131.923494ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:50.513556Z","caller":"traceutil/trace.go:171","msg":"trace[80806525] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"101.757448ms","start":"2026-05-14T14:19:50.411767Z","end":"2026-05-14T14:19:50.513525Z","steps":["trace[80806525] 'process raft request' (duration: 97.123853ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:51.846209Z","caller":"traceutil/trace.go:171","msg":"trace[2048410894] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"233.471331ms","start":"2026-05-14T14:19:51.612711Z","end":"2026-05-14T14:19:51.846183Z","steps":["trace[2048410894] 'process raft request' (duration: 232.914576ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:52.045457Z","caller":"traceutil/trace.go:171","msg":"trace[1205933655] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:635; }","duration":"100.008273ms","start":"2026-05-14T14:19:51.945419Z","end":"2026-05-14T14:19:52.045428Z","steps":["trace[1205933655] 'read index received' (duration: 32.379512ms)","trace[1205933655] 'applied index is now lower than readState.Index' (duration: 67.627221ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T14:19:52.045867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.427986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-14T14:19:52.045943Z","caller":"traceutil/trace.go:171","msg":"trace[368566854] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:613; }","duration":"100.549649ms","start":"2026-05-14T14:19:51.945376Z","end":"2026-05-14T14:19:52.045925Z","steps":["trace[368566854] 'agreement among raft nodes before linearized reading' (duration: 100.308303ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:53.122881Z","caller":"traceutil/trace.go:171","msg":"trace[865819270] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"138.156517ms","start":"2026-05-14T14:19:52.984653Z","end":"2026-05-14T14:19:53.122809Z","steps":["trace[865819270] 'read index received' (duration: 132.483399ms)","trace[865819270] 'applied index is now lower than readState.Index' (duration: 5.670898ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:19:53.124396Z","caller":"traceutil/trace.go:171","msg":"trace[1758326050] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"164.177412ms","start":"2026-05-14T14:19:52.960192Z","end":"2026-05-14T14:19:53.124369Z","steps":["trace[1758326050] 'process raft request' (duration: 157.420836ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:53.124424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.622225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T14:19:53.124417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.737269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T14:19:53.124551Z","caller":"traceutil/trace.go:171","msg":"trace[1121137760] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:617; }","duration":"139.890562ms","start":"2026-05-14T14:19:52.98463Z","end":"2026-05-14T14:19:53.12452Z","steps":["trace[1121137760] 'agreement among raft nodes before linearized reading' (duration: 139.715177ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:53.124487Z","caller":"traceutil/trace.go:171","msg":"trace[6937605] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:617; }","duration":"139.703728ms","start":"2026-05-14T14:19:52.984768Z","end":"2026-05-14T14:19:53.124472Z","steps":["trace[6937605] 'agreement among raft nodes before linearized reading' (duration: 139.593784ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:53.126402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.212886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T14:19:53.126695Z","caller":"traceutil/trace.go:171","msg":"trace[44779548] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:617; }","duration":"141.271767ms","start":"2026-05-14T14:19:52.985176Z","end":"2026-05-14T14:19:53.126448Z","steps":["trace[44779548] 'agreement among raft nodes before linearized reading' (duration: 139.490171ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:53.244233Z","caller":"traceutil/trace.go:171","msg":"trace[1827860924] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"109.177664ms","start":"2026-05-14T14:19:53.135029Z","end":"2026-05-14T14:19:53.244207Z","steps":["trace[1827860924] 'process raft request' (duration: 108.910436ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:53.244275Z","caller":"traceutil/trace.go:171","msg":"trace[2104640416] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:642; }","duration":"103.577198ms","start":"2026-05-14T14:19:53.14068Z","end":"2026-05-14T14:19:53.244258Z","steps":["trace[2104640416] 'read index received' (duration: 49.760071ms)","trace[2104640416] 'applied index is now lower than readState.Index' (duration: 53.816127ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:19:53.24445Z","caller":"traceutil/trace.go:171","msg":"trace[1239914006] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"109.457631ms","start":"2026-05-14T14:19:53.134981Z","end":"2026-05-14T14:19:53.244439Z","steps":["trace[1239914006] 'process raft request' (duration: 99.991263ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:53.244648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.940987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T14:19:53.244696Z","caller":"traceutil/trace.go:171","msg":"trace[314516551] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:621; }","duration":"104.021788ms","start":"2026-05-14T14:19:53.140662Z","end":"2026-05-14T14:19:53.244684Z","steps":["trace[314516551] 'agreement among raft nodes before linearized reading' (duration: 103.878145ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:53.244781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.760152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T14:19:53.244861Z","caller":"traceutil/trace.go:171","msg":"trace[763122332] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:621; }","duration":"103.861585ms","start":"2026-05-14T14:19:53.14098Z","end":"2026-05-14T14:19:53.244841Z","steps":["trace[763122332] 'agreement among raft nodes before linearized reading' (duration: 103.715981ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:53.245114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.515016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T14:19:53.245158Z","caller":"traceutil/trace.go:171","msg":"trace[2000908875] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:621; }","duration":"103.558008ms","start":"2026-05-14T14:19:53.141578Z","end":"2026-05-14T14:19:53.245136Z","steps":["trace[2000908875] 'agreement among raft nodes before linearized reading' (duration: 103.511026ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:54.273731Z","caller":"traceutil/trace.go:171","msg":"trace[326727937] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"495.437554ms","start":"2026-05-14T14:19:53.778269Z","end":"2026-05-14T14:19:54.273707Z","steps":["trace[326727937] 'read index received' (duration: 494.695705ms)","trace[326727937] 'applied index is now lower than readState.Index' (duration: 740.799µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:19:54.273891Z","caller":"traceutil/trace.go:171","msg":"trace[853295229] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"631.925431ms","start":"2026-05-14T14:19:53.641923Z","end":"2026-05-14T14:19:54.273848Z","steps":["trace[853295229] 'process raft request' (duration: 631.168352ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:54.274087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:53.641898Z","time spent":"632.078987ms","remote":"127.0.0.1:52152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T14:19:54.274074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.790263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6k4m7\" ","response":"range_response_count:1 size:16957"} {"level":"info","ts":"2026-05-14T14:19:54.274312Z","caller":"traceutil/trace.go:171","msg":"trace[1469039124] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6k4m7; range_end:; response_count:1; response_revision:625; }","duration":"496.056671ms","start":"2026-05-14T14:19:53.778236Z","end":"2026-05-14T14:19:54.274293Z","steps":["trace[1469039124] 'agreement among raft nodes before linearized reading' (duration: 495.61839ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:54.274658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:53.778216Z","time spent":"496.353298ms","remote":"127.0.0.1:52266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16980,"request content":"key:\"/registry/pods/kube-system/cilium-6k4m7\" "} {"level":"info","ts":"2026-05-14T14:19:54.292461Z","caller":"traceutil/trace.go:171","msg":"trace[132803587] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"359.857586ms","start":"2026-05-14T14:19:53.932579Z","end":"2026-05-14T14:19:54.292436Z","steps":["trace[132803587] 'process raft request' (duration: 359.708093ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:54.292465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.573051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-14T14:19:54.292511Z","caller":"traceutil/trace.go:171","msg":"trace[455019113] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:626; }","duration":"225.655793ms","start":"2026-05-14T14:19:54.066846Z","end":"2026-05-14T14:19:54.292501Z","steps":["trace[455019113] 'agreement among raft nodes before linearized reading' (duration: 225.53691ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:54.292559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:53.932558Z","time spent":"359.943898ms","remote":"127.0.0.1:52348","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-05-14T14:19:54.889474Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933265829151294307,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-14T14:19:54.918264Z","caller":"traceutil/trace.go:171","msg":"trace[1859454132] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"625.793833ms","start":"2026-05-14T14:19:54.292446Z","end":"2026-05-14T14:19:54.91824Z","steps":["trace[1859454132] 'process raft request' (duration: 625.634499ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:54.918461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:54.292427Z","time spent":"625.907006ms","remote":"127.0.0.1:52266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17126,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T14:19:54.983113Z","caller":"traceutil/trace.go:171","msg":"trace[326780454] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:650; }","duration":"593.672291ms","start":"2026-05-14T14:19:54.389421Z","end":"2026-05-14T14:19:54.983093Z","steps":["trace[326780454] 'read index received' (duration: 528.69795ms)","trace[326780454] 'applied index is now lower than readState.Index' (duration: 64.973611ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T14:19:54.983233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.791775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T14:19:54.983274Z","caller":"traceutil/trace.go:171","msg":"trace[1302575504] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:629; }","duration":"593.872837ms","start":"2026-05-14T14:19:54.389391Z","end":"2026-05-14T14:19:54.983264Z","steps":["trace[1302575504] 'agreement among raft nodes before linearized reading' (duration: 593.779585ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:54.983312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:54.389372Z","time spent":"593.932088ms","remote":"127.0.0.1:52024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-14T14:19:54.983328Z","caller":"traceutil/trace.go:171","msg":"trace[1732966401] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"687.377207ms","start":"2026-05-14T14:19:54.295931Z","end":"2026-05-14T14:19:54.983308Z","steps":["trace[1732966401] 'process raft request' (duration: 686.972326ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:54.983439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:54.295911Z","time spent":"687.46633ms","remote":"127.0.0.1:52348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T14:19:54.983802Z","caller":"traceutil/trace.go:171","msg":"trace[192853279] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"647.966956ms","start":"2026-05-14T14:19:54.335798Z","end":"2026-05-14T14:19:54.983765Z","steps":["trace[192853279] 'process raft request' (duration: 647.241368ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:54.983982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:54.335778Z","time spent":"648.126581ms","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T14:19:57.087726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.676789122s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T14:19:57.087817Z","caller":"traceutil/trace.go:171","msg":"trace[548364016] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:629; }","duration":"1.676930007s","start":"2026-05-14T14:19:55.410868Z","end":"2026-05-14T14:19:57.087798Z","steps":["trace[548364016] 'range keys from in-memory index tree' (duration: 1.676649569s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:57.087863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:55.410847Z","time spent":"1.677006539s","remote":"127.0.0.1:52024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-14T14:19:57.087953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.837972196s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-14T14:19:57.087999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.092171169s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T14:19:57.088055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.774569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.3\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-05-14T14:19:57.088064Z","caller":"traceutil/trace.go:171","msg":"trace[1885157345] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:629; }","duration":"1.838142221s","start":"2026-05-14T14:19:55.249904Z","end":"2026-05-14T14:19:57.088046Z","steps":["trace[1885157345] 'range keys from in-memory index tree' (duration: 1.837765991s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:57.088181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:55.249893Z","time spent":"1.838274784s","remote":"127.0.0.1:52348","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":"info","ts":"2026-05-14T14:19:57.088073Z","caller":"traceutil/trace.go:171","msg":"trace[1509845011] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:629; }","duration":"2.092301482s","start":"2026-05-14T14:19:54.995752Z","end":"2026-05-14T14:19:57.088054Z","steps":["trace[1509845011] 'range keys from in-memory index tree' (duration: 2.092098477s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:57.088411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:54.995733Z","time spent":"2.092661442s","remote":"127.0.0.1:52312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-05-14T14:19:57.087986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.837106664s","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-05-14T14:19:57.088432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.847923172s","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-05-14T14:19:57.088487Z","caller":"traceutil/trace.go:171","msg":"trace[630606360] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:629; }","duration":"1.837632198s","start":"2026-05-14T14:19:55.250842Z","end":"2026-05-14T14:19:57.088474Z","steps":["trace[630606360] 'range keys from in-memory index tree' (duration: 1.837000741s)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:57.088528Z","caller":"traceutil/trace.go:171","msg":"trace[1064855306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:629; }","duration":"1.848059845s","start":"2026-05-14T14:19:55.24044Z","end":"2026-05-14T14:19:57.0885Z","steps":["trace[1064855306] 'range keys from in-memory index tree' (duration: 1.847722516s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:57.088561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:55.250832Z","time spent":"1.837683219s","remote":"127.0.0.1:52348","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-05-14T14:19:57.0886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:55.240421Z","time spent":"1.848163097s","remote":"127.0.0.1:52348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-14T14:19:57.089008Z","caller":"traceutil/trace.go:171","msg":"trace[1869626879] range","detail":"{range_begin:/registry/masterleases/199.204.45.3; range_end:; response_count:1; response_revision:629; }","duration":"449.889161ms","start":"2026-05-14T14:19:56.638222Z","end":"2026-05-14T14:19:57.088112Z","steps":["trace[1869626879] 'range keys from in-memory index tree' (duration: 449.621565ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:57.089071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:56.638205Z","time spent":"450.853267ms","remote":"127.0.0.1:52086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":154,"request content":"key:\"/registry/masterleases/199.204.45.3\" "} {"level":"info","ts":"2026-05-14T14:20:00.337591Z","caller":"traceutil/trace.go:171","msg":"trace[633567088] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"195.350335ms","start":"2026-05-14T14:20:00.1422Z","end":"2026-05-14T14:20:00.337551Z","steps":["trace[633567088] 'process raft request' (duration: 135.805657ms)","trace[633567088] 'compare' (duration: 59.271361ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:20:02.540414Z","caller":"traceutil/trace.go:171","msg":"trace[860213906] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"237.169066ms","start":"2026-05-14T14:20:02.303219Z","end":"2026-05-14T14:20:02.540388Z","steps":["trace[860213906] 'process raft request' (duration: 236.934361ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:20:02.795366Z","caller":"traceutil/trace.go:171","msg":"trace[170721822] linearizableReadLoop","detail":"{readStateIndex:738; appliedIndex:736; }","duration":"132.811073ms","start":"2026-05-14T14:20:02.662515Z","end":"2026-05-14T14:20:02.795326Z","steps":["trace[170721822] 'read index received' (duration: 33.259209ms)","trace[170721822] 'applied index is now lower than readState.Index' (duration: 99.550374ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:20:02.795388Z","caller":"traceutil/trace.go:171","msg":"trace[767594045] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"302.973497ms","start":"2026-05-14T14:20:02.492374Z","end":"2026-05-14T14:20:02.795348Z","steps":["trace[767594045] 'process raft request' (duration: 302.710711ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:20:02.795696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.160423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-05-14T14:20:02.795788Z","caller":"traceutil/trace.go:171","msg":"trace[1610110174] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:712; }","duration":"133.298985ms","start":"2026-05-14T14:20:02.662468Z","end":"2026-05-14T14:20:02.795767Z","steps":["trace[1610110174] 'agreement among raft nodes before linearized reading' (duration: 132.998268ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:20:02.795662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:20:02.492344Z","time spent":"303.222404ms","remote":"127.0.0.1:52104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T14:20:02.7961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.51544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-14T14:20:02.796202Z","caller":"traceutil/trace.go:171","msg":"trace[496687911] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:712; }","duration":"118.666114ms","start":"2026-05-14T14:20:02.677488Z","end":"2026-05-14T14:20:02.796154Z","steps":["trace[496687911] 'agreement among raft nodes before linearized reading' (duration: 118.124152ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:20:02.95196Z","caller":"traceutil/trace.go:171","msg":"trace[748783479] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"121.446322ms","start":"2026-05-14T14:20:02.830471Z","end":"2026-05-14T14:20:02.951917Z","steps":["trace[748783479] 'process raft request' (duration: 102.856634ms)","trace[748783479] 'compare' (duration: 18.185258ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:20:03.346636Z","caller":"traceutil/trace.go:171","msg":"trace[671538340] linearizableReadLoop","detail":"{readStateIndex:743; appliedIndex:742; }","duration":"284.532325ms","start":"2026-05-14T14:20:03.062079Z","end":"2026-05-14T14:20:03.346612Z","steps":["trace[671538340] 'read index received' (duration: 284.216447ms)","trace[671538340] 'applied index is now lower than readState.Index' (duration: 314.818µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:20:03.347056Z","caller":"traceutil/trace.go:171","msg":"trace[624717668] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"315.662047ms","start":"2026-05-14T14:20:03.031331Z","end":"2026-05-14T14:20:03.346993Z","steps":["trace[624717668] 'process raft request' (duration: 315.014031ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:20:03.347172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.435556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-14T14:20:03.347171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.893384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-14T14:20:03.347192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.942045ms","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-05-14T14:20:03.347238Z","caller":"traceutil/trace.go:171","msg":"trace[221139221] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:717; }","duration":"215.53562ms","start":"2026-05-14T14:20:03.131684Z","end":"2026-05-14T14:20:03.347219Z","steps":["trace[221139221] 'agreement among raft nodes before linearized reading' (duration: 215.316154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:20:03.347229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:20:03.031311Z","time spent":"315.809791ms","remote":"127.0.0.1:52104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188237,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T14:20:03.347268Z","caller":"traceutil/trace.go:171","msg":"trace[616374333] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:717; }","duration":"220.028037ms","start":"2026-05-14T14:20:03.127215Z","end":"2026-05-14T14:20:03.347243Z","steps":["trace[616374333] 'agreement among raft nodes before linearized reading' (duration: 219.74157ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:20:03.347283Z","caller":"traceutil/trace.go:171","msg":"trace[49988474] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:717; }","duration":"215.064059ms","start":"2026-05-14T14:20:03.132196Z","end":"2026-05-14T14:20:03.34726Z","steps":["trace[49988474] 'agreement among raft nodes before linearized reading' (duration: 214.792302ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:20:03.347606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.166568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-14T14:20:03.347714Z","caller":"traceutil/trace.go:171","msg":"trace[2045091190] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:717; }","duration":"217.267791ms","start":"2026-05-14T14:20:03.130395Z","end":"2026-05-14T14:20:03.347663Z","steps":["trace[2045091190] 'agreement among raft nodes before linearized reading' (duration: 217.079996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:20:03.348673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.582024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"info","ts":"2026-05-14T14:20:03.34884Z","caller":"traceutil/trace.go:171","msg":"trace[1294305535] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:717; }","duration":"286.720777ms","start":"2026-05-14T14:20:03.062044Z","end":"2026-05-14T14:20:03.348765Z","steps":["trace[1294305535] 'agreement among raft nodes before linearized reading' (duration: 286.44807ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:20:03.470028Z","caller":"traceutil/trace.go:171","msg":"trace[987669693] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"116.823936ms","start":"2026-05-14T14:20:03.353174Z","end":"2026-05-14T14:20:03.469998Z","steps":["trace[987669693] 'process raft request' (duration: 116.733314ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:20:03.470081Z","caller":"traceutil/trace.go:171","msg":"trace[1710754856] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"116.874417ms","start":"2026-05-14T14:20:03.353142Z","end":"2026-05-14T14:20:03.470017Z","steps":["trace[1710754856] 'process raft request' (duration: 57.46205ms)","trace[1710754856] 'compare' (duration: 59.162312ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:20:03.470264Z","caller":"traceutil/trace.go:171","msg":"trace[725940463] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"116.998169ms","start":"2026-05-14T14:20:03.35324Z","end":"2026-05-14T14:20:03.470238Z","steps":["trace[725940463] 'process raft request' (duration: 116.720493ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:20:03.470531Z","caller":"traceutil/trace.go:171","msg":"trace[1757039589] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"116.695622ms","start":"2026-05-14T14:20:03.353817Z","end":"2026-05-14T14:20:03.470513Z","steps":["trace[1757039589] 'process raft request' (duration: 116.311773ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:20:13.753127Z","caller":"traceutil/trace.go:171","msg":"trace[2017932982] linearizableReadLoop","detail":"{readStateIndex:819; appliedIndex:818; }","duration":"228.280825ms","start":"2026-05-14T14:20:13.524824Z","end":"2026-05-14T14:20:13.753105Z","steps":["trace[2017932982] 'read index received' (duration: 228.096191ms)","trace[2017932982] 'applied index is now lower than readState.Index' (duration: 183.664µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:20:13.753243Z","caller":"traceutil/trace.go:171","msg":"trace[1014875435] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"231.357531ms","start":"2026-05-14T14:20:13.521875Z","end":"2026-05-14T14:20:13.753232Z","steps":["trace[1014875435] 'process raft request' (duration: 231.089356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:20:13.753527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.258973ms","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-05-14T14:20:13.753673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.833647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-14T14:20:13.753724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.123637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-14T14:20:13.753731Z","caller":"traceutil/trace.go:171","msg":"trace[319732424] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:790; }","duration":"228.922298ms","start":"2026-05-14T14:20:13.524791Z","end":"2026-05-14T14:20:13.753714Z","steps":["trace[319732424] 'agreement among raft nodes before linearized reading' (duration: 228.752525ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:20:13.753705Z","caller":"traceutil/trace.go:171","msg":"trace[2126292628] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:790; }","duration":"227.388415ms","start":"2026-05-14T14:20:13.526229Z","end":"2026-05-14T14:20:13.753617Z","steps":["trace[2126292628] 'agreement among raft nodes before linearized reading' (duration: 227.089709ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:20:13.753766Z","caller":"traceutil/trace.go:171","msg":"trace[1184911445] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:790; }","duration":"204.198218ms","start":"2026-05-14T14:20:13.549557Z","end":"2026-05-14T14:20:13.753755Z","steps":["trace[1184911445] 'agreement among raft nodes before linearized reading' (duration: 204.074915ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:21:38.496076Z","caller":"traceutil/trace.go:171","msg":"trace[2114809285] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"101.287797ms","start":"2026-05-14T14:21:38.394763Z","end":"2026-05-14T14:21:38.496051Z","steps":["trace[2114809285] 'process raft request' (duration: 95.736442ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:22:06.68278Z","caller":"traceutil/trace.go:171","msg":"trace[414934370] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"289.462708ms","start":"2026-05-14T14:22:06.393288Z","end":"2026-05-14T14:22:06.682751Z","steps":["trace[414934370] 'process raft request' (duration: 289.325276ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:22:06.724308Z","caller":"traceutil/trace.go:171","msg":"trace[1894237932] linearizableReadLoop","detail":"{readStateIndex:1391; appliedIndex:1389; }","duration":"164.947593ms","start":"2026-05-14T14:22:06.559337Z","end":"2026-05-14T14:22:06.724285Z","steps":["trace[1894237932] 'read index received' (duration: 123.279789ms)","trace[1894237932] 'applied index is now lower than readState.Index' (duration: 41.666934ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:22:06.724374Z","caller":"traceutil/trace.go:171","msg":"trace[2053655201] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"197.678037ms","start":"2026-05-14T14:22:06.526664Z","end":"2026-05-14T14:22:06.724342Z","steps":["trace[2053655201] 'process raft request' (duration: 161.178536ms)","trace[2053655201] 'compare' (duration: 36.321328ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T14:22:06.72463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.264607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-14T14:22:06.724733Z","caller":"traceutil/trace.go:171","msg":"trace[534656584] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1337; }","duration":"165.403289ms","start":"2026-05-14T14:22:06.559304Z","end":"2026-05-14T14:22:06.724707Z","steps":["trace[534656584] 'agreement among raft nodes before linearized reading' (duration: 165.127116ms)"],"step_count":1}