{"level":"warn","ts":"2026-04-16T16:49:29.274798Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-16T16:49:29.274884Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.209: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.209:2380","--initial-cluster=instance=https://199.204.45.209:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.209:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.209: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-04-16T16:49:29.274954Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-16T16:49:29.274967Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.209:2380"]} {"level":"info","ts":"2026-04-16T16:49:29.274997Z","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-04-16T16:49:29.275638Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.209:2379"]} {"level":"info","ts":"2026-04-16T16:49:29.275712Z","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.209:2380"],"listen-peer-urls":["https://199.204.45.209:2380"],"advertise-client-urls":["https://199.204.45.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.209: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-04-16T16:49:29.344021Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"67.931949ms"} {"level":"info","ts":"2026-04-16T16:49:29.379423Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"adfb641115bf6cbd","cluster-id":"3da6b677d4b80c6"} {"level":"info","ts":"2026-04-16T16:49:29.379523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd switched to configuration voters=()"} {"level":"info","ts":"2026-04-16T16:49:29.379553Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became follower at term 0"} {"level":"info","ts":"2026-04-16T16:49:29.379571Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft adfb641115bf6cbd [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-16T16:49:29.379609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became follower at term 1"} {"level":"info","ts":"2026-04-16T16:49:29.379678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd switched to configuration voters=(12536724012257995965)"} {"level":"warn","ts":"2026-04-16T16:49:29.398482Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-16T16:49:29.400263Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-16T16:49:29.40157Z","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-04-16T16:49:29.404569Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"adfb641115bf6cbd","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-16T16:49:29.404814Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"adfb641115bf6cbd","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-16T16:49:29.405713Z","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-04-16T16:49:29.405955Z","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-04-16T16:49:29.405974Z","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-04-16T16:49:29.406876Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd switched to configuration voters=(12536724012257995965)"} {"level":"info","ts":"2026-04-16T16:49:29.407013Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3da6b677d4b80c6","local-member-id":"adfb641115bf6cbd","added-peer-id":"adfb641115bf6cbd","added-peer-peer-urls":["https://199.204.45.209:2380"]} {"level":"info","ts":"2026-04-16T16:49:29.408832Z","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-04-16T16:49:29.409086Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"adfb641115bf6cbd","initial-advertise-peer-urls":["https://199.204.45.209:2380"],"listen-peer-urls":["https://199.204.45.209:2380"],"advertise-client-urls":["https://199.204.45.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-16T16:49:29.409222Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-16T16:49:29.409407Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.209:2380"} {"level":"info","ts":"2026-04-16T16:49:29.409515Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.209:2380"} {"level":"info","ts":"2026-04-16T16:49:30.079914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd is starting a new election at term 1"} {"level":"info","ts":"2026-04-16T16:49:30.080008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became pre-candidate at term 1"} {"level":"info","ts":"2026-04-16T16:49:30.080057Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd received MsgPreVoteResp from adfb641115bf6cbd at term 1"} {"level":"info","ts":"2026-04-16T16:49:30.080078Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became candidate at term 2"} {"level":"info","ts":"2026-04-16T16:49:30.080096Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd received MsgVoteResp from adfb641115bf6cbd at term 2"} {"level":"info","ts":"2026-04-16T16:49:30.080113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became leader at term 2"} {"level":"info","ts":"2026-04-16T16:49:30.080132Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: adfb641115bf6cbd elected leader adfb641115bf6cbd at term 2"} {"level":"info","ts":"2026-04-16T16:49:30.081408Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"adfb641115bf6cbd","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.209:2379]}","request-path":"/0/members/adfb641115bf6cbd/attributes","cluster-id":"3da6b677d4b80c6","publish-timeout":"7s"} {"level":"info","ts":"2026-04-16T16:49:30.081437Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T16:49:30.081547Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T16:49:30.083234Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T16:49:30.083255Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-16T16:49:30.083458Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-16T16:49:30.084225Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3da6b677d4b80c6","local-member-id":"adfb641115bf6cbd","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T16:49:30.084333Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T16:49:30.084374Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T16:49:30.085019Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.209:2379"} {"level":"info","ts":"2026-04-16T16:49:30.085689Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-16T16:49:42.777588Z","caller":"traceutil/trace.go:171","msg":"trace[1575170752] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"133.048838ms","start":"2026-04-16T16:49:42.644519Z","end":"2026-04-16T16:49:42.777568Z","steps":["trace[1575170752] 'process raft request' (duration: 132.903365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:49:49.234336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.704331ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-16T16:49:57.821179Z","caller":"traceutil/trace.go:171","msg":"trace[347134070] linearizableReadLoop","detail":"{readStateIndex:525; appliedIndex:524; }","duration":"224.40797ms","start":"2026-04-16T16:49:57.596716Z","end":"2026-04-16T16:49:57.821124Z","steps":["trace[347134070] 'read index received' (duration: 224.232234ms)","trace[347134070] 'applied index is now lower than readState.Index' (duration: 174.906µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:49:57.821325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.588467ms","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-04-16T16:49:57.821294Z","caller":"traceutil/trace.go:171","msg":"trace[1643548778] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"397.69985ms","start":"2026-04-16T16:49:57.42356Z","end":"2026-04-16T16:49:57.82126Z","steps":["trace[1643548778] 'process raft request' (duration: 397.434201ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:49:57.821353Z","caller":"traceutil/trace.go:171","msg":"trace[1421774010] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:507; }","duration":"224.638608ms","start":"2026-04-16T16:49:57.596706Z","end":"2026-04-16T16:49:57.821344Z","steps":["trace[1421774010] 'agreement among raft nodes before linearized reading' (duration: 224.561636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:49:57.821419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.018271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-16T16:49:57.821489Z","caller":"traceutil/trace.go:171","msg":"trace[1161255737] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:507; }","duration":"200.122115ms","start":"2026-04-16T16:49:57.621345Z","end":"2026-04-16T16:49:57.821468Z","steps":["trace[1161255737] 'agreement among raft nodes before linearized reading' (duration: 199.973849ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:49:57.821525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.315332ms","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-04-16T16:49:57.821633Z","caller":"traceutil/trace.go:171","msg":"trace[1532086984] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:507; }","duration":"223.498008ms","start":"2026-04-16T16:49:57.598112Z","end":"2026-04-16T16:49:57.82161Z","steps":["trace[1532086984] 'agreement among raft nodes before linearized reading' (duration: 223.193518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:49:57.821855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:49:57.423528Z","time spent":"397.838765ms","remote":"127.0.0.1:60006","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-04-16T16:49:59.513356Z","caller":"traceutil/trace.go:171","msg":"trace[1839727590] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"985.001274ms","start":"2026-04-16T16:49:58.52828Z","end":"2026-04-16T16:49:59.513281Z","steps":["trace[1839727590] 'process raft request' (duration: 963.645573ms)","trace[1839727590] 'compare' (duration: 21.062791ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:49:59.513523Z","caller":"traceutil/trace.go:171","msg":"trace[1238412788] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"985.113578ms","start":"2026-04-16T16:49:58.528389Z","end":"2026-04-16T16:49:59.513502Z","steps":["trace[1238412788] 'process raft request' (duration: 984.793306ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:49:59.513624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:49:58.528262Z","time spent":"985.239522ms","remote":"127.0.0.1:59796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7693,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:49:59.513658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:49:58.528385Z","time spent":"985.198001ms","remote":"127.0.0.1:59796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:49:59.517627Z","caller":"traceutil/trace.go:171","msg":"trace[860688164] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"969.640701ms","start":"2026-04-16T16:49:58.547972Z","end":"2026-04-16T16:49:59.517613Z","steps":["trace[860688164] 'process raft request' (duration: 969.291309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:49:59.517746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:49:58.547955Z","time spent":"969.748165ms","remote":"127.0.0.1:59796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31121,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:49:59.517956Z","caller":"traceutil/trace.go:171","msg":"trace[768693523] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"969.906112ms","start":"2026-04-16T16:49:58.548038Z","end":"2026-04-16T16:49:59.517944Z","steps":["trace[768693523] 'process raft request' (duration: 969.478676ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:49:59.518019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:49:58.548033Z","time spent":"969.954584ms","remote":"127.0.0.1:59796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:49:59.518163Z","caller":"traceutil/trace.go:171","msg":"trace[2051783876] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"958.635333ms","start":"2026-04-16T16:49:58.559499Z","end":"2026-04-16T16:49:59.518134Z","steps":["trace[2051783876] 'process raft request' (duration: 958.325861ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:49:59.518284Z","caller":"traceutil/trace.go:171","msg":"trace[952294049] linearizableReadLoop","detail":"{readStateIndex:565; appliedIndex:558; }","duration":"478.303145ms","start":"2026-04-16T16:49:59.039957Z","end":"2026-04-16T16:49:59.51826Z","steps":["trace[952294049] 'read index received' (duration: 451.933423ms)","trace[952294049] 'applied index is now lower than readState.Index' (duration: 26.368622ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:49:59.51855Z","caller":"traceutil/trace.go:171","msg":"trace[102616823] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"955.991088ms","start":"2026-04-16T16:49:58.562531Z","end":"2026-04-16T16:49:59.518522Z","steps":["trace[102616823] 'process raft request' (duration: 955.47706ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:49:59.518601Z","caller":"traceutil/trace.go:171","msg":"trace[1352959588] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"902.740101ms","start":"2026-04-16T16:49:58.615848Z","end":"2026-04-16T16:49:59.518588Z","steps":["trace[1352959588] 'process raft request' (duration: 902.263134ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:49:59.51858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:49:58.55948Z","time spent":"958.776048ms","remote":"127.0.0.1:59796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:49:59.518669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:49:58.615819Z","time spent":"902.821904ms","remote":"127.0.0.1:59796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T16:49:59.518687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:49:58.562512Z","time spent":"956.101693ms","remote":"127.0.0.1:59796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23066,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:49:59.520122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.486771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:49:59.520188Z","caller":"traceutil/trace.go:171","msg":"trace[1650599416] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:540; }","duration":"480.258655ms","start":"2026-04-16T16:49:59.039911Z","end":"2026-04-16T16:49:59.520169Z","steps":["trace[1650599416] 'agreement among raft nodes before linearized reading' (duration: 478.46847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:49:59.520241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:49:59.039892Z","time spent":"480.337058ms","remote":"127.0.0.1:59762","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-04-16T16:50:06.195254Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835592199409182740,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T16:50:06.69575Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835592199409182740,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T16:50:06.833592Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.102613129s","expected-duration":"1s"} {"level":"info","ts":"2026-04-16T16:50:06.834562Z","caller":"traceutil/trace.go:171","msg":"trace[1220284535] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:645; }","duration":"1.139710457s","start":"2026-04-16T16:50:05.694827Z","end":"2026-04-16T16:50:06.834537Z","steps":["trace[1220284535] 'read index received' (duration: 1.138937656s)","trace[1220284535] 'applied index is now lower than readState.Index' (duration: 771.791µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:50:06.834671Z","caller":"traceutil/trace.go:171","msg":"trace[640134448] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"1.149906213s","start":"2026-04-16T16:50:05.684734Z","end":"2026-04-16T16:50:06.83464Z","steps":["trace[640134448] 'process raft request' (duration: 1.149311029s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:50:06.834727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.139879304s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2qk6v\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-16T16:50:06.834763Z","caller":"traceutil/trace.go:171","msg":"trace[412822504] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2qk6v; range_end:; response_count:1; response_revision:620; }","duration":"1.139960717s","start":"2026-04-16T16:50:05.694791Z","end":"2026-04-16T16:50:06.834752Z","steps":["trace[412822504] 'agreement among raft nodes before linearized reading' (duration: 1.139847513s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:50:06.834799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:50:05.694776Z","time spent":"1.140013759s","remote":"127.0.0.1:59932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-2qk6v\" "} {"level":"warn","ts":"2026-04-16T16:50:06.834822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:50:05.68471Z","time spent":"1.150029728s","remote":"127.0.0.1:59844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:50:06.834899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"977.570881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-16T16:50:06.834935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"967.371445ms","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-04-16T16:50:06.834975Z","caller":"traceutil/trace.go:171","msg":"trace[1230045929] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:620; }","duration":"977.697356ms","start":"2026-04-16T16:50:05.857249Z","end":"2026-04-16T16:50:06.834947Z","steps":["trace[1230045929] 'agreement among raft nodes before linearized reading' (duration: 977.456717ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:50:06.834988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.800801ms","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-04-16T16:50:06.835021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:50:05.857237Z","time spent":"977.776019ms","remote":"127.0.0.1:60006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-16T16:50:06.835032Z","caller":"traceutil/trace.go:171","msg":"trace[403311945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:620; }","duration":"967.531461ms","start":"2026-04-16T16:50:05.867468Z","end":"2026-04-16T16:50:06.834999Z","steps":["trace[403311945] 'agreement among raft nodes before linearized reading' (duration: 967.272141ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:50:06.8351Z","caller":"traceutil/trace.go:171","msg":"trace[1803665946] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:620; }","duration":"970.925777ms","start":"2026-04-16T16:50:05.864104Z","end":"2026-04-16T16:50:06.83503Z","steps":["trace[1803665946] 'agreement among raft nodes before linearized reading' (duration: 970.656826ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:50:06.835156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.785321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:50:06.835184Z","caller":"traceutil/trace.go:171","msg":"trace[1565030202] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:620; }","duration":"793.864594ms","start":"2026-04-16T16:50:06.04131Z","end":"2026-04-16T16:50:06.835175Z","steps":["trace[1565030202] 'agreement among raft nodes before linearized reading' (duration: 793.819562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:50:06.835181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:50:05.864084Z","time spent":"971.085574ms","remote":"127.0.0.1:60006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-16T16:50:06.835208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:50:06.041291Z","time spent":"793.912396ms","remote":"127.0.0.1:59762","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-04-16T16:50:06.835219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"971.996001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-16T16:50:06.835102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:50:05.867436Z","time spent":"967.657067ms","remote":"127.0.0.1:60006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-16T16:50:06.835272Z","caller":"traceutil/trace.go:171","msg":"trace[2111287677] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:620; }","duration":"972.052202ms","start":"2026-04-16T16:50:05.863195Z","end":"2026-04-16T16:50:06.835248Z","steps":["trace[2111287677] 'agreement among raft nodes before linearized reading' (duration: 971.944698ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:50:06.835381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:50:05.863161Z","time spent":"972.209929ms","remote":"127.0.0.1:60006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-16T16:50:07.543003Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835592199409182744,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T16:50:08.043902Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835592199409182744,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T16:50:08.524189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.497936486s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T16:50:08.524422Z","caller":"traceutil/trace.go:171","msg":"trace[604580091] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"1.683014337s","start":"2026-04-16T16:50:06.841366Z","end":"2026-04-16T16:50:08.52438Z","steps":["trace[604580091] 'process raft request' (duration: 184.81124ms)","trace[604580091] 'compare' (duration: 1.497290859s)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:50:08.524472Z","caller":"traceutil/trace.go:171","msg":"trace[1214569437] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"1.676631809s","start":"2026-04-16T16:50:06.847821Z","end":"2026-04-16T16:50:08.524452Z","steps":["trace[1214569437] 'process raft request' (duration: 1.676572307s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:50:08.524551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:50:06.847801Z","time spent":"1.676717833s","remote":"127.0.0.1:59932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:50:08.524621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:50:06.841343Z","time spent":"1.683163423s","remote":"127.0.0.1:60006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:50:08.524837Z","caller":"traceutil/trace.go:171","msg":"trace[435279610] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"1.681764398s","start":"2026-04-16T16:50:06.843063Z","end":"2026-04-16T16:50:08.524827Z","steps":["trace[435279610] 'process raft request' (duration: 1.681217815s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:50:08.524892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:50:06.843043Z","time spent":"1.681819839s","remote":"127.0.0.1:60006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:50:08.525044Z","caller":"traceutil/trace.go:171","msg":"trace[437366205] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"1.681914883s","start":"2026-04-16T16:50:06.843119Z","end":"2026-04-16T16:50:08.525034Z","steps":["trace[437366205] 'process raft request' (duration: 1.681218855s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:50:08.525094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:50:06.843105Z","time spent":"1.681960235s","remote":"127.0.0.1:60006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:50:08.544397Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835592199409182744,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T16:50:09.042166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00011395s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-16T16:50:09.04226Z","caller":"traceutil/trace.go:171","msg":"trace[1269305803] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000257456s","start":"2026-04-16T16:50:07.041983Z","end":"2026-04-16T16:50:09.042241Z","steps":["trace[1269305803] 'agreement among raft nodes before linearized reading' (duration: 2.000125341s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:50:09.04247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:50:07.041958Z","time spent":"2.000499906s","remote":"127.0.0.1:59762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T16:50:09.045119Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835592199409182744,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T16:50:09.153622Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.127333368s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-16T16:50:09.442151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.131429ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-16T16:50:09.442387Z","caller":"traceutil/trace.go:171","msg":"trace[516849118] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"2.55300387s","start":"2026-04-16T16:50:06.889358Z","end":"2026-04-16T16:50:09.442362Z","steps":["trace[516849118] 'process raft request' (duration: 2.264529005s)","trace[516849118] 'compare' (duration: 287.375628ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:50:09.442457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:50:06.889333Z","time spent":"2.553095234s","remote":"127.0.0.1:59980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T16:50:09.456269Z","caller":"traceutil/trace.go:171","msg":"trace[734807737] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:651; }","duration":"2.414220374s","start":"2026-04-16T16:50:07.042029Z","end":"2026-04-16T16:50:09.456249Z","steps":["trace[734807737] 'read index received' (duration: 2.111859085s)","trace[734807737] 'applied index is now lower than readState.Index' (duration: 302.359769ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:50:09.456586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.710015ms","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-04-16T16:50:09.456556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.130369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:50:09.456616Z","caller":"traceutil/trace.go:171","msg":"trace[584815345] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:626; }","duration":"578.782688ms","start":"2026-04-16T16:50:08.877826Z","end":"2026-04-16T16:50:09.456609Z","steps":["trace[584815345] 'agreement among raft nodes before linearized reading' (duration: 578.645153ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:50:09.456646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:50:08.877812Z","time spent":"578.82833ms","remote":"127.0.0.1:60006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-16T16:50:09.456659Z","caller":"traceutil/trace.go:171","msg":"trace[1958207990] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:626; }","duration":"622.327687ms","start":"2026-04-16T16:50:08.834307Z","end":"2026-04-16T16:50:09.456634Z","steps":["trace[1958207990] 'agreement among raft nodes before linearized reading' (duration: 622.103368ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:50:09.456718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:50:08.834259Z","time spent":"622.448592ms","remote":"127.0.0.1:59762","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-04-16T16:50:09.456906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.428856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:50:09.456994Z","caller":"traceutil/trace.go:171","msg":"trace[1611864016] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:626; }","duration":"413.569141ms","start":"2026-04-16T16:50:09.043401Z","end":"2026-04-16T16:50:09.45697Z","steps":["trace[1611864016] 'agreement among raft nodes before linearized reading' (duration: 413.037359ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:50:09.457039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:50:09.043371Z","time spent":"413.659135ms","remote":"127.0.0.1:59768","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-04-16T16:50:11.270703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.09342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:50:11.270855Z","caller":"traceutil/trace.go:171","msg":"trace[1012527748] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:654; }","duration":"326.274969ms","start":"2026-04-16T16:50:10.944539Z","end":"2026-04-16T16:50:11.270814Z","steps":["trace[1012527748] 'range keys from in-memory index tree' (duration: 326.005516ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:50:11.27092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:50:10.944518Z","time spent":"326.381364ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 "} {"level":"warn","ts":"2026-04-16T16:50:11.270944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.4028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:50:11.271283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.426987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:50:11.271397Z","caller":"traceutil/trace.go:171","msg":"trace[1440018030] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:654; }","duration":"327.797743ms","start":"2026-04-16T16:50:10.943571Z","end":"2026-04-16T16:50:11.271368Z","steps":["trace[1440018030] 'count revisions from in-memory index tree' (duration: 327.329674ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:50:11.27141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.956953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:50:11.271458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.649717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:50:11.27162Z","caller":"traceutil/trace.go:171","msg":"trace[1371752039] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:654; }","duration":"327.934189ms","start":"2026-04-16T16:50:10.943662Z","end":"2026-04-16T16:50:11.271596Z","steps":["trace[1371752039] 'range keys from in-memory index tree' (duration: 327.556784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:50:11.271728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:50:10.943613Z","time spent":"328.091786ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 "} {"level":"info","ts":"2026-04-16T16:50:11.271541Z","caller":"traceutil/trace.go:171","msg":"trace[137655794] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:654; }","duration":"348.148242ms","start":"2026-04-16T16:50:10.92336Z","end":"2026-04-16T16:50:11.271508Z","steps":["trace[137655794] 'range keys from in-memory index tree' (duration: 347.88047ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:50:11.271932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:50:10.923333Z","time spent":"348.550629ms","remote":"127.0.0.1:57948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"info","ts":"2026-04-16T16:50:11.272025Z","caller":"traceutil/trace.go:171","msg":"trace[717687154] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:654; }","duration":"348.443624ms","start":"2026-04-16T16:50:10.923506Z","end":"2026-04-16T16:50:11.271949Z","steps":["trace[717687154] 'count revisions from in-memory index tree' (duration: 347.262413ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:50:11.271459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:50:10.943557Z","time spent":"327.887858ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-04-16T16:50:11.272275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:50:10.923502Z","time spent":"348.660424ms","remote":"127.0.0.1:57948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-04-16T16:50:11.284838Z","caller":"traceutil/trace.go:171","msg":"trace[934681075] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"337.546207ms","start":"2026-04-16T16:50:10.947267Z","end":"2026-04-16T16:50:11.284813Z","steps":["trace[934681075] 'process raft request' (duration: 336.839077ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:50:11.285006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:50:10.947254Z","time spent":"337.656193ms","remote":"127.0.0.1:59796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5571,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:50:11.289515Z","caller":"traceutil/trace.go:171","msg":"trace[1171387989] linearizableReadLoop","detail":"{readStateIndex:683; appliedIndex:681; }","duration":"249.015136ms","start":"2026-04-16T16:50:11.040478Z","end":"2026-04-16T16:50:11.289493Z","steps":["trace[1171387989] 'read index received' (duration: 243.704186ms)","trace[1171387989] 'applied index is now lower than readState.Index' (duration: 5.281459ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:50:11.289658Z","caller":"traceutil/trace.go:171","msg":"trace[690010383] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"292.28531ms","start":"2026-04-16T16:50:10.997354Z","end":"2026-04-16T16:50:11.289639Z","steps":["trace[690010383] 'process raft request' (duration: 291.736067ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:50:11.289776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.286798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:50:11.289855Z","caller":"traceutil/trace.go:171","msg":"trace[2012027900] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:657; }","duration":"249.432584ms","start":"2026-04-16T16:50:11.04041Z","end":"2026-04-16T16:50:11.289842Z","steps":["trace[2012027900] 'agreement among raft nodes before linearized reading' (duration: 249.198064ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:50:25.577056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.381024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:50:25.577228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"967.415576ms","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-04-16T16:50:25.578213Z","caller":"traceutil/trace.go:171","msg":"trace[1872133014] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:786; }","duration":"968.424687ms","start":"2026-04-16T16:50:24.609765Z","end":"2026-04-16T16:50:25.578189Z","steps":["trace[1872133014] 'range keys from in-memory index tree' (duration: 967.283699ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:50:25.578272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:50:24.609744Z","time spent":"968.512881ms","remote":"127.0.0.1:60006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-16T16:50:25.578139Z","caller":"traceutil/trace.go:171","msg":"trace[820552588] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:786; }","duration":"537.474228ms","start":"2026-04-16T16:50:25.040618Z","end":"2026-04-16T16:50:25.578093Z","steps":["trace[820552588] 'range keys from in-memory index tree' (duration: 536.32423ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:50:25.577431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.045241ms","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-04-16T16:50:25.578747Z","caller":"traceutil/trace.go:171","msg":"trace[1392748106] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:786; }","duration":"710.42467ms","start":"2026-04-16T16:50:24.86831Z","end":"2026-04-16T16:50:25.578735Z","steps":["trace[1392748106] 'range keys from in-memory index tree' (duration: 708.805739ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:50:25.578787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:50:24.868292Z","time spent":"710.483423ms","remote":"127.0.0.1:60006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-16T16:50:25.577579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"961.83528ms","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-04-16T16:50:25.57888Z","caller":"traceutil/trace.go:171","msg":"trace[67692724] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:786; }","duration":"963.165527ms","start":"2026-04-16T16:50:24.615695Z","end":"2026-04-16T16:50:25.57886Z","steps":["trace[67692724] 'range keys from in-memory index tree' (duration: 961.681043ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:50:25.578951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:50:24.615672Z","time spent":"963.257771ms","remote":"127.0.0.1:60006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-16T16:50:25.577683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.188507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-pfbxd\" ","response":"range_response_count:1 size:3965"} {"level":"warn","ts":"2026-04-16T16:50:25.577706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"961.839991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-16T16:50:25.579205Z","caller":"traceutil/trace.go:171","msg":"trace[964825174] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:786; }","duration":"963.374488ms","start":"2026-04-16T16:50:24.615818Z","end":"2026-04-16T16:50:25.579192Z","steps":["trace[964825174] 'range keys from in-memory index tree' (duration: 961.713593ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:50:25.579247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:50:24.615797Z","time spent":"963.438541ms","remote":"127.0.0.1:60006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-16T16:50:25.579112Z","caller":"traceutil/trace.go:171","msg":"trace[243802309] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-pfbxd; range_end:; response_count:1; response_revision:786; }","duration":"770.612898ms","start":"2026-04-16T16:50:24.80845Z","end":"2026-04-16T16:50:25.579063Z","steps":["trace[243802309] 'range keys from in-memory index tree' (duration: 769.066791ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:50:25.578699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:50:25.040602Z","time spent":"538.000924ms","remote":"127.0.0.1:59762","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-04-16T16:50:25.579541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:50:24.808427Z","time spent":"770.985457ms","remote":"127.0.0.1:59932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-pfbxd\" "} {"level":"info","ts":"2026-04-16T16:50:25.837147Z","caller":"traceutil/trace.go:171","msg":"trace[1165572825] linearizableReadLoop","detail":"{readStateIndex:818; appliedIndex:817; }","duration":"253.847431ms","start":"2026-04-16T16:50:25.583258Z","end":"2026-04-16T16:50:25.837106Z","steps":["trace[1165572825] 'read index received' (duration: 253.654391ms)","trace[1165572825] 'applied index is now lower than readState.Index' (duration: 191.77µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:50:25.837204Z","caller":"traceutil/trace.go:171","msg":"trace[1538173595] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"282.50332ms","start":"2026-04-16T16:50:25.554654Z","end":"2026-04-16T16:50:25.837157Z","steps":["trace[1538173595] 'process raft request' (duration: 282.29447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:50:25.837261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.983097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:50:25.837294Z","caller":"traceutil/trace.go:171","msg":"trace[1019005717] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:787; }","duration":"254.088342ms","start":"2026-04-16T16:50:25.583194Z","end":"2026-04-16T16:50:25.837282Z","steps":["trace[1019005717] 'agreement among raft nodes before linearized reading' (duration: 254.020769ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:50:25.848634Z","caller":"traceutil/trace.go:171","msg":"trace[1922170263] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"265.26877ms","start":"2026-04-16T16:50:25.583347Z","end":"2026-04-16T16:50:25.848616Z","steps":["trace[1922170263] 'process raft request' (duration: 265.194806ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:50:25.848786Z","caller":"traceutil/trace.go:171","msg":"trace[31332888] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"265.490961ms","start":"2026-04-16T16:50:25.583263Z","end":"2026-04-16T16:50:25.848754Z","steps":["trace[31332888] 'process raft request' (duration: 265.168375ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:50:25.849036Z","caller":"traceutil/trace.go:171","msg":"trace[1327683622] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"265.216867ms","start":"2026-04-16T16:50:25.58381Z","end":"2026-04-16T16:50:25.849027Z","steps":["trace[1327683622] 'process raft request' (duration: 264.768485ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:50:25.84908Z","caller":"traceutil/trace.go:171","msg":"trace[1646433620] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"265.07577ms","start":"2026-04-16T16:50:25.583986Z","end":"2026-04-16T16:50:25.849062Z","steps":["trace[1646433620] 'process raft request' (duration: 264.883381ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:50:25.849116Z","caller":"traceutil/trace.go:171","msg":"trace[1011010097] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"263.87898ms","start":"2026-04-16T16:50:25.585219Z","end":"2026-04-16T16:50:25.849098Z","steps":["trace[1011010097] 'process raft request' (duration: 263.766364ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:50:25.849131Z","caller":"traceutil/trace.go:171","msg":"trace[1944891329] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"260.65072ms","start":"2026-04-16T16:50:25.588468Z","end":"2026-04-16T16:50:25.849119Z","steps":["trace[1944891329] 'process raft request' (duration: 260.573476ms)"],"step_count":1}