{"level":"warn","ts":"2026-07-25T13:33:47.60828Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-25T13:33:47.608362Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.95: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.95:2380","--initial-cluster=instance=https://199.204.45.95:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.95:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.95:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-25T13:33:47.608422Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-25T13:33:47.608435Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-07-25T13:33:47.608452Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-25T13:33:47.609047Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"]} {"level":"info","ts":"2026-07-25T13:33:47.609141Z","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.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.95:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-25T13:33:47.617003Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.677433ms"} {"level":"info","ts":"2026-07-25T13:33:47.624749Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"be773dfc027af3eb","cluster-id":"9ba5fbd7288fd5af"} {"level":"info","ts":"2026-07-25T13:33:47.624803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=()"} {"level":"info","ts":"2026-07-25T13:33:47.624821Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 0"} {"level":"info","ts":"2026-07-25T13:33:47.624832Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft be773dfc027af3eb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-25T13:33:47.624839Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 1"} {"level":"info","ts":"2026-07-25T13:33:47.624871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"warn","ts":"2026-07-25T13:33:47.631571Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-25T13:33:47.633663Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-25T13:33:47.635225Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-25T13:33:47.639011Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"be773dfc027af3eb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-25T13:33:47.644176Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"be773dfc027af3eb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-25T13:33:47.644236Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-25T13:33:47.644532Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-25T13:33:47.644561Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-25T13:33:47.645678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"info","ts":"2026-07-25T13:33:47.646074Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","added-peer-id":"be773dfc027af3eb","added-peer-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-07-25T13:33:47.647759Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-25T13:33:47.647849Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-07-25T13:33:47.647889Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-07-25T13:33:47.648021Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"be773dfc027af3eb","initial-advertise-peer-urls":["https://199.204.45.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-25T13:33:47.648048Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-25T13:33:48.626006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb is starting a new election at term 1"} {"level":"info","ts":"2026-07-25T13:33:48.626064Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became pre-candidate at term 1"} {"level":"info","ts":"2026-07-25T13:33:48.626088Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgPreVoteResp from be773dfc027af3eb at term 1"} {"level":"info","ts":"2026-07-25T13:33:48.626108Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became candidate at term 2"} {"level":"info","ts":"2026-07-25T13:33:48.626131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgVoteResp from be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-07-25T13:33:48.626156Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became leader at term 2"} {"level":"info","ts":"2026-07-25T13:33:48.626174Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: be773dfc027af3eb elected leader be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-07-25T13:33:48.627031Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T13:33:48.627858Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"be773dfc027af3eb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.95:2379]}","request-path":"/0/members/be773dfc027af3eb/attributes","cluster-id":"9ba5fbd7288fd5af","publish-timeout":"7s"} {"level":"info","ts":"2026-07-25T13:33:48.628076Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-25T13:33:48.628153Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-25T13:33:48.628366Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T13:33:48.628477Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T13:33:48.62851Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T13:33:48.63178Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.95:2379"} {"level":"info","ts":"2026-07-25T13:33:48.632051Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-25T13:33:48.6339Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-25T13:33:48.633943Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-25T13:34:17.536272Z","caller":"traceutil/trace.go:171","msg":"trace[206571102] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"104.73932ms","start":"2026-07-25T13:34:17.431506Z","end":"2026-07-25T13:34:17.536245Z","steps":["trace[206571102] 'process raft request' (duration: 104.546576ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:32.726807Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576317452292254840,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-25T13:34:32.800826Z","caller":"traceutil/trace.go:171","msg":"trace[1081330287] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"667.242127ms","start":"2026-07-25T13:34:32.133559Z","end":"2026-07-25T13:34:32.800802Z","steps":["trace[1081330287] 'process raft request' (duration: 667.124625ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:32.801305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:32.133543Z","time spent":"667.351169ms","remote":"127.0.0.1:53444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T13:34:32.809575Z","caller":"traceutil/trace.go:171","msg":"trace[2133745472] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"673.358568ms","start":"2026-07-25T13:34:32.136197Z","end":"2026-07-25T13:34:32.809556Z","steps":["trace[2133745472] 'process raft request' (duration: 673.195606ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:32.809729Z","caller":"traceutil/trace.go:171","msg":"trace[1558946218] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"658.847721ms","start":"2026-07-25T13:34:32.150839Z","end":"2026-07-25T13:34:32.809686Z","steps":["trace[1558946218] 'process raft request' (duration: 658.723589ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:32.809766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:32.136168Z","time spent":"673.456649ms","remote":"127.0.0.1:53444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T13:34:32.809777Z","caller":"traceutil/trace.go:171","msg":"trace[1190921984] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"664.527226ms","start":"2026-07-25T13:34:32.145207Z","end":"2026-07-25T13:34:32.809734Z","steps":["trace[1190921984] 'process raft request' (duration: 664.312383ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:32.809891Z","caller":"traceutil/trace.go:171","msg":"trace[275643189] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"654.665489ms","start":"2026-07-25T13:34:32.155216Z","end":"2026-07-25T13:34:32.809882Z","steps":["trace[275643189] 'process raft request' (duration: 654.456406ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:32.80988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:32.150822Z","time spent":"658.995063ms","remote":"127.0.0.1:53444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T13:34:32.809973Z","caller":"traceutil/trace.go:171","msg":"trace[1242785698] linearizableReadLoop","detail":"{readStateIndex:531; appliedIndex:519; }","duration":"584.014886ms","start":"2026-07-25T13:34:32.225937Z","end":"2026-07-25T13:34:32.809952Z","steps":["trace[1242785698] 'read index received' (duration: 574.807689ms)","trace[1242785698] 'applied index is now lower than readState.Index' (duration: 9.206367ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T13:34:32.810003Z","caller":"traceutil/trace.go:171","msg":"trace[1136338691] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"654.921102ms","start":"2026-07-25T13:34:32.155067Z","end":"2026-07-25T13:34:32.809988Z","steps":["trace[1136338691] 'process raft request' (duration: 654.573637ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:32.810094Z","caller":"traceutil/trace.go:171","msg":"trace[1883669791] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"637.882349ms","start":"2026-07-25T13:34:32.172203Z","end":"2026-07-25T13:34:32.810085Z","steps":["trace[1883669791] 'process raft request' (duration: 637.566274ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:32.810101Z","caller":"traceutil/trace.go:171","msg":"trace[1590220217] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"515.102108ms","start":"2026-07-25T13:34:32.294991Z","end":"2026-07-25T13:34:32.810093Z","steps":["trace[1590220217] 'process raft request' (duration: 514.906365ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:32.810154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:32.294958Z","time spent":"515.17392ms","remote":"127.0.0.1:53444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T13:34:32.810167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:32.17219Z","time spent":"637.932779ms","remote":"127.0.0.1:53444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T13:34:32.810221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:32.155064Z","time spent":"655.042404ms","remote":"127.0.0.1:53444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T13:34:32.810063Z","caller":"traceutil/trace.go:171","msg":"trace[496068647] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"644.077041ms","start":"2026-07-25T13:34:32.165976Z","end":"2026-07-25T13:34:32.810053Z","steps":["trace[496068647] 'process raft request' (duration: 643.740946ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:32.810526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:32.165968Z","time spent":"644.533808ms","remote":"127.0.0.1:53444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T13:34:32.810266Z","caller":"traceutil/trace.go:171","msg":"trace[499997519] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"621.73943ms","start":"2026-07-25T13:34:32.188518Z","end":"2026-07-25T13:34:32.810257Z","steps":["trace[499997519] 'process raft request' (duration: 621.315963ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:32.810644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:32.188506Z","time spent":"622.095965ms","remote":"127.0.0.1:53444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T13:34:32.809679Z","caller":"traceutil/trace.go:171","msg":"trace[882607991] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"673.132615ms","start":"2026-07-25T13:34:32.136455Z","end":"2026-07-25T13:34:32.809588Z","steps":["trace[882607991] 'process raft request' (duration: 673.019883ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:32.811395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:32.136446Z","time spent":"674.89809ms","remote":"127.0.0.1:53444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T13:34:32.813683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.752155ms","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-07-25T13:34:32.813769Z","caller":"traceutil/trace.go:171","msg":"trace[1645373218] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:513; }","duration":"543.874448ms","start":"2026-07-25T13:34:32.269873Z","end":"2026-07-25T13:34:32.813747Z","steps":["trace[1645373218] 'agreement among raft nodes before linearized reading' (duration: 543.31607ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:32.813872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:32.269863Z","time spent":"543.995349ms","remote":"127.0.0.1:53700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-25T13:34:32.810036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:32.155211Z","time spent":"654.73343ms","remote":"127.0.0.1:53444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T13:34:32.810236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.253919ms","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-07-25T13:34:32.815724Z","caller":"traceutil/trace.go:171","msg":"trace[554619901] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:511; }","duration":"589.581199ms","start":"2026-07-25T13:34:32.225876Z","end":"2026-07-25T13:34:32.815457Z","steps":["trace[554619901] 'agreement among raft nodes before linearized reading' (duration: 584.215229ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:32.815773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:32.225856Z","time spent":"589.902303ms","remote":"127.0.0.1:53700","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-07-25T13:34:32.810364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:32.145179Z","time spent":"664.725098ms","remote":"127.0.0.1:53444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T13:34:32.809799Z","caller":"traceutil/trace.go:171","msg":"trace[1784027351] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"654.70629ms","start":"2026-07-25T13:34:32.155028Z","end":"2026-07-25T13:34:32.809734Z","steps":["trace[1784027351] 'process raft request' (duration: 654.576848ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:32.816536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:32.15502Z","time spent":"661.495401ms","remote":"127.0.0.1:53444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T13:34:34.325033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.465854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:7708"} {"level":"info","ts":"2026-07-25T13:34:34.325224Z","caller":"traceutil/trace.go:171","msg":"trace[362703322] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:576; }","duration":"296.967332ms","start":"2026-07-25T13:34:34.028213Z","end":"2026-07-25T13:34:34.325181Z","steps":["trace[362703322] 'range keys from in-memory index tree' (duration: 296.087209ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:34.36698Z","caller":"traceutil/trace.go:171","msg":"trace[180823613] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"265.275004ms","start":"2026-07-25T13:34:34.101655Z","end":"2026-07-25T13:34:34.36693Z","steps":["trace[180823613] 'process raft request' (duration: 260.809329ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:37.289047Z","caller":"traceutil/trace.go:171","msg":"trace[336313443] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:616; }","duration":"184.547016ms","start":"2026-07-25T13:34:37.104474Z","end":"2026-07-25T13:34:37.289021Z","steps":["trace[336313443] 'read index received' (duration: 181.880367ms)","trace[336313443] 'applied index is now lower than readState.Index' (duration: 2.665519ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T13:34:37.291283Z","caller":"traceutil/trace.go:171","msg":"trace[1837209992] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"192.684025ms","start":"2026-07-25T13:34:37.098583Z","end":"2026-07-25T13:34:37.291267Z","steps":["trace[1837209992] 'process raft request' (duration: 188.768398ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:37.291809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.316716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T13:34:37.291865Z","caller":"traceutil/trace.go:171","msg":"trace[2007903639] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:593; }","duration":"187.416157ms","start":"2026-07-25T13:34:37.104434Z","end":"2026-07-25T13:34:37.29185Z","steps":["trace[2007903639] 'agreement among raft nodes before linearized reading' (duration: 187.333056ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:37.407819Z","caller":"traceutil/trace.go:171","msg":"trace[1474304805] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"108.791912ms","start":"2026-07-25T13:34:37.298999Z","end":"2026-07-25T13:34:37.407791Z","steps":["trace[1474304805] 'process raft request' (duration: 99.160123ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:39.327723Z","caller":"traceutil/trace.go:171","msg":"trace[1236973711] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:626; }","duration":"472.096663ms","start":"2026-07-25T13:34:38.855597Z","end":"2026-07-25T13:34:39.327693Z","steps":["trace[1236973711] 'read index received' (duration: 470.976877ms)","trace[1236973711] 'applied index is now lower than readState.Index' (duration: 1.118416ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T13:34:39.327787Z","caller":"traceutil/trace.go:171","msg":"trace[994453695] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"510.29366ms","start":"2026-07-25T13:34:38.817449Z","end":"2026-07-25T13:34:39.327743Z","steps":["trace[994453695] 'process raft request' (duration: 509.285627ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:39.328067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.453778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-25T13:34:39.32813Z","caller":"traceutil/trace.go:171","msg":"trace[70743848] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:603; }","duration":"472.59978ms","start":"2026-07-25T13:34:38.855511Z","end":"2026-07-25T13:34:39.328111Z","steps":["trace[70743848] 'agreement among raft nodes before linearized reading' (duration: 472.349147ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:39.328184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:38.85548Z","time spent":"472.692231ms","remote":"127.0.0.1:53700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-25T13:34:39.328176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:38.817418Z","time spent":"510.461584ms","remote":"127.0.0.1:53496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036265,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T13:34:39.328149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.300986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T13:34:39.328278Z","caller":"traceutil/trace.go:171","msg":"trace[1648522402] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:603; }","duration":"225.497229ms","start":"2026-07-25T13:34:39.102753Z","end":"2026-07-25T13:34:39.32825Z","steps":["trace[1648522402] 'agreement among raft nodes before linearized reading' (duration: 225.268296ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:39.328392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.028731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-5ssvl\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-25T13:34:39.32857Z","caller":"traceutil/trace.go:171","msg":"trace[1572040525] range","detail":"{range_begin:/registry/pods/kube-system/cilium-5ssvl; range_end:; response_count:1; response_revision:603; }","duration":"192.246994ms","start":"2026-07-25T13:34:39.136306Z","end":"2026-07-25T13:34:39.328553Z","steps":["trace[1572040525] 'agreement among raft nodes before linearized reading' (duration: 191.719557ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:39.328464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.045391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-25T13:34:39.328837Z","caller":"traceutil/trace.go:171","msg":"trace[365946455] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:603; }","duration":"332.587788ms","start":"2026-07-25T13:34:38.996229Z","end":"2026-07-25T13:34:39.328817Z","steps":["trace[365946455] 'agreement among raft nodes before linearized reading' (duration: 331.952959ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:39.328897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:38.996216Z","time spent":"332.6659ms","remote":"127.0.0.1:53700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-25T13:34:39.329563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.694341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-25T13:34:39.333723Z","caller":"traceutil/trace.go:171","msg":"trace[574394242] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:603; }","duration":"449.828262ms","start":"2026-07-25T13:34:38.879814Z","end":"2026-07-25T13:34:39.329642Z","steps":["trace[574394242] 'agreement among raft nodes before linearized reading' (duration: 448.141308ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:39.333804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:38.879799Z","time spent":"453.987331ms","remote":"127.0.0.1:53700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-25T13:34:39.911651Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576317452292255007,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-25T13:34:40.411873Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576317452292255007,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-25T13:34:40.544649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.183867056s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T13:34:40.544743Z","caller":"traceutil/trace.go:171","msg":"trace[787468253] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"1.210388055s","start":"2026-07-25T13:34:39.334336Z","end":"2026-07-25T13:34:40.544724Z","steps":["trace[787468253] 'process raft request' (duration: 26.206524ms)","trace[787468253] 'compare' (duration: 1.183646833s)"],"step_count":2} {"level":"warn","ts":"2026-07-25T13:34:40.544852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:39.334308Z","time spent":"1.210510117s","remote":"127.0.0.1:53700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T13:34:40.679552Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.319013024s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-25T13:34:40.781761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.901767ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T13:34:40.781992Z","caller":"traceutil/trace.go:171","msg":"trace[1867015203] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:628; }","duration":"1.370996988s","start":"2026-07-25T13:34:39.41098Z","end":"2026-07-25T13:34:40.781977Z","steps":["trace[1867015203] 'read index received' (duration: 1.268893408s)","trace[1867015203] 'applied index is now lower than readState.Index' (duration: 102.10275ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T13:34:40.782158Z","caller":"traceutil/trace.go:171","msg":"trace[909460639] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"1.439338864s","start":"2026-07-25T13:34:39.342808Z","end":"2026-07-25T13:34:40.782147Z","steps":["trace[909460639] 'process raft request' (duration: 1.43901936s)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:40.78216Z","caller":"traceutil/trace.go:171","msg":"trace[409764126] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"1.44402365s","start":"2026-07-25T13:34:39.338116Z","end":"2026-07-25T13:34:40.782139Z","steps":["trace[409764126] 'process raft request' (duration: 1.341669227s)","trace[409764126] 'compare' (duration: 101.793265ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T13:34:40.782217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:39.34277Z","time spent":"1.439413364s","remote":"127.0.0.1:53700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T13:34:40.782249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:39.338077Z","time spent":"1.444127322s","remote":"127.0.0.1:53700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T13:34:40.782418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.371448583s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-25T13:34:40.782458Z","caller":"traceutil/trace.go:171","msg":"trace[807881099] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:608; }","duration":"1.371521965s","start":"2026-07-25T13:34:39.410925Z","end":"2026-07-25T13:34:40.782447Z","steps":["trace[807881099] 'agreement among raft nodes before linearized reading' (duration: 1.371404083s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:40.782484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:39.410912Z","time spent":"1.371566835s","remote":"127.0.0.1:53700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-25T13:34:40.782549Z","caller":"traceutil/trace.go:171","msg":"trace[379327747] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"1.399717608s","start":"2026-07-25T13:34:39.382824Z","end":"2026-07-25T13:34:40.782542Z","steps":["trace[379327747] 'process raft request' (duration: 1.399116119s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:40.782586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:39.382801Z","time spent":"1.399766309s","remote":"127.0.0.1:53798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T13:34:40.782656Z","caller":"traceutil/trace.go:171","msg":"trace[126384350] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"1.432325243s","start":"2026-07-25T13:34:39.350299Z","end":"2026-07-25T13:34:40.782624Z","steps":["trace[126384350] 'process raft request' (duration: 1.431583412s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:40.782821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:39.350265Z","time spent":"1.432472245s","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T13:34:40.798568Z","caller":"traceutil/trace.go:171","msg":"trace[318893026] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"100.209443ms","start":"2026-07-25T13:34:40.698342Z","end":"2026-07-25T13:34:40.798552Z","steps":["trace[318893026] 'process raft request' (duration: 100.055641ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:40.798684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.254713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T13:34:40.798773Z","caller":"traceutil/trace.go:171","msg":"trace[135401555] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:609; }","duration":"694.391495ms","start":"2026-07-25T13:34:40.10437Z","end":"2026-07-25T13:34:40.798761Z","steps":["trace[135401555] 'agreement among raft nodes before linearized reading' (duration: 694.280863ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:40.798805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:40.104348Z","time spent":"694.450526ms","remote":"127.0.0.1:55050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-25T13:34:41.935421Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576317452292255018,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-25T13:34:42.268582Z","caller":"traceutil/trace.go:171","msg":"trace[1042894451] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:634; }","duration":"833.232434ms","start":"2026-07-25T13:34:41.435319Z","end":"2026-07-25T13:34:42.268552Z","steps":["trace[1042894451] 'read index received' (duration: 832.406942ms)","trace[1042894451] 'applied index is now lower than readState.Index' (duration: 824.312µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T13:34:42.268631Z","caller":"traceutil/trace.go:171","msg":"trace[1397940099] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"1.058086586s","start":"2026-07-25T13:34:41.210516Z","end":"2026-07-25T13:34:42.268603Z","steps":["trace[1397940099] 'process raft request' (duration: 1.057502317s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:42.268753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"833.429586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.95\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-07-25T13:34:42.268778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:41.210493Z","time spent":"1.058190478s","remote":"127.0.0.1:53496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036297,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T13:34:42.268804Z","caller":"traceutil/trace.go:171","msg":"trace[170620004] range","detail":"{range_begin:/registry/masterleases/199.204.45.95; range_end:; response_count:1; response_revision:611; }","duration":"833.518218ms","start":"2026-07-25T13:34:41.435267Z","end":"2026-07-25T13:34:42.268786Z","steps":["trace[170620004] 'agreement among raft nodes before linearized reading' (duration: 833.426216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:42.26891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:41.435246Z","time spent":"833.649649ms","remote":"127.0.0.1:53416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.95\" "} {"level":"warn","ts":"2026-07-25T13:34:42.268963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.787782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T13:34:42.269056Z","caller":"traceutil/trace.go:171","msg":"trace[155007889] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:611; }","duration":"164.934183ms","start":"2026-07-25T13:34:42.104098Z","end":"2026-07-25T13:34:42.269032Z","steps":["trace[155007889] 'agreement among raft nodes before linearized reading' (duration: 164.759221ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:47.43808Z","caller":"traceutil/trace.go:171","msg":"trace[161233566] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"123.505814ms","start":"2026-07-25T13:34:47.314547Z","end":"2026-07-25T13:34:47.438053Z","steps":["trace[161233566] 'process raft request' (duration: 123.328382ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:55.374396Z","caller":"traceutil/trace.go:171","msg":"trace[1413173840] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"137.473997ms","start":"2026-07-25T13:34:55.236903Z","end":"2026-07-25T13:34:55.374377Z","steps":["trace[1413173840] 'process raft request' (duration: 137.354375ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:58.485493Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576317452292255409,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-25T13:34:58.986429Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576317452292255409,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-25T13:34:58.991717Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.032593289s","expected-duration":"1s"} {"level":"info","ts":"2026-07-25T13:34:58.99208Z","caller":"traceutil/trace.go:171","msg":"trace[103959466] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"1.033029885s","start":"2026-07-25T13:34:57.959025Z","end":"2026-07-25T13:34:58.992055Z","steps":["trace[103959466] 'process raft request' (duration: 1.032851462s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:58.992239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:57.959011Z","time spent":"1.033138806s","remote":"127.0.0.1:53700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T13:34:59.005294Z","caller":"traceutil/trace.go:171","msg":"trace[1082739558] linearizableReadLoop","detail":"{readStateIndex:810; appliedIndex:808; }","duration":"1.020600083s","start":"2026-07-25T13:34:57.984672Z","end":"2026-07-25T13:34:59.005272Z","steps":["trace[1082739558] 'read index received' (duration: 1.007251271s)","trace[1082739558] 'applied index is now lower than readState.Index' (duration: 13.347752ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T13:34:59.005494Z","caller":"traceutil/trace.go:171","msg":"trace[1465241981] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"748.238305ms","start":"2026-07-25T13:34:58.257149Z","end":"2026-07-25T13:34:59.005388Z","steps":["trace[1465241981] 'process raft request' (duration: 748.006921ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:59.005667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:58.257126Z","time spent":"748.408766ms","remote":"127.0.0.1:53654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T13:34:59.005803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.021090149s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T13:34:59.005914Z","caller":"traceutil/trace.go:171","msg":"trace[1838120479] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:780; }","duration":"1.021250071s","start":"2026-07-25T13:34:57.984645Z","end":"2026-07-25T13:34:59.005896Z","steps":["trace[1838120479] 'agreement among raft nodes before linearized reading' (duration: 1.021053028s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:59.005936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.192293ms","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-07-25T13:34:59.005971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:57.984635Z","time spent":"1.021324751s","remote":"127.0.0.1:38916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 "} {"level":"info","ts":"2026-07-25T13:34:59.005996Z","caller":"traceutil/trace.go:171","msg":"trace[13706869] 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:780; }","duration":"918.268594ms","start":"2026-07-25T13:34:58.087715Z","end":"2026-07-25T13:34:59.005983Z","steps":["trace[13706869] 'agreement among raft nodes before linearized reading' (duration: 918.166543ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:59.006036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"919.204355ms","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":"warn","ts":"2026-07-25T13:34:59.006039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"935.019449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T13:34:59.006079Z","caller":"traceutil/trace.go:171","msg":"trace[2111510777] 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:780; }","duration":"919.254466ms","start":"2026-07-25T13:34:58.086812Z","end":"2026-07-25T13:34:59.006067Z","steps":["trace[2111510777] 'agreement among raft nodes before linearized reading' (duration: 919.206195ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:59.006098Z","caller":"traceutil/trace.go:171","msg":"trace[1114267269] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:780; }","duration":"935.08986ms","start":"2026-07-25T13:34:58.070989Z","end":"2026-07-25T13:34:59.006079Z","steps":["trace[1114267269] 'agreement among raft nodes before linearized reading' (duration: 935.030799ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:59.006119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:58.086803Z","time spent":"919.307646ms","remote":"127.0.0.1:38978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"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-07-25T13:34:59.006145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:58.070975Z","time spent":"935.160261ms","remote":"127.0.0.1:38972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 "} {"level":"warn","ts":"2026-07-25T13:34:59.006233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.389397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-25T13:34:59.00627Z","caller":"traceutil/trace.go:171","msg":"trace[711047054] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:780; }","duration":"119.460947ms","start":"2026-07-25T13:34:58.8868Z","end":"2026-07-25T13:34:59.006261Z","steps":["trace[711047054] 'agreement among raft nodes before linearized reading' (duration: 119.260975ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:59.005999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.983402ms","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-07-25T13:34:59.006391Z","caller":"traceutil/trace.go:171","msg":"trace[1506349034] 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:780; }","duration":"919.379547ms","start":"2026-07-25T13:34:58.086993Z","end":"2026-07-25T13:34:59.006373Z","steps":["trace[1506349034] 'agreement among raft nodes before linearized reading' (duration: 918.983863ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:59.006439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:58.086988Z","time spent":"919.436618ms","remote":"127.0.0.1:38978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"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-07-25T13:34:59.00604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:58.087708Z","time spent":"918.322654ms","remote":"127.0.0.1:38978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"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-07-25T13:34:59.005942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"934.557763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T13:34:59.006577Z","caller":"traceutil/trace.go:171","msg":"trace[386040448] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:780; }","duration":"935.207572ms","start":"2026-07-25T13:34:58.071359Z","end":"2026-07-25T13:34:59.006567Z","steps":["trace[386040448] 'agreement among raft nodes before linearized reading' (duration: 934.528223ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:59.006608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:58.071328Z","time spent":"935.269562ms","remote":"127.0.0.1:38972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 "} {"level":"warn","ts":"2026-07-25T13:34:59.006625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.010062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-25T13:34:59.006662Z","caller":"traceutil/trace.go:171","msg":"trace[20924843] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:780; }","duration":"140.078714ms","start":"2026-07-25T13:34:58.866571Z","end":"2026-07-25T13:34:59.006649Z","steps":["trace[20924843] 'agreement among raft nodes before linearized reading' (duration: 139.950691ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:59.006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"934.941048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-25T13:34:59.006752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.719814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-25T13:34:59.006788Z","caller":"traceutil/trace.go:171","msg":"trace[1796113636] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:780; }","duration":"935.742029ms","start":"2026-07-25T13:34:58.071034Z","end":"2026-07-25T13:34:59.006776Z","steps":["trace[1796113636] 'agreement among raft nodes before linearized reading' (duration: 934.940139ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:59.006834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:58.071028Z","time spent":"935.79272ms","remote":"127.0.0.1:38972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-07-25T13:34:59.006875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"950.66306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T13:34:59.006901Z","caller":"traceutil/trace.go:171","msg":"trace[809218220] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:780; }","duration":"950.709111ms","start":"2026-07-25T13:34:58.056183Z","end":"2026-07-25T13:34:59.006892Z","steps":["trace[809218220] 'agreement among raft nodes before linearized reading' (duration: 950.67127ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:59.006916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.006930466s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-25T13:34:59.006948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:58.056172Z","time spent":"950.767381ms","remote":"127.0.0.1:38964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-07-25T13:34:59.006979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.007007727s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-25T13:34:59.007029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.005829282s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T13:34:59.007065Z","caller":"traceutil/trace.go:171","msg":"trace[562104957] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:780; }","duration":"1.005892193s","start":"2026-07-25T13:34:58.001165Z","end":"2026-07-25T13:34:59.007057Z","steps":["trace[562104957] 'agreement among raft nodes before linearized reading' (duration: 1.005846722s)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:59.007074Z","caller":"traceutil/trace.go:171","msg":"trace[446294775] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:780; }","duration":"1.007114248s","start":"2026-07-25T13:34:57.999941Z","end":"2026-07-25T13:34:59.007055Z","steps":["trace[446294775] 'agreement among raft nodes before linearized reading' (duration: 1.006987416s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:59.007102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:58.001149Z","time spent":"1.005945163s","remote":"127.0.0.1:38926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 "} {"level":"warn","ts":"2026-07-25T13:34:59.007112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:57.999933Z","time spent":"1.007167568s","remote":"127.0.0.1:38926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 "} {"level":"warn","ts":"2026-07-25T13:34:59.007145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.021297982s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T13:34:59.007188Z","caller":"traceutil/trace.go:171","msg":"trace[1199497337] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:780; }","duration":"1.021388073s","start":"2026-07-25T13:34:57.98579Z","end":"2026-07-25T13:34:59.007179Z","steps":["trace[1199497337] 'agreement among raft nodes before linearized reading' (duration: 1.021324052s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:59.007208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"967.395695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-25T13:34:59.007224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:57.985768Z","time spent":"1.021447794s","remote":"127.0.0.1:38916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 "} {"level":"info","ts":"2026-07-25T13:34:59.007268Z","caller":"traceutil/trace.go:171","msg":"trace[2115807373] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:780; }","duration":"967.446557ms","start":"2026-07-25T13:34:58.03978Z","end":"2026-07-25T13:34:59.007226Z","steps":["trace[2115807373] 'agreement among raft nodes before linearized reading' (duration: 967.402956ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:59.007298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:58.039765Z","time spent":"967.524957ms","remote":"127.0.0.1:38950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"warn","ts":"2026-07-25T13:34:59.006837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"984.72728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T13:34:59.007334Z","caller":"traceutil/trace.go:171","msg":"trace[703112632] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:780; }","duration":"985.244946ms","start":"2026-07-25T13:34:58.022079Z","end":"2026-07-25T13:34:59.007324Z","steps":["trace[703112632] 'agreement among raft nodes before linearized reading' (duration: 984.73387ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:59.007367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:58.022067Z","time spent":"985.290366ms","remote":"127.0.0.1:38942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"warn","ts":"2026-07-25T13:34:59.007451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"966.951479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-25T13:34:59.007292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"951.087195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T13:34:59.007486Z","caller":"traceutil/trace.go:171","msg":"trace[959489448] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:780; }","duration":"966.999121ms","start":"2026-07-25T13:34:58.040479Z","end":"2026-07-25T13:34:59.007478Z","steps":["trace[959489448] 'agreement among raft nodes before linearized reading' (duration: 966.95001ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:59.007514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:58.040471Z","time spent":"967.035841ms","remote":"127.0.0.1:38942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 "} {"level":"info","ts":"2026-07-25T13:34:59.007511Z","caller":"traceutil/trace.go:171","msg":"trace[271816852] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:780; }","duration":"951.415489ms","start":"2026-07-25T13:34:58.056083Z","end":"2026-07-25T13:34:59.007498Z","steps":["trace[271816852] 'agreement among raft nodes before linearized reading' (duration: 951.081305ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:59.007019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.800689ms","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-07-25T13:34:59.007635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"967.648339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T13:34:59.007681Z","caller":"traceutil/trace.go:171","msg":"trace[1223301121] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:780; }","duration":"967.75309ms","start":"2026-07-25T13:34:58.039917Z","end":"2026-07-25T13:34:59.00767Z","steps":["trace[1223301121] 'agreement among raft nodes before linearized reading' (duration: 967.685199ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:59.007719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:58.039867Z","time spent":"967.843082ms","remote":"127.0.0.1:38950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-25T13:34:59.008329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.022836201s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T13:34:59.00844Z","caller":"traceutil/trace.go:171","msg":"trace[1048266829] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:780; }","duration":"1.022992963s","start":"2026-07-25T13:34:57.985421Z","end":"2026-07-25T13:34:59.008414Z","steps":["trace[1048266829] 'agreement among raft nodes before linearized reading' (duration: 1.020347279s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:59.009049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:57.985409Z","time spent":"1.02359306s","remote":"127.0.0.1:38916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"info","ts":"2026-07-25T13:34:59.00679Z","caller":"traceutil/trace.go:171","msg":"trace[1346860631] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:780; }","duration":"144.783964ms","start":"2026-07-25T13:34:58.861997Z","end":"2026-07-25T13:34:59.006781Z","steps":["trace[1346860631] 'agreement among raft nodes before linearized reading' (duration: 144.684123ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:59.006954Z","caller":"traceutil/trace.go:171","msg":"trace[138020840] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:780; }","duration":"1.006973656s","start":"2026-07-25T13:34:57.999968Z","end":"2026-07-25T13:34:59.006941Z","steps":["trace[138020840] 'agreement among raft nodes before linearized reading' (duration: 1.006917576s)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:59.010279Z","caller":"traceutil/trace.go:171","msg":"trace[1379588634] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:780; }","duration":"376.536448ms","start":"2026-07-25T13:34:58.631119Z","end":"2026-07-25T13:34:59.007655Z","steps":["trace[1379588634] 'agreement among raft nodes before linearized reading' (duration: 375.231731ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:59.010437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:58.631063Z","time spent":"379.355114ms","remote":"127.0.0.1:53700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-25T13:34:59.009557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:57.999964Z","time spent":"1.00957202s","remote":"127.0.0.1:38926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-07-25T13:34:59.005914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"948.900258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T13:34:59.0109Z","caller":"traceutil/trace.go:171","msg":"trace[9109636] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:780; }","duration":"953.939523ms","start":"2026-07-25T13:34:58.056945Z","end":"2026-07-25T13:34:59.010884Z","steps":["trace[9109636] 'agreement among raft nodes before linearized reading' (duration: 948.874198ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:59.01095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:58.056934Z","time spent":"954.000954ms","remote":"127.0.0.1:38964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 "} {"level":"warn","ts":"2026-07-25T13:34:59.006493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.100688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T13:34:59.01109Z","caller":"traceutil/trace.go:171","msg":"trace[1711832676] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:780; }","duration":"907.727398ms","start":"2026-07-25T13:34:58.103347Z","end":"2026-07-25T13:34:59.011075Z","steps":["trace[1711832676] 'agreement among raft nodes before linearized reading' (duration: 903.119149ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:59.011152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:58.103334Z","time spent":"907.796329ms","remote":"127.0.0.1:55066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-25T13:34:59.006993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"984.921902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T13:34:59.011341Z","caller":"traceutil/trace.go:171","msg":"trace[442101510] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:780; }","duration":"989.277208ms","start":"2026-07-25T13:34:58.022052Z","end":"2026-07-25T13:34:59.01133Z","steps":["trace[442101510] 'agreement among raft nodes before linearized reading' (duration: 984.920942ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:59.011384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:58.022046Z","time spent":"989.324278ms","remote":"127.0.0.1:38942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-25T13:34:59.007582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:58.056075Z","time spent":"951.489501ms","remote":"127.0.0.1:38964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"info","ts":"2026-07-25T13:35:53.602184Z","caller":"traceutil/trace.go:171","msg":"trace[207720442] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"120.877662ms","start":"2026-07-25T13:35:53.481269Z","end":"2026-07-25T13:35:53.602147Z","steps":["trace[207720442] 'process raft request' (duration: 120.734779ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:35:55.364391Z","caller":"traceutil/trace.go:171","msg":"trace[1522761231] linearizableReadLoop","detail":"{readStateIndex:1104; appliedIndex:1103; }","duration":"260.74786ms","start":"2026-07-25T13:35:55.103606Z","end":"2026-07-25T13:35:55.364354Z","steps":["trace[1522761231] 'read index received' (duration: 260.496408ms)","trace[1522761231] 'applied index is now lower than readState.Index' (duration: 250.102µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T13:35:55.36449Z","caller":"traceutil/trace.go:171","msg":"trace[175050746] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"279.468156ms","start":"2026-07-25T13:35:55.084987Z","end":"2026-07-25T13:35:55.364455Z","steps":["trace[175050746] 'process raft request' (duration: 279.190884ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:35:55.364579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.940522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T13:35:55.364627Z","caller":"traceutil/trace.go:171","msg":"trace[1646684749] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1063; }","duration":"261.046473ms","start":"2026-07-25T13:35:55.103566Z","end":"2026-07-25T13:35:55.364612Z","steps":["trace[1646684749] 'agreement among raft nodes before linearized reading' (duration: 260.947962ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:35:55.364825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.766177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-25T13:35:55.364876Z","caller":"traceutil/trace.go:171","msg":"trace[1009917256] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1063; }","duration":"122.894248ms","start":"2026-07-25T13:35:55.241971Z","end":"2026-07-25T13:35:55.364865Z","steps":["trace[1009917256] 'agreement among raft nodes before linearized reading' (duration: 122.714016ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:36:22.284499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.950447ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T13:36:22.284621Z","caller":"traceutil/trace.go:171","msg":"trace[762016463] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"756.557023ms","start":"2026-07-25T13:36:21.528045Z","end":"2026-07-25T13:36:22.284602Z","steps":["trace[762016463] 'process raft request' (duration: 490.421675ms)","trace[762016463] 'compare' (duration: 265.696295ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T13:36:22.284678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:36:21.528021Z","time spent":"756.629855ms","remote":"127.0.0.1:53416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T13:36:22.290377Z","caller":"traceutil/trace.go:171","msg":"trace[1373033816] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"759.134787ms","start":"2026-07-25T13:36:21.531228Z","end":"2026-07-25T13:36:22.290362Z","steps":["trace[1373033816] 'process raft request' (duration: 759.079666ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:36:22.290394Z","caller":"traceutil/trace.go:171","msg":"trace[1644621272] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"759.890673ms","start":"2026-07-25T13:36:21.53047Z","end":"2026-07-25T13:36:22.29036Z","steps":["trace[1644621272] 'process raft request' (duration: 759.775512ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:36:22.290437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:36:21.531205Z","time spent":"759.204177ms","remote":"127.0.0.1:53700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T13:36:22.290513Z","caller":"traceutil/trace.go:171","msg":"trace[24541502] linearizableReadLoop","detail":"{readStateIndex:1197; appliedIndex:1194; }","duration":"719.149422ms","start":"2026-07-25T13:36:21.571356Z","end":"2026-07-25T13:36:22.290505Z","steps":["trace[24541502] 'read index received' (duration: 447.140952ms)","trace[24541502] 'applied index is now lower than readState.Index' (duration: 272.00778ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T13:36:22.290586Z","caller":"traceutil/trace.go:171","msg":"trace[1415118141] transaction","detail":"{read_only:false; response_revision:1151; number_of_response:1; }","duration":"606.942338ms","start":"2026-07-25T13:36:21.683636Z","end":"2026-07-25T13:36:22.290578Z","steps":["trace[1415118141] 'process raft request' (duration: 606.698065ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:36:22.290605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:36:21.530453Z","time spent":"760.008394ms","remote":"127.0.0.1:53700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T13:36:22.290662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:36:21.68362Z","time spent":"606.9846ms","remote":"127.0.0.1:53700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T13:36:22.290837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.478675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-25T13:36:22.290863Z","caller":"traceutil/trace.go:171","msg":"trace[384891966] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1151; }","duration":"719.522107ms","start":"2026-07-25T13:36:21.571333Z","end":"2026-07-25T13:36:22.290855Z","steps":["trace[384891966] 'agreement among raft nodes before linearized reading' (duration: 719.413796ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:36:22.290856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.678564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-25T13:36:22.291008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.347873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-25T13:36:22.290927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.508077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-25T13:36:22.291058Z","caller":"traceutil/trace.go:171","msg":"trace[2121897984] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1151; }","duration":"703.410054ms","start":"2026-07-25T13:36:21.587632Z","end":"2026-07-25T13:36:22.291042Z","steps":["trace[2121897984] 'agreement among raft nodes before linearized reading' (duration: 703.333573ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:36:22.291079Z","caller":"traceutil/trace.go:171","msg":"trace[911108917] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1151; }","duration":"710.676608ms","start":"2026-07-25T13:36:21.580369Z","end":"2026-07-25T13:36:22.291046Z","steps":["trace[911108917] 'agreement among raft nodes before linearized reading' (duration: 710.413606ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:36:22.291092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:36:21.587621Z","time spent":"703.465814ms","remote":"127.0.0.1:53700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-25T13:36:22.291123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:36:21.580339Z","time spent":"710.772619ms","remote":"127.0.0.1:53700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-25T13:36:22.291017Z","caller":"traceutil/trace.go:171","msg":"trace[219176134] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1151; }","duration":"186.836726ms","start":"2026-07-25T13:36:22.104163Z","end":"2026-07-25T13:36:22.291Z","steps":["trace[219176134] 'agreement among raft nodes before linearized reading' (duration: 186.646874ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:36:22.291185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:36:21.571321Z","time spent":"719.853389ms","remote":"127.0.0.1:53700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-25T13:36:22.510816Z","caller":"traceutil/trace.go:171","msg":"trace[207252002] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"215.424019ms","start":"2026-07-25T13:36:22.295371Z","end":"2026-07-25T13:36:22.510795Z","steps":["trace[207252002] 'process raft request' (duration: 204.314171ms)","trace[207252002] 'compare' (duration: 10.667334ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T13:36:22.554883Z","caller":"traceutil/trace.go:171","msg":"trace[1377321257] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"259.284948ms","start":"2026-07-25T13:36:22.295586Z","end":"2026-07-25T13:36:22.554871Z","steps":["trace[1377321257] 'process raft request' (duration: 259.184817ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:36:22.555078Z","caller":"traceutil/trace.go:171","msg":"trace[23492192] linearizableReadLoop","detail":"{readStateIndex:1201; appliedIndex:1198; }","duration":"257.769874ms","start":"2026-07-25T13:36:22.297283Z","end":"2026-07-25T13:36:22.555053Z","steps":["trace[23492192] 'read index received' (duration: 202.395844ms)","trace[23492192] 'applied index is now lower than readState.Index' (duration: 55.37207ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T13:36:22.555269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.956487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-25T13:36:22.555362Z","caller":"traceutil/trace.go:171","msg":"trace[975885679] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1154; }","duration":"258.080497ms","start":"2026-07-25T13:36:22.297258Z","end":"2026-07-25T13:36:22.555338Z","steps":["trace[975885679] 'agreement among raft nodes before linearized reading' (duration: 257.882725ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:36:22.555117Z","caller":"traceutil/trace.go:171","msg":"trace[882252790] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"259.259297ms","start":"2026-07-25T13:36:22.295849Z","end":"2026-07-25T13:36:22.555108Z","steps":["trace[882252790] 'process raft request' (duration: 258.998495ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:36:23.395656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.063975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-25T13:36:23.39577Z","caller":"traceutil/trace.go:171","msg":"trace[1103424804] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1155; }","duration":"242.249576ms","start":"2026-07-25T13:36:23.153495Z","end":"2026-07-25T13:36:23.395745Z","steps":["trace[1103424804] 'count revisions from in-memory index tree' (duration: 241.972835ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:36:26.024884Z","caller":"traceutil/trace.go:171","msg":"trace[913683913] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"334.337753ms","start":"2026-07-25T13:36:25.69051Z","end":"2026-07-25T13:36:26.024847Z","steps":["trace[913683913] 'process raft request' (duration: 334.0594ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:36:26.025113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:36:25.690486Z","time spent":"334.542934ms","remote":"127.0.0.1:53700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T13:36:26.436835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.93472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T13:36:26.43739Z","caller":"traceutil/trace.go:171","msg":"trace[756231070] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"130.88038ms","start":"2026-07-25T13:36:26.306495Z","end":"2026-07-25T13:36:26.437376Z","steps":["trace[756231070] 'process raft request' (duration: 86.480587ms)","trace[756231070] 'compare' (duration: 43.773108ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T13:36:26.436977Z","caller":"traceutil/trace.go:171","msg":"trace[941624993] linearizableReadLoop","detail":"{readStateIndex:1210; appliedIndex:1209; }","duration":"127.012836ms","start":"2026-07-25T13:36:26.309945Z","end":"2026-07-25T13:36:26.436958Z","steps":["trace[941624993] 'read index received' (duration: 82.947535ms)","trace[941624993] 'applied index is now lower than readState.Index' (duration: 44.064451ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T13:36:26.437238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.274858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-25T13:36:26.437672Z","caller":"traceutil/trace.go:171","msg":"trace[345937072] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1163; }","duration":"127.707972ms","start":"2026-07-25T13:36:26.309939Z","end":"2026-07-25T13:36:26.437647Z","steps":["trace[345937072] 'agreement among raft nodes before linearized reading' (duration: 127.169367ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:36:26.437401Z","caller":"traceutil/trace.go:171","msg":"trace[1840109503] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1162; }","duration":"333.546745ms","start":"2026-07-25T13:36:26.103834Z","end":"2026-07-25T13:36:26.437381Z","steps":["trace[1840109503] 'range keys from in-memory index tree' (duration: 332.796709ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:36:26.437776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.561882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-25T13:36:26.437847Z","caller":"traceutil/trace.go:171","msg":"trace[1904755772] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1163; }","duration":"127.634932ms","start":"2026-07-25T13:36:26.310197Z","end":"2026-07-25T13:36:26.437832Z","steps":["trace[1904755772] 'agreement among raft nodes before linearized reading' (duration: 127.46655ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:36:26.437955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:36:26.103812Z","time spent":"334.128081ms","remote":"127.0.0.1:55050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-25T13:36:26.704857Z","caller":"traceutil/trace.go:171","msg":"trace[1323003694] linearizableReadLoop","detail":"{readStateIndex:1212; appliedIndex:1211; }","duration":"121.263894ms","start":"2026-07-25T13:36:26.583574Z","end":"2026-07-25T13:36:26.704838Z","steps":["trace[1323003694] 'read index received' (duration: 121.026002ms)","trace[1323003694] 'applied index is now lower than readState.Index' (duration: 235.022µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T13:36:26.704893Z","caller":"traceutil/trace.go:171","msg":"trace[1442541013] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"261.82214ms","start":"2026-07-25T13:36:26.443044Z","end":"2026-07-25T13:36:26.704866Z","steps":["trace[1442541013] 'process raft request' (duration: 261.649119ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:36:26.70508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.498546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-25T13:36:26.70512Z","caller":"traceutil/trace.go:171","msg":"trace[1801400770] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1165; }","duration":"121.590906ms","start":"2026-07-25T13:36:26.583519Z","end":"2026-07-25T13:36:26.70511Z","steps":["trace[1801400770] 'agreement among raft nodes before linearized reading' (duration: 121.428645ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:37:55.226064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.392846ms","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-07-25T13:37:55.226743Z","caller":"traceutil/trace.go:171","msg":"trace[626023231] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1453; }","duration":"108.090296ms","start":"2026-07-25T13:37:55.118632Z","end":"2026-07-25T13:37:55.226722Z","steps":["trace[626023231] 'range keys from in-memory index tree' (duration: 107.240145ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:37:55.226249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.580467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T13:37:55.227048Z","caller":"traceutil/trace.go:171","msg":"trace[242698013] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1453; }","duration":"123.412348ms","start":"2026-07-25T13:37:55.103621Z","end":"2026-07-25T13:37:55.227033Z","steps":["trace[242698013] 'range keys from in-memory index tree' (duration: 122.514007ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:37:57.098168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.291004ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-25T13:37:57.766858Z","caller":"traceutil/trace.go:171","msg":"trace[638153795] transaction","detail":"{read_only:false; response_revision:1462; number_of_response:1; }","duration":"435.64625ms","start":"2026-07-25T13:37:57.331188Z","end":"2026-07-25T13:37:57.766834Z","steps":["trace[638153795] 'process raft request' (duration: 433.756632ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:37:57.766996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:37:57.331157Z","time spent":"435.769121ms","remote":"127.0.0.1:53700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T13:37:58.694811Z","caller":"traceutil/trace.go:171","msg":"trace[1033814815] transaction","detail":"{read_only:false; response_revision:1464; number_of_response:1; }","duration":"1.362405777s","start":"2026-07-25T13:37:57.332386Z","end":"2026-07-25T13:37:58.694792Z","steps":["trace[1033814815] 'process raft request' (duration: 1.362320228s)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:37:58.694848Z","caller":"traceutil/trace.go:171","msg":"trace[737349522] transaction","detail":"{read_only:false; response_revision:1465; number_of_response:1; }","duration":"1.049965157s","start":"2026-07-25T13:37:57.644862Z","end":"2026-07-25T13:37:58.694827Z","steps":["trace[737349522] 'process raft request' (duration: 1.049904591s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:37:58.694924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:37:57.332372Z","time spent":"1.362497044s","remote":"127.0.0.1:53700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T13:37:58.694932Z","caller":"traceutil/trace.go:171","msg":"trace[229334328] transaction","detail":"{read_only:false; response_revision:1463; number_of_response:1; }","duration":"1.362543158s","start":"2026-07-25T13:37:57.332374Z","end":"2026-07-25T13:37:58.694917Z","steps":["trace[229334328] 'process raft request' (duration: 1.357268002s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:37:58.694957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:37:57.644837Z","time spent":"1.050064315s","remote":"127.0.0.1:53700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T13:37:58.694991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:37:57.332357Z","time spent":"1.362604713s","remote":"127.0.0.1:53700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T13:38:05.589615Z","caller":"traceutil/trace.go:171","msg":"trace[845510556] transaction","detail":"{read_only:false; response_revision:1487; number_of_response:1; }","duration":"229.35053ms","start":"2026-07-25T13:38:05.360215Z","end":"2026-07-25T13:38:05.589566Z","steps":["trace[845510556] 'process raft request' (duration: 229.168003ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:38:06.134954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.796523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-25T13:38:06.135011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.169838ms","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":"info","ts":"2026-07-25T13:38:06.135096Z","caller":"traceutil/trace.go:171","msg":"trace[1908677128] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1487; }","duration":"332.009842ms","start":"2026-07-25T13:38:05.803056Z","end":"2026-07-25T13:38:06.135065Z","steps":["trace[1908677128] 'range keys from in-memory index tree' (duration: 331.53097ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:38:06.135115Z","caller":"traceutil/trace.go:171","msg":"trace[563572807] 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:1487; }","duration":"285.334563ms","start":"2026-07-25T13:38:05.849756Z","end":"2026-07-25T13:38:06.135091Z","steps":["trace[563572807] 'count revisions from in-memory index tree' (duration: 285.070539ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:38:06.135162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:38:05.803033Z","time spent":"332.118481ms","remote":"127.0.0.1:53700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-25T13:38:06.257222Z","caller":"traceutil/trace.go:171","msg":"trace[175423152] linearizableReadLoop","detail":"{readStateIndex:1555; appliedIndex:1554; }","duration":"153.577235ms","start":"2026-07-25T13:38:06.103614Z","end":"2026-07-25T13:38:06.257191Z","steps":["trace[175423152] 'read index received' (duration: 153.500769ms)","trace[175423152] 'applied index is now lower than readState.Index' (duration: 75.066µs)"],"step_count":2} {"level":"warn","ts":"2026-07-25T13:38:06.257365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.73569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T13:38:06.257414Z","caller":"traceutil/trace.go:171","msg":"trace[1329585049] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1488; }","duration":"153.856131ms","start":"2026-07-25T13:38:06.103544Z","end":"2026-07-25T13:38:06.257401Z","steps":["trace[1329585049] 'agreement among raft nodes before linearized reading' (duration: 153.762711ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:38:06.257302Z","caller":"traceutil/trace.go:171","msg":"trace[762021900] transaction","detail":"{read_only:false; response_revision:1488; number_of_response:1; }","duration":"311.879489ms","start":"2026-07-25T13:38:05.945392Z","end":"2026-07-25T13:38:06.257272Z","steps":["trace[762021900] 'process raft request' (duration: 311.614506ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:38:06.257723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:38:05.945368Z","time spent":"312.236822ms","remote":"127.0.0.1:53700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T13:38:06.279981Z","caller":"traceutil/trace.go:171","msg":"trace[1541089231] transaction","detail":"{read_only:false; response_revision:1489; number_of_response:1; }","duration":"139.434296ms","start":"2026-07-25T13:38:06.140528Z","end":"2026-07-25T13:38:06.279962Z","steps":["trace[1541089231] 'process raft request' (duration: 139.246218ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:38:07.117201Z","caller":"traceutil/trace.go:171","msg":"trace[1857226315] linearizableReadLoop","detail":"{readStateIndex:1559; appliedIndex:1557; }","duration":"180.765003ms","start":"2026-07-25T13:38:06.936401Z","end":"2026-07-25T13:38:07.117166Z","steps":["trace[1857226315] 'read index received' (duration: 137.354592ms)","trace[1857226315] 'applied index is now lower than readState.Index' (duration: 43.40891ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T13:38:07.117243Z","caller":"traceutil/trace.go:171","msg":"trace[969958342] transaction","detail":"{read_only:false; response_revision:1491; number_of_response:1; }","duration":"369.095553ms","start":"2026-07-25T13:38:06.748096Z","end":"2026-07-25T13:38:07.117192Z","steps":["trace[969958342] 'process raft request' (duration: 325.65508ms)","trace[969958342] 'compare' (duration: 43.149726ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T13:38:07.117415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:38:06.748054Z","time spent":"369.273649ms","remote":"127.0.0.1:53700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T13:38:07.117509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.08264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-25T13:38:07.117562Z","caller":"traceutil/trace.go:171","msg":"trace[881109314] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1492; }","duration":"181.207882ms","start":"2026-07-25T13:38:06.936336Z","end":"2026-07-25T13:38:07.117544Z","steps":["trace[881109314] 'agreement among raft nodes before linearized reading' (duration: 180.995643ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:38:07.117268Z","caller":"traceutil/trace.go:171","msg":"trace[600303602] transaction","detail":"{read_only:false; response_revision:1492; number_of_response:1; }","duration":"326.147683ms","start":"2026-07-25T13:38:06.791078Z","end":"2026-07-25T13:38:07.117226Z","steps":["trace[600303602] 'process raft request' (duration: 326.019281ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:38:07.118164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:38:06.791059Z","time spent":"326.947723ms","remote":"127.0.0.1:53700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T13:38:07.288343Z","caller":"traceutil/trace.go:171","msg":"trace[1894559193] transaction","detail":"{read_only:false; response_revision:1493; number_of_response:1; }","duration":"166.769824ms","start":"2026-07-25T13:38:07.121542Z","end":"2026-07-25T13:38:07.288312Z","steps":["trace[1894559193] 'process raft request' (duration: 166.61152ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:38:07.288366Z","caller":"traceutil/trace.go:171","msg":"trace[115188398] linearizableReadLoop","detail":"{readStateIndex:1560; appliedIndex:1559; }","duration":"171.03383ms","start":"2026-07-25T13:38:07.117295Z","end":"2026-07-25T13:38:07.288329Z","steps":["trace[115188398] 'read index received' (duration: 107.943679ms)","trace[115188398] 'applied index is now lower than readState.Index' (duration: 63.085021ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T13:38:07.288474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.71134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-25T13:38:07.28851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.284965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-25T13:38:07.288516Z","caller":"traceutil/trace.go:171","msg":"trace[1794112649] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1493; }","duration":"184.831011ms","start":"2026-07-25T13:38:07.103674Z","end":"2026-07-25T13:38:07.288505Z","steps":["trace[1794112649] 'agreement among raft nodes before linearized reading' (duration: 184.712101ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:38:07.288551Z","caller":"traceutil/trace.go:171","msg":"trace[974014606] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1493; }","duration":"274.382803ms","start":"2026-07-25T13:38:07.014155Z","end":"2026-07-25T13:38:07.288538Z","steps":["trace[974014606] 'agreement among raft nodes before linearized reading' (duration: 274.283635ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:42:49.450957Z","caller":"traceutil/trace.go:171","msg":"trace[986543354] transaction","detail":"{read_only:false; response_revision:2419; number_of_response:1; }","duration":"278.212168ms","start":"2026-07-25T13:42:49.172714Z","end":"2026-07-25T13:42:49.450927Z","steps":["trace[986543354] 'process raft request' (duration: 278.038635ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:42:49.924839Z","caller":"traceutil/trace.go:171","msg":"trace[941273534] transaction","detail":"{read_only:false; response_revision:2420; number_of_response:1; }","duration":"206.769358ms","start":"2026-07-25T13:42:49.71804Z","end":"2026-07-25T13:42:49.924809Z","steps":["trace[941273534] 'process raft request' (duration: 206.226318ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:43:14.587777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.710196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-25T13:43:14.587895Z","caller":"traceutil/trace.go:171","msg":"trace[573702231] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2501; }","duration":"251.9237ms","start":"2026-07-25T13:43:14.335945Z","end":"2026-07-25T13:43:14.587869Z","steps":["trace[573702231] 'range keys from in-memory index tree' (duration: 251.489452ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:43:14.974933Z","caller":"traceutil/trace.go:171","msg":"trace[1592880842] transaction","detail":"{read_only:false; response_revision:2502; number_of_response:1; }","duration":"383.032929ms","start":"2026-07-25T13:43:14.591873Z","end":"2026-07-25T13:43:14.974905Z","steps":["trace[1592880842] 'process raft request' (duration: 382.878925ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:43:14.975136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:43:14.59185Z","time spent":"383.169732ms","remote":"127.0.0.1:53700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T13:43:15.042334Z","caller":"traceutil/trace.go:171","msg":"trace[970101946] linearizableReadLoop","detail":"{readStateIndex:2632; appliedIndex:2630; }","duration":"425.268664ms","start":"2026-07-25T13:43:14.616983Z","end":"2026-07-25T13:43:15.042252Z","steps":["trace[970101946] 'read index received' (duration: 357.886104ms)","trace[970101946] 'applied index is now lower than readState.Index' (duration: 67.38153ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T13:43:15.042426Z","caller":"traceutil/trace.go:171","msg":"trace[1964258548] transaction","detail":"{read_only:false; response_revision:2503; number_of_response:1; }","duration":"450.44134ms","start":"2026-07-25T13:43:14.591952Z","end":"2026-07-25T13:43:15.042394Z","steps":["trace[1964258548] 'process raft request' (duration: 428.611038ms)","trace[1964258548] 'compare' (duration: 21.568067ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T13:43:15.042552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:43:14.591928Z","time spent":"450.555771ms","remote":"127.0.0.1:53700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T13:43:15.04264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.63005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-25T13:43:15.042696Z","caller":"traceutil/trace.go:171","msg":"trace[193479036] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:2503; }","duration":"425.710382ms","start":"2026-07-25T13:43:14.616967Z","end":"2026-07-25T13:43:15.042678Z","steps":["trace[193479036] 'agreement among raft nodes before linearized reading' (duration: 425.484848ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:43:15.042753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:43:14.616932Z","time spent":"425.810924ms","remote":"127.0.0.1:53444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":31,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-07-25T13:43:15.042901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.895065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-25T13:43:15.042985Z","caller":"traceutil/trace.go:171","msg":"trace[1652509593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2503; }","duration":"273.024797ms","start":"2026-07-25T13:43:14.769939Z","end":"2026-07-25T13:43:15.042963Z","steps":["trace[1652509593] 'agreement among raft nodes before linearized reading' (duration: 272.808393ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:43:16.057757Z","caller":"traceutil/trace.go:171","msg":"trace[1540415379] transaction","detail":"{read_only:false; response_revision:2506; number_of_response:1; }","duration":"154.740006ms","start":"2026-07-25T13:43:15.902989Z","end":"2026-07-25T13:43:16.057729Z","steps":["trace[1540415379] 'process raft request' (duration: 154.379649ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:43:16.340778Z","caller":"traceutil/trace.go:171","msg":"trace[884532176] linearizableReadLoop","detail":"{readStateIndex:2636; appliedIndex:2635; }","duration":"237.507482ms","start":"2026-07-25T13:43:16.103253Z","end":"2026-07-25T13:43:16.340761Z","steps":["trace[884532176] 'read index received' (duration: 194.887561ms)","trace[884532176] 'applied index is now lower than readState.Index' (duration: 42.619141ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T13:43:16.340887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.624664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T13:43:16.34091Z","caller":"traceutil/trace.go:171","msg":"trace[592582794] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2507; }","duration":"237.688445ms","start":"2026-07-25T13:43:16.103214Z","end":"2026-07-25T13:43:16.340903Z","steps":["trace[592582794] 'agreement among raft nodes before linearized reading' (duration: 237.610173ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:43:16.340933Z","caller":"traceutil/trace.go:171","msg":"trace[164788184] transaction","detail":"{read_only:false; response_revision:2507; number_of_response:1; }","duration":"277.120866ms","start":"2026-07-25T13:43:16.063776Z","end":"2026-07-25T13:43:16.340896Z","steps":["trace[164788184] 'process raft request' (duration: 234.29887ms)","trace[164788184] 'compare' (duration: 42.559761ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T13:43:16.360138Z","caller":"traceutil/trace.go:171","msg":"trace[298872673] transaction","detail":"{read_only:false; response_revision:2508; number_of_response:1; }","duration":"153.038601ms","start":"2026-07-25T13:43:16.207065Z","end":"2026-07-25T13:43:16.360103Z","steps":["trace[298872673] 'process raft request' (duration: 152.756376ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:43:17.237693Z","caller":"traceutil/trace.go:171","msg":"trace[1626766649] linearizableReadLoop","detail":"{readStateIndex:2640; appliedIndex:2639; }","duration":"164.865261ms","start":"2026-07-25T13:43:17.072803Z","end":"2026-07-25T13:43:17.237669Z","steps":["trace[1626766649] 'read index received' (duration: 85.136693ms)","trace[1626766649] 'applied index is now lower than readState.Index' (duration: 79.727138ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T13:43:17.23774Z","caller":"traceutil/trace.go:171","msg":"trace[735143495] transaction","detail":"{read_only:false; response_revision:2510; number_of_response:1; }","duration":"186.334905ms","start":"2026-07-25T13:43:17.051373Z","end":"2026-07-25T13:43:17.237708Z","steps":["trace[735143495] 'process raft request' (duration: 106.572076ms)","trace[735143495] 'compare' (duration: 79.607546ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T13:43:17.238022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.202917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-25T13:43:17.238077Z","caller":"traceutil/trace.go:171","msg":"trace[876474791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2510; }","duration":"165.325819ms","start":"2026-07-25T13:43:17.072739Z","end":"2026-07-25T13:43:17.238065Z","steps":["trace[876474791] 'agreement among raft nodes before linearized reading' (duration: 165.063985ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:43:17.238166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.698128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T13:43:17.23884Z","caller":"traceutil/trace.go:171","msg":"trace[129654312] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2510; }","duration":"135.484063ms","start":"2026-07-25T13:43:17.103325Z","end":"2026-07-25T13:43:17.23881Z","steps":["trace[129654312] 'agreement among raft nodes before linearized reading' (duration: 134.705579ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:43:38.925314Z","caller":"traceutil/trace.go:171","msg":"trace[1119988604] linearizableReadLoop","detail":"{readStateIndex:2767; appliedIndex:2766; }","duration":"488.232901ms","start":"2026-07-25T13:43:38.437051Z","end":"2026-07-25T13:43:38.925283Z","steps":["trace[1119988604] 'read index received' (duration: 488.006457ms)","trace[1119988604] 'applied index is now lower than readState.Index' (duration: 225.144µs)"],"step_count":2} {"level":"warn","ts":"2026-07-25T13:43:38.925576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.507387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-25T13:43:38.925619Z","caller":"traceutil/trace.go:171","msg":"trace[586419522] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2631; }","duration":"488.598649ms","start":"2026-07-25T13:43:38.437009Z","end":"2026-07-25T13:43:38.925608Z","steps":["trace[586419522] 'agreement among raft nodes before linearized reading' (duration: 488.403074ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:43:38.925656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:43:38.436996Z","time spent":"488.6525ms","remote":"127.0.0.1:53700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-25T13:43:38.925897Z","caller":"traceutil/trace.go:171","msg":"trace[888588942] transaction","detail":"{read_only:false; response_revision:2631; number_of_response:1; }","duration":"496.591423ms","start":"2026-07-25T13:43:38.4292Z","end":"2026-07-25T13:43:38.925791Z","steps":["trace[888588942] 'process raft request' (duration: 495.900629ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:43:38.926207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:43:38.429178Z","time spent":"496.864489ms","remote":"127.0.0.1:53700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T13:43:39.305071Z","caller":"traceutil/trace.go:171","msg":"trace[574123998] linearizableReadLoop","detail":"{readStateIndex:2768; appliedIndex:2767; }","duration":"202.659773ms","start":"2026-07-25T13:43:39.102386Z","end":"2026-07-25T13:43:39.305046Z","steps":["trace[574123998] 'read index received' (duration: 202.498339ms)","trace[574123998] 'applied index is now lower than readState.Index' (duration: 160.104µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T13:43:39.305367Z","caller":"traceutil/trace.go:171","msg":"trace[1505000711] transaction","detail":"{read_only:false; response_revision:2632; number_of_response:1; }","duration":"376.04535ms","start":"2026-07-25T13:43:38.929306Z","end":"2026-07-25T13:43:39.305352Z","steps":["trace[1505000711] 'process raft request' (duration: 375.59525ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:43:39.30548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:43:38.929288Z","time spent":"376.118022ms","remote":"127.0.0.1:53700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T13:43:39.305678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.301375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T13:43:39.305719Z","caller":"traceutil/trace.go:171","msg":"trace[2104077809] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2632; }","duration":"203.387836ms","start":"2026-07-25T13:43:39.102321Z","end":"2026-07-25T13:43:39.305709Z","steps":["trace[2104077809] 'agreement among raft nodes before linearized reading' (duration: 203.328425ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:43:39.306071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.55109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-25T13:43:39.306123Z","caller":"traceutil/trace.go:171","msg":"trace[843988329] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2632; }","duration":"188.620581ms","start":"2026-07-25T13:43:39.117491Z","end":"2026-07-25T13:43:39.306112Z","steps":["trace[843988329] 'agreement among raft nodes before linearized reading' (duration: 188.428087ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:43:39.306222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.732629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-25T13:43:39.306259Z","caller":"traceutil/trace.go:171","msg":"trace[224503670] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:2632; }","duration":"132.826861ms","start":"2026-07-25T13:43:39.173422Z","end":"2026-07-25T13:43:39.306248Z","steps":["trace[224503670] 'agreement among raft nodes before linearized reading' (duration: 132.755159ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:43:48.654034Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":1628} {"level":"info","ts":"2026-07-25T13:43:48.700034Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":1628,"took":"45.224457ms","hash":3287425274,"current-db-size-bytes":23052288,"current-db-size":"23 MB","current-db-size-in-use-bytes":5808128,"current-db-size-in-use":"5.8 MB"} {"level":"info","ts":"2026-07-25T13:43:48.700116Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3287425274,"revision":1628,"compact-revision":-1} {"level":"info","ts":"2026-07-25T13:43:53.927511Z","caller":"traceutil/trace.go:171","msg":"trace[1704466094] transaction","detail":"{read_only:false; response_revision:2700; number_of_response:1; }","duration":"129.944255ms","start":"2026-07-25T13:43:53.797541Z","end":"2026-07-25T13:43:53.927485Z","steps":["trace[1704466094] 'process raft request' (duration: 129.66996ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:43:56.14031Z","caller":"traceutil/trace.go:171","msg":"trace[1560931908] linearizableReadLoop","detail":"{readStateIndex:2857; appliedIndex:2856; }","duration":"208.7035ms","start":"2026-07-25T13:43:55.931582Z","end":"2026-07-25T13:43:56.140285Z","steps":["trace[1560931908] 'read index received' (duration: 208.587138ms)","trace[1560931908] 'applied index is now lower than readState.Index' (duration: 115.282µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T13:43:56.14038Z","caller":"traceutil/trace.go:171","msg":"trace[1240868218] transaction","detail":"{read_only:false; response_revision:2717; number_of_response:1; }","duration":"330.294214ms","start":"2026-07-25T13:43:55.810042Z","end":"2026-07-25T13:43:56.140336Z","steps":["trace[1240868218] 'process raft request' (duration: 330.1087ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:43:56.140542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.942824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-07-25T13:43:56.140579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:43:55.810026Z","time spent":"330.434516ms","remote":"127.0.0.1:53566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5639,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T13:43:56.140643Z","caller":"traceutil/trace.go:171","msg":"trace[1997368712] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2717; }","duration":"209.078937ms","start":"2026-07-25T13:43:55.931541Z","end":"2026-07-25T13:43:56.14062Z","steps":["trace[1997368712] 'agreement among raft nodes before linearized reading' (duration: 208.832013ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:43:56.301746Z","caller":"traceutil/trace.go:171","msg":"trace[448606232] transaction","detail":"{read_only:false; response_revision:2718; number_of_response:1; }","duration":"155.806996ms","start":"2026-07-25T13:43:56.145913Z","end":"2026-07-25T13:43:56.30172Z","steps":["trace[448606232] 'process raft request' (duration: 155.639133ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:43:57.487005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.599011ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-25T13:43:57.517712Z","caller":"traceutil/trace.go:171","msg":"trace[1783842127] transaction","detail":"{read_only:false; response_revision:2720; number_of_response:1; }","duration":"529.927408ms","start":"2026-07-25T13:43:56.987764Z","end":"2026-07-25T13:43:57.517692Z","steps":["trace[1783842127] 'process raft request' (duration: 529.802035ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:43:57.517835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:43:56.987744Z","time spent":"530.02647ms","remote":"127.0.0.1:53700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T13:43:57.651638Z","caller":"traceutil/trace.go:171","msg":"trace[363567530] linearizableReadLoop","detail":"{readStateIndex:2862; appliedIndex:2861; }","duration":"548.508378ms","start":"2026-07-25T13:43:57.103106Z","end":"2026-07-25T13:43:57.651614Z","steps":["trace[363567530] 'read index received' (duration: 415.091846ms)","trace[363567530] 'applied index is now lower than readState.Index' (duration: 133.415622ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T13:43:57.651706Z","caller":"traceutil/trace.go:171","msg":"trace[678234240] transaction","detail":"{read_only:false; response_revision:2721; number_of_response:1; }","duration":"554.410202ms","start":"2026-07-25T13:43:57.097265Z","end":"2026-07-25T13:43:57.651675Z","steps":["trace[678234240] 'process raft request' (duration: 509.34777ms)","trace[678234240] 'compare' (duration: 44.857669ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T13:43:57.651816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:43:57.097242Z","time spent":"554.511135ms","remote":"127.0.0.1:53700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T13:43:57.651795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.667342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-25T13:43:57.651886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.154153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-25T13:43:57.651932Z","caller":"traceutil/trace.go:171","msg":"trace[14936439] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2721; }","duration":"548.788034ms","start":"2026-07-25T13:43:57.103086Z","end":"2026-07-25T13:43:57.651874Z","steps":["trace[14936439] 'agreement among raft nodes before linearized reading' (duration: 548.615541ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:43:57.651994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:43:57.103039Z","time spent":"548.945136ms","remote":"127.0.0.1:55050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-25T13:43:57.652004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.335584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-25T13:43:57.651947Z","caller":"traceutil/trace.go:171","msg":"trace[481546708] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2721; }","duration":"265.278675ms","start":"2026-07-25T13:43:57.386654Z","end":"2026-07-25T13:43:57.651933Z","steps":["trace[481546708] 'agreement among raft nodes before linearized reading' (duration: 265.090732ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:43:57.652033Z","caller":"traceutil/trace.go:171","msg":"trace[31963509] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2721; }","duration":"253.383375ms","start":"2026-07-25T13:43:57.398642Z","end":"2026-07-25T13:43:57.652025Z","steps":["trace[31963509] 'agreement among raft nodes before linearized reading' (duration: 253.325934ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:43:57.65202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.149334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-07-25T13:43:57.65202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.192764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-25T13:43:57.652105Z","caller":"traceutil/trace.go:171","msg":"trace[397212340] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2721; }","duration":"185.287476ms","start":"2026-07-25T13:43:57.4668Z","end":"2026-07-25T13:43:57.652088Z","steps":["trace[397212340] 'agreement among raft nodes before linearized reading' (duration: 185.023071ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:43:57.652105Z","caller":"traceutil/trace.go:171","msg":"trace[649374917] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2721; }","duration":"232.275536ms","start":"2026-07-25T13:43:57.419807Z","end":"2026-07-25T13:43:57.652083Z","steps":["trace[649374917] 'agreement among raft nodes before linearized reading' (duration: 232.118023ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:43:57.926672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.581502ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T13:43:57.926862Z","caller":"traceutil/trace.go:171","msg":"trace[376781414] transaction","detail":"{read_only:false; response_revision:2725; number_of_response:1; }","duration":"270.759252ms","start":"2026-07-25T13:43:57.65609Z","end":"2026-07-25T13:43:57.92685Z","steps":["trace[376781414] 'process raft request' (duration: 270.722801ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:43:57.926896Z","caller":"traceutil/trace.go:171","msg":"trace[465780976] transaction","detail":"{read_only:false; response_revision:2723; number_of_response:1; }","duration":"270.878514ms","start":"2026-07-25T13:43:57.655991Z","end":"2026-07-25T13:43:57.92687Z","steps":["trace[465780976] 'process raft request' (duration: 270.748902ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:43:57.927049Z","caller":"traceutil/trace.go:171","msg":"trace[273380329] transaction","detail":"{read_only:false; response_revision:2724; number_of_response:1; }","duration":"270.976876ms","start":"2026-07-25T13:43:57.656053Z","end":"2026-07-25T13:43:57.92703Z","steps":["trace[273380329] 'process raft request' (duration: 270.739222ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:43:57.926856Z","caller":"traceutil/trace.go:171","msg":"trace[679076274] transaction","detail":"{read_only:false; response_revision:2722; number_of_response:1; }","duration":"270.837263ms","start":"2026-07-25T13:43:57.655982Z","end":"2026-07-25T13:43:57.92682Z","steps":["trace[679076274] 'process raft request' (duration: 116.046416ms)","trace[679076274] 'compare' (duration: 154.47912ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T13:43:57.949865Z","caller":"traceutil/trace.go:171","msg":"trace[251193871] transaction","detail":"{read_only:false; response_revision:2726; number_of_response:1; }","duration":"175.701271ms","start":"2026-07-25T13:43:57.774143Z","end":"2026-07-25T13:43:57.949844Z","steps":["trace[251193871] 'process raft request' (duration: 175.551788ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:43:58.462058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.309753ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-25T13:43:58.462198Z","caller":"traceutil/trace.go:171","msg":"trace[276079012] transaction","detail":"{read_only:false; response_revision:2727; number_of_response:1; }","duration":"508.471582ms","start":"2026-07-25T13:43:57.953703Z","end":"2026-07-25T13:43:58.462175Z","steps":["trace[276079012] 'process raft request' (duration: 268.966646ms)","trace[276079012] 'compare' (duration: 238.966706ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T13:43:58.462286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:43:57.953682Z","time spent":"508.551704ms","remote":"127.0.0.1:53700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T13:43:58.587875Z","caller":"traceutil/trace.go:171","msg":"trace[261416786] linearizableReadLoop","detail":"{readStateIndex:2869; appliedIndex:2868; }","duration":"282.379717ms","start":"2026-07-25T13:43:58.305471Z","end":"2026-07-25T13:43:58.587851Z","steps":["trace[261416786] 'read index received' (duration: 282.310705ms)","trace[261416786] 'applied index is now lower than readState.Index' (duration: 67.922µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T13:43:58.587997Z","caller":"traceutil/trace.go:171","msg":"trace[1762354578] transaction","detail":"{read_only:false; response_revision:2728; number_of_response:1; }","duration":"306.897381ms","start":"2026-07-25T13:43:58.281089Z","end":"2026-07-25T13:43:58.587986Z","steps":["trace[1762354578] 'process raft request' (duration: 306.643146ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:43:58.588095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:43:58.281068Z","time spent":"306.956312ms","remote":"127.0.0.1:53474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T13:43:58.588424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.956637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-25T13:43:58.588465Z","caller":"traceutil/trace.go:171","msg":"trace[362110208] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2728; }","duration":"283.047219ms","start":"2026-07-25T13:43:58.305407Z","end":"2026-07-25T13:43:58.588454Z","steps":["trace[362110208] 'agreement among raft nodes before linearized reading' (duration: 282.898006ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:43:58.680232Z","caller":"traceutil/trace.go:171","msg":"trace[1670884226] transaction","detail":"{read_only:false; response_revision:2729; number_of_response:1; }","duration":"208.71533ms","start":"2026-07-25T13:43:58.471494Z","end":"2026-07-25T13:43:58.680209Z","steps":["trace[1670884226] 'process raft request' (duration: 208.475195ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:43:58.68033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.972046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-25T13:43:58.680378Z","caller":"traceutil/trace.go:171","msg":"trace[1256363279] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2729; }","duration":"209.070178ms","start":"2026-07-25T13:43:58.471295Z","end":"2026-07-25T13:43:58.680365Z","steps":["trace[1256363279] 'agreement among raft nodes before linearized reading' (duration: 208.966546ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:43:58.797584Z","caller":"traceutil/trace.go:171","msg":"trace[127065509] transaction","detail":"{read_only:false; response_revision:2732; number_of_response:1; }","duration":"109.948408ms","start":"2026-07-25T13:43:58.687613Z","end":"2026-07-25T13:43:58.797561Z","steps":["trace[127065509] 'process raft request' (duration: 109.908527ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:43:58.797966Z","caller":"traceutil/trace.go:171","msg":"trace[683915471] transaction","detail":"{read_only:false; response_revision:2730; number_of_response:1; }","duration":"206.282614ms","start":"2026-07-25T13:43:58.591661Z","end":"2026-07-25T13:43:58.797944Z","steps":["trace[683915471] 'process raft request' (duration: 165.034355ms)","trace[683915471] 'compare' (duration: 40.457373ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T13:43:58.79828Z","caller":"traceutil/trace.go:171","msg":"trace[789185604] transaction","detail":"{read_only:false; response_revision:2731; number_of_response:1; }","duration":"206.417286ms","start":"2026-07-25T13:43:58.591848Z","end":"2026-07-25T13:43:58.798265Z","steps":["trace[789185604] 'process raft request' (duration: 205.588239ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:43:58.798528Z","caller":"traceutil/trace.go:171","msg":"trace[1724276822] linearizableReadLoop","detail":"{readStateIndex:2872; appliedIndex:2870; }","duration":"118.281159ms","start":"2026-07-25T13:43:58.680236Z","end":"2026-07-25T13:43:58.798518Z","steps":["trace[1724276822] 'read index received' (duration: 76.46754ms)","trace[1724276822] 'applied index is now lower than readState.Index' (duration: 41.812789ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T13:43:58.798598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.95425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-25T13:43:58.79863Z","caller":"traceutil/trace.go:171","msg":"trace[59013779] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:2732; }","duration":"170.035121ms","start":"2026-07-25T13:43:58.628587Z","end":"2026-07-25T13:43:58.798622Z","steps":["trace[59013779] 'agreement among raft nodes before linearized reading' (duration: 169.97364ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:44:24.92656Z","caller":"traceutil/trace.go:171","msg":"trace[408805847] transaction","detail":"{read_only:false; response_revision:2873; number_of_response:1; }","duration":"181.3467ms","start":"2026-07-25T13:44:24.745178Z","end":"2026-07-25T13:44:24.926525Z","steps":["trace[408805847] 'process raft request' (duration: 181.098345ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:44:25.596127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.848044ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T13:44:25.596249Z","caller":"traceutil/trace.go:171","msg":"trace[384393445] linearizableReadLoop","detail":"{readStateIndex:3020; appliedIndex:3019; }","duration":"507.897624ms","start":"2026-07-25T13:44:25.088331Z","end":"2026-07-25T13:44:25.596229Z","steps":["trace[384393445] 'read index received' (duration: 398.828065ms)","trace[384393445] 'applied index is now lower than readState.Index' (duration: 109.067959ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T13:44:25.596333Z","caller":"traceutil/trace.go:171","msg":"trace[510532075] transaction","detail":"{read_only:false; response_revision:2874; number_of_response:1; }","duration":"665.385249ms","start":"2026-07-25T13:44:24.930892Z","end":"2026-07-25T13:44:25.596277Z","steps":["trace[510532075] 'process raft request' (duration: 556.291209ms)","trace[510532075] 'compare' (duration: 108.680641ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T13:44:25.596447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.123869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-07-25T13:44:25.596492Z","caller":"traceutil/trace.go:171","msg":"trace[1795456186] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2874; }","duration":"508.205271ms","start":"2026-07-25T13:44:25.088272Z","end":"2026-07-25T13:44:25.596477Z","steps":["trace[1795456186] 'agreement among raft nodes before linearized reading' (duration: 508.060208ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:44:25.59653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:44:25.08825Z","time spent":"508.271512ms","remote":"127.0.0.1:53700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-25T13:44:25.596534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:44:24.930871Z","time spent":"665.564953ms","remote":"127.0.0.1:53700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T13:44:25.596677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.254219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T13:44:25.596767Z","caller":"traceutil/trace.go:171","msg":"trace[1568627265] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2874; }","duration":"492.381942ms","start":"2026-07-25T13:44:25.104362Z","end":"2026-07-25T13:44:25.596744Z","steps":["trace[1568627265] 'agreement among raft nodes before linearized reading' (duration: 492.242379ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:44:25.596812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:44:25.104342Z","time spent":"492.460823ms","remote":"127.0.0.1:55050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-25T13:44:25.966878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.468828ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T13:44:25.967128Z","caller":"traceutil/trace.go:171","msg":"trace[1845608759] transaction","detail":"{read_only:false; response_revision:2877; number_of_response:1; }","duration":"358.956767ms","start":"2026-07-25T13:44:25.60815Z","end":"2026-07-25T13:44:25.967107Z","steps":["trace[1845608759] 'process raft request' (duration: 358.863905ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:44:25.967205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:44:25.608141Z","time spent":"359.030708ms","remote":"127.0.0.1:53700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T13:44:25.96733Z","caller":"traceutil/trace.go:171","msg":"trace[1035173249] transaction","detail":"{read_only:false; response_revision:2876; number_of_response:1; }","duration":"365.138576ms","start":"2026-07-25T13:44:25.601974Z","end":"2026-07-25T13:44:25.967112Z","steps":["trace[1035173249] 'process raft request' (duration: 154.279591ms)","trace[1035173249] 'compare' (duration: 210.283915ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T13:44:25.96763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:44:25.601958Z","time spent":"365.492725ms","remote":"127.0.0.1:53700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T13:44:31.663669Z","caller":"traceutil/trace.go:171","msg":"trace[1551416291] linearizableReadLoop","detail":"{readStateIndex:3049; appliedIndex:3048; }","duration":"194.515448ms","start":"2026-07-25T13:44:31.469135Z","end":"2026-07-25T13:44:31.66365Z","steps":["trace[1551416291] 'read index received' (duration: 194.350995ms)","trace[1551416291] 'applied index is now lower than readState.Index' (duration: 163.743µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T13:44:31.664095Z","caller":"traceutil/trace.go:171","msg":"trace[440089341] transaction","detail":"{read_only:false; response_revision:2902; number_of_response:1; }","duration":"320.991696ms","start":"2026-07-25T13:44:31.342942Z","end":"2026-07-25T13:44:31.663934Z","steps":["trace[440089341] 'process raft request' (duration: 320.597879ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:44:31.66421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:44:31.342934Z","time spent":"321.20467ms","remote":"127.0.0.1:53700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T13:44:31.664472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.339754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.95\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-25T13:44:31.664513Z","caller":"traceutil/trace.go:171","msg":"trace[518475334] range","detail":"{range_begin:/registry/masterleases/199.204.45.95; range_end:; response_count:1; response_revision:2902; }","duration":"195.420316ms","start":"2026-07-25T13:44:31.469083Z","end":"2026-07-25T13:44:31.664503Z","steps":["trace[518475334] 'agreement among raft nodes before linearized reading' (duration: 195.281003ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:48:48.661481Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2664} {"level":"info","ts":"2026-07-25T13:48:48.681579Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2664,"took":"19.594774ms","hash":381960952,"current-db-size-bytes":23052288,"current-db-size":"23 MB","current-db-size-in-use-bytes":6262784,"current-db-size-in-use":"6.3 MB"} {"level":"info","ts":"2026-07-25T13:48:48.681661Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":381960952,"revision":2664,"compact-revision":1628} {"level":"info","ts":"2026-07-25T13:49:09.563955Z","caller":"traceutil/trace.go:171","msg":"trace[152810234] linearizableReadLoop","detail":"{readStateIndex:4406; appliedIndex:4405; }","duration":"154.169241ms","start":"2026-07-25T13:49:09.40971Z","end":"2026-07-25T13:49:09.563879Z","steps":["trace[152810234] 'read index received' (duration: 153.997688ms)","trace[152810234] 'applied index is now lower than readState.Index' (duration: 169.903µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T13:49:09.563964Z","caller":"traceutil/trace.go:171","msg":"trace[1932191996] transaction","detail":"{read_only:false; response_revision:4201; number_of_response:1; }","duration":"168.374199ms","start":"2026-07-25T13:49:09.395563Z","end":"2026-07-25T13:49:09.563937Z","steps":["trace[1932191996] 'process raft request' (duration: 168.151765ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:49:09.564255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.505918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-25T13:49:09.564352Z","caller":"traceutil/trace.go:171","msg":"trace[300634155] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:4201; }","duration":"154.66717ms","start":"2026-07-25T13:49:09.409666Z","end":"2026-07-25T13:49:09.564333Z","steps":["trace[300634155] 'agreement among raft nodes before linearized reading' (duration: 154.360474ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:49:09.580639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.865144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-25T13:49:09.580691Z","caller":"traceutil/trace.go:171","msg":"trace[1637761801] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4202; }","duration":"106.940775ms","start":"2026-07-25T13:49:09.473739Z","end":"2026-07-25T13:49:09.58068Z","steps":["trace[1637761801] 'agreement among raft nodes before linearized reading' (duration: 106.782052ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:49:09.580782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.036717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-25T13:49:09.580763Z","caller":"traceutil/trace.go:171","msg":"trace[770194703] transaction","detail":"{read_only:false; response_revision:4202; number_of_response:1; }","duration":"134.272641ms","start":"2026-07-25T13:49:09.446408Z","end":"2026-07-25T13:49:09.580681Z","steps":["trace[770194703] 'process raft request' (duration: 133.835143ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:49:09.580862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.713201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-25T13:49:09.580911Z","caller":"traceutil/trace.go:171","msg":"trace[1530615235] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4202; }","duration":"107.21327ms","start":"2026-07-25T13:49:09.473676Z","end":"2026-07-25T13:49:09.580889Z","steps":["trace[1530615235] 'agreement among raft nodes before linearized reading' (duration: 106.900884ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:49:09.580994Z","caller":"traceutil/trace.go:171","msg":"trace[2003143115] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4202; }","duration":"106.870224ms","start":"2026-07-25T13:49:09.474092Z","end":"2026-07-25T13:49:09.580963Z","steps":["trace[2003143115] 'agreement among raft nodes before linearized reading' (duration: 106.547918ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:49:09.581365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.095877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-25T13:49:09.581429Z","caller":"traceutil/trace.go:171","msg":"trace[1321673388] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4202; }","duration":"111.157388ms","start":"2026-07-25T13:49:09.470254Z","end":"2026-07-25T13:49:09.581411Z","steps":["trace[1321673388] 'agreement among raft nodes before linearized reading' (duration: 110.197919ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:49:09.673Z","caller":"traceutil/trace.go:171","msg":"trace[488739336] transaction","detail":"{read_only:false; response_revision:4203; number_of_response:1; }","duration":"105.546458ms","start":"2026-07-25T13:49:09.567419Z","end":"2026-07-25T13:49:09.672966Z","steps":["trace[488739336] 'process raft request' (duration: 50.248845ms)","trace[488739336] 'compare' (duration: 55.191521ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T13:49:09.684274Z","caller":"traceutil/trace.go:171","msg":"trace[614605907] transaction","detail":"{read_only:false; response_revision:4204; number_of_response:1; }","duration":"100.410928ms","start":"2026-07-25T13:49:09.583834Z","end":"2026-07-25T13:49:09.684245Z","steps":["trace[614605907] 'process raft request' (duration: 100.185883ms)"],"step_count":1}