{"level":"warn","ts":"2026-04-16T16:15:40.791175Z","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:15:40.791257Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.246: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.246:2380","--initial-cluster=instance=https://199.204.45.246:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.246:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.246: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:15:40.79134Z","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:15:40.791356Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-04-16T16:15:40.791375Z","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:15:40.79202Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"]} {"level":"info","ts":"2026-04-16T16:15:40.79209Z","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.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.246: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:15:40.797237Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.913431ms"} {"level":"info","ts":"2026-04-16T16:15:40.805228Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ab97322ebdd79664","cluster-id":"87bc89380c3dc2c"} {"level":"info","ts":"2026-04-16T16:15:40.805303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=()"} {"level":"info","ts":"2026-04-16T16:15:40.805323Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 0"} {"level":"info","ts":"2026-04-16T16:15:40.805336Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ab97322ebdd79664 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-16T16:15:40.805345Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 1"} {"level":"info","ts":"2026-04-16T16:15:40.805388Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"warn","ts":"2026-04-16T16:15:40.815871Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-16T16:15:40.817484Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-16T16:15:40.81875Z","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:15:40.821824Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ab97322ebdd79664","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-16T16:15:40.822004Z","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:15:40.822091Z","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:15:40.82215Z","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:15:40.823702Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"info","ts":"2026-04-16T16:15:40.823926Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","added-peer-id":"ab97322ebdd79664","added-peer-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-04-16T16:15:40.822095Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ab97322ebdd79664","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-16T16:15:40.833088Z","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:15:40.833223Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-04-16T16:15:40.833238Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-04-16T16:15:40.833351Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ab97322ebdd79664","initial-advertise-peer-urls":["https://199.204.45.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-16T16:15:40.83337Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-16T16:15:41.406613Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 is starting a new election at term 1"} {"level":"info","ts":"2026-04-16T16:15:41.406759Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-16T16:15:41.406828Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgPreVoteResp from ab97322ebdd79664 at term 1"} {"level":"info","ts":"2026-04-16T16:15:41.406871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became candidate at term 2"} {"level":"info","ts":"2026-04-16T16:15:41.406917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgVoteResp from ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-04-16T16:15:41.40696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became leader at term 2"} {"level":"info","ts":"2026-04-16T16:15:41.407017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ab97322ebdd79664 elected leader ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-04-16T16:15:41.445864Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ab97322ebdd79664","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.246:2379]}","request-path":"/0/members/ab97322ebdd79664/attributes","cluster-id":"87bc89380c3dc2c","publish-timeout":"7s"} {"level":"info","ts":"2026-04-16T16:15:41.445923Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T16:15:41.446064Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T16:15:41.446174Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T16:15:41.446431Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-16T16:15:41.44649Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-16T16:15:41.449595Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.246:2379"} {"level":"info","ts":"2026-04-16T16:15:41.450301Z","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:15:41.477515Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T16:15:41.477655Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T16:15:41.477688Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T16:15:42.950905Z","caller":"traceutil/trace.go:171","msg":"trace[2074580155] transaction","detail":"{read_only:false; response_revision:57; number_of_response:1; }","duration":"101.828911ms","start":"2026-04-16T16:15:42.849044Z","end":"2026-04-16T16:15:42.950873Z","steps":["trace[2074580155] 'process raft request' (duration: 101.549066ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:15:42.950689Z","caller":"traceutil/trace.go:171","msg":"trace[2111299563] transaction","detail":"{read_only:false; response_revision:56; number_of_response:1; }","duration":"102.505532ms","start":"2026-04-16T16:15:42.848158Z","end":"2026-04-16T16:15:42.950663Z","steps":["trace[2111299563] 'process raft request' (duration: 99.842778ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:15:47.306861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.269496ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T16:15:47.307344Z","caller":"traceutil/trace.go:171","msg":"trace[762053667] linearizableReadLoop","detail":"{readStateIndex:287; appliedIndex:286; }","duration":"238.047145ms","start":"2026-04-16T16:15:47.069233Z","end":"2026-04-16T16:15:47.30728Z","steps":["trace[762053667] 'read index received' (duration: 101.143717ms)","trace[762053667] 'applied index is now lower than readState.Index' (duration: 136.901328ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:15:47.307593Z","caller":"traceutil/trace.go:171","msg":"trace[1546315808] transaction","detail":"{read_only:false; response_revision:276; number_of_response:1; }","duration":"255.62722ms","start":"2026-04-16T16:15:47.051933Z","end":"2026-04-16T16:15:47.30756Z","steps":["trace[1546315808] 'process raft request' (duration: 118.541429ms)","trace[1546315808] 'compare' (duration: 132.178274ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:15:47.307684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.455671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:15:47.307678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.965979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:15:47.307772Z","caller":"traceutil/trace.go:171","msg":"trace[379589399] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:0; response_revision:276; }","duration":"238.572503ms","start":"2026-04-16T16:15:47.069163Z","end":"2026-04-16T16:15:47.307736Z","steps":["trace[379589399] 'agreement among raft nodes before linearized reading' (duration: 238.454141ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:15:47.307815Z","caller":"traceutil/trace.go:171","msg":"trace[1034153110] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:276; }","duration":"223.157232ms","start":"2026-04-16T16:15:47.084634Z","end":"2026-04-16T16:15:47.307791Z","steps":["trace[1034153110] 'agreement among raft nodes before linearized reading' (duration: 222.966019ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:15:49.593225Z","caller":"traceutil/trace.go:171","msg":"trace[839720525] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"114.847696ms","start":"2026-04-16T16:15:49.478353Z","end":"2026-04-16T16:15:49.5932Z","steps":["trace[839720525] 'process raft request' (duration: 104.989863ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:15:54.983797Z","caller":"traceutil/trace.go:171","msg":"trace[1144691102] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"122.249833ms","start":"2026-04-16T16:15:54.861524Z","end":"2026-04-16T16:15:54.983773Z","steps":["trace[1144691102] 'process raft request' (duration: 122.122391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:15:59.949878Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836959875555592533,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T16:16:00.213133Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.275503755s","expected-duration":"1s"} {"level":"info","ts":"2026-04-16T16:16:00.213879Z","caller":"traceutil/trace.go:171","msg":"trace[44659803] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"1.27656696s","start":"2026-04-16T16:15:58.937289Z","end":"2026-04-16T16:16:00.213856Z","steps":["trace[44659803] 'process raft request' (duration: 1.276399877s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:00.214033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:15:58.937268Z","time spent":"1.276670641s","remote":"127.0.0.1:39658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:16:01.449571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000734316s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-16T16:16:01.449717Z","caller":"traceutil/trace.go:171","msg":"trace[631691661] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000993499s","start":"2026-04-16T16:15:59.448695Z","end":"2026-04-16T16:16:01.449688Z","steps":["trace[631691661] 'agreement among raft nodes before linearized reading' (duration: 2.000799166s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:01.449789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:15:59.448664Z","time spent":"2.00111172s","remote":"127.0.0.1:39374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/16 16:16:01 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-16T16:16:03.12105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.343421583s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-16T16:16:03.121307Z","caller":"traceutil/trace.go:171","msg":"trace[1949397786] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"1.668128011s","start":"2026-04-16T16:16:01.45316Z","end":"2026-04-16T16:16:03.121288Z","steps":["trace[1949397786] 'process raft request' (duration: 1.66799556s)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:16:03.121312Z","caller":"traceutil/trace.go:171","msg":"trace[1222024721] linearizableReadLoop","detail":"{readStateIndex:390; appliedIndex:388; }","duration":"3.672487618s","start":"2026-04-16T16:15:59.448789Z","end":"2026-04-16T16:16:03.121276Z","steps":["trace[1222024721] 'read index received' (duration: 764.637398ms)","trace[1222024721] 'applied index is now lower than readState.Index' (duration: 2.90784702s)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:16:03.121401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:01.453133Z","time spent":"1.668218142s","remote":"127.0.0.1:39470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":757,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T16:16:03.121541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.652006123s","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-04-16T16:16:03.1216Z","caller":"traceutil/trace.go:171","msg":"trace[1709663112] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:377; }","duration":"3.652114355s","start":"2026-04-16T16:15:59.469473Z","end":"2026-04-16T16:16:03.121587Z","steps":["trace[1709663112] 'agreement among raft nodes before linearized reading' (duration: 3.651961982s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:03.121638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:15:59.469449Z","time spent":"3.652181175s","remote":"127.0.0.1:39658","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-04-16T16:16:03.121741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.384751323s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4425"} {"level":"info","ts":"2026-04-16T16:16:03.121785Z","caller":"traceutil/trace.go:171","msg":"trace[108940377] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:377; }","duration":"2.384837325s","start":"2026-04-16T16:16:00.736935Z","end":"2026-04-16T16:16:03.121773Z","steps":["trace[108940377] 'agreement among raft nodes before linearized reading' (duration: 2.384758084s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:03.121816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:00.736908Z","time spent":"2.384901276s","remote":"127.0.0.1:39566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4448,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-16T16:16:03.121846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.476762967s","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:16:03.121884Z","caller":"traceutil/trace.go:171","msg":"trace[1488287046] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:377; }","duration":"3.476820218s","start":"2026-04-16T16:15:59.645055Z","end":"2026-04-16T16:16:03.121875Z","steps":["trace[1488287046] 'agreement among raft nodes before linearized reading' (duration: 3.476759028s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:03.121912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:15:59.645035Z","time spent":"3.476871099s","remote":"127.0.0.1:39658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T16:16:03.121974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.054034ms","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-04-16T16:16:03.122012Z","caller":"traceutil/trace.go:171","msg":"trace[29624682] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:377; }","duration":"903.134854ms","start":"2026-04-16T16:16:02.218869Z","end":"2026-04-16T16:16:03.122004Z","steps":["trace[29624682] 'agreement among raft nodes before linearized reading' (duration: 903.074683ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:03.12204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:02.218844Z","time spent":"903.190296ms","remote":"127.0.0.1:39658","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-04-16T16:16:03.122048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.47765692s","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:16:03.122076Z","caller":"traceutil/trace.go:171","msg":"trace[1294821414] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:377; }","duration":"3.477735821s","start":"2026-04-16T16:15:59.644333Z","end":"2026-04-16T16:16:03.122069Z","steps":["trace[1294821414] 'agreement among raft nodes before linearized reading' (duration: 3.47768741s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:03.122101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:15:59.644305Z","time spent":"3.477790331s","remote":"127.0.0.1:39658","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:16:03.122245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.51791812s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:16:03.122335Z","caller":"traceutil/trace.go:171","msg":"trace[1218322113] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:377; }","duration":"3.517992671s","start":"2026-04-16T16:15:59.604316Z","end":"2026-04-16T16:16:03.122309Z","steps":["trace[1218322113] 'agreement among raft nodes before linearized reading' (duration: 3.51790908s)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:16:03.433917Z","caller":"traceutil/trace.go:171","msg":"trace[234488932] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"307.482944ms","start":"2026-04-16T16:16:03.12641Z","end":"2026-04-16T16:16:03.433893Z","steps":["trace[234488932] 'process raft request' (duration: 300.79585ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:03.434073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:03.126385Z","time spent":"307.612006ms","remote":"127.0.0.1:39658","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-04-16T16:16:03.593947Z","caller":"traceutil/trace.go:171","msg":"trace[2120342415] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"467.49457ms","start":"2026-04-16T16:16:03.126427Z","end":"2026-04-16T16:16:03.593921Z","steps":["trace[2120342415] 'process raft request' (duration: 467.282646ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:16:03.593954Z","caller":"traceutil/trace.go:171","msg":"trace[2124026654] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"466.886692ms","start":"2026-04-16T16:16:03.127041Z","end":"2026-04-16T16:16:03.593928Z","steps":["trace[2124026654] 'process raft request' (duration: 466.810201ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:03.594096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:03.126398Z","time spent":"467.615962ms","remote":"127.0.0.1:39658","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-04-16T16:16:03.594323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:03.12703Z","time spent":"467.000533ms","remote":"127.0.0.1:39658","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:16:03.594519Z","caller":"traceutil/trace.go:171","msg":"trace[939718648] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"467.296027ms","start":"2026-04-16T16:16:03.127208Z","end":"2026-04-16T16:16:03.594504Z","steps":["trace[939718648] 'process raft request' (duration: 466.681918ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:03.594653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:03.127199Z","time spent":"467.386249ms","remote":"127.0.0.1:39658","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:16:03.594859Z","caller":"traceutil/trace.go:171","msg":"trace[116269012] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"462.141225ms","start":"2026-04-16T16:16:03.132594Z","end":"2026-04-16T16:16:03.594735Z","steps":["trace[116269012] 'process raft request' (duration: 461.704109ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:16:03.594877Z","caller":"traceutil/trace.go:171","msg":"trace[114917268] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"461.607477ms","start":"2026-04-16T16:16:03.133232Z","end":"2026-04-16T16:16:03.594839Z","steps":["trace[114917268] 'process raft request' (duration: 461.388074ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:03.595028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:03.132573Z","time spent":"462.366768ms","remote":"127.0.0.1:39582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5321,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:16:03.595227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:03.13322Z","time spent":"461.890322ms","remote":"127.0.0.1:39566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4406,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:16:06.408238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.102697ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T16:16:06.40842Z","caller":"traceutil/trace.go:171","msg":"trace[1453741873] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"802.893156ms","start":"2026-04-16T16:16:05.60551Z","end":"2026-04-16T16:16:06.408404Z","steps":["trace[1453741873] 'process raft request' (duration: 802.827525ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:16:06.408417Z","caller":"traceutil/trace.go:171","msg":"trace[1216270240] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"803.359963ms","start":"2026-04-16T16:16:05.605033Z","end":"2026-04-16T16:16:06.408393Z","steps":["trace[1216270240] 'process raft request' (duration: 679.010172ms)","trace[1216270240] 'compare' (duration: 123.989246ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:16:06.408494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:05.605503Z","time spent":"802.962417ms","remote":"127.0.0.1:39658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:16:06.408527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:05.605016Z","time spent":"803.454214ms","remote":"127.0.0.1:39658","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-04-16T16:16:07.660249Z","caller":"traceutil/trace.go:171","msg":"trace[209898651] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"209.430895ms","start":"2026-04-16T16:16:07.450794Z","end":"2026-04-16T16:16:07.660225Z","steps":["trace[209898651] 'process raft request' (duration: 209.319783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:07.942614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.826628ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T16:16:07.942748Z","caller":"traceutil/trace.go:171","msg":"trace[722463075] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"234.384596ms","start":"2026-04-16T16:16:07.70833Z","end":"2026-04-16T16:16:07.942714Z","steps":["trace[722463075] 'process raft request' (duration: 98.374165ms)","trace[722463075] 'compare' (duration: 135.640206ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:16:08.556642Z","caller":"traceutil/trace.go:171","msg":"trace[2091397328] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"140.669062ms","start":"2026-04-16T16:16:08.415948Z","end":"2026-04-16T16:16:08.556617Z","steps":["trace[2091397328] 'process raft request' (duration: 139.189903ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:16:19.004465Z","caller":"traceutil/trace.go:171","msg":"trace[232399280] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"102.17077ms","start":"2026-04-16T16:16:18.902255Z","end":"2026-04-16T16:16:19.004426Z","steps":["trace[232399280] 'process raft request' (duration: 33.317837ms)","trace[232399280] 'compare' (duration: 68.384487ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:16:19.268387Z","caller":"traceutil/trace.go:171","msg":"trace[293711196] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"162.779263ms","start":"2026-04-16T16:16:19.105589Z","end":"2026-04-16T16:16:19.268368Z","steps":["trace[293711196] 'process raft request' (duration: 101.053407ms)","trace[293711196] 'compare' (duration: 61.546274ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:16:20.049729Z","caller":"traceutil/trace.go:171","msg":"trace[968645396] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"929.373769ms","start":"2026-04-16T16:16:19.120312Z","end":"2026-04-16T16:16:20.049686Z","steps":["trace[968645396] 'process raft request' (duration: 929.178637ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:20.049931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:19.120291Z","time spent":"929.542082ms","remote":"127.0.0.1:39432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T16:16:20.049941Z","caller":"traceutil/trace.go:171","msg":"trace[380513451] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"941.541414ms","start":"2026-04-16T16:16:19.108363Z","end":"2026-04-16T16:16:20.049904Z","steps":["trace[380513451] 'process raft request' (duration: 929.118916ms)","trace[380513451] 'compare' (duration: 11.860711ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:16:20.050043Z","caller":"traceutil/trace.go:171","msg":"trace[1544127674] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"926.539503ms","start":"2026-04-16T16:16:19.12347Z","end":"2026-04-16T16:16:20.050009Z","steps":["trace[1544127674] 'process raft request' (duration: 926.115778ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:16:20.05008Z","caller":"traceutil/trace.go:171","msg":"trace[151189605] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:541; }","duration":"938.577166ms","start":"2026-04-16T16:16:19.111444Z","end":"2026-04-16T16:16:20.050021Z","steps":["trace[151189605] 'read index received' (duration: 94.860339ms)","trace[151189605] 'applied index is now lower than readState.Index' (duration: 843.712237ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:16:20.050147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:19.108341Z","time spent":"941.698075ms","remote":"127.0.0.1:39432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29546,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:16:20.050194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:19.123441Z","time spent":"926.673355ms","remote":"127.0.0.1:39432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31111,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:16:20.06377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"854.116429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:16:20.063845Z","caller":"traceutil/trace.go:171","msg":"trace[2106746187] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:525; }","duration":"854.23099ms","start":"2026-04-16T16:16:19.209597Z","end":"2026-04-16T16:16:20.063828Z","steps":["trace[2106746187] 'agreement among raft nodes before linearized reading' (duration: 854.096079ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:20.064083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.493179ms","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:16:20.064079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.026353ms","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:16:20.064133Z","caller":"traceutil/trace.go:171","msg":"trace[739657512] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:525; }","duration":"338.569391ms","start":"2026-04-16T16:16:19.72555Z","end":"2026-04-16T16:16:20.064119Z","steps":["trace[739657512] 'agreement among raft nodes before linearized reading' (duration: 338.379498ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:20.064169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:19.725529Z","time spent":"338.633581ms","remote":"127.0.0.1:39658","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:16:20.064181Z","caller":"traceutil/trace.go:171","msg":"trace[1570000713] 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:525; }","duration":"531.182815ms","start":"2026-04-16T16:16:19.532978Z","end":"2026-04-16T16:16:20.06416Z","steps":["trace[1570000713] 'agreement among raft nodes before linearized reading' (duration: 530.990203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:20.064198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.375606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:16:20.064256Z","caller":"traceutil/trace.go:171","msg":"trace[2033937611] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:525; }","duration":"797.463027ms","start":"2026-04-16T16:16:19.266778Z","end":"2026-04-16T16:16:20.064241Z","steps":["trace[2033937611] 'agreement among raft nodes before linearized reading' (duration: 797.365756ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:20.064258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:19.53296Z","time spent":"531.284676ms","remote":"127.0.0.1:53276","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":"warn","ts":"2026-04-16T16:16:20.064303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:19.266742Z","time spent":"797.539189ms","remote":"127.0.0.1:53272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-16T16:16:20.064373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"952.924968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:16:20.064418Z","caller":"traceutil/trace.go:171","msg":"trace[93799160] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:525; }","duration":"953.006319ms","start":"2026-04-16T16:16:19.111398Z","end":"2026-04-16T16:16:20.064404Z","steps":["trace[93799160] 'agreement among raft nodes before linearized reading' (duration: 938.770829ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:20.064454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:19.111389Z","time spent":"953.05811ms","remote":"127.0.0.1:39374","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:16:20.064666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.834973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:16:20.06469Z","caller":"traceutil/trace.go:171","msg":"trace[579066960] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:525; }","duration":"823.861694ms","start":"2026-04-16T16:16:19.240822Z","end":"2026-04-16T16:16:20.064684Z","steps":["trace[579066960] 'agreement among raft nodes before linearized reading' (duration: 823.821093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:20.064707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:19.240812Z","time spent":"823.891164ms","remote":"127.0.0.1:53260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 "} {"level":"warn","ts":"2026-04-16T16:16:20.064907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.569992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:16:20.064928Z","caller":"traceutil/trace.go:171","msg":"trace[266889469] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:525; }","duration":"824.594643ms","start":"2026-04-16T16:16:19.240329Z","end":"2026-04-16T16:16:20.064924Z","steps":["trace[266889469] 'agreement among raft nodes before linearized reading' (duration: 824.559362ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:20.064944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:19.240312Z","time spent":"824.628293ms","remote":"127.0.0.1:53260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-04-16T16:16:20.065055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.363676ms","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":"warn","ts":"2026-04-16T16:16:20.065073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.779364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:16:20.065091Z","caller":"traceutil/trace.go:171","msg":"trace[1777543086] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:525; }","duration":"824.830366ms","start":"2026-04-16T16:16:19.240256Z","end":"2026-04-16T16:16:20.065086Z","steps":["trace[1777543086] 'agreement among raft nodes before linearized reading' (duration: 824.795535ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:20.065107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:19.240238Z","time spent":"824.864766ms","remote":"127.0.0.1:53260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 "} {"level":"info","ts":"2026-04-16T16:16:20.065087Z","caller":"traceutil/trace.go:171","msg":"trace[49905600] 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:525; }","duration":"531.416507ms","start":"2026-04-16T16:16:19.533662Z","end":"2026-04-16T16:16:20.065079Z","steps":["trace[49905600] 'agreement among raft nodes before linearized reading' (duration: 531.344956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:20.065262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:19.533653Z","time spent":"531.601849ms","remote":"127.0.0.1:53276","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:16:20.065261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.251578ms","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:16:20.06532Z","caller":"traceutil/trace.go:171","msg":"trace[1937892105] 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:525; }","duration":"532.337839ms","start":"2026-04-16T16:16:19.532965Z","end":"2026-04-16T16:16:20.065303Z","steps":["trace[1937892105] 'agreement among raft nodes before linearized reading' (duration: 532.257828ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:20.065366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:19.532948Z","time spent":"532.40755ms","remote":"127.0.0.1:53276","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:16:20.065385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.390028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:16:20.064108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.035992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:16:20.065418Z","caller":"traceutil/trace.go:171","msg":"trace[690305923] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:525; }","duration":"838.44203ms","start":"2026-04-16T16:16:19.226966Z","end":"2026-04-16T16:16:20.065408Z","steps":["trace[690305923] 'agreement among raft nodes before linearized reading' (duration: 838.378559ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:16:20.065431Z","caller":"traceutil/trace.go:171","msg":"trace[1244559297] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:525; }","duration":"838.407879ms","start":"2026-04-16T16:16:19.227016Z","end":"2026-04-16T16:16:20.065424Z","steps":["trace[1244559297] 'agreement among raft nodes before linearized reading' (duration: 836.998681ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:20.06547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:19.226606Z","time spent":"838.856794ms","remote":"127.0.0.1:53250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-16T16:16:20.06548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:19.226953Z","time spent":"838.50293ms","remote":"127.0.0.1:53250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-16T16:16:20.065326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.735872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:16:20.065526Z","caller":"traceutil/trace.go:171","msg":"trace[1878083947] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:525; }","duration":"838.983236ms","start":"2026-04-16T16:16:19.226525Z","end":"2026-04-16T16:16:20.065508Z","steps":["trace[1878083947] 'agreement among raft nodes before linearized reading' (duration: 838.715833ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:20.065579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:19.226503Z","time spent":"839.058516ms","remote":"127.0.0.1:53250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-16T16:16:20.065866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"856.211866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:16:20.065868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"856.206106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:16:20.065893Z","caller":"traceutil/trace.go:171","msg":"trace[1113440604] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:525; }","duration":"856.267026ms","start":"2026-04-16T16:16:19.209619Z","end":"2026-04-16T16:16:20.065886Z","steps":["trace[1113440604] 'agreement among raft nodes before linearized reading' (duration: 856.224106ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:20.065913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:19.209599Z","time spent":"856.308177ms","remote":"127.0.0.1:53240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 "} {"level":"info","ts":"2026-04-16T16:16:20.065908Z","caller":"traceutil/trace.go:171","msg":"trace[34226537] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:525; }","duration":"856.276687ms","start":"2026-04-16T16:16:19.20962Z","end":"2026-04-16T16:16:20.065896Z","steps":["trace[34226537] 'agreement among raft nodes before linearized reading' (duration: 856.211486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:20.065944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:19.209599Z","time spent":"856.335847ms","remote":"127.0.0.1:53240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-04-16T16:16:20.065955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"798.270047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:16:20.066007Z","caller":"traceutil/trace.go:171","msg":"trace[1721888864] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:525; }","duration":"798.334379ms","start":"2026-04-16T16:16:19.267647Z","end":"2026-04-16T16:16:20.065981Z","steps":["trace[1721888864] 'agreement among raft nodes before linearized reading' (duration: 798.281408ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:20.066054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:19.267635Z","time spent":"798.394389ms","remote":"127.0.0.1:53272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 "} {"level":"warn","ts":"2026-04-16T16:16:20.06389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:19.209584Z","time spent":"854.294652ms","remote":"127.0.0.1:53240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 "} {"level":"warn","ts":"2026-04-16T16:16:20.066321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.480054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:16:20.066398Z","caller":"traceutil/trace.go:171","msg":"trace[1824193991] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:525; }","duration":"799.580054ms","start":"2026-04-16T16:16:19.26679Z","end":"2026-04-16T16:16:20.06637Z","steps":["trace[1824193991] 'agreement among raft nodes before linearized reading' (duration: 797.124123ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:20.066428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:19.266773Z","time spent":"799.646486ms","remote":"127.0.0.1:53272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"info","ts":"2026-04-16T16:16:21.301576Z","caller":"traceutil/trace.go:171","msg":"trace[923590598] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"399.837696ms","start":"2026-04-16T16:16:20.901675Z","end":"2026-04-16T16:16:21.301512Z","steps":["trace[923590598] 'process raft request' (duration: 398.58678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:21.301767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:20.901642Z","time spent":"400.028838ms","remote":"127.0.0.1:39432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:16:21.311504Z","caller":"traceutil/trace.go:171","msg":"trace[139114918] linearizableReadLoop","detail":"{readStateIndex:578; appliedIndex:576; }","duration":"205.418788ms","start":"2026-04-16T16:16:21.105986Z","end":"2026-04-16T16:16:21.311405Z","steps":["trace[139114918] 'read index received' (duration: 193.980924ms)","trace[139114918] 'applied index is now lower than readState.Index' (duration: 11.436964ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:16:21.311639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.632711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:16:21.311672Z","caller":"traceutil/trace.go:171","msg":"trace[1375798193] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:557; }","duration":"205.703142ms","start":"2026-04-16T16:16:21.105958Z","end":"2026-04-16T16:16:21.311661Z","steps":["trace[1375798193] 'agreement among raft nodes before linearized reading' (duration: 205.612221ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:16:21.311849Z","caller":"traceutil/trace.go:171","msg":"trace[152223120] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"369.297747ms","start":"2026-04-16T16:16:20.942539Z","end":"2026-04-16T16:16:21.311837Z","steps":["trace[152223120] 'process raft request' (duration: 368.735611ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:21.311929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:20.942489Z","time spent":"369.393028ms","remote":"127.0.0.1:39432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T16:16:26.953437Z","caller":"traceutil/trace.go:171","msg":"trace[1222023591] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"403.142137ms","start":"2026-04-16T16:16:26.550265Z","end":"2026-04-16T16:16:26.953407Z","steps":["trace[1222023591] 'process raft request' (duration: 402.981235ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:16:26.953479Z","caller":"traceutil/trace.go:171","msg":"trace[956585680] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:603; }","duration":"294.289301ms","start":"2026-04-16T16:16:26.659144Z","end":"2026-04-16T16:16:26.953433Z","steps":["trace[956585680] 'read index received' (duration: 294.270871ms)","trace[956585680] 'applied index is now lower than readState.Index' (duration: 14.91µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:16:26.953605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:26.550246Z","time spent":"403.281099ms","remote":"127.0.0.1:39658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:16:26.953773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.602755ms","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-04-16T16:16:26.953815Z","caller":"traceutil/trace.go:171","msg":"trace[1158027585] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:581; }","duration":"294.711227ms","start":"2026-04-16T16:16:26.659093Z","end":"2026-04-16T16:16:26.953804Z","steps":["trace[1158027585] 'agreement among raft nodes before linearized reading' (duration: 294.487754ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:16:26.967239Z","caller":"traceutil/trace.go:171","msg":"trace[1440318158] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"123.427084ms","start":"2026-04-16T16:16:26.843754Z","end":"2026-04-16T16:16:26.967181Z","steps":["trace[1440318158] 'process raft request' (duration: 121.855045ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:16:28.266634Z","caller":"traceutil/trace.go:171","msg":"trace[203707744] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"252.380815ms","start":"2026-04-16T16:16:28.014227Z","end":"2026-04-16T16:16:28.266608Z","steps":["trace[203707744] 'read index received' (duration: 251.419184ms)","trace[203707744] 'applied index is now lower than readState.Index' (duration: 958.511µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:16:28.266726Z","caller":"traceutil/trace.go:171","msg":"trace[1441507422] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"763.13024ms","start":"2026-04-16T16:16:27.503562Z","end":"2026-04-16T16:16:28.266692Z","steps":["trace[1441507422] 'process raft request' (duration: 762.25439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:28.266889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:27.503541Z","time spent":"763.262282ms","remote":"127.0.0.1:39494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036197,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:16:28.266885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.645064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-16T16:16:28.267023Z","caller":"traceutil/trace.go:171","msg":"trace[634875411] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:589; }","duration":"129.788657ms","start":"2026-04-16T16:16:28.137186Z","end":"2026-04-16T16:16:28.266975Z","steps":["trace[634875411] 'agreement among raft nodes before linearized reading' (duration: 129.572784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:28.267182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.934062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zm658\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-16T16:16:28.267271Z","caller":"traceutil/trace.go:171","msg":"trace[776189446] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zm658; range_end:; response_count:1; response_revision:589; }","duration":"253.067614ms","start":"2026-04-16T16:16:28.014184Z","end":"2026-04-16T16:16:28.267252Z","steps":["trace[776189446] 'agreement among raft nodes before linearized reading' (duration: 252.572338ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:16:28.542209Z","caller":"traceutil/trace.go:171","msg":"trace[374269521] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"268.582352ms","start":"2026-04-16T16:16:28.273589Z","end":"2026-04-16T16:16:28.542172Z","steps":["trace[374269521] 'process raft request' (duration: 198.421209ms)","trace[374269521] 'compare' (duration: 70.014181ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:16:28.616048Z","caller":"traceutil/trace.go:171","msg":"trace[1529081238] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"288.402583ms","start":"2026-04-16T16:16:28.327621Z","end":"2026-04-16T16:16:28.616024Z","steps":["trace[1529081238] 'process raft request' (duration: 288.353963ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:16:28.616267Z","caller":"traceutil/trace.go:171","msg":"trace[1436471970] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"341.699021ms","start":"2026-04-16T16:16:28.274526Z","end":"2026-04-16T16:16:28.616225Z","steps":["trace[1436471970] 'process raft request' (duration: 341.256686ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:16:28.61631Z","caller":"traceutil/trace.go:171","msg":"trace[1970580203] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"328.470709ms","start":"2026-04-16T16:16:28.287805Z","end":"2026-04-16T16:16:28.616276Z","steps":["trace[1970580203] 'process raft request' (duration: 328.119485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:28.616418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:28.274505Z","time spent":"341.828622ms","remote":"127.0.0.1:39658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:16:28.616471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:28.287787Z","time spent":"328.591442ms","remote":"127.0.0.1:39582","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:16:29.607659Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836959875555592948,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T16:16:30.108033Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836959875555592948,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T16:16:30.214974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.239801995s","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-04-16T16:16:30.215027Z","caller":"traceutil/trace.go:171","msg":"trace[1494854983] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:593; }","duration":"1.239880846s","start":"2026-04-16T16:16:28.975133Z","end":"2026-04-16T16:16:30.215014Z","steps":["trace[1494854983] 'range keys from in-memory index tree' (duration: 1.239688382s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:30.215048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:28.975122Z","time spent":"1.239922146s","remote":"127.0.0.1:39658","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-04-16T16:16:30.215164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.257801252s","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:16:30.215181Z","caller":"traceutil/trace.go:171","msg":"trace[804713623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:593; }","duration":"1.257845223s","start":"2026-04-16T16:16:28.957331Z","end":"2026-04-16T16:16:30.215176Z","steps":["trace[804713623] 'range keys from in-memory index tree' (duration: 1.257468757s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:30.215193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:28.957315Z","time spent":"1.257876004s","remote":"127.0.0.1:39658","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:16:30.608421Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836959875555592948,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T16:16:31.056584Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.01685651s","expected-duration":"1s"} {"level":"info","ts":"2026-04-16T16:16:31.057631Z","caller":"traceutil/trace.go:171","msg":"trace[209560053] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"2.021985301s","start":"2026-04-16T16:16:29.035623Z","end":"2026-04-16T16:16:31.057608Z","steps":["trace[209560053] 'process raft request' (duration: 2.021383464s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:31.057763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:29.035604Z","time spent":"2.022081143s","remote":"127.0.0.1:39494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036233,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:16:31.104977Z","caller":"traceutil/trace.go:171","msg":"trace[1482075877] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:615; }","duration":"1.99830008s","start":"2026-04-16T16:16:29.106653Z","end":"2026-04-16T16:16:31.104954Z","steps":["trace[1482075877] 'read index received' (duration: 1.950183394s)","trace[1482075877] 'applied index is now lower than readState.Index' (duration: 48.115856ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:16:31.10524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.998573673s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:16:31.105322Z","caller":"traceutil/trace.go:171","msg":"trace[390018385] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:596; }","duration":"1.998696075s","start":"2026-04-16T16:16:29.106605Z","end":"2026-04-16T16:16:31.105301Z","steps":["trace[390018385] 'agreement among raft nodes before linearized reading' (duration: 1.998550593s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:31.10538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:29.106587Z","time spent":"1.998786326s","remote":"127.0.0.1:39374","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-04-16T16:16:31.105306Z","caller":"traceutil/trace.go:171","msg":"trace[1443649965] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"885.259061ms","start":"2026-04-16T16:16:30.220017Z","end":"2026-04-16T16:16:31.105276Z","steps":["trace[1443649965] 'process raft request' (duration: 884.897906ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:16:31.105255Z","caller":"traceutil/trace.go:171","msg":"trace[2069261535] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"886.134131ms","start":"2026-04-16T16:16:30.219089Z","end":"2026-04-16T16:16:31.105223Z","steps":["trace[2069261535] 'process raft request' (duration: 885.742466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:31.10554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:30.220007Z","time spent":"885.484313ms","remote":"127.0.0.1:39658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:16:31.105329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.605483ms","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:16:31.105618Z","caller":"traceutil/trace.go:171","msg":"trace[1256839313] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:596; }","duration":"559.930017ms","start":"2026-04-16T16:16:30.545669Z","end":"2026-04-16T16:16:31.105599Z","steps":["trace[1256839313] 'agreement among raft nodes before linearized reading' (duration: 559.552643ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:31.105694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:30.545649Z","time spent":"560.019808ms","remote":"127.0.0.1:39658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-16T16:16:31.105723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:30.21907Z","time spent":"886.511995ms","remote":"127.0.0.1:39658","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-04-16T16:16:31.106071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.13798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-16T16:16:31.106125Z","caller":"traceutil/trace.go:171","msg":"trace[683979312] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:596; }","duration":"486.731428ms","start":"2026-04-16T16:16:30.61938Z","end":"2026-04-16T16:16:31.106112Z","steps":["trace[683979312] 'agreement among raft nodes before linearized reading' (duration: 485.984439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:31.106255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:30.619365Z","time spent":"486.87302ms","remote":"127.0.0.1:39658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-16T16:16:32.619106Z","caller":"traceutil/trace.go:171","msg":"trace[468047917] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"436.569489ms","start":"2026-04-16T16:16:32.182506Z","end":"2026-04-16T16:16:32.619076Z","steps":["trace[468047917] 'process raft request' (duration: 427.822085ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:32.619396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:32.182482Z","time spent":"436.69315ms","remote":"127.0.0.1:39856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T16:16:33.25927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.514714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:16:33.2594Z","caller":"traceutil/trace.go:171","msg":"trace[1169592947] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:641; }","duration":"113.667806ms","start":"2026-04-16T16:16:33.145707Z","end":"2026-04-16T16:16:33.259375Z","steps":["trace[1169592947] 'range keys from in-memory index tree' (duration: 113.471374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:33.259534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.938229ms","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:16:33.25981Z","caller":"traceutil/trace.go:171","msg":"trace[1431759495] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:641; }","duration":"114.339234ms","start":"2026-04-16T16:16:33.145445Z","end":"2026-04-16T16:16:33.259785Z","steps":["trace[1431759495] 'range keys from in-memory index tree' (duration: 113.757158ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:16:36.354104Z","caller":"traceutil/trace.go:171","msg":"trace[871440216] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"106.407974ms","start":"2026-04-16T16:16:36.247667Z","end":"2026-04-16T16:16:36.354075Z","steps":["trace[871440216] 'process raft request' (duration: 105.766196ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:47.508742Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836959875555593283,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-16T16:16:47.791477Z","caller":"traceutil/trace.go:171","msg":"trace[1394460528] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"828.49565ms","start":"2026-04-16T16:16:46.962905Z","end":"2026-04-16T16:16:47.791401Z","steps":["trace[1394460528] 'process raft request' (duration: 828.283968ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:47.791651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:46.962885Z","time spent":"828.681872ms","remote":"127.0.0.1:39566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5052,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:16:47.80789Z","caller":"traceutil/trace.go:171","msg":"trace[1882543731] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"235.750146ms","start":"2026-04-16T16:16:47.57211Z","end":"2026-04-16T16:16:47.807861Z","steps":["trace[1882543731] 'process raft request' (duration: 235.597544ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:16:47.807922Z","caller":"traceutil/trace.go:171","msg":"trace[147801784] linearizableReadLoop","detail":"{readStateIndex:796; appliedIndex:794; }","duration":"799.246482ms","start":"2026-04-16T16:16:47.008655Z","end":"2026-04-16T16:16:47.807901Z","steps":["trace[147801784] 'read index received' (duration: 782.583107ms)","trace[147801784] 'applied index is now lower than readState.Index' (duration: 16.513534ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:16:47.808197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.498675ms","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":"warn","ts":"2026-04-16T16:16:47.808285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.198319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:16:47.808291Z","caller":"traceutil/trace.go:171","msg":"trace[22156134] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:769; }","duration":"799.655406ms","start":"2026-04-16T16:16:47.008613Z","end":"2026-04-16T16:16:47.808268Z","steps":["trace[22156134] 'agreement among raft nodes before linearized reading' (duration: 799.344513ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:47.808284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.27501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:16:47.808329Z","caller":"traceutil/trace.go:171","msg":"trace[104975476] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:769; }","duration":"499.311711ms","start":"2026-04-16T16:16:47.309008Z","end":"2026-04-16T16:16:47.80832Z","steps":["trace[104975476] 'agreement among raft nodes before linearized reading' (duration: 499.20843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:47.808347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:47.008595Z","time spent":"799.744987ms","remote":"127.0.0.1:39658","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":"info","ts":"2026-04-16T16:16:47.808348Z","caller":"traceutil/trace.go:171","msg":"trace[1818217940] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:769; }","duration":"701.388192ms","start":"2026-04-16T16:16:47.106944Z","end":"2026-04-16T16:16:47.808332Z","steps":["trace[1818217940] 'agreement among raft nodes before linearized reading' (duration: 701.23029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:47.808418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.724752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-16T16:16:47.808397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:47.106923Z","time spent":"701.464363ms","remote":"127.0.0.1:39374","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:16:47.808577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.859547ms","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:16:47.808604Z","caller":"traceutil/trace.go:171","msg":"trace[767870533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:769; }","duration":"481.919677ms","start":"2026-04-16T16:16:47.326678Z","end":"2026-04-16T16:16:47.808598Z","steps":["trace[767870533] 'agreement among raft nodes before linearized reading' (duration: 481.777505ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:47.808623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:47.326666Z","time spent":"481.952758ms","remote":"127.0.0.1:39658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T16:16:47.808359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:47.308987Z","time spent":"499.364311ms","remote":"127.0.0.1:39542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-04-16T16:16:47.808807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.722838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-16T16:16:47.808805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.395492ms","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:16:47.80883Z","caller":"traceutil/trace.go:171","msg":"trace[829736927] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:769; }","duration":"625.763029ms","start":"2026-04-16T16:16:47.183059Z","end":"2026-04-16T16:16:47.808822Z","steps":["trace[829736927] 'agreement among raft nodes before linearized reading' (duration: 625.693379ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:47.808843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.5789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-16T16:16:47.808849Z","caller":"traceutil/trace.go:171","msg":"trace[18372438] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:769; }","duration":"614.459533ms","start":"2026-04-16T16:16:47.194377Z","end":"2026-04-16T16:16:47.808836Z","steps":["trace[18372438] 'agreement among raft nodes before linearized reading' (duration: 614.26785ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:16:47.808872Z","caller":"traceutil/trace.go:171","msg":"trace[1216526724] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:769; }","duration":"636.63498ms","start":"2026-04-16T16:16:47.172231Z","end":"2026-04-16T16:16:47.808866Z","steps":["trace[1216526724] 'agreement among raft nodes before linearized reading' (duration: 636.561199ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:47.808892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:47.172215Z","time spent":"636.67326ms","remote":"127.0.0.1:39658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-16T16:16:47.808444Z","caller":"traceutil/trace.go:171","msg":"trace[70133630] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:769; }","duration":"364.789782ms","start":"2026-04-16T16:16:47.443647Z","end":"2026-04-16T16:16:47.808437Z","steps":["trace[70133630] 'agreement among raft nodes before linearized reading' (duration: 364.741522ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:47.808925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:47.443624Z","time spent":"365.290228ms","remote":"127.0.0.1:39716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":14,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-04-16T16:16:47.808849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:47.183048Z","time spent":"625.79706ms","remote":"127.0.0.1:39658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-16T16:16:47.808885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:47.194368Z","time spent":"614.509764ms","remote":"127.0.0.1:39658","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:16:47.808049Z","caller":"traceutil/trace.go:171","msg":"trace[845933391] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"677.103242ms","start":"2026-04-16T16:16:47.130923Z","end":"2026-04-16T16:16:47.808026Z","steps":["trace[845933391] 'process raft request' (duration: 676.667847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:16:47.8093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:16:47.130897Z","time spent":"678.336035ms","remote":"127.0.0.1:39638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:16:49.957676Z","caller":"traceutil/trace.go:171","msg":"trace[1248381148] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"114.719527ms","start":"2026-04-16T16:16:49.842934Z","end":"2026-04-16T16:16:49.957654Z","steps":["trace[1248381148] 'process raft request' (duration: 114.672807ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:16:49.957763Z","caller":"traceutil/trace.go:171","msg":"trace[39915242] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"114.886769ms","start":"2026-04-16T16:16:49.842852Z","end":"2026-04-16T16:16:49.957739Z","steps":["trace[39915242] 'process raft request' (duration: 108.731831ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:16:49.958039Z","caller":"traceutil/trace.go:171","msg":"trace[1773486388] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"113.903667ms","start":"2026-04-16T16:16:49.844124Z","end":"2026-04-16T16:16:49.958027Z","steps":["trace[1773486388] 'process raft request' (duration: 113.870977ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:16:49.958089Z","caller":"traceutil/trace.go:171","msg":"trace[1000187356] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"114.11508ms","start":"2026-04-16T16:16:49.843938Z","end":"2026-04-16T16:16:49.958053Z","steps":["trace[1000187356] 'process raft request' (duration: 113.975248ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:16:51.499996Z","caller":"traceutil/trace.go:171","msg":"trace[1016786364] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"184.23819ms","start":"2026-04-16T16:16:51.31573Z","end":"2026-04-16T16:16:51.499968Z","steps":["trace[1016786364] 'process raft request' (duration: 147.019191ms)","trace[1016786364] 'compare' (duration: 37.094567ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:16:51.726708Z","caller":"traceutil/trace.go:171","msg":"trace[1104209638] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"219.916782ms","start":"2026-04-16T16:16:51.506764Z","end":"2026-04-16T16:16:51.726681Z","steps":["trace[1104209638] 'process raft request' (duration: 213.506091ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:16:51.93478Z","caller":"traceutil/trace.go:171","msg":"trace[731137732] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"201.874453ms","start":"2026-04-16T16:16:51.732865Z","end":"2026-04-16T16:16:51.934739Z","steps":["trace[731137732] 'process raft request' (duration: 151.380809ms)","trace[731137732] 'compare' (duration: 50.1773ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:17:13.363065Z","caller":"traceutil/trace.go:171","msg":"trace[268294355] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"144.439947ms","start":"2026-04-16T16:17:13.218601Z","end":"2026-04-16T16:17:13.363041Z","steps":["trace[268294355] 'process raft request' (duration: 144.305076ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:17:21.221309Z","caller":"traceutil/trace.go:171","msg":"trace[477708403] linearizableReadLoop","detail":"{readStateIndex:1044; appliedIndex:1043; }","duration":"114.176504ms","start":"2026-04-16T16:17:21.107111Z","end":"2026-04-16T16:17:21.221287Z","steps":["trace[477708403] 'read index received' (duration: 40.455031ms)","trace[477708403] 'applied index is now lower than readState.Index' (duration: 73.720353ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:17:21.221594Z","caller":"traceutil/trace.go:171","msg":"trace[1433071698] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"148.537606ms","start":"2026-04-16T16:17:21.072889Z","end":"2026-04-16T16:17:21.221427Z","steps":["trace[1433071698] 'process raft request' (duration: 74.743023ms)","trace[1433071698] 'compare' (duration: 73.53999ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:17:21.221801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.671709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:17:21.221872Z","caller":"traceutil/trace.go:171","msg":"trace[398492336] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1008; }","duration":"114.788151ms","start":"2026-04-16T16:17:21.107067Z","end":"2026-04-16T16:17:21.221855Z","steps":["trace[398492336] 'agreement among raft nodes before linearized reading' (duration: 114.68562ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:17:21.403841Z","caller":"traceutil/trace.go:171","msg":"trace[2119561019] linearizableReadLoop","detail":"{readStateIndex:1046; appliedIndex:1045; }","duration":"113.72444ms","start":"2026-04-16T16:17:21.290097Z","end":"2026-04-16T16:17:21.403822Z","steps":["trace[2119561019] 'read index received' (duration: 50.852145ms)","trace[2119561019] 'applied index is now lower than readState.Index' (duration: 62.871135ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:17:21.403956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.848441ms","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":"info","ts":"2026-04-16T16:17:21.40393Z","caller":"traceutil/trace.go:171","msg":"trace[2077907399] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1010; }","duration":"162.406454ms","start":"2026-04-16T16:17:21.241474Z","end":"2026-04-16T16:17:21.403881Z","steps":["trace[2077907399] 'process raft request' (duration: 99.503858ms)","trace[2077907399] 'compare' (duration: 62.751213ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:17:21.403985Z","caller":"traceutil/trace.go:171","msg":"trace[164206936] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1010; }","duration":"113.923283ms","start":"2026-04-16T16:17:21.290053Z","end":"2026-04-16T16:17:21.403976Z","steps":["trace[164206936] 'agreement among raft nodes before linearized reading' (duration: 113.843012ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:17:34.395719Z","caller":"traceutil/trace.go:171","msg":"trace[376191744] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"103.133403ms","start":"2026-04-16T16:17:34.292558Z","end":"2026-04-16T16:17:34.395692Z","steps":["trace[376191744] 'process raft request' (duration: 102.922361ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:17:40.83691Z","caller":"traceutil/trace.go:171","msg":"trace[1088757492] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"268.725193ms","start":"2026-04-16T16:17:40.56816Z","end":"2026-04-16T16:17:40.836885Z","steps":["trace[1088757492] 'process raft request' (duration: 268.259418ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:17:40.837285Z","caller":"traceutil/trace.go:171","msg":"trace[716045366] linearizableReadLoop","detail":"{readStateIndex:1116; appliedIndex:1113; }","duration":"265.421751ms","start":"2026-04-16T16:17:40.571841Z","end":"2026-04-16T16:17:40.837263Z","steps":["trace[716045366] 'read index received' (duration: 10.757082ms)","trace[716045366] 'applied index is now lower than readState.Index' (duration: 254.663699ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:17:40.837355Z","caller":"traceutil/trace.go:171","msg":"trace[1149672260] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"269.114556ms","start":"2026-04-16T16:17:40.568212Z","end":"2026-04-16T16:17:40.837326Z","steps":["trace[1149672260] 'process raft request' (duration: 268.51337ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:17:40.837421Z","caller":"traceutil/trace.go:171","msg":"trace[907257018] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"269.178777ms","start":"2026-04-16T16:17:40.568226Z","end":"2026-04-16T16:17:40.837405Z","steps":["trace[907257018] 'process raft request' (duration: 268.990155ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:17:40.837694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.549159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:17:40.837748Z","caller":"traceutil/trace.go:171","msg":"trace[1847347730] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1076; }","duration":"252.65434ms","start":"2026-04-16T16:17:40.585079Z","end":"2026-04-16T16:17:40.837733Z","steps":["trace[1847347730] 'agreement among raft nodes before linearized reading' (duration: 252.563439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:17:40.837873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.994757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T16:17:40.837946Z","caller":"traceutil/trace.go:171","msg":"trace[913567212] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1076; }","duration":"266.111447ms","start":"2026-04-16T16:17:40.571821Z","end":"2026-04-16T16:17:40.837932Z","steps":["trace[913567212] 'agreement among raft nodes before linearized reading' (duration: 265.962806ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:17:41.967395Z","caller":"traceutil/trace.go:171","msg":"trace[790178513] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"330.305051ms","start":"2026-04-16T16:17:41.637066Z","end":"2026-04-16T16:17:41.967371Z","steps":["trace[790178513] 'process raft request' (duration: 330.16285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:17:41.967603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:17:41.637049Z","time spent":"330.457492ms","remote":"127.0.0.1:39658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:17:42.215047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.494487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:17:42.215123Z","caller":"traceutil/trace.go:171","msg":"trace[1615559277] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1077; }","duration":"109.615098ms","start":"2026-04-16T16:17:42.10549Z","end":"2026-04-16T16:17:42.215105Z","steps":["trace[1615559277] 'range keys from in-memory index tree' (duration: 109.402586ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:17:42.549873Z","caller":"traceutil/trace.go:171","msg":"trace[113439039] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"144.490667ms","start":"2026-04-16T16:17:42.405356Z","end":"2026-04-16T16:17:42.549847Z","steps":["trace[113439039] 'process raft request' (duration: 144.311005ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:17:42.848625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.904162ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T16:17:42.849157Z","caller":"traceutil/trace.go:171","msg":"trace[2039401422] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"257.795198ms","start":"2026-04-16T16:17:42.591329Z","end":"2026-04-16T16:17:42.849124Z","steps":["trace[2039401422] 'process raft request' (duration: 77.27629ms)","trace[2039401422] 'compare' (duration: 179.749601ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:17:43.495797Z","caller":"traceutil/trace.go:171","msg":"trace[1195358009] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"640.4919ms","start":"2026-04-16T16:17:42.855281Z","end":"2026-04-16T16:17:43.495773Z","steps":["trace[1195358009] 'process raft request' (duration: 599.171669ms)","trace[1195358009] 'compare' (duration: 40.812016ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:17:43.495908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:17:42.855268Z","time spent":"640.58474ms","remote":"127.0.0.1:39658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:17:43.528693Z","caller":"traceutil/trace.go:171","msg":"trace[260020725] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"673.192317ms","start":"2026-04-16T16:17:42.855472Z","end":"2026-04-16T16:17:43.528664Z","steps":["trace[260020725] 'process raft request' (duration: 672.991025ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:17:43.528794Z","caller":"traceutil/trace.go:171","msg":"trace[1624036135] linearizableReadLoop","detail":"{readStateIndex:1122; appliedIndex:1119; }","duration":"421.843472ms","start":"2026-04-16T16:17:43.106902Z","end":"2026-04-16T16:17:43.528746Z","steps":["trace[1624036135] 'read index received' (duration: 347.637391ms)","trace[1624036135] 'applied index is now lower than readState.Index' (duration: 74.203801ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:17:43.52881Z","caller":"traceutil/trace.go:171","msg":"trace[2093956667] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"673.253039ms","start":"2026-04-16T16:17:42.855532Z","end":"2026-04-16T16:17:43.528785Z","steps":["trace[2093956667] 'process raft request' (duration: 673.076577ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:17:43.528867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:17:42.85545Z","time spent":"673.331189ms","remote":"127.0.0.1:39658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:17:43.528901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.984194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:17:43.528922Z","caller":"traceutil/trace.go:171","msg":"trace[1717008602] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1082; }","duration":"422.062525ms","start":"2026-04-16T16:17:43.106853Z","end":"2026-04-16T16:17:43.528916Z","steps":["trace[1717008602] 'agreement among raft nodes before linearized reading' (duration: 421.992994ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:17:43.528942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:17:43.106834Z","time spent":"422.103575ms","remote":"127.0.0.1:39374","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:17:43.528943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:17:42.855526Z","time spent":"673.34257ms","remote":"127.0.0.1:39658","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-04-16T16:17:44.369558Z","caller":"traceutil/trace.go:171","msg":"trace[402982543] linearizableReadLoop","detail":"{readStateIndex:1123; appliedIndex:1122; }","duration":"262.215351ms","start":"2026-04-16T16:17:44.107324Z","end":"2026-04-16T16:17:44.36954Z","steps":["trace[402982543] 'read index received' (duration: 248.901235ms)","trace[402982543] 'applied index is now lower than readState.Index' (duration: 13.313036ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:17:44.369594Z","caller":"traceutil/trace.go:171","msg":"trace[2055220655] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"396.031123ms","start":"2026-04-16T16:17:43.973527Z","end":"2026-04-16T16:17:44.369558Z","steps":["trace[2055220655] 'process raft request' (duration: 382.761087ms)","trace[2055220655] 'compare' (duration: 12.405547ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:17:44.369765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:17:43.973494Z","time spent":"396.167814ms","remote":"127.0.0.1:39658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:17:44.3701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.301132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:17:44.37015Z","caller":"traceutil/trace.go:171","msg":"trace[863272287] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1083; }","duration":"262.852167ms","start":"2026-04-16T16:17:44.107284Z","end":"2026-04-16T16:17:44.370136Z","steps":["trace[863272287] 'agreement among raft nodes before linearized reading' (duration: 262.311192ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:17:44.369142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.627187ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:17:44.370191Z","caller":"traceutil/trace.go:171","msg":"trace[877536166] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1082; }","duration":"452.688478ms","start":"2026-04-16T16:17:43.917487Z","end":"2026-04-16T16:17:44.370176Z","steps":["trace[877536166] 'range keys from in-memory index tree' (duration: 451.614377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:17:45.814877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.261661ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T16:17:45.815498Z","caller":"traceutil/trace.go:171","msg":"trace[645392113] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"222.062518ms","start":"2026-04-16T16:17:45.59341Z","end":"2026-04-16T16:17:45.815472Z","steps":["trace[645392113] 'process raft request' (duration: 221.637844ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:17:45.815493Z","caller":"traceutil/trace.go:171","msg":"trace[7286039] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"222.18138ms","start":"2026-04-16T16:17:45.593205Z","end":"2026-04-16T16:17:45.815386Z","steps":["trace[7286039] 'process raft request' (duration: 101.266993ms)","trace[7286039] 'compare' (duration: 120.086299ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:18:11.960645Z","caller":"traceutil/trace.go:171","msg":"trace[1398189809] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"225.951414ms","start":"2026-04-16T16:18:11.734664Z","end":"2026-04-16T16:18:11.960616Z","steps":["trace[1398189809] 'process raft request' (duration: 225.747462ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:18:40.902107Z","caller":"traceutil/trace.go:171","msg":"trace[701430182] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"177.570386ms","start":"2026-04-16T16:18:40.724326Z","end":"2026-04-16T16:18:40.901896Z","steps":["trace[701430182] 'process raft request' (duration: 177.384108ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:18:42.324093Z","caller":"traceutil/trace.go:171","msg":"trace[1044758695] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"124.44061ms","start":"2026-04-16T16:18:42.19962Z","end":"2026-04-16T16:18:42.324061Z","steps":["trace[1044758695] 'process raft request' (duration: 124.241522ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:18:42.767986Z","caller":"traceutil/trace.go:171","msg":"trace[446883978] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"438.26581ms","start":"2026-04-16T16:18:42.329699Z","end":"2026-04-16T16:18:42.767965Z","steps":["trace[446883978] 'process raft request' (duration: 438.146116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:18:42.768148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:18:42.329669Z","time spent":"438.378975ms","remote":"127.0.0.1:39658","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-04-16T16:18:42.779962Z","caller":"traceutil/trace.go:171","msg":"trace[837373717] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"448.6785ms","start":"2026-04-16T16:18:42.331253Z","end":"2026-04-16T16:18:42.779931Z","steps":["trace[837373717] 'process raft request' (duration: 448.524364ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:18:42.780135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:18:42.331225Z","time spent":"448.818345ms","remote":"127.0.0.1:39658","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:18:54.968425Z","caller":"traceutil/trace.go:171","msg":"trace[748480168] linearizableReadLoop","detail":"{readStateIndex:1414; appliedIndex:1413; }","duration":"108.680233ms","start":"2026-04-16T16:18:54.859712Z","end":"2026-04-16T16:18:54.968392Z","steps":["trace[748480168] 'read index received' (duration: 94.896102ms)","trace[748480168] 'applied index is now lower than readState.Index' (duration: 13.783071ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:18:54.968595Z","caller":"traceutil/trace.go:171","msg":"trace[1120554650] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"110.419837ms","start":"2026-04-16T16:18:54.858139Z","end":"2026-04-16T16:18:54.968559Z","steps":["trace[1120554650] 'process raft request' (duration: 96.329225ms)","trace[1120554650] 'compare' (duration: 13.757739ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:18:54.96873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.961453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:endpointslicemirroring-controller\" ","response":"range_response_count:1 size:807"} {"level":"info","ts":"2026-04-16T16:18:54.968829Z","caller":"traceutil/trace.go:171","msg":"trace[1417230198] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:endpointslicemirroring-controller; range_end:; response_count:1; response_revision:1357; }","duration":"109.127549ms","start":"2026-04-16T16:18:54.85968Z","end":"2026-04-16T16:18:54.968807Z","steps":["trace[1417230198] 'agreement among raft nodes before linearized reading' (duration: 108.89018ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:19:16.853336Z","caller":"traceutil/trace.go:171","msg":"trace[1494563271] transaction","detail":"{read_only:false; response_revision:1428; number_of_response:1; }","duration":"108.103714ms","start":"2026-04-16T16:19:16.745194Z","end":"2026-04-16T16:19:16.853298Z","steps":["trace[1494563271] 'process raft request' (duration: 107.852235ms)"],"step_count":1}