{"level":"warn","ts":"2026-07-16T00:19:08.36473Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-16T00:19:08.364828Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.153: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.153:2380","--initial-cluster=instance=https://199.204.45.153:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.153:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.153:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-16T00:19:08.364909Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-16T00:19:08.364926Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.153:2380"]} {"level":"info","ts":"2026-07-16T00:19:08.364952Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-16T00:19:08.365848Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"]} {"level":"info","ts":"2026-07-16T00:19:08.36595Z","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.153:2380"],"listen-peer-urls":["https://199.204.45.153:2380"],"advertise-client-urls":["https://199.204.45.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.153:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-16T00:19:08.370517Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.319233ms"} {"level":"info","ts":"2026-07-16T00:19:08.376622Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"355195d5aae85944","cluster-id":"e424441517bc0b32"} {"level":"info","ts":"2026-07-16T00:19:08.376727Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=()"} {"level":"info","ts":"2026-07-16T00:19:08.376766Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became follower at term 0"} {"level":"info","ts":"2026-07-16T00:19:08.37679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 355195d5aae85944 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-16T00:19:08.376802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became follower at term 1"} {"level":"info","ts":"2026-07-16T00:19:08.376864Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=(3842016702051670340)"} {"level":"warn","ts":"2026-07-16T00:19:08.384777Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-16T00:19:08.386141Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-16T00:19:08.387509Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-16T00:19:08.389863Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"355195d5aae85944","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-16T00:19:08.390149Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-16T00:19:08.390236Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-16T00:19:08.390253Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-16T00:19:08.390453Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"355195d5aae85944","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-16T00:19:08.393614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=(3842016702051670340)"} {"level":"info","ts":"2026-07-16T00:19:08.39377Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e424441517bc0b32","local-member-id":"355195d5aae85944","added-peer-id":"355195d5aae85944","added-peer-peer-urls":["https://199.204.45.153:2380"]} {"level":"info","ts":"2026-07-16T00:19:08.396693Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-16T00:19:08.397002Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"355195d5aae85944","initial-advertise-peer-urls":["https://199.204.45.153:2380"],"listen-peer-urls":["https://199.204.45.153:2380"],"advertise-client-urls":["https://199.204.45.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-16T00:19:08.397048Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-16T00:19:08.397027Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.153:2380"} {"level":"info","ts":"2026-07-16T00:19:08.397082Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.153:2380"} {"level":"info","ts":"2026-07-16T00:19:09.377588Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 is starting a new election at term 1"} {"level":"info","ts":"2026-07-16T00:19:09.377653Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-16T00:19:09.377674Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 received MsgPreVoteResp from 355195d5aae85944 at term 1"} {"level":"info","ts":"2026-07-16T00:19:09.377698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became candidate at term 2"} {"level":"info","ts":"2026-07-16T00:19:09.377706Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 received MsgVoteResp from 355195d5aae85944 at term 2"} {"level":"info","ts":"2026-07-16T00:19:09.377717Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became leader at term 2"} {"level":"info","ts":"2026-07-16T00:19:09.377727Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 355195d5aae85944 elected leader 355195d5aae85944 at term 2"} {"level":"info","ts":"2026-07-16T00:19:09.37873Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T00:19:09.378952Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-16T00:19:09.378966Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-16T00:19:09.379422Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"355195d5aae85944","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.153:2379]}","request-path":"/0/members/355195d5aae85944/attributes","cluster-id":"e424441517bc0b32","publish-timeout":"7s"} {"level":"info","ts":"2026-07-16T00:19:09.37962Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e424441517bc0b32","local-member-id":"355195d5aae85944","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T00:19:09.379641Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-16T00:19:09.379702Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-16T00:19:09.379716Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T00:19:09.379745Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T00:19:09.382555Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.153:2379"} {"level":"info","ts":"2026-07-16T00:19:09.382885Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-16T00:19:11.830961Z","caller":"traceutil/trace.go:171","msg":"trace[16066683] linearizableReadLoop","detail":"{readStateIndex:117; appliedIndex:116; }","duration":"103.099627ms","start":"2026-07-16T00:19:11.727825Z","end":"2026-07-16T00:19:11.830925Z","steps":["trace[16066683] 'read index received' (duration: 102.87031ms)","trace[16066683] 'applied index is now lower than readState.Index' (duration: 228.347µs)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:19:11.831172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.285623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-16T00:19:11.831229Z","caller":"traceutil/trace.go:171","msg":"trace[1024620988] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:112; }","duration":"103.406596ms","start":"2026-07-16T00:19:11.727808Z","end":"2026-07-16T00:19:11.831215Z","steps":["trace[1024620988] 'agreement among raft nodes before linearized reading' (duration: 103.248751ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:19:11.831212Z","caller":"traceutil/trace.go:171","msg":"trace[1844063617] transaction","detail":"{read_only:false; response_revision:112; number_of_response:1; }","duration":"104.566353ms","start":"2026-07-16T00:19:11.726597Z","end":"2026-07-16T00:19:11.831163Z","steps":["trace[1844063617] 'process raft request' (duration: 104.16798ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:19:12.044192Z","caller":"traceutil/trace.go:171","msg":"trace[1771245362] transaction","detail":"{read_only:false; response_revision:114; number_of_response:1; }","duration":"118.138082ms","start":"2026-07-16T00:19:11.926013Z","end":"2026-07-16T00:19:12.044151Z","steps":["trace[1771245362] 'process raft request' (duration: 115.874672ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:19:12.242865Z","caller":"traceutil/trace.go:171","msg":"trace[1425186736] transaction","detail":"{read_only:false; response_revision:116; number_of_response:1; }","duration":"125.802068ms","start":"2026-07-16T00:19:12.117028Z","end":"2026-07-16T00:19:12.24283Z","steps":["trace[1425186736] 'process raft request' (duration: 29.579864ms)","trace[1425186736] 'compare' (duration: 96.032958ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:19:13.169558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.289596ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-16T00:19:13.169676Z","caller":"traceutil/trace.go:171","msg":"trace[1223037720] linearizableReadLoop","detail":"{readStateIndex:122; appliedIndex:121; }","duration":"338.098875ms","start":"2026-07-16T00:19:12.83156Z","end":"2026-07-16T00:19:13.169659Z","steps":["trace[1223037720] 'read index received' (duration: 47.431µs)","trace[1223037720] 'applied index is now lower than readState.Index' (duration: 338.050204ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:19:13.169837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.277101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-16T00:19:13.16987Z","caller":"traceutil/trace.go:171","msg":"trace[1364305463] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:117; }","duration":"338.366123ms","start":"2026-07-16T00:19:12.831495Z","end":"2026-07-16T00:19:13.169861Z","steps":["trace[1364305463] 'agreement among raft nodes before linearized reading' (duration: 338.206398ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:19:13.169899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:12.831467Z","time spent":"338.424595ms","remote":"127.0.0.1:47884","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-07-16T00:19:13.169917Z","caller":"traceutil/trace.go:171","msg":"trace[1264899409] transaction","detail":"{read_only:false; response_revision:117; number_of_response:1; }","duration":"921.668517ms","start":"2026-07-16T00:19:12.248176Z","end":"2026-07-16T00:19:13.169845Z","steps":["trace[1264899409] 'process raft request' (duration: 465.594506ms)","trace[1264899409] 'compare' (duration: 455.142642ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:19:13.170111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:12.248154Z","time spent":"921.863483ms","remote":"127.0.0.1:58832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":665,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T00:19:13.805406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.00163ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-16T00:19:13.805891Z","caller":"traceutil/trace.go:171","msg":"trace[760227922] transaction","detail":"{read_only:false; response_revision:118; number_of_response:1; }","duration":"630.800204ms","start":"2026-07-16T00:19:13.175071Z","end":"2026-07-16T00:19:13.805871Z","steps":["trace[760227922] 'process raft request' (duration: 529.274578ms)","trace[760227922] 'compare' (duration: 100.871485ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:19:13.805972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:13.175047Z","time spent":"630.893687ms","remote":"127.0.0.1:58832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":600,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T00:19:14.802553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"884.4202ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-16T00:19:14.802799Z","caller":"traceutil/trace.go:171","msg":"trace[1050714731] transaction","detail":"{read_only:false; response_revision:120; number_of_response:1; }","duration":"688.077426ms","start":"2026-07-16T00:19:14.114697Z","end":"2026-07-16T00:19:14.802774Z","steps":["trace[1050714731] 'process raft request' (duration: 687.968993ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:19:14.802874Z","caller":"traceutil/trace.go:171","msg":"trace[2094915655] transaction","detail":"{read_only:false; response_revision:119; number_of_response:1; }","duration":"1.086573285s","start":"2026-07-16T00:19:13.716183Z","end":"2026-07-16T00:19:14.802757Z","steps":["trace[2094915655] 'process raft request' (duration: 201.877866ms)","trace[2094915655] 'compare' (duration: 883.957786ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:19:14.802904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:14.114668Z","time spent":"688.1987ms","remote":"127.0.0.1:58692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T00:19:14.803041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:13.716145Z","time spent":"1.086823232s","remote":"127.0.0.1:58692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T00:19:14.802885Z","caller":"traceutil/trace.go:171","msg":"trace[810504541] linearizableReadLoop","detail":"{readStateIndex:124; appliedIndex:123; }","duration":"1.04339106s","start":"2026-07-16T00:19:13.759387Z","end":"2026-07-16T00:19:14.802778Z","steps":["trace[810504541] 'read index received' (duration: 158.64174ms)","trace[810504541] 'applied index is now lower than readState.Index' (duration: 884.74471ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:19:14.803377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"994.302384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-16T00:19:14.803417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.74113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-16T00:19:14.803432Z","caller":"traceutil/trace.go:171","msg":"trace[460009785] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:0; response_revision:120; }","duration":"994.391177ms","start":"2026-07-16T00:19:13.809022Z","end":"2026-07-16T00:19:14.803414Z","steps":["trace[460009785] 'agreement among raft nodes before linearized reading' (duration: 994.288174ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:19:14.803457Z","caller":"traceutil/trace.go:171","msg":"trace[1365065427] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:120; }","duration":"872.815512ms","start":"2026-07-16T00:19:13.930628Z","end":"2026-07-16T00:19:14.803444Z","steps":["trace[1365065427] 'agreement among raft nodes before linearized reading' (duration: 872.702058ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:19:14.803476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:13.808967Z","time spent":"994.498451ms","remote":"127.0.0.1:58832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/admin\" "} {"level":"warn","ts":"2026-07-16T00:19:14.803487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:13.930609Z","time spent":"872.872623ms","remote":"127.0.0.1:47884","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-07-16T00:19:14.803463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.286438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-16T00:19:14.803572Z","caller":"traceutil/trace.go:171","msg":"trace[1996970227] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:120; }","duration":"272.424923ms","start":"2026-07-16T00:19:14.531104Z","end":"2026-07-16T00:19:14.803529Z","steps":["trace[1996970227] 'agreement among raft nodes before linearized reading' (duration: 272.222686ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:19:14.803601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.044200626s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-16T00:19:14.803661Z","caller":"traceutil/trace.go:171","msg":"trace[1513119916] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:120; }","duration":"1.044297099s","start":"2026-07-16T00:19:13.759347Z","end":"2026-07-16T00:19:14.803644Z","steps":["trace[1513119916] 'agreement among raft nodes before linearized reading' (duration: 1.043661059s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:19:14.803705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:13.759328Z","time spent":"1.044365241s","remote":"127.0.0.1:58742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-16T00:19:48.814408Z","caller":"traceutil/trace.go:171","msg":"trace[469477910] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"360.255458ms","start":"2026-07-16T00:19:48.454127Z","end":"2026-07-16T00:19:48.814382Z","steps":["trace[469477910] 'process raft request' (duration: 360.127314ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:19:48.814551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:48.454105Z","time spent":"360.381772ms","remote":"127.0.0.1:58576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T00:19:48.821787Z","caller":"traceutil/trace.go:171","msg":"trace[810408146] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"367.620513ms","start":"2026-07-16T00:19:48.454151Z","end":"2026-07-16T00:19:48.821771Z","steps":["trace[810408146] 'process raft request' (duration: 367.386566ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:19:48.821923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:48.454123Z","time spent":"367.741047ms","remote":"127.0.0.1:58576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T00:19:48.822108Z","caller":"traceutil/trace.go:171","msg":"trace[273550426] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"355.085039ms","start":"2026-07-16T00:19:48.467Z","end":"2026-07-16T00:19:48.822085Z","steps":["trace[273550426] 'process raft request' (duration: 354.864603ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:19:48.822107Z","caller":"traceutil/trace.go:171","msg":"trace[1517675970] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"364.348153ms","start":"2026-07-16T00:19:48.457735Z","end":"2026-07-16T00:19:48.822083Z","steps":["trace[1517675970] 'process raft request' (duration: 363.961552ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:19:48.822125Z","caller":"traceutil/trace.go:171","msg":"trace[679675977] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"356.491952ms","start":"2026-07-16T00:19:48.465608Z","end":"2026-07-16T00:19:48.8221Z","steps":["trace[679675977] 'process raft request' (duration: 356.147561ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:19:48.822193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:48.466994Z","time spent":"355.154891ms","remote":"127.0.0.1:58576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T00:19:48.822263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:48.45772Z","time spent":"364.440316ms","remote":"127.0.0.1:58576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T00:19:48.822282Z","caller":"traceutil/trace.go:171","msg":"trace[1762244124] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"353.15672ms","start":"2026-07-16T00:19:48.468989Z","end":"2026-07-16T00:19:48.822146Z","steps":["trace[1762244124] 'process raft request' (duration: 352.939004ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:19:48.822359Z","caller":"traceutil/trace.go:171","msg":"trace[2056479694] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"335.041526ms","start":"2026-07-16T00:19:48.487308Z","end":"2026-07-16T00:19:48.82235Z","steps":["trace[2056479694] 'process raft request' (duration: 334.804138ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:19:48.822298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:48.465595Z","time spent":"356.585125ms","remote":"127.0.0.1:58576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T00:19:48.822327Z","caller":"traceutil/trace.go:171","msg":"trace[364926592] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"349.779607ms","start":"2026-07-16T00:19:48.472429Z","end":"2026-07-16T00:19:48.822208Z","steps":["trace[364926592] 'process raft request' (duration: 349.55099ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:19:48.822456Z","caller":"traceutil/trace.go:171","msg":"trace[662147222] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"355.867703ms","start":"2026-07-16T00:19:48.46658Z","end":"2026-07-16T00:19:48.822448Z","steps":["trace[662147222] 'process raft request' (duration: 355.228434ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:19:48.822492Z","caller":"traceutil/trace.go:171","msg":"trace[1437817126] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"290.022077ms","start":"2026-07-16T00:19:48.532424Z","end":"2026-07-16T00:19:48.822446Z","steps":["trace[1437817126] 'process raft request' (duration: 289.76905ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:19:48.82256Z","caller":"traceutil/trace.go:171","msg":"trace[1415651595] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"197.914178ms","start":"2026-07-16T00:19:48.624638Z","end":"2026-07-16T00:19:48.822552Z","steps":["trace[1415651595] 'process raft request' (duration: 197.777063ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:19:48.822416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:48.487294Z","time spent":"335.097348ms","remote":"127.0.0.1:58576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T00:19:48.822667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:48.468983Z","time spent":"353.407088ms","remote":"127.0.0.1:58576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T00:19:48.822634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:48.472418Z","time spent":"350.078316ms","remote":"127.0.0.1:58576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T00:19:48.822515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:48.466574Z","time spent":"355.905014ms","remote":"127.0.0.1:58576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T00:19:48.822321Z","caller":"traceutil/trace.go:171","msg":"trace[875948556] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"349.333353ms","start":"2026-07-16T00:19:48.472978Z","end":"2026-07-16T00:19:48.822312Z","steps":["trace[875948556] 'process raft request' (duration: 349.063255ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:19:48.823875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:48.47296Z","time spent":"350.881961ms","remote":"127.0.0.1:58576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T00:19:49.362722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.800873ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T00:19:49.362882Z","caller":"traceutil/trace.go:171","msg":"trace[197387060] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"292.104778ms","start":"2026-07-16T00:19:49.070758Z","end":"2026-07-16T00:19:49.362863Z","steps":["trace[197387060] 'process raft request' (duration: 159.906013ms)","trace[197387060] 'compare' (duration: 131.621348ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:19:49.380006Z","caller":"traceutil/trace.go:171","msg":"trace[1354315914] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"303.049774ms","start":"2026-07-16T00:19:49.076932Z","end":"2026-07-16T00:19:49.379982Z","steps":["trace[1354315914] 'process raft request' (duration: 302.878859ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:19:49.380158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:49.076913Z","time spent":"303.167997ms","remote":"127.0.0.1:58576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65199,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T00:19:49.380281Z","caller":"traceutil/trace.go:171","msg":"trace[757830704] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"276.750689ms","start":"2026-07-16T00:19:49.103509Z","end":"2026-07-16T00:19:49.38026Z","steps":["trace[757830704] 'process raft request' (duration: 276.426599ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:19:49.581567Z","caller":"traceutil/trace.go:171","msg":"trace[806943741] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"194.812562ms","start":"2026-07-16T00:19:49.38672Z","end":"2026-07-16T00:19:49.581532Z","steps":["trace[806943741] 'process raft request' (duration: 125.898083ms)","trace[806943741] 'compare' (duration: 68.677692ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:19:49.605784Z","caller":"traceutil/trace.go:171","msg":"trace[1662260464] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"166.912609ms","start":"2026-07-16T00:19:49.438854Z","end":"2026-07-16T00:19:49.605767Z","steps":["trace[1662260464] 'process raft request' (duration: 166.871778ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:19:49.60611Z","caller":"traceutil/trace.go:171","msg":"trace[432184726] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"200.637921ms","start":"2026-07-16T00:19:49.405463Z","end":"2026-07-16T00:19:49.606101Z","steps":["trace[432184726] 'process raft request' (duration: 200.111954ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:19:50.423357Z","caller":"traceutil/trace.go:171","msg":"trace[429897074] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"785.076536ms","start":"2026-07-16T00:19:49.638264Z","end":"2026-07-16T00:19:50.423341Z","steps":["trace[429897074] 'process raft request' (duration: 778.65631ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:19:50.423441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:49.638246Z","time spent":"785.155019ms","remote":"127.0.0.1:58576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19834,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T00:19:50.42468Z","caller":"traceutil/trace.go:171","msg":"trace[783719611] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"782.703924ms","start":"2026-07-16T00:19:49.641933Z","end":"2026-07-16T00:19:50.424637Z","steps":["trace[783719611] 'process raft request' (duration: 782.408905ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:19:50.424752Z","caller":"traceutil/trace.go:171","msg":"trace[550041857] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:602; }","duration":"503.778997ms","start":"2026-07-16T00:19:49.920949Z","end":"2026-07-16T00:19:50.424728Z","steps":["trace[550041857] 'read index received' (duration: 496.04788ms)","trace[550041857] 'applied index is now lower than readState.Index' (duration: 7.730057ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:19:50.424811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:49.641924Z","time spent":"782.815087ms","remote":"127.0.0.1:58576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80688,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T00:19:50.424864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.908351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T00:19:50.424898Z","caller":"traceutil/trace.go:171","msg":"trace[676276086] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:585; }","duration":"503.991174ms","start":"2026-07-16T00:19:49.920896Z","end":"2026-07-16T00:19:50.424887Z","steps":["trace[676276086] 'agreement among raft nodes before linearized reading' (duration: 503.921001ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:19:50.42493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:49.920869Z","time spent":"504.052956ms","remote":"127.0.0.1:47884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-16T00:19:50.424981Z","caller":"traceutil/trace.go:171","msg":"trace[196940077] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"747.385153ms","start":"2026-07-16T00:19:49.67758Z","end":"2026-07-16T00:19:50.424965Z","steps":["trace[196940077] 'process raft request' (duration: 746.95578ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:19:50.425066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:49.67756Z","time spent":"747.454025ms","remote":"127.0.0.1:58576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151212,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T00:19:55.108326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.574337ms","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-07-16T00:19:55.108423Z","caller":"traceutil/trace.go:171","msg":"trace[1220397577] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:631; }","duration":"174.721882ms","start":"2026-07-16T00:19:54.933678Z","end":"2026-07-16T00:19:55.1084Z","steps":["trace[1220397577] 'range keys from in-memory index tree' (duration: 174.384281ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:19:55.214415Z","caller":"traceutil/trace.go:171","msg":"trace[1381507790] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"101.585636ms","start":"2026-07-16T00:19:55.112807Z","end":"2026-07-16T00:19:55.214393Z","steps":["trace[1381507790] 'process raft request' (duration: 101.442312ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:19:55.576694Z","caller":"traceutil/trace.go:171","msg":"trace[675755796] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"460.233071ms","start":"2026-07-16T00:19:55.116441Z","end":"2026-07-16T00:19:55.576674Z","steps":["trace[675755796] 'process raft request' (duration: 460.157578ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:19:55.57669Z","caller":"traceutil/trace.go:171","msg":"trace[1991003534] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"461.83053ms","start":"2026-07-16T00:19:55.114836Z","end":"2026-07-16T00:19:55.576666Z","steps":["trace[1991003534] 'process raft request' (duration: 456.548528ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:19:55.576964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:55.114823Z","time spent":"462.038886ms","remote":"127.0.0.1:58742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T00:19:55.577189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:55.116434Z","time spent":"460.318893ms","remote":"127.0.0.1:58742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T00:19:56.168397Z","caller":"traceutil/trace.go:171","msg":"trace[770200898] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:654; }","duration":"891.314218ms","start":"2026-07-16T00:19:55.277046Z","end":"2026-07-16T00:19:56.16836Z","steps":["trace[770200898] 'read index received' (duration: 294.446981ms)","trace[770200898] 'applied index is now lower than readState.Index' (duration: 596.866067ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:19:56.168443Z","caller":"traceutil/trace.go:171","msg":"trace[1337170842] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"917.617812ms","start":"2026-07-16T00:19:55.250789Z","end":"2026-07-16T00:19:56.168407Z","steps":["trace[1337170842] 'process raft request' (duration: 916.967632ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:19:56.168651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:55.250763Z","time spent":"917.798138ms","remote":"127.0.0.1:58598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036221,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T00:19:56.168665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.592616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qrp5v\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-16T00:19:56.168707Z","caller":"traceutil/trace.go:171","msg":"trace[1841248028] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qrp5v; range_end:; response_count:1; response_revision:635; }","duration":"891.654298ms","start":"2026-07-16T00:19:55.27704Z","end":"2026-07-16T00:19:56.168694Z","steps":["trace[1841248028] 'agreement among raft nodes before linearized reading' (duration: 891.474902ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:19:56.168767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:55.276978Z","time spent":"891.773682ms","remote":"127.0.0.1:58692","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-qrp5v\" "} {"level":"warn","ts":"2026-07-16T00:19:56.169102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.31741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-16T00:19:56.169139Z","caller":"traceutil/trace.go:171","msg":"trace[1638389573] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:635; }","duration":"719.383362ms","start":"2026-07-16T00:19:55.449746Z","end":"2026-07-16T00:19:56.16913Z","steps":["trace[1638389573] 'agreement among raft nodes before linearized reading' (duration: 719.257658ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:19:56.169162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:55.449726Z","time spent":"719.430223ms","remote":"127.0.0.1:58742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-16T00:19:56.169318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.462224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T00:19:56.169382Z","caller":"traceutil/trace.go:171","msg":"trace[2089390154] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"247.557717ms","start":"2026-07-16T00:19:55.921808Z","end":"2026-07-16T00:19:56.169366Z","steps":["trace[2089390154] 'agreement among raft nodes before linearized reading' (duration: 247.468595ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:19:56.169639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.125866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.153\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-16T00:19:56.169674Z","caller":"traceutil/trace.go:171","msg":"trace[2050963034] range","detail":"{range_begin:/registry/masterleases/199.204.45.153; range_end:; response_count:1; response_revision:635; }","duration":"635.2327ms","start":"2026-07-16T00:19:55.534432Z","end":"2026-07-16T00:19:56.169665Z","steps":["trace[2050963034] 'agreement among raft nodes before linearized reading' (duration: 635.103006ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:19:56.169697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:55.534384Z","time spent":"635.306811ms","remote":"127.0.0.1:58556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.153\" "} {"level":"warn","ts":"2026-07-16T00:19:56.777639Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432441438069185917,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-16T00:19:57.753992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.859419ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T00:19:57.754135Z","caller":"traceutil/trace.go:171","msg":"trace[1270008350] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:661; }","duration":"1.476987337s","start":"2026-07-16T00:19:56.277128Z","end":"2026-07-16T00:19:57.754115Z","steps":["trace[1270008350] 'read index received' (duration: 890.909591ms)","trace[1270008350] 'applied index is now lower than readState.Index' (duration: 586.076006ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:19:57.754271Z","caller":"traceutil/trace.go:171","msg":"trace[483624796] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"1.492966556s","start":"2026-07-16T00:19:56.261289Z","end":"2026-07-16T00:19:57.754255Z","steps":["trace[483624796] 'process raft request' (duration: 906.745666ms)","trace[483624796] 'compare' (duration: 585.630782ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:19:57.754382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:56.26127Z","time spent":"1.493051028s","remote":"127.0.0.1:58556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T00:19:57.754749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"832.58583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T00:19:57.754838Z","caller":"traceutil/trace.go:171","msg":"trace[2112514951] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"832.717824ms","start":"2026-07-16T00:19:56.922097Z","end":"2026-07-16T00:19:57.754815Z","steps":["trace[2112514951] 'agreement among raft nodes before linearized reading' (duration: 832.525538ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:19:57.754878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:56.922077Z","time spent":"832.792836ms","remote":"127.0.0.1:47884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-16T00:19:57.754888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.384271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-16T00:19:57.754894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.489904ms","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-07-16T00:19:57.754945Z","caller":"traceutil/trace.go:171","msg":"trace[379378615] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:639; }","duration":"174.482785ms","start":"2026-07-16T00:19:57.580447Z","end":"2026-07-16T00:19:57.75493Z","steps":["trace[379378615] 'agreement among raft nodes before linearized reading' (duration: 174.312979ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:19:57.754955Z","caller":"traceutil/trace.go:171","msg":"trace[1736497151] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:639; }","duration":"174.572137ms","start":"2026-07-16T00:19:57.580367Z","end":"2026-07-16T00:19:57.754939Z","steps":["trace[1736497151] 'agreement among raft nodes before linearized reading' (duration: 174.394961ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:19:57.75497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.127029ms","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-07-16T00:19:57.755029Z","caller":"traceutil/trace.go:171","msg":"trace[13534201] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:639; }","duration":"537.225722ms","start":"2026-07-16T00:19:57.217787Z","end":"2026-07-16T00:19:57.755013Z","steps":["trace[13534201] 'agreement among raft nodes before linearized reading' (duration: 537.025416ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:19:57.755066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:57.21776Z","time spent":"537.297374ms","remote":"127.0.0.1:58742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-16T00:19:57.755123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.477974068s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T00:19:57.755212Z","caller":"traceutil/trace.go:171","msg":"trace[2055877821] range","detail":"{range_begin:/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system; range_end:; response_count:0; response_revision:639; }","duration":"1.478094581s","start":"2026-07-16T00:19:56.277095Z","end":"2026-07-16T00:19:57.755189Z","steps":["trace[2055877821] 'agreement among raft nodes before linearized reading' (duration: 1.477947936s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:19:57.755271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:19:56.27705Z","time spent":"1.478211315s","remote":"127.0.0.1:58844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":28,"request content":"key:\"/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" "} {"level":"info","ts":"2026-07-16T00:19:59.672278Z","caller":"traceutil/trace.go:171","msg":"trace[2008123821] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"169.20826ms","start":"2026-07-16T00:19:59.503008Z","end":"2026-07-16T00:19:59.672216Z","steps":["trace[2008123821] 'process raft request' (duration: 166.243889ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:20:10.021375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.765144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T00:20:10.021424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.390749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-16T00:20:10.021493Z","caller":"traceutil/trace.go:171","msg":"trace[1510316313] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:783; }","duration":"100.895418ms","start":"2026-07-16T00:20:09.920546Z","end":"2026-07-16T00:20:10.021442Z","steps":["trace[1510316313] 'range keys from in-memory index tree' (duration: 100.670651ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:20:10.021525Z","caller":"traceutil/trace.go:171","msg":"trace[753011416] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:783; }","duration":"171.503152ms","start":"2026-07-16T00:20:09.85Z","end":"2026-07-16T00:20:10.021503Z","steps":["trace[753011416] 'range keys from in-memory index tree' (duration: 171.150981ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:20:10.021375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.674617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-16T00:20:10.021775Z","caller":"traceutil/trace.go:171","msg":"trace[1893237611] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:783; }","duration":"172.09653ms","start":"2026-07-16T00:20:09.849646Z","end":"2026-07-16T00:20:10.021742Z","steps":["trace[1893237611] 'range keys from in-memory index tree' (duration: 171.402269ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:20:10.021998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.261145ms","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-07-16T00:20:10.022068Z","caller":"traceutil/trace.go:171","msg":"trace[1455582572] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:783; }","duration":"172.358019ms","start":"2026-07-16T00:20:09.849694Z","end":"2026-07-16T00:20:10.022052Z","steps":["trace[1455582572] 'range keys from in-memory index tree' (duration: 172.000557ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:20:10.167395Z","caller":"traceutil/trace.go:171","msg":"trace[643718674] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"141.008772ms","start":"2026-07-16T00:20:10.026368Z","end":"2026-07-16T00:20:10.167377Z","steps":["trace[643718674] 'process raft request' (duration: 140.97015ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:20:10.167522Z","caller":"traceutil/trace.go:171","msg":"trace[202350272] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"141.227678ms","start":"2026-07-16T00:20:10.026248Z","end":"2026-07-16T00:20:10.167476Z","steps":["trace[202350272] 'process raft request' (duration: 103.887329ms)","trace[202350272] 'compare' (duration: 37.102361ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:20:12.729359Z","caller":"traceutil/trace.go:171","msg":"trace[417164068] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:827; }","duration":"371.262751ms","start":"2026-07-16T00:20:12.358077Z","end":"2026-07-16T00:20:12.72934Z","steps":["trace[417164068] 'read index received' (duration: 371.163407ms)","trace[417164068] 'applied index is now lower than readState.Index' (duration: 98.533µs)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:20:12.729508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.411536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-16T00:20:12.729535Z","caller":"traceutil/trace.go:171","msg":"trace[1272760038] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:800; }","duration":"371.494918ms","start":"2026-07-16T00:20:12.358032Z","end":"2026-07-16T00:20:12.729526Z","steps":["trace[1272760038] 'agreement among raft nodes before linearized reading' (duration: 371.403895ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:20:12.72956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:20:12.358009Z","time spent":"371.544309ms","remote":"127.0.0.1:58696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":2,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"info","ts":"2026-07-16T00:20:12.729538Z","caller":"traceutil/trace.go:171","msg":"trace[444450270] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"386.583098ms","start":"2026-07-16T00:20:12.342863Z","end":"2026-07-16T00:20:12.729446Z","steps":["trace[444450270] 'process raft request' (duration: 386.349871ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:20:12.729744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:20:12.342837Z","time spent":"386.805775ms","remote":"127.0.0.1:58742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T00:20:13.278707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.892814ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T00:20:13.278824Z","caller":"traceutil/trace.go:171","msg":"trace[648868524] linearizableReadLoop","detail":"{readStateIndex:829; appliedIndex:828; }","duration":"549.394662ms","start":"2026-07-16T00:20:12.729413Z","end":"2026-07-16T00:20:13.278808Z","steps":["trace[648868524] 'read index received' (duration: 349.26203ms)","trace[648868524] 'applied index is now lower than readState.Index' (duration: 200.130772ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:20:13.278957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.472469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T00:20:13.279005Z","caller":"traceutil/trace.go:171","msg":"trace[270169742] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:801; }","duration":"356.554542ms","start":"2026-07-16T00:20:12.922437Z","end":"2026-07-16T00:20:13.278992Z","steps":["trace[270169742] 'agreement among raft nodes before linearized reading' (duration: 356.474459ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:20:13.279046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:20:12.922418Z","time spent":"356.618723ms","remote":"127.0.0.1:47884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-16T00:20:13.27906Z","caller":"traceutil/trace.go:171","msg":"trace[1953888602] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"803.647526ms","start":"2026-07-16T00:20:12.47531Z","end":"2026-07-16T00:20:13.278957Z","steps":["trace[1953888602] 'process raft request' (duration: 603.415141ms)","trace[1953888602] 'compare' (duration: 199.388519ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:20:13.279337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:20:12.475286Z","time spent":"803.954304ms","remote":"127.0.0.1:58742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T00:20:13.279547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"873.513776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T00:20:13.279718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"904.667236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-tbpct\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-07-16T00:20:13.279608Z","caller":"traceutil/trace.go:171","msg":"trace[1182217671] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:801; }","duration":"873.628459ms","start":"2026-07-16T00:20:12.405967Z","end":"2026-07-16T00:20:13.279596Z","steps":["trace[1182217671] 'agreement among raft nodes before linearized reading' (duration: 873.498065ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:20:13.279809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:20:12.405952Z","time spent":"873.849695ms","remote":"127.0.0.1:58724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"info","ts":"2026-07-16T00:20:13.279806Z","caller":"traceutil/trace.go:171","msg":"trace[2136910155] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-tbpct; range_end:; response_count:1; response_revision:801; }","duration":"904.781119ms","start":"2026-07-16T00:20:12.375004Z","end":"2026-07-16T00:20:13.279785Z","steps":["trace[2136910155] 'agreement among raft nodes before linearized reading' (duration: 903.895962ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:20:13.279848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:20:12.374994Z","time spent":"904.845482ms","remote":"127.0.0.1:58692","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-tbpct\" "} {"level":"warn","ts":"2026-07-16T00:20:17.201944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.550933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050248"} {"level":"info","ts":"2026-07-16T00:20:17.202034Z","caller":"traceutil/trace.go:171","msg":"trace[1442653948] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:852; }","duration":"138.689656ms","start":"2026-07-16T00:20:17.063324Z","end":"2026-07-16T00:20:17.202014Z","steps":["trace[1442653948] 'range keys from in-memory index tree' (duration: 137.605413ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:20:17.596852Z","caller":"traceutil/trace.go:171","msg":"trace[6888123] linearizableReadLoop","detail":"{readStateIndex:882; appliedIndex:881; }","duration":"202.295024ms","start":"2026-07-16T00:20:17.394535Z","end":"2026-07-16T00:20:17.59683Z","steps":["trace[6888123] 'read index received' (duration: 202.126889ms)","trace[6888123] 'applied index is now lower than readState.Index' (duration: 167.525µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:20:17.597349Z","caller":"traceutil/trace.go:171","msg":"trace[1391288019] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"282.52185ms","start":"2026-07-16T00:20:17.314803Z","end":"2026-07-16T00:20:17.597325Z","steps":["trace[1391288019] 'process raft request' (duration: 281.914321ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:20:17.597617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.066819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050248"} {"level":"info","ts":"2026-07-16T00:20:17.59765Z","caller":"traceutil/trace.go:171","msg":"trace[72088222] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:853; }","duration":"203.13911ms","start":"2026-07-16T00:20:17.394503Z","end":"2026-07-16T00:20:17.597642Z","steps":["trace[72088222] 'agreement among raft nodes before linearized reading' (duration: 202.404948ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:20:18.530774Z","caller":"traceutil/trace.go:171","msg":"trace[1964324783] linearizableReadLoop","detail":"{readStateIndex:891; appliedIndex:890; }","duration":"145.516334ms","start":"2026-07-16T00:20:18.385223Z","end":"2026-07-16T00:20:18.530739Z","steps":["trace[1964324783] 'read index received' (duration: 32.009475ms)","trace[1964324783] 'applied index is now lower than readState.Index' (duration: 113.505849ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:20:18.530838Z","caller":"traceutil/trace.go:171","msg":"trace[767176839] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"150.897818ms","start":"2026-07-16T00:20:18.379887Z","end":"2026-07-16T00:20:18.530784Z","steps":["trace[767176839] 'process raft request' (duration: 95.202791ms)","trace[767176839] 'compare' (duration: 55.491261ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:20:18.531337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.838307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-6b2tq\" ","response":"range_response_count:1 size:5393"} {"level":"info","ts":"2026-07-16T00:20:18.53149Z","caller":"traceutil/trace.go:171","msg":"trace[745679890] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-6b2tq; range_end:; response_count:1; response_revision:862; }","duration":"131.032393ms","start":"2026-07-16T00:20:18.400437Z","end":"2026-07-16T00:20:18.531469Z","steps":["trace[745679890] 'agreement among raft nodes before linearized reading' (duration: 130.676712ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:20:18.531495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.94598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/eg\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T00:20:18.531695Z","caller":"traceutil/trace.go:171","msg":"trace[366148617] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/eg; range_end:; response_count:0; response_revision:862; }","duration":"109.200938ms","start":"2026-07-16T00:20:18.422385Z","end":"2026-07-16T00:20:18.531586Z","steps":["trace[366148617] 'agreement among raft nodes before linearized reading' (duration: 108.907809ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:20:18.532329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.894566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/\" range_end:\"/registry/serviceaccounts/envoy-gateway-system0\" limit:10000 ","response":"range_response_count:3 size:1862"} {"level":"info","ts":"2026-07-16T00:20:18.532419Z","caller":"traceutil/trace.go:171","msg":"trace[649456812] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/; range_end:/registry/serviceaccounts/envoy-gateway-system0; response_count:3; response_revision:862; }","duration":"147.210005ms","start":"2026-07-16T00:20:18.385186Z","end":"2026-07-16T00:20:18.532396Z","steps":["trace[649456812] 'agreement among raft nodes before linearized reading' (duration: 145.697769ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:20:43.014869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.404885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-16T00:20:43.014942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.067509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-16T00:20:43.014976Z","caller":"traceutil/trace.go:171","msg":"trace[1800507550] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1026; }","duration":"538.5583ms","start":"2026-07-16T00:20:42.476395Z","end":"2026-07-16T00:20:43.014953Z","steps":["trace[1800507550] 'range keys from in-memory index tree' (duration: 538.23387ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:20:43.014996Z","caller":"traceutil/trace.go:171","msg":"trace[92578284] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1026; }","duration":"525.151592ms","start":"2026-07-16T00:20:42.489832Z","end":"2026-07-16T00:20:43.014983Z","steps":["trace[92578284] 'range keys from in-memory index tree' (duration: 524.950956ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:20:43.015032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:20:42.476375Z","time spent":"538.642012ms","remote":"127.0.0.1:58742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-16T00:20:43.015029Z","caller":"traceutil/trace.go:171","msg":"trace[1791210475] linearizableReadLoop","detail":"{readStateIndex:1061; appliedIndex:1060; }","duration":"107.775855ms","start":"2026-07-16T00:20:42.907234Z","end":"2026-07-16T00:20:43.01501Z","steps":["trace[1791210475] 'read index received' (duration: 107.042743ms)","trace[1791210475] 'applied index is now lower than readState.Index' (duration: 731.732µs)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:20:43.015048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:20:42.489818Z","time spent":"525.220214ms","remote":"127.0.0.1:58742","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-07-16T00:20:43.015136Z","caller":"traceutil/trace.go:171","msg":"trace[60520166] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"517.50841ms","start":"2026-07-16T00:20:42.49759Z","end":"2026-07-16T00:20:43.015099Z","steps":["trace[60520166] 'process raft request' (duration: 516.793488ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:20:43.015273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:20:42.497552Z","time spent":"517.647354ms","remote":"127.0.0.1:58742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T00:20:43.015423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.182087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-16T00:20:43.015498Z","caller":"traceutil/trace.go:171","msg":"trace[884787017] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1027; }","duration":"108.29656ms","start":"2026-07-16T00:20:42.907179Z","end":"2026-07-16T00:20:43.015476Z","steps":["trace[884787017] 'agreement among raft nodes before linearized reading' (duration: 107.929019ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:20:43.378471Z","caller":"traceutil/trace.go:171","msg":"trace[965857105] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"359.501287ms","start":"2026-07-16T00:20:43.018954Z","end":"2026-07-16T00:20:43.378455Z","steps":["trace[965857105] 'process raft request' (duration: 359.470226ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:20:43.378574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:20:43.018949Z","time spent":"359.570179ms","remote":"127.0.0.1:58742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T00:20:43.378682Z","caller":"traceutil/trace.go:171","msg":"trace[1153195108] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"359.837518ms","start":"2026-07-16T00:20:43.018805Z","end":"2026-07-16T00:20:43.378643Z","steps":["trace[1153195108] 'process raft request' (duration: 358.387674ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:20:43.379519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:20:43.01879Z","time spent":"360.609331ms","remote":"127.0.0.1:58742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T00:20:48.923091Z","caller":"traceutil/trace.go:171","msg":"trace[670414985] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"103.412712ms","start":"2026-07-16T00:20:48.819662Z","end":"2026-07-16T00:20:48.923074Z","steps":["trace[670414985] 'process raft request' (duration: 103.282378ms)"],"step_count":1}