{"level":"warn","ts":"2026-05-12T16:33:16.574026Z","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-12T16:33:16.574163Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.147:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.147:2380","--initial-cluster=instance=https://199.19.213.147:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.147:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.147: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-12T16:33:16.574276Z","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-12T16:33:16.574305Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.147:2380"]} {"level":"info","ts":"2026-05-12T16:33:16.574351Z","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-12T16:33:16.575317Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.147:2379"]} {"level":"info","ts":"2026-05-12T16:33:16.575442Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.147:2380"],"listen-peer-urls":["https://199.19.213.147:2380"],"advertise-client-urls":["https://199.19.213.147:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.147:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.147: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-12T16:33:16.591232Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"15.206941ms"} {"level":"info","ts":"2026-05-12T16:33:16.601882Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6244e483e416226f","cluster-id":"68a374eab3e2de92"} {"level":"info","ts":"2026-05-12T16:33:16.602112Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6244e483e416226f switched to configuration voters=()"} {"level":"info","ts":"2026-05-12T16:33:16.602234Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6244e483e416226f became follower at term 0"} {"level":"info","ts":"2026-05-12T16:33:16.602377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6244e483e416226f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-12T16:33:16.60249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6244e483e416226f became follower at term 1"} {"level":"info","ts":"2026-05-12T16:33:16.602608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6244e483e416226f switched to configuration voters=(7081035769251766895)"} {"level":"warn","ts":"2026-05-12T16:33:16.62155Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-12T16:33:16.624644Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-12T16:33:16.62612Z","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-12T16:33:16.630808Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6244e483e416226f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-12T16:33:16.633834Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6244e483e416226f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-12T16:33:16.634118Z","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-12T16:33:16.634287Z","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-12T16:33:16.634409Z","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-12T16:33:16.635759Z","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-12T16:33:16.635957Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6244e483e416226f","initial-advertise-peer-urls":["https://199.19.213.147:2380"],"listen-peer-urls":["https://199.19.213.147:2380"],"advertise-client-urls":["https://199.19.213.147:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.147:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-12T16:33:16.635986Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-12T16:33:16.635978Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.147:2380"} {"level":"info","ts":"2026-05-12T16:33:16.636724Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.147:2380"} {"level":"info","ts":"2026-05-12T16:33:16.6444Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6244e483e416226f switched to configuration voters=(7081035769251766895)"} {"level":"info","ts":"2026-05-12T16:33:16.644677Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"68a374eab3e2de92","local-member-id":"6244e483e416226f","added-peer-id":"6244e483e416226f","added-peer-peer-urls":["https://199.19.213.147:2380"]} {"level":"info","ts":"2026-05-12T16:33:16.903581Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6244e483e416226f is starting a new election at term 1"} {"level":"info","ts":"2026-05-12T16:33:16.903629Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6244e483e416226f became pre-candidate at term 1"} {"level":"info","ts":"2026-05-12T16:33:16.903652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6244e483e416226f received MsgPreVoteResp from 6244e483e416226f at term 1"} {"level":"info","ts":"2026-05-12T16:33:16.903664Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6244e483e416226f became candidate at term 2"} {"level":"info","ts":"2026-05-12T16:33:16.903669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6244e483e416226f received MsgVoteResp from 6244e483e416226f at term 2"} {"level":"info","ts":"2026-05-12T16:33:16.90368Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6244e483e416226f became leader at term 2"} {"level":"info","ts":"2026-05-12T16:33:16.903687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6244e483e416226f elected leader 6244e483e416226f at term 2"} {"level":"info","ts":"2026-05-12T16:33:16.921269Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6244e483e416226f","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.147:2379]}","request-path":"/0/members/6244e483e416226f/attributes","cluster-id":"68a374eab3e2de92","publish-timeout":"7s"} {"level":"info","ts":"2026-05-12T16:33:16.921466Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-12T16:33:16.921619Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T16:33:16.921686Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-12T16:33:16.921729Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-12T16:33:16.921515Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-12T16:33:16.922763Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"68a374eab3e2de92","local-member-id":"6244e483e416226f","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T16:33:16.922851Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T16:33:16.922882Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T16:33:16.925015Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.147:2379"} {"level":"info","ts":"2026-05-12T16:33:16.925442Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-12T16:33:46.680816Z","caller":"traceutil/trace.go:171","msg":"trace[751100136] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"163.888379ms","start":"2026-05-12T16:33:46.516907Z","end":"2026-05-12T16:33:46.680795Z","steps":["trace[751100136] 'process raft request' (duration: 163.784187ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:33:46.861934Z","caller":"traceutil/trace.go:171","msg":"trace[878381047] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"256.959535ms","start":"2026-05-12T16:33:46.604941Z","end":"2026-05-12T16:33:46.8619Z","steps":["trace[878381047] 'process raft request' (duration: 256.459964ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:33:46.862085Z","caller":"traceutil/trace.go:171","msg":"trace[1790261638] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"239.072382ms","start":"2026-05-12T16:33:46.623003Z","end":"2026-05-12T16:33:46.862075Z","steps":["trace[1790261638] 'process raft request' (duration: 238.804386ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:33:46.861951Z","caller":"traceutil/trace.go:171","msg":"trace[1343543326] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"244.143886ms","start":"2026-05-12T16:33:46.617782Z","end":"2026-05-12T16:33:46.861926Z","steps":["trace[1343543326] 'process raft request' (duration: 243.950532ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:33:46.86208Z","caller":"traceutil/trace.go:171","msg":"trace[331276254] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"239.103722ms","start":"2026-05-12T16:33:46.622917Z","end":"2026-05-12T16:33:46.862021Z","steps":["trace[331276254] 'process raft request' (duration: 238.851957ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:33:46.862269Z","caller":"traceutil/trace.go:171","msg":"trace[1695889329] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"248.267619ms","start":"2026-05-12T16:33:46.613599Z","end":"2026-05-12T16:33:46.861867Z","steps":["trace[1695889329] 'process raft request' (duration: 248.090915ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:33:46.863027Z","caller":"traceutil/trace.go:171","msg":"trace[2115638924] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"236.520634ms","start":"2026-05-12T16:33:46.626492Z","end":"2026-05-12T16:33:46.863012Z","steps":["trace[2115638924] 'process raft request' (duration: 236.100935ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:33:46.863412Z","caller":"traceutil/trace.go:171","msg":"trace[1423514467] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"234.537861ms","start":"2026-05-12T16:33:46.62884Z","end":"2026-05-12T16:33:46.863378Z","steps":["trace[1423514467] 'process raft request' (duration: 233.810413ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:33:46.863513Z","caller":"traceutil/trace.go:171","msg":"trace[1408984376] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"233.724213ms","start":"2026-05-12T16:33:46.62975Z","end":"2026-05-12T16:33:46.863474Z","steps":["trace[1408984376] 'process raft request' (duration: 233.024406ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:33:46.863964Z","caller":"traceutil/trace.go:171","msg":"trace[355028009] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"187.045671ms","start":"2026-05-12T16:33:46.676905Z","end":"2026-05-12T16:33:46.863951Z","steps":["trace[355028009] 'process raft request' (duration: 187.00095ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:33:46.864104Z","caller":"traceutil/trace.go:171","msg":"trace[2039582219] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"232.600896ms","start":"2026-05-12T16:33:46.631492Z","end":"2026-05-12T16:33:46.864093Z","steps":["trace[2039582219] 'process raft request' (duration: 231.925621ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:33:46.864203Z","caller":"traceutil/trace.go:171","msg":"trace[940657201] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"221.023366ms","start":"2026-05-12T16:33:46.643172Z","end":"2026-05-12T16:33:46.864196Z","steps":["trace[940657201] 'process raft request' (duration: 220.635257ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:33:49.857229Z","caller":"traceutil/trace.go:171","msg":"trace[725755798] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"104.938142ms","start":"2026-05-12T16:33:49.752237Z","end":"2026-05-12T16:33:49.857175Z","steps":["trace[725755798] 'process raft request' (duration: 99.948709ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:33:49.970952Z","caller":"traceutil/trace.go:171","msg":"trace[1555219128] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"108.737298ms","start":"2026-05-12T16:33:49.862184Z","end":"2026-05-12T16:33:49.970921Z","steps":["trace[1555219128] 'process raft request' (duration: 108.558414ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:33:49.971185Z","caller":"traceutil/trace.go:171","msg":"trace[452097242] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"108.886791ms","start":"2026-05-12T16:33:49.861735Z","end":"2026-05-12T16:33:49.970622Z","steps":["trace[452097242] 'process raft request' (duration: 107.577971ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:33:53.084619Z","caller":"traceutil/trace.go:171","msg":"trace[184005193] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"157.714521ms","start":"2026-05-12T16:33:52.926884Z","end":"2026-05-12T16:33:53.084598Z","steps":["trace[184005193] 'process raft request' (duration: 157.261251ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:33:53.299322Z","caller":"traceutil/trace.go:171","msg":"trace[1074518419] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"118.404445ms","start":"2026-05-12T16:33:53.180895Z","end":"2026-05-12T16:33:53.2993Z","steps":["trace[1074518419] 'read index received' (duration: 71.350546ms)","trace[1074518419] 'applied index is now lower than readState.Index' (duration: 47.052909ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:33:53.299379Z","caller":"traceutil/trace.go:171","msg":"trace[1449196284] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"155.751197ms","start":"2026-05-12T16:33:53.143603Z","end":"2026-05-12T16:33:53.299354Z","steps":["trace[1449196284] 'process raft request' (duration: 108.592675ms)","trace[1449196284] 'compare' (duration: 46.990958ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T16:33:53.299667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.795654ms","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-12T16:33:53.299757Z","caller":"traceutil/trace.go:171","msg":"trace[569454844] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:577; }","duration":"118.929767ms","start":"2026-05-12T16:33:53.18081Z","end":"2026-05-12T16:33:53.29974Z","steps":["trace[569454844] 'agreement among raft nodes before linearized reading' (duration: 118.60924ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:33:53.618163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.272176ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T16:33:53.618353Z","caller":"traceutil/trace.go:171","msg":"trace[1320533721] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:599; }","duration":"306.590073ms","start":"2026-05-12T16:33:53.311745Z","end":"2026-05-12T16:33:53.618335Z","steps":["trace[1320533721] 'read index received' (duration: 134.57468ms)","trace[1320533721] 'applied index is now lower than readState.Index' (duration: 172.013603ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T16:33:53.618453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.700075ms","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":"info","ts":"2026-05-12T16:33:53.618513Z","caller":"traceutil/trace.go:171","msg":"trace[1123709389] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:578; }","duration":"306.784777ms","start":"2026-05-12T16:33:53.311714Z","end":"2026-05-12T16:33:53.618499Z","steps":["trace[1123709389] 'agreement among raft nodes before linearized reading' (duration: 306.700885ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:33:53.618588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:33:53.311699Z","time spent":"306.878219ms","remote":"127.0.0.1:42632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-05-12T16:33:53.618514Z","caller":"traceutil/trace.go:171","msg":"trace[1089037513] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"314.415808ms","start":"2026-05-12T16:33:53.304005Z","end":"2026-05-12T16:33:53.618421Z","steps":["trace[1089037513] 'process raft request' (duration: 142.322163ms)","trace[1089037513] 'compare' (duration: 171.129193ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T16:33:53.618911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:33:53.303986Z","time spent":"314.769406ms","remote":"127.0.0.1:42660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T16:33:54.131363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.901955ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T16:33:54.13144Z","caller":"traceutil/trace.go:171","msg":"trace[1539048715] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:600; }","duration":"202.050009ms","start":"2026-05-12T16:33:53.929377Z","end":"2026-05-12T16:33:54.131427Z","steps":["trace[1539048715] 'read index received' (duration: 70.202µs)","trace[1539048715] 'applied index is now lower than readState.Index' (duration: 201.978967ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:33:54.131574Z","caller":"traceutil/trace.go:171","msg":"trace[2095068796] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"818.585798ms","start":"2026-05-12T16:33:53.312953Z","end":"2026-05-12T16:33:54.131538Z","steps":["trace[2095068796] 'process raft request' (duration: 612.444208ms)","trace[2095068796] 'compare' (duration: 205.581078ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T16:33:54.131622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.277644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T16:33:54.132042Z","caller":"traceutil/trace.go:171","msg":"trace[639120233] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:579; }","duration":"202.731594ms","start":"2026-05-12T16:33:53.929292Z","end":"2026-05-12T16:33:54.132024Z","steps":["trace[639120233] 'agreement among raft nodes before linearized reading' (duration: 202.307085ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:33:54.131985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:33:53.312937Z","time spent":"818.972617ms","remote":"127.0.0.1:42572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T16:33:54.480922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.275298ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T16:33:54.481Z","caller":"traceutil/trace.go:171","msg":"trace[1743480214] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:601; }","duration":"349.530408ms","start":"2026-05-12T16:33:54.131457Z","end":"2026-05-12T16:33:54.480988Z","steps":["trace[1743480214] 'read index received' (duration: 204.841541ms)","trace[1743480214] 'applied index is now lower than readState.Index' (duration: 144.688157ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:33:54.481065Z","caller":"traceutil/trace.go:171","msg":"trace[240936409] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"469.142852ms","start":"2026-05-12T16:33:54.011889Z","end":"2026-05-12T16:33:54.481032Z","steps":["trace[240936409] 'process raft request' (duration: 324.69833ms)","trace[240936409] 'compare' (duration: 143.995312ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T16:33:54.481115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.425025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-12T16:33:54.481134Z","caller":"traceutil/trace.go:171","msg":"trace[814680855] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:580; }","duration":"496.475066ms","start":"2026-05-12T16:33:53.984653Z","end":"2026-05-12T16:33:54.481128Z","steps":["trace[814680855] 'agreement among raft nodes before linearized reading' (duration: 496.395325ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:33:54.481473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:33:53.984629Z","time spent":"496.807404ms","remote":"127.0.0.1:42660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-12T16:33:54.481669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.985401ms","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-12T16:33:54.481699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:33:54.011856Z","time spent":"469.279625ms","remote":"127.0.0.1:42496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T16:33:54.481747Z","caller":"traceutil/trace.go:171","msg":"trace[1327037180] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:580; }","duration":"498.076313ms","start":"2026-05-12T16:33:53.983635Z","end":"2026-05-12T16:33:54.481711Z","steps":["trace[1327037180] 'agreement among raft nodes before linearized reading' (duration: 497.90964ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:33:54.481783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:33:53.983609Z","time spent":"498.166735ms","remote":"127.0.0.1:42660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-12T16:33:54.481522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.856824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T16:33:54.485254Z","caller":"traceutil/trace.go:171","msg":"trace[1149573140] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:580; }","duration":"352.561096ms","start":"2026-05-12T16:33:54.132629Z","end":"2026-05-12T16:33:54.48519Z","steps":["trace[1149573140] 'agreement among raft nodes before linearized reading' (duration: 348.479965ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:33:54.48534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:33:54.132616Z","time spent":"352.66821ms","remote":"127.0.0.1:42404","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-12T16:33:55.219696Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2481375767248453337,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T16:33:55.606076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.142408ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T16:33:55.606259Z","caller":"traceutil/trace.go:171","msg":"trace[598043456] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:603; }","duration":"887.650933ms","start":"2026-05-12T16:33:54.718593Z","end":"2026-05-12T16:33:55.606244Z","steps":["trace[598043456] 'read index received' (duration: 710.376082ms)","trace[598043456] 'applied index is now lower than readState.Index' (duration: 177.273871ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:33:55.606374Z","caller":"traceutil/trace.go:171","msg":"trace[2048770235] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"1.113245963s","start":"2026-05-12T16:33:54.493103Z","end":"2026-05-12T16:33:55.606349Z","steps":["trace[2048770235] 'process raft request' (duration: 935.743596ms)","trace[2048770235] 'compare' (duration: 176.984314ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T16:33:55.606428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.849998ms","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-12T16:33:55.606458Z","caller":"traceutil/trace.go:171","msg":"trace[1072690711] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:583; }","duration":"887.91727ms","start":"2026-05-12T16:33:54.718531Z","end":"2026-05-12T16:33:55.606448Z","steps":["trace[1072690711] 'agreement among raft nodes before linearized reading' (duration: 887.755706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:33:55.60649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:33:54.718511Z","time spent":"887.971271ms","remote":"127.0.0.1:42660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-12T16:33:55.606532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:33:54.493096Z","time spent":"1.113326374s","remote":"127.0.0.1:42660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T16:33:55.606578Z","caller":"traceutil/trace.go:171","msg":"trace[1434195209] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"1.06336805s","start":"2026-05-12T16:33:54.54318Z","end":"2026-05-12T16:33:55.606548Z","steps":["trace[1434195209] 'process raft request' (duration: 1.062996491s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:33:55.606718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:33:54.543158Z","time spent":"1.063488572s","remote":"127.0.0.1:42632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T16:33:57.588256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.285383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-12T16:33:57.588268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.205481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T16:33:57.588328Z","caller":"traceutil/trace.go:171","msg":"trace[281124438] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:617; }","duration":"134.429626ms","start":"2026-05-12T16:33:57.45388Z","end":"2026-05-12T16:33:57.58831Z","steps":["trace[281124438] 'range keys from in-memory index tree' (duration: 134.246662ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:33:57.588363Z","caller":"traceutil/trace.go:171","msg":"trace[1957225565] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:617; }","duration":"134.417676ms","start":"2026-05-12T16:33:57.453904Z","end":"2026-05-12T16:33:57.588322Z","steps":["trace[1957225565] 'range keys from in-memory index tree' (duration: 133.995247ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:33:57.588271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.62424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T16:33:57.588626Z","caller":"traceutil/trace.go:171","msg":"trace[1608397707] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:617; }","duration":"135.02928ms","start":"2026-05-12T16:33:57.453571Z","end":"2026-05-12T16:33:57.5886Z","steps":["trace[1608397707] 'count revisions from in-memory index tree' (duration: 134.530758ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:33:57.592318Z","caller":"traceutil/trace.go:171","msg":"trace[1430352108] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"136.900801ms","start":"2026-05-12T16:33:57.45536Z","end":"2026-05-12T16:33:57.59226Z","steps":["trace[1430352108] 'process raft request' (duration: 136.641265ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:34:10.869465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.578805ms","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-05-12T16:34:10.870025Z","caller":"traceutil/trace.go:171","msg":"trace[1366776271] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:740; }","duration":"666.146837ms","start":"2026-05-12T16:34:10.203854Z","end":"2026-05-12T16:34:10.870001Z","steps":["trace[1366776271] 'range keys from in-memory index tree' (duration: 665.562364ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:34:10.869482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.437298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-12T16:34:10.870228Z","caller":"traceutil/trace.go:171","msg":"trace[1474433462] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:740; }","duration":"303.202555ms","start":"2026-05-12T16:34:10.566989Z","end":"2026-05-12T16:34:10.870191Z","steps":["trace[1474433462] 'range keys from in-memory index tree' (duration: 302.234584ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:34:10.870306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:34:10.566971Z","time spent":"303.310397ms","remote":"127.0.0.1:42660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-12T16:34:10.869488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.819483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-7r2sj\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-12T16:34:10.870563Z","caller":"traceutil/trace.go:171","msg":"trace[323256200] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-7r2sj; range_end:; response_count:1; response_revision:740; }","duration":"548.881387ms","start":"2026-05-12T16:34:10.3216Z","end":"2026-05-12T16:34:10.870482Z","steps":["trace[323256200] 'range keys from in-memory index tree' (duration: 547.63571ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:34:10.870683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:34:10.32158Z","time spent":"549.03548ms","remote":"127.0.0.1:42572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-7r2sj\" "} {"level":"warn","ts":"2026-05-12T16:34:10.869582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.999348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.147\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-12T16:34:10.873604Z","caller":"traceutil/trace.go:171","msg":"trace[691645830] range","detail":"{range_begin:/registry/masterleases/199.19.213.147; range_end:; response_count:1; response_revision:740; }","duration":"372.830703ms","start":"2026-05-12T16:34:10.500541Z","end":"2026-05-12T16:34:10.873371Z","steps":["trace[691645830] 'range keys from in-memory index tree' (duration: 368.67489ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:34:10.874034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:34:10.500522Z","time spent":"373.193523ms","remote":"127.0.0.1:42442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.147\" "} {"level":"warn","ts":"2026-05-12T16:34:45.831021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.912371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-12T16:34:45.831101Z","caller":"traceutil/trace.go:171","msg":"trace[1006456350] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:953; }","duration":"467.073225ms","start":"2026-05-12T16:34:45.364013Z","end":"2026-05-12T16:34:45.831087Z","steps":["trace[1006456350] 'range keys from in-memory index tree' (duration: 466.686055ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:34:45.831134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:34:45.363986Z","time spent":"467.141426ms","remote":"127.0.0.1:42660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-12T16:34:46.042339Z","caller":"traceutil/trace.go:171","msg":"trace[1484171477] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"393.577991ms","start":"2026-05-12T16:34:45.648743Z","end":"2026-05-12T16:34:46.042321Z","steps":["trace[1484171477] 'process raft request' (duration: 393.436408ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:34:46.042481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:34:45.648717Z","time spent":"393.682804ms","remote":"127.0.0.1:42660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T16:34:46.135139Z","caller":"traceutil/trace.go:171","msg":"trace[730777972] linearizableReadLoop","detail":"{readStateIndex:993; appliedIndex:990; }","duration":"253.405145ms","start":"2026-05-12T16:34:45.881714Z","end":"2026-05-12T16:34:46.135119Z","steps":["trace[730777972] 'read index received' (duration: 160.472543ms)","trace[730777972] 'applied index is now lower than readState.Index' (duration: 92.931772ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:34:46.135238Z","caller":"traceutil/trace.go:171","msg":"trace[733913367] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"300.26616ms","start":"2026-05-12T16:34:45.834934Z","end":"2026-05-12T16:34:46.1352Z","steps":["trace[733913367] 'process raft request' (duration: 300.084456ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:34:46.135361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.508505ms","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-12T16:34:46.135377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.650491ms","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-12T16:34:46.135418Z","caller":"traceutil/trace.go:171","msg":"trace[1812990905] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:955; }","duration":"248.598767ms","start":"2026-05-12T16:34:45.886807Z","end":"2026-05-12T16:34:46.135406Z","steps":["trace[1812990905] 'agreement among raft nodes before linearized reading' (duration: 248.433954ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:34:46.135447Z","caller":"traceutil/trace.go:171","msg":"trace[257013125] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:955; }","duration":"253.763053ms","start":"2026-05-12T16:34:45.881669Z","end":"2026-05-12T16:34:46.135432Z","steps":["trace[257013125] 'agreement among raft nodes before linearized reading' (duration: 253.555348ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:34:46.135442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:34:45.834905Z","time spent":"300.447834ms","remote":"127.0.0.1:42660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T16:34:46.135441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.222018ms","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-12T16:34:46.135501Z","caller":"traceutil/trace.go:171","msg":"trace[1281486381] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:955; }","duration":"248.29927ms","start":"2026-05-12T16:34:45.88719Z","end":"2026-05-12T16:34:46.13549Z","steps":["trace[1281486381] 'agreement among raft nodes before linearized reading' (duration: 248.097096ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:34:57.325558Z","caller":"traceutil/trace.go:171","msg":"trace[181321334] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"118.100989ms","start":"2026-05-12T16:34:57.20744Z","end":"2026-05-12T16:34:57.325541Z","steps":["trace[181321334] 'process raft request' (duration: 117.967026ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:35:09.829629Z","caller":"traceutil/trace.go:171","msg":"trace[624811305] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"444.933357ms","start":"2026-05-12T16:35:09.384659Z","end":"2026-05-12T16:35:09.829592Z","steps":["trace[624811305] 'process raft request' (duration: 444.776464ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:09.829782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:35:09.384635Z","time spent":"445.06947ms","remote":"127.0.0.1:42660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T16:35:10.330549Z","caller":"traceutil/trace.go:171","msg":"trace[1870577030] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"188.476783ms","start":"2026-05-12T16:35:10.142043Z","end":"2026-05-12T16:35:10.33052Z","steps":["trace[1870577030] 'process raft request' (duration: 188.215408ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:11.426814Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2481375767248454373,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-12T16:35:11.427423Z","caller":"traceutil/trace.go:171","msg":"trace[1903120471] linearizableReadLoop","detail":"{readStateIndex:1081; appliedIndex:1080; }","duration":"500.675971ms","start":"2026-05-12T16:35:10.926724Z","end":"2026-05-12T16:35:11.427399Z","steps":["trace[1903120471] 'read index received' (duration: 500.352094ms)","trace[1903120471] 'applied index is now lower than readState.Index' (duration: 322.547µs)"],"step_count":2} {"level":"warn","ts":"2026-05-12T16:35:11.427626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.788474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T16:35:11.427524Z","caller":"traceutil/trace.go:171","msg":"trace[1729856888] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"550.509354ms","start":"2026-05-12T16:35:10.876969Z","end":"2026-05-12T16:35:11.427478Z","steps":["trace[1729856888] 'process raft request' (duration: 550.242468ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:35:11.427674Z","caller":"traceutil/trace.go:171","msg":"trace[1408441142] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1038; }","duration":"500.973199ms","start":"2026-05-12T16:35:10.926688Z","end":"2026-05-12T16:35:11.427662Z","steps":["trace[1408441142] 'agreement among raft nodes before linearized reading' (duration: 500.789234ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:11.427885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:35:10.926676Z","time spent":"501.125313ms","remote":"127.0.0.1:42414","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-12T16:35:11.427939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:35:10.876931Z","time spent":"550.757799ms","remote":"127.0.0.1:42660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T16:35:12.487074Z","caller":"traceutil/trace.go:171","msg":"trace[1729458329] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"151.162702ms","start":"2026-05-12T16:35:12.335886Z","end":"2026-05-12T16:35:12.487048Z","steps":["trace[1729458329] 'process raft request' (duration: 150.466186ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:35:12.675904Z","caller":"traceutil/trace.go:171","msg":"trace[1369505606] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"183.645915ms","start":"2026-05-12T16:35:12.492231Z","end":"2026-05-12T16:35:12.675877Z","steps":["trace[1369505606] 'process raft request' (duration: 151.169023ms)","trace[1369505606] 'compare' (duration: 32.38704ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:35:12.812407Z","caller":"traceutil/trace.go:171","msg":"trace[736729975] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"319.509453ms","start":"2026-05-12T16:35:12.492863Z","end":"2026-05-12T16:35:12.812372Z","steps":["trace[736729975] 'process raft request' (duration: 319.197506ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:35:12.812365Z","caller":"traceutil/trace.go:171","msg":"trace[653138967] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"319.969724ms","start":"2026-05-12T16:35:12.49237Z","end":"2026-05-12T16:35:12.81234Z","steps":["trace[653138967] 'process raft request' (duration: 319.440441ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:35:12.812514Z","caller":"traceutil/trace.go:171","msg":"trace[1083825854] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"320.178249ms","start":"2026-05-12T16:35:12.492272Z","end":"2026-05-12T16:35:12.81245Z","steps":["trace[1083825854] 'process raft request' (duration: 309.882286ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:12.812587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:35:12.492362Z","time spent":"320.141658ms","remote":"127.0.0.1:42660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T16:35:12.813618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:35:12.492252Z","time spent":"321.147511ms","remote":"127.0.0.1:42660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T16:35:12.812775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:35:12.492853Z","time spent":"319.623486ms","remote":"127.0.0.1:42660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T16:35:13.359623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.545104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-12T16:35:13.359688Z","caller":"traceutil/trace.go:171","msg":"trace[231460707] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1044; }","duration":"299.657156ms","start":"2026-05-12T16:35:13.060019Z","end":"2026-05-12T16:35:13.359676Z","steps":["trace[231460707] 'count revisions from in-memory index tree' (duration: 299.473342ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:13.359727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.120378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T16:35:13.359812Z","caller":"traceutil/trace.go:171","msg":"trace[1250449924] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1044; }","duration":"432.253201ms","start":"2026-05-12T16:35:12.927537Z","end":"2026-05-12T16:35:13.35979Z","steps":["trace[1250449924] 'range keys from in-memory index tree' (duration: 432.030436ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:13.359858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:35:12.927516Z","time spent":"432.333283ms","remote":"127.0.0.1:42414","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-12T16:35:14.988566Z","caller":"traceutil/trace.go:171","msg":"trace[525561556] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"165.608718ms","start":"2026-05-12T16:35:14.822936Z","end":"2026-05-12T16:35:14.988545Z","steps":["trace[525561556] 'process raft request' (duration: 163.212765ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:35:14.988748Z","caller":"traceutil/trace.go:171","msg":"trace[39258026] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"165.268831ms","start":"2026-05-12T16:35:14.823466Z","end":"2026-05-12T16:35:14.988735Z","steps":["trace[39258026] 'process raft request' (duration: 164.771159ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:22.948697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.022709ms","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-12T16:35:22.948862Z","caller":"traceutil/trace.go:171","msg":"trace[1325087061] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1073; }","duration":"223.236705ms","start":"2026-05-12T16:35:22.725605Z","end":"2026-05-12T16:35:22.948842Z","steps":["trace[1325087061] 'range keys from in-memory index tree' (duration: 222.848486ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:26.30032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.590381ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-12T16:35:26.300606Z","caller":"traceutil/trace.go:171","msg":"trace[584803927] linearizableReadLoop","detail":"{readStateIndex:1185; appliedIndex:1184; }","duration":"375.493373ms","start":"2026-05-12T16:35:25.925096Z","end":"2026-05-12T16:35:26.30059Z","steps":["trace[584803927] 'read index received' (duration: 40.311µs)","trace[584803927] 'applied index is now lower than readState.Index' (duration: 375.451622ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T16:35:26.300726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.610907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T16:35:26.300802Z","caller":"traceutil/trace.go:171","msg":"trace[1253369009] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1136; }","duration":"375.720689ms","start":"2026-05-12T16:35:25.925062Z","end":"2026-05-12T16:35:26.300783Z","steps":["trace[1253369009] 'agreement among raft nodes before linearized reading' (duration: 375.595016ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:26.30081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.994873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-12T16:35:26.300846Z","caller":"traceutil/trace.go:171","msg":"trace[1054357674] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1136; }","duration":"219.075375ms","start":"2026-05-12T16:35:26.081761Z","end":"2026-05-12T16:35:26.300836Z","steps":["trace[1054357674] 'agreement among raft nodes before linearized reading' (duration: 218.926311ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:26.300842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:35:25.925041Z","time spent":"375.793321ms","remote":"127.0.0.1:42404","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-12T16:35:26.539578Z","caller":"traceutil/trace.go:171","msg":"trace[217073171] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"234.819303ms","start":"2026-05-12T16:35:26.304726Z","end":"2026-05-12T16:35:26.539545Z","steps":["trace[217073171] 'process raft request' (duration: 234.595917ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:28.494704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.247231412s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T16:35:28.494991Z","caller":"traceutil/trace.go:171","msg":"trace[2088472658] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1142; }","duration":"1.247564199s","start":"2026-05-12T16:35:27.247412Z","end":"2026-05-12T16:35:28.494976Z","steps":["trace[2088472658] 'count revisions from in-memory index tree' (duration: 1.24717298s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:28.495049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:35:27.247396Z","time spent":"1.247641731s","remote":"127.0.0.1:41092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-05-12T16:35:28.495266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.043838494s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T16:35:28.495313Z","caller":"traceutil/trace.go:171","msg":"trace[1361722126] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1142; }","duration":"1.043910016s","start":"2026-05-12T16:35:27.451397Z","end":"2026-05-12T16:35:28.495307Z","steps":["trace[1361722126] 'count revisions from in-memory index tree' (duration: 1.043786282s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:28.495345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:35:27.451384Z","time spent":"1.043954186s","remote":"127.0.0.1:42544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-05-12T16:35:28.495454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.495828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T16:35:28.495497Z","caller":"traceutil/trace.go:171","msg":"trace[1395477771] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1142; }","duration":"937.56156ms","start":"2026-05-12T16:35:27.557928Z","end":"2026-05-12T16:35:28.49549Z","steps":["trace[1395477771] 'count revisions from in-memory index tree' (duration: 937.445277ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:28.495528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:35:27.557914Z","time spent":"937.607581ms","remote":"127.0.0.1:41076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-05-12T16:35:28.496122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.513011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T16:35:28.496153Z","caller":"traceutil/trace.go:171","msg":"trace[385591184] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1142; }","duration":"568.572583ms","start":"2026-05-12T16:35:27.927573Z","end":"2026-05-12T16:35:28.496146Z","steps":["trace[385591184] 'range keys from in-memory index tree' (duration: 568.471461ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:28.496174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:35:27.927558Z","time spent":"568.606504ms","remote":"127.0.0.1:42414","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-12T16:35:40.714442Z","caller":"traceutil/trace.go:171","msg":"trace[829321816] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"115.805603ms","start":"2026-05-12T16:35:40.598611Z","end":"2026-05-12T16:35:40.714417Z","steps":["trace[829321816] 'process raft request' (duration: 115.650679ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:35:44.880776Z","caller":"traceutil/trace.go:171","msg":"trace[414532372] linearizableReadLoop","detail":"{readStateIndex:1266; appliedIndex:1265; }","duration":"154.608695ms","start":"2026-05-12T16:35:44.726147Z","end":"2026-05-12T16:35:44.880755Z","steps":["trace[414532372] 'read index received' (duration: 154.535733ms)","trace[414532372] 'applied index is now lower than readState.Index' (duration: 72.042µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:35:44.880809Z","caller":"traceutil/trace.go:171","msg":"trace[1918851864] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"581.747669ms","start":"2026-05-12T16:35:44.299033Z","end":"2026-05-12T16:35:44.88078Z","steps":["trace[1918851864] 'process raft request' (duration: 581.600226ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:44.880934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:35:44.299017Z","time spent":"581.850202ms","remote":"127.0.0.1:42566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5457,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T16:35:44.880934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.767308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-12T16:35:44.881047Z","caller":"traceutil/trace.go:171","msg":"trace[923594079] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1214; }","duration":"154.933843ms","start":"2026-05-12T16:35:44.726105Z","end":"2026-05-12T16:35:44.881039Z","steps":["trace[923594079] 'agreement among raft nodes before linearized reading' (duration: 154.725938ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:45.384729Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2481375767248454786,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-12T16:35:45.452682Z","caller":"traceutil/trace.go:171","msg":"trace[20245603] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"691.304702ms","start":"2026-05-12T16:35:44.761343Z","end":"2026-05-12T16:35:45.452647Z","steps":["trace[20245603] 'process raft request' (duration: 670.098723ms)","trace[20245603] 'compare' (duration: 20.748208ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T16:35:45.452893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:35:44.76132Z","time spent":"691.440855ms","remote":"127.0.0.1:42660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T16:35:45.491372Z","caller":"traceutil/trace.go:171","msg":"trace[1412581699] linearizableReadLoop","detail":"{readStateIndex:1269; appliedIndex:1266; }","duration":"606.652358ms","start":"2026-05-12T16:35:44.884686Z","end":"2026-05-12T16:35:45.491339Z","steps":["trace[1412581699] 'read index received' (duration: 546.892123ms)","trace[1412581699] 'applied index is now lower than readState.Index' (duration: 59.758435ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:35:45.491405Z","caller":"traceutil/trace.go:171","msg":"trace[649385850] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"606.823482ms","start":"2026-05-12T16:35:44.884541Z","end":"2026-05-12T16:35:45.491365Z","steps":["trace[649385850] 'process raft request' (duration: 606.550705ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:35:45.491478Z","caller":"traceutil/trace.go:171","msg":"trace[269838133] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"412.360309ms","start":"2026-05-12T16:35:45.079053Z","end":"2026-05-12T16:35:45.491413Z","steps":["trace[269838133] 'process raft request' (duration: 412.229836ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:45.491565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.977551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-12T16:35:45.491567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.858773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-05-12T16:35:45.491617Z","caller":"traceutil/trace.go:171","msg":"trace[1407600887] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1217; }","duration":"564.060493ms","start":"2026-05-12T16:35:44.927544Z","end":"2026-05-12T16:35:45.491605Z","steps":["trace[1407600887] 'agreement among raft nodes before linearized reading' (duration: 563.996432ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:35:45.491629Z","caller":"traceutil/trace.go:171","msg":"trace[889326411] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:1217; }","duration":"606.969606ms","start":"2026-05-12T16:35:44.884646Z","end":"2026-05-12T16:35:45.491616Z","steps":["trace[889326411] 'agreement among raft nodes before linearized reading' (duration: 606.838933ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:45.49164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:35:44.927523Z","time spent":"564.112885ms","remote":"127.0.0.1:42414","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-12T16:35:45.491586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:35:44.884522Z","time spent":"606.971176ms","remote":"127.0.0.1:42660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T16:35:45.491661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:35:44.884632Z","time spent":"607.022097ms","remote":"127.0.0.1:42790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":686,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-05-12T16:35:45.491726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.990412ms","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-12T16:35:45.491796Z","caller":"traceutil/trace.go:171","msg":"trace[592552257] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1217; }","duration":"358.147967ms","start":"2026-05-12T16:35:45.133631Z","end":"2026-05-12T16:35:45.491779Z","steps":["trace[592552257] 'agreement among raft nodes before linearized reading' (duration: 357.912021ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:45.491817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.373602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-12T16:35:45.491847Z","caller":"traceutil/trace.go:171","msg":"trace[1901028457] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1217; }","duration":"418.402723ms","start":"2026-05-12T16:35:45.073438Z","end":"2026-05-12T16:35:45.491841Z","steps":["trace[1901028457] 'agreement among raft nodes before linearized reading' (duration: 418.306541ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:45.49184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:35:45.13361Z","time spent":"358.218728ms","remote":"127.0.0.1:42660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-12T16:35:45.491812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:35:45.07903Z","time spent":"412.610004ms","remote":"127.0.0.1:42660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T16:35:45.491896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.56565ms","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-12T16:35:45.491916Z","caller":"traceutil/trace.go:171","msg":"trace[65500930] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1217; }","duration":"436.635763ms","start":"2026-05-12T16:35:45.055275Z","end":"2026-05-12T16:35:45.491911Z","steps":["trace[65500930] 'agreement among raft nodes before linearized reading' (duration: 436.594922ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:45.491928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:35:45.055246Z","time spent":"436.679183ms","remote":"127.0.0.1:42660","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-05-12T16:35:45.491862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:35:45.073353Z","time spent":"418.505745ms","remote":"127.0.0.1:42660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-12T16:35:45.49202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.022711ms","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-05-12T16:35:45.492061Z","caller":"traceutil/trace.go:171","msg":"trace[1037789432] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1217; }","duration":"293.090232ms","start":"2026-05-12T16:35:45.198961Z","end":"2026-05-12T16:35:45.492052Z","steps":["trace[1037789432] 'agreement among raft nodes before linearized reading' (duration: 292.98776ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:35:45.636346Z","caller":"traceutil/trace.go:171","msg":"trace[780853380] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"139.742858ms","start":"2026-05-12T16:35:45.49658Z","end":"2026-05-12T16:35:45.636322Z","steps":["trace[780853380] 'process raft request' (duration: 96.738504ms)","trace[780853380] 'compare' (duration: 42.913002ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T16:35:45.858767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.520926ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T16:35:45.859106Z","caller":"traceutil/trace.go:171","msg":"trace[978925927] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"361.829704ms","start":"2026-05-12T16:35:45.49724Z","end":"2026-05-12T16:35:45.859069Z","steps":["trace[978925927] 'process raft request' (duration: 361.622379ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:45.85917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:35:45.497229Z","time spent":"361.909537ms","remote":"127.0.0.1:42660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T16:35:45.859238Z","caller":"traceutil/trace.go:171","msg":"trace[516921544] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"361.474307ms","start":"2026-05-12T16:35:45.497736Z","end":"2026-05-12T16:35:45.85921Z","steps":["trace[516921544] 'process raft request' (duration: 361.226241ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:35:45.859267Z","caller":"traceutil/trace.go:171","msg":"trace[18174966] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"362.433079ms","start":"2026-05-12T16:35:45.496804Z","end":"2026-05-12T16:35:45.859237Z","steps":["trace[18174966] 'process raft request' (duration: 246.3596ms)","trace[18174966] 'compare' (duration: 115.371263ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T16:35:45.859372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:35:45.497728Z","time spent":"361.570789ms","remote":"127.0.0.1:42660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T16:35:45.859403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:35:45.49679Z","time spent":"362.538961ms","remote":"127.0.0.1:42790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":797,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T16:35:45.859431Z","caller":"traceutil/trace.go:171","msg":"trace[1179221636] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"362.131712ms","start":"2026-05-12T16:35:45.497291Z","end":"2026-05-12T16:35:45.859423Z","steps":["trace[1179221636] 'process raft request' (duration: 361.63545ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:45.859472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:35:45.497286Z","time spent":"362.163902ms","remote":"127.0.0.1:42660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T16:35:47.660213Z","caller":"traceutil/trace.go:171","msg":"trace[1174798656] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"138.514819ms","start":"2026-05-12T16:35:47.521672Z","end":"2026-05-12T16:35:47.660187Z","steps":["trace[1174798656] 'process raft request' (duration: 83.572849ms)","trace[1174798656] 'compare' (duration: 54.414457ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:35:59.386992Z","caller":"traceutil/trace.go:171","msg":"trace[968329295] linearizableReadLoop","detail":"{readStateIndex:1365; appliedIndex:1364; }","duration":"206.675897ms","start":"2026-05-12T16:35:59.180293Z","end":"2026-05-12T16:35:59.386969Z","steps":["trace[968329295] 'read index received' (duration: 206.492272ms)","trace[968329295] 'applied index is now lower than readState.Index' (duration: 179.465µs)"],"step_count":2} {"level":"warn","ts":"2026-05-12T16:35:59.387137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.82875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T16:35:59.387175Z","caller":"traceutil/trace.go:171","msg":"trace[41606614] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1310; }","duration":"206.915712ms","start":"2026-05-12T16:35:59.180248Z","end":"2026-05-12T16:35:59.387164Z","steps":["trace[41606614] 'agreement among raft nodes before linearized reading' (duration: 206.81575ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:35:59.387397Z","caller":"traceutil/trace.go:171","msg":"trace[1265810923] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"212.2802ms","start":"2026-05-12T16:35:59.175086Z","end":"2026-05-12T16:35:59.387367Z","steps":["trace[1265810923] 'process raft request' (duration: 211.689926ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:36:36.741585Z","caller":"traceutil/trace.go:171","msg":"trace[545347950] linearizableReadLoop","detail":"{readStateIndex:1547; appliedIndex:1546; }","duration":"126.115946ms","start":"2026-05-12T16:36:36.61544Z","end":"2026-05-12T16:36:36.741556Z","steps":["trace[545347950] 'read index received' (duration: 120.190683ms)","trace[545347950] 'applied index is now lower than readState.Index' (duration: 5.923893ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T16:36:36.741848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.385242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-12T16:36:36.7419Z","caller":"traceutil/trace.go:171","msg":"trace[1581135277] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1484; }","duration":"126.485125ms","start":"2026-05-12T16:36:36.6154Z","end":"2026-05-12T16:36:36.741886Z","steps":["trace[1581135277] 'agreement among raft nodes before linearized reading' (duration: 126.27451ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:36:36.742103Z","caller":"traceutil/trace.go:171","msg":"trace[1400616431] transaction","detail":"{read_only:false; response_revision:1484; number_of_response:1; }","duration":"235.060799ms","start":"2026-05-12T16:36:36.507014Z","end":"2026-05-12T16:36:36.742075Z","steps":["trace[1400616431] 'process raft request' (duration: 228.731436ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:37:10.377749Z","caller":"traceutil/trace.go:171","msg":"trace[1663143847] transaction","detail":"{read_only:false; response_revision:1636; number_of_response:1; }","duration":"168.46495ms","start":"2026-05-12T16:37:10.209238Z","end":"2026-05-12T16:37:10.377703Z","steps":["trace[1663143847] 'process raft request' (duration: 149.815139ms)","trace[1663143847] 'compare' (duration: 18.416626ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:37:22.125705Z","caller":"traceutil/trace.go:171","msg":"trace[1156309282] linearizableReadLoop","detail":"{readStateIndex:1761; appliedIndex:1760; }","duration":"139.738006ms","start":"2026-05-12T16:37:21.985947Z","end":"2026-05-12T16:37:22.125685Z","steps":["trace[1156309282] 'read index received' (duration: 63.153656ms)","trace[1156309282] 'applied index is now lower than readState.Index' (duration: 76.58349ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T16:37:22.125854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.894809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-05-12T16:37:22.125878Z","caller":"traceutil/trace.go:171","msg":"trace[160068973] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1689; }","duration":"139.967381ms","start":"2026-05-12T16:37:21.985901Z","end":"2026-05-12T16:37:22.125868Z","steps":["trace[160068973] 'agreement among raft nodes before linearized reading' (duration: 139.856489ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:37:22.12592Z","caller":"traceutil/trace.go:171","msg":"trace[1815514521] transaction","detail":"{read_only:false; response_revision:1689; number_of_response:1; }","duration":"148.20164ms","start":"2026-05-12T16:37:21.977686Z","end":"2026-05-12T16:37:22.125887Z","steps":["trace[1815514521] 'process raft request' (duration: 71.455926ms)","trace[1815514521] 'compare' (duration: 76.467717ms)"],"step_count":2}