{"level":"warn","ts":"2026-05-19T16:58:51.490757Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-19T16:58:51.490866Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.30: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.30:2380","--initial-cluster=instance=https://199.204.45.30:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.30:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.30:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-19T16:58:51.490938Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-19T16:58:51.490951Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.30:2380"]} {"level":"info","ts":"2026-05-19T16:58:51.490967Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-19T16:58:51.491925Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"]} {"level":"info","ts":"2026-05-19T16:58:51.491999Z","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.30:2380"],"listen-peer-urls":["https://199.204.45.30:2380"],"advertise-client-urls":["https://199.204.45.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.30:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-19T16:58:51.498612Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.379294ms"} {"level":"info","ts":"2026-05-19T16:58:51.509126Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c6cf68dde442ffa5","cluster-id":"98b7ecdca9045526"} {"level":"info","ts":"2026-05-19T16:58:51.509252Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=()"} {"level":"info","ts":"2026-05-19T16:58:51.509285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became follower at term 0"} {"level":"info","ts":"2026-05-19T16:58:51.509306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c6cf68dde442ffa5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-19T16:58:51.509321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became follower at term 1"} {"level":"info","ts":"2026-05-19T16:58:51.509379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=(14325784241915494309)"} {"level":"warn","ts":"2026-05-19T16:58:51.515612Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-19T16:58:51.517695Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-19T16:58:51.519905Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-19T16:58:51.525821Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c6cf68dde442ffa5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-19T16:58:51.52754Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c6cf68dde442ffa5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-19T16:58:51.530642Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-19T16:58:51.530739Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-19T16:58:51.530762Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-19T16:58:51.533089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=(14325784241915494309)"} {"level":"info","ts":"2026-05-19T16:58:51.533296Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"98b7ecdca9045526","local-member-id":"c6cf68dde442ffa5","added-peer-id":"c6cf68dde442ffa5","added-peer-peer-urls":["https://199.204.45.30:2380"]} {"level":"info","ts":"2026-05-19T16:58:51.534646Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-19T16:58:51.534803Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.30:2380"} {"level":"info","ts":"2026-05-19T16:58:51.534848Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.30:2380"} {"level":"info","ts":"2026-05-19T16:58:51.535171Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c6cf68dde442ffa5","initial-advertise-peer-urls":["https://199.204.45.30:2380"],"listen-peer-urls":["https://199.204.45.30:2380"],"advertise-client-urls":["https://199.204.45.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-19T16:58:51.535241Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-19T16:58:51.910584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 is starting a new election at term 1"} {"level":"info","ts":"2026-05-19T16:58:51.910659Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-19T16:58:51.910701Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 received MsgPreVoteResp from c6cf68dde442ffa5 at term 1"} {"level":"info","ts":"2026-05-19T16:58:51.910728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became candidate at term 2"} {"level":"info","ts":"2026-05-19T16:58:51.910741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 received MsgVoteResp from c6cf68dde442ffa5 at term 2"} {"level":"info","ts":"2026-05-19T16:58:51.910762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became leader at term 2"} {"level":"info","ts":"2026-05-19T16:58:51.91078Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c6cf68dde442ffa5 elected leader c6cf68dde442ffa5 at term 2"} {"level":"info","ts":"2026-05-19T16:58:51.91167Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T16:58:51.914736Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T16:58:51.914765Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T16:58:51.914733Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c6cf68dde442ffa5","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.30:2379]}","request-path":"/0/members/c6cf68dde442ffa5/attributes","cluster-id":"98b7ecdca9045526","publish-timeout":"7s"} {"level":"info","ts":"2026-05-19T16:58:51.915008Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"98b7ecdca9045526","local-member-id":"c6cf68dde442ffa5","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T16:58:51.915062Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-19T16:58:51.91514Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-19T16:58:51.915337Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T16:58:51.915447Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T16:58:51.918968Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.30:2379"} {"level":"info","ts":"2026-05-19T16:58:51.919813Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-19T16:59:07.822168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.432178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-19T16:59:07.822191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.80856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-19T16:59:07.822395Z","caller":"traceutil/trace.go:171","msg":"trace[1544435363] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:367; }","duration":"310.649759ms","start":"2026-05-19T16:59:07.511677Z","end":"2026-05-19T16:59:07.822327Z","steps":["trace[1544435363] 'range keys from in-memory index tree' (duration: 310.326097ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T16:59:07.822398Z","caller":"traceutil/trace.go:171","msg":"trace[1418528548] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:367; }","duration":"315.094443ms","start":"2026-05-19T16:59:07.507279Z","end":"2026-05-19T16:59:07.822374Z","steps":["trace[1418528548] 'range keys from in-memory index tree' (duration: 314.665649ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T16:59:07.822468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T16:59:07.507253Z","time spent":"315.204574ms","remote":"127.0.0.1:42686","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-05-19T16:59:07.822489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T16:59:07.511666Z","time spent":"310.801311ms","remote":"127.0.0.1:42686","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-05-19T16:59:07.972683Z","caller":"traceutil/trace.go:171","msg":"trace[100107177] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"144.653891ms","start":"2026-05-19T16:59:07.82799Z","end":"2026-05-19T16:59:07.972644Z","steps":["trace[100107177] 'process raft request' (duration: 144.420588ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T16:59:08.9283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"902.470225ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T16:59:08.928399Z","caller":"traceutil/trace.go:171","msg":"trace[484833742] linearizableReadLoop","detail":"{readStateIndex:381; appliedIndex:379; }","duration":"1.076600661s","start":"2026-05-19T16:59:07.851783Z","end":"2026-05-19T16:59:08.928384Z","steps":["trace[484833742] 'read index received' (duration: 120.75392ms)","trace[484833742] 'applied index is now lower than readState.Index' (duration: 955.845411ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T16:59:08.928476Z","caller":"traceutil/trace.go:171","msg":"trace[1497819150] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"1.100513223s","start":"2026-05-19T16:59:07.827955Z","end":"2026-05-19T16:59:08.928469Z","steps":["trace[1497819150] 'process raft request' (duration: 197.345822ms)","trace[1497819150] 'compare' (duration: 901.824488ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T16:59:08.928523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T16:59:07.827933Z","time spent":"1.100564253s","remote":"127.0.0.1:42686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T16:59:08.928709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.076925394s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-19T16:59:08.928736Z","caller":"traceutil/trace.go:171","msg":"trace[1337010124] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:369; }","duration":"1.076987855s","start":"2026-05-19T16:59:07.851742Z","end":"2026-05-19T16:59:08.928729Z","steps":["trace[1337010124] 'agreement among raft nodes before linearized reading' (duration: 1.076925955s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T16:59:08.928759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T16:59:07.85169Z","time spent":"1.077064615s","remote":"127.0.0.1:42686","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-05-19T16:59:08.928878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.066131569s","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-05-19T16:59:08.928904Z","caller":"traceutil/trace.go:171","msg":"trace[813364852] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:369; }","duration":"1.06618954s","start":"2026-05-19T16:59:07.862709Z","end":"2026-05-19T16:59:08.928899Z","steps":["trace[813364852] 'agreement among raft nodes before linearized reading' (duration: 1.066142859s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T16:59:08.928924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T16:59:07.86269Z","time spent":"1.06623046s","remote":"127.0.0.1:42686","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-05-19T16:59:09.034443Z","caller":"traceutil/trace.go:171","msg":"trace[1574879660] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"100.034327ms","start":"2026-05-19T16:59:08.934392Z","end":"2026-05-19T16:59:09.034427Z","steps":["trace[1574879660] 'process raft request' (duration: 95.667025ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T16:59:09.039413Z","caller":"traceutil/trace.go:171","msg":"trace[2089053381] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"104.959803ms","start":"2026-05-19T16:59:08.93444Z","end":"2026-05-19T16:59:09.0394Z","steps":["trace[2089053381] 'process raft request' (duration: 104.873482ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T16:59:28.072752Z","caller":"traceutil/trace.go:171","msg":"trace[1444265869] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"552.013053ms","start":"2026-05-19T16:59:27.520693Z","end":"2026-05-19T16:59:28.072706Z","steps":["trace[1444265869] 'process raft request' (duration: 551.67199ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T16:59:28.072973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T16:59:27.520659Z","time spent":"552.211356ms","remote":"127.0.0.1:42452","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-05-19T16:59:28.085892Z","caller":"traceutil/trace.go:171","msg":"trace[1365400801] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"557.705557ms","start":"2026-05-19T16:59:27.528162Z","end":"2026-05-19T16:59:28.085867Z","steps":["trace[1365400801] 'process raft request' (duration: 557.478925ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T16:59:28.086003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T16:59:27.52812Z","time spent":"557.828558ms","remote":"127.0.0.1:42452","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-05-19T16:59:28.086085Z","caller":"traceutil/trace.go:171","msg":"trace[254207554] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"556.988561ms","start":"2026-05-19T16:59:27.52903Z","end":"2026-05-19T16:59:28.086019Z","steps":["trace[254207554] 'process raft request' (duration: 556.733758ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T16:59:28.086198Z","caller":"traceutil/trace.go:171","msg":"trace[1404006675] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"545.684435ms","start":"2026-05-19T16:59:27.540504Z","end":"2026-05-19T16:59:28.086189Z","steps":["trace[1404006675] 'process raft request' (duration: 545.535534ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T16:59:28.086255Z","caller":"traceutil/trace.go:171","msg":"trace[2129470286] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"544.02524ms","start":"2026-05-19T16:59:27.542216Z","end":"2026-05-19T16:59:28.086242Z","steps":["trace[2129470286] 'process raft request' (duration: 543.869458ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T16:59:28.086307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T16:59:27.540477Z","time spent":"545.794846ms","remote":"127.0.0.1:42452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T16:59:28.086306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T16:59:27.529018Z","time spent":"557.192332ms","remote":"127.0.0.1:42452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T16:59:28.086178Z","caller":"traceutil/trace.go:171","msg":"trace[1532459579] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"546.568673ms","start":"2026-05-19T16:59:27.539576Z","end":"2026-05-19T16:59:28.086145Z","steps":["trace[1532459579] 'process raft request' (duration: 546.424172ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T16:59:28.086163Z","caller":"traceutil/trace.go:171","msg":"trace[473061376] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"550.898654ms","start":"2026-05-19T16:59:27.535201Z","end":"2026-05-19T16:59:28.0861Z","steps":["trace[473061376] 'process raft request' (duration: 550.613102ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T16:59:28.0864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T16:59:27.542207Z","time spent":"544.12211ms","remote":"127.0.0.1:42452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T16:59:28.08615Z","caller":"traceutil/trace.go:171","msg":"trace[986050653] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"548.30617ms","start":"2026-05-19T16:59:27.53781Z","end":"2026-05-19T16:59:28.086116Z","steps":["trace[986050653] 'process raft request' (duration: 548.145568ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T16:59:28.086474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T16:59:27.537784Z","time spent":"548.673272ms","remote":"127.0.0.1:42452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T16:59:28.086487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T16:59:27.539567Z","time spent":"546.801635ms","remote":"127.0.0.1:42452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T16:59:28.086578Z","caller":"traceutil/trace.go:171","msg":"trace[1278730713] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"518.536651ms","start":"2026-05-19T16:59:27.568029Z","end":"2026-05-19T16:59:28.086566Z","steps":["trace[1278730713] 'process raft request' (duration: 518.177648ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T16:59:28.086626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T16:59:27.568002Z","time spent":"518.604492ms","remote":"127.0.0.1:42452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T16:59:28.086683Z","caller":"traceutil/trace.go:171","msg":"trace[1809352493] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"490.727632ms","start":"2026-05-19T16:59:27.595934Z","end":"2026-05-19T16:59:28.086661Z","steps":["trace[1809352493] 'process raft request' (duration: 490.344069ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T16:59:28.086732Z","caller":"traceutil/trace.go:171","msg":"trace[517598072] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"375.957673ms","start":"2026-05-19T16:59:27.710751Z","end":"2026-05-19T16:59:28.086708Z","steps":["trace[517598072] 'process raft request' (duration: 375.597119ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T16:59:28.08678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T16:59:27.710717Z","time spent":"376.042433ms","remote":"127.0.0.1:42452","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-05-19T16:59:28.086774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T16:59:27.595906Z","time spent":"490.824143ms","remote":"127.0.0.1:42452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T16:59:28.086717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T16:59:27.535173Z","time spent":"551.258987ms","remote":"127.0.0.1:42452","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-05-19T16:59:28.086936Z","caller":"traceutil/trace.go:171","msg":"trace[2138493684] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"544.012109ms","start":"2026-05-19T16:59:27.542731Z","end":"2026-05-19T16:59:28.086743Z","steps":["trace[2138493684] 'process raft request' (duration: 543.405414ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T16:59:28.087037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T16:59:27.542714Z","time spent":"544.276632ms","remote":"127.0.0.1:42452","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-05-19T16:59:29.304099Z","caller":"traceutil/trace.go:171","msg":"trace[1233849091] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"644.313651ms","start":"2026-05-19T16:59:28.659737Z","end":"2026-05-19T16:59:29.304051Z","steps":["trace[1233849091] 'process raft request' (duration: 623.334196ms)","trace[1233849091] 'compare' (duration: 20.608172ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T16:59:29.304309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T16:59:28.659708Z","time spent":"644.481652ms","remote":"127.0.0.1:42452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18041,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T16:59:29.312096Z","caller":"traceutil/trace.go:171","msg":"trace[1858205722] linearizableReadLoop","detail":"{readStateIndex:565; appliedIndex:562; }","duration":"267.769028ms","start":"2026-05-19T16:59:29.044292Z","end":"2026-05-19T16:59:29.312061Z","steps":["trace[1858205722] 'read index received' (duration: 238.716038ms)","trace[1858205722] 'applied index is now lower than readState.Index' (duration: 29.05228ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T16:59:29.312217Z","caller":"traceutil/trace.go:171","msg":"trace[386391862] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"647.901474ms","start":"2026-05-19T16:59:28.664309Z","end":"2026-05-19T16:59:29.31221Z","steps":["trace[386391862] 'process raft request' (duration: 640.067841ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T16:59:29.312343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T16:59:28.664284Z","time spent":"647.966395ms","remote":"127.0.0.1:42452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65412,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T16:59:29.315688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.930418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-19T16:59:29.31578Z","caller":"traceutil/trace.go:171","msg":"trace[1298873507] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:543; }","duration":"171.091559ms","start":"2026-05-19T16:59:29.144662Z","end":"2026-05-19T16:59:29.315754Z","steps":["trace[1298873507] 'agreement among raft nodes before linearized reading' (duration: 170.778846ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T16:59:29.316012Z","caller":"traceutil/trace.go:171","msg":"trace[415724901] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"483.392303ms","start":"2026-05-19T16:59:28.832609Z","end":"2026-05-19T16:59:29.316002Z","steps":["trace[415724901] 'process raft request' (duration: 479.076593ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T16:59:29.316089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T16:59:28.832572Z","time spent":"483.456143ms","remote":"127.0.0.1:42452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T16:59:29.316214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.921716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-19T16:59:29.316236Z","caller":"traceutil/trace.go:171","msg":"trace[850650831] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:543; }","duration":"271.966237ms","start":"2026-05-19T16:59:29.044265Z","end":"2026-05-19T16:59:29.316231Z","steps":["trace[850650831] 'agreement among raft nodes before linearized reading' (duration: 271.927166ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T16:59:34.653163Z","caller":"traceutil/trace.go:171","msg":"trace[408454174] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"266.294634ms","start":"2026-05-19T16:59:34.386839Z","end":"2026-05-19T16:59:34.653134Z","steps":["trace[408454174] 'process raft request' (duration: 260.854064ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T16:59:34.928322Z","caller":"traceutil/trace.go:171","msg":"trace[1134473772] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"209.124229ms","start":"2026-05-19T16:59:34.719171Z","end":"2026-05-19T16:59:34.928295Z","steps":["trace[1134473772] 'process raft request' (duration: 208.924127ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T16:59:34.928554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.273882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zdg8l\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-19T16:59:34.92864Z","caller":"traceutil/trace.go:171","msg":"trace[1492381107] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zdg8l; range_end:; response_count:1; response_revision:587; }","duration":"178.409104ms","start":"2026-05-19T16:59:34.750208Z","end":"2026-05-19T16:59:34.928617Z","steps":["trace[1492381107] 'agreement among raft nodes before linearized reading' (duration: 178.185342ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T16:59:34.92871Z","caller":"traceutil/trace.go:171","msg":"trace[1439730195] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:609; }","duration":"178.439104ms","start":"2026-05-19T16:59:34.750257Z","end":"2026-05-19T16:59:34.928696Z","steps":["trace[1439730195] 'read index received' (duration: 177.869999ms)","trace[1439730195] 'applied index is now lower than readState.Index' (duration: 139.501µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T16:59:35.299856Z","caller":"traceutil/trace.go:171","msg":"trace[1749131561] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"258.756762ms","start":"2026-05-19T16:59:35.041076Z","end":"2026-05-19T16:59:35.299833Z","steps":["trace[1749131561] 'read index received' (duration: 258.57728ms)","trace[1749131561] 'applied index is now lower than readState.Index' (duration: 178.342µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T16:59:35.29998Z","caller":"traceutil/trace.go:171","msg":"trace[1133258508] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"358.43201ms","start":"2026-05-19T16:59:34.941539Z","end":"2026-05-19T16:59:35.299971Z","steps":["trace[1133258508] 'process raft request' (duration: 358.144457ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T16:59:35.300078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T16:59:34.941514Z","time spent":"358.49078ms","remote":"127.0.0.1:42596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T16:59:35.300239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.159955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.30\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-19T16:59:35.300271Z","caller":"traceutil/trace.go:171","msg":"trace[2136418222] range","detail":"{range_begin:/registry/masterleases/199.204.45.30; range_end:; response_count:1; response_revision:588; }","duration":"259.215455ms","start":"2026-05-19T16:59:35.041044Z","end":"2026-05-19T16:59:35.30026Z","steps":["trace[2136418222] 'agreement among raft nodes before linearized reading' (duration: 259.133075ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T16:59:35.300487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.778087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T16:59:35.300524Z","caller":"traceutil/trace.go:171","msg":"trace[944454450] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:588; }","duration":"252.825567ms","start":"2026-05-19T16:59:35.047689Z","end":"2026-05-19T16:59:35.300514Z","steps":["trace[944454450] 'agreement among raft nodes before linearized reading' (duration: 252.766657ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T16:59:35.862921Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421303853594638893,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T16:59:36.364128Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421303853594638893,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T16:59:36.864669Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421303853594638893,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T16:59:37.36541Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421303853594638893,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T16:59:37.462216Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.105931284s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-19T16:59:37.522214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.083260534s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T16:59:37.522328Z","caller":"traceutil/trace.go:171","msg":"trace[842960745] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:589; }","duration":"2.083388075s","start":"2026-05-19T16:59:35.438922Z","end":"2026-05-19T16:59:37.52231Z","steps":["trace[842960745] 'range keys from in-memory index tree' (duration: 2.083243044s)"],"step_count":1} {"level":"info","ts":"2026-05-19T16:59:37.523099Z","caller":"traceutil/trace.go:171","msg":"trace[1159127002] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:613; }","duration":"2.160417523s","start":"2026-05-19T16:59:35.362658Z","end":"2026-05-19T16:59:37.523075Z","steps":["trace[1159127002] 'read index received' (duration: 2.099895758s)","trace[1159127002] 'applied index is now lower than readState.Index' (duration: 60.520855ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T16:59:37.523936Z","caller":"traceutil/trace.go:171","msg":"trace[1241362734] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"353.378823ms","start":"2026-05-19T16:59:37.170463Z","end":"2026-05-19T16:59:37.523841Z","steps":["trace[1241362734] 'process raft request' (duration: 352.559855ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T16:59:37.524139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T16:59:37.170423Z","time spent":"353.610275ms","remote":"127.0.0.1:42686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T16:59:37.524179Z","caller":"traceutil/trace.go:171","msg":"trace[1397423072] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"2.168948782s","start":"2026-05-19T16:59:35.355171Z","end":"2026-05-19T16:59:37.52412Z","steps":["trace[1397423072] 'process raft request' (duration: 2.107655099s)","trace[1397423072] 'compare' (duration: 59.81902ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T16:59:37.524396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T16:59:35.355154Z","time spent":"2.169123934s","remote":"127.0.0.1:42516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T16:59:37.524512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.146341564s","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-05-19T16:59:37.524549Z","caller":"traceutil/trace.go:171","msg":"trace[529838095] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:591; }","duration":"2.146428835s","start":"2026-05-19T16:59:35.378107Z","end":"2026-05-19T16:59:37.524536Z","steps":["trace[529838095] 'agreement among raft nodes before linearized reading' (duration: 2.146325304s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T16:59:37.524582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T16:59:35.378077Z","time spent":"2.146496665s","remote":"127.0.0.1:42686","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":"warn","ts":"2026-05-19T16:59:37.524393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.161716935s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-19T16:59:37.524663Z","caller":"traceutil/trace.go:171","msg":"trace[2128158901] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:591; }","duration":"2.162019379s","start":"2026-05-19T16:59:35.362619Z","end":"2026-05-19T16:59:37.524638Z","steps":["trace[2128158901] 'agreement among raft nodes before linearized reading' (duration: 2.161701106s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T16:59:37.524789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T16:59:35.362607Z","time spent":"2.1621569s","remote":"127.0.0.1:42686","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-05-19T16:59:37.524847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.476745472s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T16:59:37.524873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.146207063s","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-05-19T16:59:37.524921Z","caller":"traceutil/trace.go:171","msg":"trace[913927946] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:591; }","duration":"2.146270933s","start":"2026-05-19T16:59:35.378636Z","end":"2026-05-19T16:59:37.524907Z","steps":["trace[913927946] 'agreement among raft nodes before linearized reading' (duration: 2.146112922s)"],"step_count":1} {"level":"info","ts":"2026-05-19T16:59:37.524924Z","caller":"traceutil/trace.go:171","msg":"trace[1146056555] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:591; }","duration":"1.476859563s","start":"2026-05-19T16:59:36.048042Z","end":"2026-05-19T16:59:37.524901Z","steps":["trace[1146056555] 'agreement among raft nodes before linearized reading' (duration: 1.476732912s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T16:59:37.52495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T16:59:35.378628Z","time spent":"2.146314404s","remote":"127.0.0.1:42686","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":"warn","ts":"2026-05-19T16:59:37.52493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.364831354s","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-05-19T16:59:37.525034Z","caller":"traceutil/trace.go:171","msg":"trace[749620875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:591; }","duration":"1.365036407s","start":"2026-05-19T16:59:36.159978Z","end":"2026-05-19T16:59:37.525015Z","steps":["trace[749620875] 'agreement among raft nodes before linearized reading' (duration: 1.364704174s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T16:59:37.525107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T16:59:36.159945Z","time spent":"1.365151098s","remote":"127.0.0.1:42686","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-05-19T16:59:37.524965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T16:59:36.04802Z","time spent":"1.476938323s","remote":"127.0.0.1:42388","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-05-19T16:59:38.097484Z","caller":"traceutil/trace.go:171","msg":"trace[1783635670] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"233.480891ms","start":"2026-05-19T16:59:37.863979Z","end":"2026-05-19T16:59:38.09746Z","steps":["trace[1783635670] 'process raft request' (duration: 233.285789ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T16:59:38.354885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.42182ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T16:59:38.355045Z","caller":"traceutil/trace.go:171","msg":"trace[441465160] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"270.149926ms","start":"2026-05-19T16:59:38.084871Z","end":"2026-05-19T16:59:38.35502Z","steps":["trace[441465160] 'process raft request' (duration: 169.470133ms)","trace[441465160] 'compare' (duration: 99.923836ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T16:59:38.366623Z","caller":"traceutil/trace.go:171","msg":"trace[627476614] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"214.167682ms","start":"2026-05-19T16:59:38.152424Z","end":"2026-05-19T16:59:38.366591Z","steps":["trace[627476614] 'process raft request' (duration: 214.04131ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T16:59:38.535195Z","caller":"traceutil/trace.go:171","msg":"trace[516414802] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"116.015784ms","start":"2026-05-19T16:59:38.419153Z","end":"2026-05-19T16:59:38.535169Z","steps":["trace[516414802] 'process raft request' (duration: 115.859382ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T16:59:45.846388Z","caller":"traceutil/trace.go:171","msg":"trace[1442300185] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"109.05076ms","start":"2026-05-19T16:59:45.737301Z","end":"2026-05-19T16:59:45.846352Z","steps":["trace[1442300185] 'process raft request' (duration: 104.414577ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T16:59:46.468637Z","caller":"traceutil/trace.go:171","msg":"trace[2097858183] linearizableReadLoop","detail":"{readStateIndex:736; appliedIndex:735; }","duration":"111.181618ms","start":"2026-05-19T16:59:46.357425Z","end":"2026-05-19T16:59:46.468607Z","steps":["trace[2097858183] 'read index received' (duration: 110.917925ms)","trace[2097858183] 'applied index is now lower than readState.Index' (duration: 262.133µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T16:59:46.468768Z","caller":"traceutil/trace.go:171","msg":"trace[474232206] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"113.69842ms","start":"2026-05-19T16:59:46.355059Z","end":"2026-05-19T16:59:46.468757Z","steps":["trace[474232206] 'process raft request' (duration: 113.368487ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T16:59:46.469065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.49059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T16:59:46.469292Z","caller":"traceutil/trace.go:171","msg":"trace[32490371] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:709; }","duration":"111.719173ms","start":"2026-05-19T16:59:46.357525Z","end":"2026-05-19T16:59:46.469244Z","steps":["trace[32490371] 'agreement among raft nodes before linearized reading' (duration: 111.47302ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T16:59:46.469132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.665243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T16:59:46.469483Z","caller":"traceutil/trace.go:171","msg":"trace[1315877437] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:709; }","duration":"112.045086ms","start":"2026-05-19T16:59:46.357418Z","end":"2026-05-19T16:59:46.469463Z","steps":["trace[1315877437] 'agreement among raft nodes before linearized reading' (duration: 111.604211ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T16:59:46.46912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.399001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T16:59:46.469622Z","caller":"traceutil/trace.go:171","msg":"trace[1419549375] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:709; }","duration":"111.926075ms","start":"2026-05-19T16:59:46.357682Z","end":"2026-05-19T16:59:46.469608Z","steps":["trace[1419549375] 'agreement among raft nodes before linearized reading' (duration: 111.359689ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T16:59:47.881153Z","caller":"traceutil/trace.go:171","msg":"trace[2110730719] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"220.008452ms","start":"2026-05-19T16:59:47.661102Z","end":"2026-05-19T16:59:47.88111Z","steps":["trace[2110730719] 'process raft request' (duration: 219.922661ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T16:59:47.881181Z","caller":"traceutil/trace.go:171","msg":"trace[1195983006] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"220.486776ms","start":"2026-05-19T16:59:47.660653Z","end":"2026-05-19T16:59:47.881139Z","steps":["trace[1195983006] 'process raft request' (duration: 219.985102ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T16:59:47.992669Z","caller":"traceutil/trace.go:171","msg":"trace[1309572592] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"298.120028ms","start":"2026-05-19T16:59:47.694477Z","end":"2026-05-19T16:59:47.992597Z","steps":["trace[1309572592] 'process raft request' (duration: 250.323735ms)","trace[1309572592] 'compare' (duration: 47.583851ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T16:59:47.992689Z","caller":"traceutil/trace.go:171","msg":"trace[1505579376] linearizableReadLoop","detail":"{readStateIndex:741; appliedIndex:738; }","duration":"284.751359ms","start":"2026-05-19T16:59:47.707883Z","end":"2026-05-19T16:59:47.992635Z","steps":["trace[1505579376] 'read index received' (duration: 172.739695ms)","trace[1505579376] 'applied index is now lower than readState.Index' (duration: 112.008724ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T16:59:47.993277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.347094ms","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-05-19T16:59:47.993344Z","caller":"traceutil/trace.go:171","msg":"trace[1265545566] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:715; }","duration":"285.506275ms","start":"2026-05-19T16:59:47.707822Z","end":"2026-05-19T16:59:47.993328Z","steps":["trace[1265545566] 'agreement among raft nodes before linearized reading' (duration: 285.218942ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T16:59:48.245277Z","caller":"traceutil/trace.go:171","msg":"trace[674125326] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"246.271487ms","start":"2026-05-19T16:59:47.998974Z","end":"2026-05-19T16:59:48.245245Z","steps":["trace[674125326] 'process raft request' (duration: 193.699242ms)","trace[674125326] 'compare' (duration: 52.393693ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T16:59:48.246076Z","caller":"traceutil/trace.go:171","msg":"trace[1850950594] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"246.543509ms","start":"2026-05-19T16:59:47.999495Z","end":"2026-05-19T16:59:48.246039Z","steps":["trace[1850950594] 'process raft request' (duration: 246.225495ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T16:59:48.246236Z","caller":"traceutil/trace.go:171","msg":"trace[1099015398] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"242.903477ms","start":"2026-05-19T16:59:48.003307Z","end":"2026-05-19T16:59:48.246211Z","steps":["trace[1099015398] 'process raft request' (duration: 242.493893ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T16:59:54.92841Z","caller":"traceutil/trace.go:171","msg":"trace[1461995603] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"629.562981ms","start":"2026-05-19T16:59:54.298822Z","end":"2026-05-19T16:59:54.928384Z","steps":["trace[1461995603] 'process raft request' (duration: 629.41037ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T16:59:54.928578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T16:59:54.298795Z","time spent":"629.696002ms","remote":"127.0.0.1:42686","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-05-19T16:59:55.515767Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421303853594639217,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T16:59:56.016814Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421303853594639217,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T16:59:56.458813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.019630398s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T16:59:56.45899Z","caller":"traceutil/trace.go:171","msg":"trace[1295101885] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:760; }","duration":"1.019909201s","start":"2026-05-19T16:59:55.439058Z","end":"2026-05-19T16:59:56.458967Z","steps":["trace[1295101885] 'range keys from in-memory index tree' (duration: 1.019609718s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T16:59:56.459213Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.53059998s","expected-duration":"1s"} {"level":"info","ts":"2026-05-19T16:59:56.46Z","caller":"traceutil/trace.go:171","msg":"trace[1817644054] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"1.827180332s","start":"2026-05-19T16:59:54.632767Z","end":"2026-05-19T16:59:56.459947Z","steps":["trace[1817644054] 'process raft request' (duration: 1.826684208s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T16:59:56.460268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T16:59:54.632734Z","time spent":"1.827343644s","remote":"127.0.0.1:42686","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-05-19T16:59:56.461909Z","caller":"traceutil/trace.go:171","msg":"trace[439202387] linearizableReadLoop","detail":"{readStateIndex:790; appliedIndex:788; }","duration":"1.447193612s","start":"2026-05-19T16:59:55.014687Z","end":"2026-05-19T16:59:56.46188Z","steps":["trace[439202387] 'read index received' (duration: 1.444681669s)","trace[439202387] 'applied index is now lower than readState.Index' (duration: 2.510353ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T16:59:56.46206Z","caller":"traceutil/trace.go:171","msg":"trace[2094422459] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"1.563784797s","start":"2026-05-19T16:59:54.898262Z","end":"2026-05-19T16:59:56.462047Z","steps":["trace[2094422459] 'process raft request' (duration: 1.561363555s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T16:59:56.462197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T16:59:54.898235Z","time spent":"1.563860739s","remote":"127.0.0.1:42652","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-05-19T16:59:56.462463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.25881ms","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-05-19T16:59:56.462502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.047159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-19T16:59:56.462532Z","caller":"traceutil/trace.go:171","msg":"trace[328828001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:762; }","duration":"501.35077ms","start":"2026-05-19T16:59:55.961163Z","end":"2026-05-19T16:59:56.462514Z","steps":["trace[328828001] 'agreement among raft nodes before linearized reading' (duration: 501.162969ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T16:59:56.46257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T16:59:55.961152Z","time spent":"501.409851ms","remote":"127.0.0.1:42686","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-05-19T16:59:56.462585Z","caller":"traceutil/trace.go:171","msg":"trace[1714066108] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:762; }","duration":"693.139309ms","start":"2026-05-19T16:59:55.769399Z","end":"2026-05-19T16:59:56.462539Z","steps":["trace[1714066108] 'agreement among raft nodes before linearized reading' (duration: 693.004148ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T16:59:56.462595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.260477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-19T16:59:56.462622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.978899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-19T16:59:56.462622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T16:59:55.769379Z","time spent":"693.234561ms","remote":"127.0.0.1:42686","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":"info","ts":"2026-05-19T16:59:56.462674Z","caller":"traceutil/trace.go:171","msg":"trace[1828635137] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:762; }","duration":"698.392237ms","start":"2026-05-19T16:59:55.764265Z","end":"2026-05-19T16:59:56.462657Z","steps":["trace[1828635137] 'agreement among raft nodes before linearized reading' (duration: 698.140925ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T16:59:56.462691Z","caller":"traceutil/trace.go:171","msg":"trace[349494363] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:762; }","duration":"528.10784ms","start":"2026-05-19T16:59:55.934568Z","end":"2026-05-19T16:59:56.462675Z","steps":["trace[349494363] 'agreement among raft nodes before linearized reading' (duration: 527.868629ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T16:59:56.462753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T16:59:55.764243Z","time spent":"698.498328ms","remote":"127.0.0.1:42686","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":"warn","ts":"2026-05-19T16:59:56.462771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.415001792s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T16:59:56.462784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T16:59:55.934543Z","time spent":"528.230721ms","remote":"127.0.0.1:42686","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-05-19T16:59:56.462823Z","caller":"traceutil/trace.go:171","msg":"trace[2028099606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:762; }","duration":"1.415136834s","start":"2026-05-19T16:59:55.047672Z","end":"2026-05-19T16:59:56.462809Z","steps":["trace[2028099606] 'agreement among raft nodes before linearized reading' (duration: 1.414995162s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T16:59:56.462862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T16:59:55.047659Z","time spent":"1.415190494s","remote":"127.0.0.1:42392","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-05-19T16:59:56.463009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.421395959s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.30\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-19T16:59:56.463047Z","caller":"traceutil/trace.go:171","msg":"trace[387981785] range","detail":"{range_begin:/registry/masterleases/199.204.45.30; range_end:; response_count:1; response_revision:762; }","duration":"1.42145444s","start":"2026-05-19T16:59:55.04158Z","end":"2026-05-19T16:59:56.463035Z","steps":["trace[387981785] 'agreement among raft nodes before linearized reading' (duration: 1.420743565s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T16:59:56.46307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T16:59:55.041568Z","time spent":"1.421496511s","remote":"127.0.0.1:42436","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.30\" "} {"level":"warn","ts":"2026-05-19T16:59:56.462442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.447744226s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T16:59:56.463955Z","caller":"traceutil/trace.go:171","msg":"trace[1039301807] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:762; }","duration":"1.44930021s","start":"2026-05-19T16:59:55.014631Z","end":"2026-05-19T16:59:56.463931Z","steps":["trace[1039301807] 'agreement among raft nodes before linearized reading' (duration: 1.447712266s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T16:59:56.464013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T16:59:55.014609Z","time spent":"1.4493931s","remote":"127.0.0.1:42388","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-05-19T16:59:56.942039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.291938ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-05-19T16:59:56.942132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T16:59:56.465426Z","time spent":"476.70247ms","remote":"127.0.0.1:42436","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-19T16:59:57.022374Z","caller":"traceutil/trace.go:171","msg":"trace[657568541] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"552.554439ms","start":"2026-05-19T16:59:56.469792Z","end":"2026-05-19T16:59:57.022347Z","steps":["trace[657568541] 'process raft request' (duration: 552.478909ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T16:59:57.022413Z","caller":"traceutil/trace.go:171","msg":"trace[1013745344] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"553.545657ms","start":"2026-05-19T16:59:56.468829Z","end":"2026-05-19T16:59:57.022375Z","steps":["trace[1013745344] 'process raft request' (duration: 553.118194ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T16:59:57.022599Z","caller":"traceutil/trace.go:171","msg":"trace[884585216] linearizableReadLoop","detail":"{readStateIndex:794; appliedIndex:790; }","duration":"552.737741ms","start":"2026-05-19T16:59:56.469838Z","end":"2026-05-19T16:59:57.022576Z","steps":["trace[884585216] 'read index received' (duration: 351.950393ms)","trace[884585216] 'applied index is now lower than readState.Index' (duration: 200.784368ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T16:59:57.022652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T16:59:56.468806Z","time spent":"553.686528ms","remote":"127.0.0.1:42686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T16:59:57.022703Z","caller":"traceutil/trace.go:171","msg":"trace[1953842770] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"552.118795ms","start":"2026-05-19T16:59:56.47056Z","end":"2026-05-19T16:59:57.022679Z","steps":["trace[1953842770] 'process raft request' (duration: 551.748252ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T16:59:57.022826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.976083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T16:59:57.022847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T16:59:56.47055Z","time spent":"552.211796ms","remote":"127.0.0.1:42686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T16:59:57.022888Z","caller":"traceutil/trace.go:171","msg":"trace[1704264335] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:766; }","duration":"553.063384ms","start":"2026-05-19T16:59:56.469805Z","end":"2026-05-19T16:59:57.022868Z","steps":["trace[1704264335] 'agreement among raft nodes before linearized reading' (duration: 552.892542ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T16:59:57.022932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T16:59:56.469794Z","time spent":"553.127304ms","remote":"127.0.0.1:42388","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-05-19T16:59:57.022955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T16:59:56.469779Z","time spent":"552.68745ms","remote":"127.0.0.1:42686","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-05-19T16:59:57.023202Z","caller":"traceutil/trace.go:171","msg":"trace[2129420493] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"553.247725ms","start":"2026-05-19T16:59:56.4692Z","end":"2026-05-19T16:59:57.022447Z","steps":["trace[2129420493] 'process raft request' (duration: 553.026923ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T16:59:57.023298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T16:59:56.469177Z","time spent":"554.073753ms","remote":"127.0.0.1:42686","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-05-19T16:59:57.162903Z","caller":"traceutil/trace.go:171","msg":"trace[2145087121] linearizableReadLoop","detail":"{readStateIndex:796; appliedIndex:795; }","duration":"140.222074ms","start":"2026-05-19T16:59:57.022657Z","end":"2026-05-19T16:59:57.162879Z","steps":["trace[2145087121] 'read index received' (duration: 139.933002ms)","trace[2145087121] 'applied index is now lower than readState.Index' (duration: 286.972µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T16:59:57.162917Z","caller":"traceutil/trace.go:171","msg":"trace[1305876889] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"219.585035ms","start":"2026-05-19T16:59:56.943299Z","end":"2026-05-19T16:59:57.162884Z","steps":["trace[1305876889] 'process raft request' (duration: 219.318143ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T16:59:57.163144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.143411ms","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-05-19T16:59:57.163174Z","caller":"traceutil/trace.go:171","msg":"trace[1426473872] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:767; }","duration":"228.265312ms","start":"2026-05-19T16:59:56.9349Z","end":"2026-05-19T16:59:57.163166Z","steps":["trace[1426473872] 'agreement among raft nodes before linearized reading' (duration: 228.095891ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T16:59:57.163361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.610022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T16:59:57.163407Z","caller":"traceutil/trace.go:171","msg":"trace[878861746] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:767; }","duration":"137.688732ms","start":"2026-05-19T16:59:57.025713Z","end":"2026-05-19T16:59:57.163402Z","steps":["trace[878861746] 'agreement among raft nodes before linearized reading' (duration: 137.623602ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T16:59:57.324094Z","caller":"traceutil/trace.go:171","msg":"trace[627160945] linearizableReadLoop","detail":"{readStateIndex:797; appliedIndex:796; }","duration":"155.245348ms","start":"2026-05-19T16:59:57.168821Z","end":"2026-05-19T16:59:57.324066Z","steps":["trace[627160945] 'read index received' (duration: 153.13712ms)","trace[627160945] 'applied index is now lower than readState.Index' (duration: 2.106958ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T16:59:57.324325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.473281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-19T16:59:57.324359Z","caller":"traceutil/trace.go:171","msg":"trace[2002409158] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:768; }","duration":"155.542481ms","start":"2026-05-19T16:59:57.168805Z","end":"2026-05-19T16:59:57.324347Z","steps":["trace[2002409158] 'agreement among raft nodes before linearized reading' (duration: 155.358759ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T16:59:57.324354Z","caller":"traceutil/trace.go:171","msg":"trace[1522178623] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"155.907575ms","start":"2026-05-19T16:59:57.168339Z","end":"2026-05-19T16:59:57.324246Z","steps":["trace[1522178623] 'process raft request' (duration: 153.699225ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:00:06.103859Z","caller":"traceutil/trace.go:171","msg":"trace[1030579875] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"163.886104ms","start":"2026-05-19T17:00:05.939946Z","end":"2026-05-19T17:00:06.103832Z","steps":["trace[1030579875] 'process raft request' (duration: 163.695702ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:00:06.282366Z","caller":"traceutil/trace.go:171","msg":"trace[1896290803] linearizableReadLoop","detail":"{readStateIndex:889; appliedIndex:888; }","duration":"172.169836ms","start":"2026-05-19T17:00:06.11015Z","end":"2026-05-19T17:00:06.28232Z","steps":["trace[1896290803] 'read index received' (duration: 163.771622ms)","trace[1896290803] 'applied index is now lower than readState.Index' (duration: 8.396454ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:00:06.282422Z","caller":"traceutil/trace.go:171","msg":"trace[1746589424] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"196.703594ms","start":"2026-05-19T17:00:06.085689Z","end":"2026-05-19T17:00:06.282393Z","steps":["trace[1746589424] 'process raft request' (duration: 188.216059ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:00:06.282634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.461569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-rq2zv\" ","response":"range_response_count:1 size:4776"} {"level":"info","ts":"2026-05-19T17:00:06.282683Z","caller":"traceutil/trace.go:171","msg":"trace[1919714005] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-rq2zv; range_end:; response_count:1; response_revision:857; }","duration":"172.572789ms","start":"2026-05-19T17:00:06.110096Z","end":"2026-05-19T17:00:06.282668Z","steps":["trace[1919714005] 'agreement among raft nodes before linearized reading' (duration: 172.357467ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:00:06.317748Z","caller":"traceutil/trace.go:171","msg":"trace[962027617] transaction","detail":"{read_only:false; response_revision:859; number_of_response:1; }","duration":"206.642012ms","start":"2026-05-19T17:00:06.111073Z","end":"2026-05-19T17:00:06.317715Z","steps":["trace[962027617] 'process raft request' (duration: 206.544031ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:00:06.317768Z","caller":"traceutil/trace.go:171","msg":"trace[2030229470] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"207.312347ms","start":"2026-05-19T17:00:06.110413Z","end":"2026-05-19T17:00:06.317726Z","steps":["trace[2030229470] 'process raft request' (duration: 207.011595ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:00:06.535322Z","caller":"traceutil/trace.go:171","msg":"trace[1227613325] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"130.118693ms","start":"2026-05-19T17:00:06.40517Z","end":"2026-05-19T17:00:06.535289Z","steps":["trace[1227613325] 'process raft request' (duration: 86.185154ms)","trace[1227613325] 'compare' (duration: 43.444415ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:00:06.535366Z","caller":"traceutil/trace.go:171","msg":"trace[1754402435] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"127.342599ms","start":"2026-05-19T17:00:06.408007Z","end":"2026-05-19T17:00:06.53535Z","steps":["trace[1754402435] 'process raft request' (duration: 126.946006ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:00:06.818107Z","caller":"traceutil/trace.go:171","msg":"trace[669794205] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"276.745103ms","start":"2026-05-19T17:00:06.541317Z","end":"2026-05-19T17:00:06.818062Z","steps":["trace[669794205] 'process raft request' (duration: 274.20658ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:00:06.818291Z","caller":"traceutil/trace.go:171","msg":"trace[1551389380] linearizableReadLoop","detail":"{readStateIndex:906; appliedIndex:902; }","duration":"276.728642ms","start":"2026-05-19T17:00:06.541512Z","end":"2026-05-19T17:00:06.818241Z","steps":["trace[1551389380] 'read index received' (duration: 41.459127ms)","trace[1551389380] 'applied index is now lower than readState.Index' (duration: 235.268395ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:00:06.818386Z","caller":"traceutil/trace.go:171","msg":"trace[1795460580] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"276.881153ms","start":"2026-05-19T17:00:06.541472Z","end":"2026-05-19T17:00:06.818353Z","steps":["trace[1795460580] 'process raft request' (duration: 276.633051ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:00:06.818493Z","caller":"traceutil/trace.go:171","msg":"trace[591957488] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"277.098716ms","start":"2026-05-19T17:00:06.541373Z","end":"2026-05-19T17:00:06.818472Z","steps":["trace[591957488] 'process raft request' (duration: 276.604491ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:00:06.818508Z","caller":"traceutil/trace.go:171","msg":"trace[677147124] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"276.279009ms","start":"2026-05-19T17:00:06.542198Z","end":"2026-05-19T17:00:06.818477Z","steps":["trace[677147124] 'process raft request' (duration: 275.952606ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:00:06.818549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.992006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-k5g9l\" ","response":"range_response_count:1 size:4883"} {"level":"info","ts":"2026-05-19T17:00:06.818589Z","caller":"traceutil/trace.go:171","msg":"trace[1718369135] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-k5g9l; range_end:; response_count:1; response_revision:876; }","duration":"277.086096ms","start":"2026-05-19T17:00:06.541492Z","end":"2026-05-19T17:00:06.818579Z","steps":["trace[1718369135] 'agreement among raft nodes before linearized reading' (duration: 276.885224ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:00:06.819012Z","caller":"traceutil/trace.go:171","msg":"trace[1747170832] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"274.546384ms","start":"2026-05-19T17:00:06.544363Z","end":"2026-05-19T17:00:06.818909Z","steps":["trace[1747170832] 'process raft request' (duration: 273.826997ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:00:07.107461Z","caller":"traceutil/trace.go:171","msg":"trace[2030132573] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"280.145172ms","start":"2026-05-19T17:00:06.827288Z","end":"2026-05-19T17:00:07.107433Z","steps":["trace[2030132573] 'process raft request' (duration: 278.549908ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:00:07.260566Z","caller":"traceutil/trace.go:171","msg":"trace[1286775919] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"147.160813ms","start":"2026-05-19T17:00:07.113366Z","end":"2026-05-19T17:00:07.260527Z","steps":["trace[1286775919] 'process raft request' (duration: 145.318327ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:00:07.260646Z","caller":"traceutil/trace.go:171","msg":"trace[1407191851] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"147.172083ms","start":"2026-05-19T17:00:07.113437Z","end":"2026-05-19T17:00:07.260609Z","steps":["trace[1407191851] 'process raft request' (duration: 146.980342ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:00:07.26079Z","caller":"traceutil/trace.go:171","msg":"trace[1877958768] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"146.879ms","start":"2026-05-19T17:00:07.113893Z","end":"2026-05-19T17:00:07.260771Z","steps":["trace[1877958768] 'process raft request' (duration: 146.648478ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:00:07.286353Z","caller":"traceutil/trace.go:171","msg":"trace[1186801589] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"169.180138ms","start":"2026-05-19T17:00:07.117152Z","end":"2026-05-19T17:00:07.286332Z","steps":["trace[1186801589] 'process raft request' (duration: 169.033287ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:00:11.764878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.010072ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-19T17:00:11.765014Z","caller":"traceutil/trace.go:171","msg":"trace[405253645] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"175.76074ms","start":"2026-05-19T17:00:11.589231Z","end":"2026-05-19T17:00:11.764992Z","steps":["trace[405253645] 'process raft request' (duration: 57.546468ms)","trace[405253645] 'compare' (duration: 117.675188ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:00:27.437777Z","caller":"traceutil/trace.go:171","msg":"trace[1502063493] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"204.149728ms","start":"2026-05-19T17:00:27.233587Z","end":"2026-05-19T17:00:27.437737Z","steps":["trace[1502063493] 'process raft request' (duration: 203.899266ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:00:27.592546Z","caller":"traceutil/trace.go:171","msg":"trace[1094968396] linearizableReadLoop","detail":"{readStateIndex:1030; appliedIndex:1028; }","duration":"257.452903ms","start":"2026-05-19T17:00:27.335065Z","end":"2026-05-19T17:00:27.592518Z","steps":["trace[1094968396] 'read index received' (duration: 102.436302ms)","trace[1094968396] 'applied index is now lower than readState.Index' (duration: 155.015341ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:00:27.592632Z","caller":"traceutil/trace.go:171","msg":"trace[16153119] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"281.355251ms","start":"2026-05-19T17:00:27.311218Z","end":"2026-05-19T17:00:27.592573Z","steps":["trace[16153119] 'process raft request' (duration: 250.726014ms)","trace[16153119] 'compare' (duration: 30.320965ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:00:27.592796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.35359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-19T17:00:27.59284Z","caller":"traceutil/trace.go:171","msg":"trace[1234369597] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:993; }","duration":"181.40071ms","start":"2026-05-19T17:00:27.411425Z","end":"2026-05-19T17:00:27.592826Z","steps":["trace[1234369597] 'agreement among raft nodes before linearized reading' (duration: 181.255289ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:00:27.592913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.608975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T17:00:27.592949Z","caller":"traceutil/trace.go:171","msg":"trace[2016865] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:993; }","duration":"257.918047ms","start":"2026-05-19T17:00:27.335024Z","end":"2026-05-19T17:00:27.592942Z","steps":["trace[2016865] 'agreement among raft nodes before linearized reading' (duration: 257.610155ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:00:29.665969Z","caller":"traceutil/trace.go:171","msg":"trace[1907113646] linearizableReadLoop","detail":"{readStateIndex:1037; appliedIndex:1036; }","duration":"223.575685ms","start":"2026-05-19T17:00:29.442361Z","end":"2026-05-19T17:00:29.665936Z","steps":["trace[1907113646] 'read index received' (duration: 223.367454ms)","trace[1907113646] 'applied index is now lower than readState.Index' (duration: 206.701µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:00:29.666417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.027999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-19T17:00:29.666526Z","caller":"traceutil/trace.go:171","msg":"trace[1842973416] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1000; }","duration":"224.219331ms","start":"2026-05-19T17:00:29.442281Z","end":"2026-05-19T17:00:29.666501Z","steps":["trace[1842973416] 'agreement among raft nodes before linearized reading' (duration: 223.815088ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:00:29.666148Z","caller":"traceutil/trace.go:171","msg":"trace[1761689755] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"687.600783ms","start":"2026-05-19T17:00:28.978478Z","end":"2026-05-19T17:00:29.666079Z","steps":["trace[1761689755] 'process raft request' (duration: 687.25778ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:00:29.666753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:00:28.978448Z","time spent":"688.209327ms","remote":"127.0.0.1:42594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:00:29.903841Z","caller":"traceutil/trace.go:171","msg":"trace[413275516] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"232.341611ms","start":"2026-05-19T17:00:29.671452Z","end":"2026-05-19T17:00:29.903794Z","steps":["trace[413275516] 'process raft request' (duration: 221.96711ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:00:29.903864Z","caller":"traceutil/trace.go:171","msg":"trace[737480231] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"232.315261ms","start":"2026-05-19T17:00:29.671495Z","end":"2026-05-19T17:00:29.903811Z","steps":["trace[737480231] 'process raft request' (duration: 232.027358ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:00:29.903962Z","caller":"traceutil/trace.go:171","msg":"trace[81730553] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"230.349805ms","start":"2026-05-19T17:00:29.673573Z","end":"2026-05-19T17:00:29.903923Z","steps":["trace[81730553] 'process raft request' (duration: 230.058583ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:00:29.904278Z","caller":"traceutil/trace.go:171","msg":"trace[1772654796] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"230.527186ms","start":"2026-05-19T17:00:29.673736Z","end":"2026-05-19T17:00:29.904263Z","steps":["trace[1772654796] 'process raft request' (duration: 230.118383ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:00:30.359555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.97719ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-19T17:00:30.367854Z","caller":"traceutil/trace.go:171","msg":"trace[2001092967] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"213.311635ms","start":"2026-05-19T17:00:30.154513Z","end":"2026-05-19T17:00:30.367825Z","steps":["trace[2001092967] 'process raft request' (duration: 213.131703ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:00:31.858361Z","caller":"traceutil/trace.go:171","msg":"trace[1021059342] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"142.909972ms","start":"2026-05-19T17:00:31.715417Z","end":"2026-05-19T17:00:31.858327Z","steps":["trace[1021059342] 'process raft request' (duration: 142.725981ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:00:32.170415Z","caller":"traceutil/trace.go:171","msg":"trace[1471689377] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"164.126246ms","start":"2026-05-19T17:00:32.006254Z","end":"2026-05-19T17:00:32.170381Z","steps":["trace[1471689377] 'process raft request' (duration: 163.934623ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:00:32.174535Z","caller":"traceutil/trace.go:171","msg":"trace[344426835] linearizableReadLoop","detail":"{readStateIndex:1053; appliedIndex:1049; }","duration":"125.811852ms","start":"2026-05-19T17:00:32.048688Z","end":"2026-05-19T17:00:32.1745Z","steps":["trace[344426835] 'read index received' (duration: 121.661616ms)","trace[344426835] 'applied index is now lower than readState.Index' (duration: 4.148626ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:00:32.174592Z","caller":"traceutil/trace.go:171","msg":"trace[1272484539] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"167.847337ms","start":"2026-05-19T17:00:32.006722Z","end":"2026-05-19T17:00:32.174569Z","steps":["trace[1272484539] 'process raft request' (duration: 167.685955ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:00:32.174677Z","caller":"traceutil/trace.go:171","msg":"trace[502709878] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"167.859868ms","start":"2026-05-19T17:00:32.006775Z","end":"2026-05-19T17:00:32.174635Z","steps":["trace[502709878] 'process raft request' (duration: 167.673525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:00:32.17472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.011834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:00:32.174557Z","caller":"traceutil/trace.go:171","msg":"trace[518378327] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"167.851457ms","start":"2026-05-19T17:00:32.006664Z","end":"2026-05-19T17:00:32.174515Z","steps":["trace[518378327] 'process raft request' (duration: 167.537034ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:00:32.174777Z","caller":"traceutil/trace.go:171","msg":"trace[424793493] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1015; }","duration":"126.155735ms","start":"2026-05-19T17:00:32.048605Z","end":"2026-05-19T17:00:32.17476Z","steps":["trace[424793493] 'agreement among raft nodes before linearized reading' (duration: 126.040564ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:00:34.689943Z","caller":"traceutil/trace.go:171","msg":"trace[316064609] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"145.369888ms","start":"2026-05-19T17:00:34.544538Z","end":"2026-05-19T17:00:34.689908Z","steps":["trace[316064609] 'process raft request' (duration: 145.141505ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:00:35.191069Z","caller":"traceutil/trace.go:171","msg":"trace[1635948564] linearizableReadLoop","detail":"{readStateIndex:1061; appliedIndex:1061; }","duration":"142.14287ms","start":"2026-05-19T17:00:35.048901Z","end":"2026-05-19T17:00:35.191044Z","steps":["trace[1635948564] 'read index received' (duration: 142.13557ms)","trace[1635948564] 'applied index is now lower than readState.Index' (duration: 5.97µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:00:35.191211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.248104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T17:00:35.191244Z","caller":"traceutil/trace.go:171","msg":"trace[106275874] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1022; }","duration":"138.325628ms","start":"2026-05-19T17:00:35.052911Z","end":"2026-05-19T17:00:35.191237Z","steps":["trace[106275874] 'agreement among raft nodes before linearized reading' (duration: 138.239494ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:00:35.191433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.536009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:00:35.191463Z","caller":"traceutil/trace.go:171","msg":"trace[1473990193] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1022; }","duration":"142.583992ms","start":"2026-05-19T17:00:35.048872Z","end":"2026-05-19T17:00:35.191456Z","steps":["trace[1473990193] 'agreement among raft nodes before linearized reading' (duration: 142.535129ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:00:35.990119Z","caller":"traceutil/trace.go:171","msg":"trace[643304343] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"113.760214ms","start":"2026-05-19T17:00:35.876318Z","end":"2026-05-19T17:00:35.990079Z","steps":["trace[643304343] 'process raft request' (duration: 113.26456ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:00:36.366629Z","caller":"traceutil/trace.go:171","msg":"trace[868387569] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"166.54392ms","start":"2026-05-19T17:00:36.200048Z","end":"2026-05-19T17:00:36.366592Z","steps":["trace[868387569] 'process raft request' (duration: 80.724544ms)","trace[868387569] 'compare' (duration: 85.26854ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:00:36.366789Z","caller":"traceutil/trace.go:171","msg":"trace[707106560] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"165.959593ms","start":"2026-05-19T17:00:36.200804Z","end":"2026-05-19T17:00:36.366764Z","steps":["trace[707106560] 'process raft request' (duration: 165.860269ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:00:36.367116Z","caller":"traceutil/trace.go:171","msg":"trace[1495316463] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"165.694889ms","start":"2026-05-19T17:00:36.201362Z","end":"2026-05-19T17:00:36.367057Z","steps":["trace[1495316463] 'process raft request' (duration: 165.361453ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:00:37.92569Z","caller":"traceutil/trace.go:171","msg":"trace[230880548] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"151.094305ms","start":"2026-05-19T17:00:37.774568Z","end":"2026-05-19T17:00:37.925662Z","steps":["trace[230880548] 'process raft request' (duration: 150.928677ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:00:42.767986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.969235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T17:00:42.768085Z","caller":"traceutil/trace.go:171","msg":"trace[1621266437] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1048; }","duration":"130.100951ms","start":"2026-05-19T17:00:42.637963Z","end":"2026-05-19T17:00:42.768064Z","steps":["trace[1621266437] 'count revisions from in-memory index tree' (duration: 129.849999ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:01:07.171655Z","caller":"traceutil/trace.go:171","msg":"trace[1244548745] linearizableReadLoop","detail":"{readStateIndex:1173; appliedIndex:1172; }","duration":"122.985877ms","start":"2026-05-19T17:01:07.048646Z","end":"2026-05-19T17:01:07.171632Z","steps":["trace[1244548745] 'read index received' (duration: 122.871423ms)","trace[1244548745] 'applied index is now lower than readState.Index' (duration: 113.414µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:01:07.171768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.097492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:01:07.171797Z","caller":"traceutil/trace.go:171","msg":"trace[1912494789] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1128; }","duration":"123.209366ms","start":"2026-05-19T17:01:07.048578Z","end":"2026-05-19T17:01:07.171787Z","steps":["trace[1912494789] 'agreement among raft nodes before linearized reading' (duration: 123.136113ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:01:07.171935Z","caller":"traceutil/trace.go:171","msg":"trace[1948221512] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"165.77431ms","start":"2026-05-19T17:01:07.00613Z","end":"2026-05-19T17:01:07.171904Z","steps":["trace[1948221512] 'process raft request' (duration: 165.341992ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:01:42.835739Z","caller":"traceutil/trace.go:171","msg":"trace[2053752093] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"210.914903ms","start":"2026-05-19T17:01:42.624774Z","end":"2026-05-19T17:01:42.835689Z","steps":["trace[2053752093] 'process raft request' (duration: 209.980751ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:01:42.842873Z","caller":"traceutil/trace.go:171","msg":"trace[1158025792] linearizableReadLoop","detail":"{readStateIndex:1297; appliedIndex:1295; }","duration":"177.357161ms","start":"2026-05-19T17:01:42.665476Z","end":"2026-05-19T17:01:42.842833Z","steps":["trace[1158025792] 'read index received' (duration: 169.302861ms)","trace[1158025792] 'applied index is now lower than readState.Index' (duration: 8.053ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:01:42.842972Z","caller":"traceutil/trace.go:171","msg":"trace[1130810577] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"213.645483ms","start":"2026-05-19T17:01:42.629277Z","end":"2026-05-19T17:01:42.842922Z","steps":["trace[1130810577] 'process raft request' (duration: 213.428676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:01:42.8431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.625369ms","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-05-19T17:01:42.843146Z","caller":"traceutil/trace.go:171","msg":"trace[1899382307] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1244; }","duration":"177.752414ms","start":"2026-05-19T17:01:42.665383Z","end":"2026-05-19T17:01:42.843135Z","steps":["trace[1899382307] 'agreement among raft nodes before linearized reading' (duration: 177.596289ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:01:43.064139Z","caller":"traceutil/trace.go:171","msg":"trace[230916410] linearizableReadLoop","detail":"{readStateIndex:1300; appliedIndex:1299; }","duration":"125.625781ms","start":"2026-05-19T17:01:42.938475Z","end":"2026-05-19T17:01:43.0641Z","steps":["trace[230916410] 'read index received' (duration: 67.667413ms)","trace[230916410] 'applied index is now lower than readState.Index' (duration: 57.956408ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:01:43.064277Z","caller":"traceutil/trace.go:171","msg":"trace[986002797] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"129.527612ms","start":"2026-05-19T17:01:42.934714Z","end":"2026-05-19T17:01:43.064242Z","steps":["trace[986002797] 'process raft request' (duration: 71.380107ms)","trace[986002797] 'compare' (duration: 57.770363ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:01:43.064363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.85962ms","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-05-19T17:01:43.064439Z","caller":"traceutil/trace.go:171","msg":"trace[1270587374] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1247; }","duration":"125.960833ms","start":"2026-05-19T17:01:42.938466Z","end":"2026-05-19T17:01:43.064427Z","steps":["trace[1270587374] 'agreement among raft nodes before linearized reading' (duration: 125.755666ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:01:43.462878Z","caller":"traceutil/trace.go:171","msg":"trace[1586544495] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"248.716027ms","start":"2026-05-19T17:01:43.214131Z","end":"2026-05-19T17:01:43.462847Z","steps":["trace[1586544495] 'process raft request' (duration: 248.558122ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:01:43.464674Z","caller":"traceutil/trace.go:171","msg":"trace[2021383464] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"132.936034ms","start":"2026-05-19T17:01:43.331711Z","end":"2026-05-19T17:01:43.464647Z","steps":["trace[2021383464] 'process raft request' (duration: 132.792849ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:01:43.59657Z","caller":"traceutil/trace.go:171","msg":"trace[591761247] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"112.062729ms","start":"2026-05-19T17:01:43.484455Z","end":"2026-05-19T17:01:43.596518Z","steps":["trace[591761247] 'process raft request' (duration: 109.980051ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:01:43.88217Z","caller":"traceutil/trace.go:171","msg":"trace[1593257411] linearizableReadLoop","detail":"{readStateIndex:1310; appliedIndex:1309; }","duration":"163.601584ms","start":"2026-05-19T17:01:43.718541Z","end":"2026-05-19T17:01:43.882143Z","steps":["trace[1593257411] 'read index received' (duration: 163.323135ms)","trace[1593257411] 'applied index is now lower than readState.Index' (duration: 277.329µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:01:43.882308Z","caller":"traceutil/trace.go:171","msg":"trace[1815014519] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"168.348912ms","start":"2026-05-19T17:01:43.713945Z","end":"2026-05-19T17:01:43.882294Z","steps":["trace[1815014519] 'process raft request' (duration: 168.041392ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:01:43.882678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.112232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2aae0a23-59ec-4493-9b87-f4a875ab998e\" ","response":"range_response_count:1 size:3578"} {"level":"info","ts":"2026-05-19T17:01:43.882764Z","caller":"traceutil/trace.go:171","msg":"trace[1637423449] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2aae0a23-59ec-4493-9b87-f4a875ab998e; range_end:; response_count:1; response_revision:1257; }","duration":"164.244316ms","start":"2026-05-19T17:01:43.718499Z","end":"2026-05-19T17:01:43.882743Z","steps":["trace[1637423449] 'agreement among raft nodes before linearized reading' (duration: 164.116492ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:01:49.63442Z","caller":"traceutil/trace.go:171","msg":"trace[1049093697] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"119.020068ms","start":"2026-05-19T17:01:49.515358Z","end":"2026-05-19T17:01:49.634378Z","steps":["trace[1049093697] 'process raft request' (duration: 104.475367ms)","trace[1049093697] 'compare' (duration: 14.262431ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:01:59.69848Z","caller":"traceutil/trace.go:171","msg":"trace[433874394] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"126.41833ms","start":"2026-05-19T17:01:59.572009Z","end":"2026-05-19T17:01:59.698427Z","steps":["trace[433874394] 'process raft request' (duration: 76.274553ms)","trace[433874394] 'compare' (duration: 49.901059ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:02:00.542256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.581839ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-05-19T17:02:01.454486Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421303853594640625,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-19T17:02:01.816886Z","caller":"traceutil/trace.go:171","msg":"trace[1723498969] linearizableReadLoop","detail":"{readStateIndex:1399; appliedIndex:1398; }","duration":"863.37918ms","start":"2026-05-19T17:02:00.953481Z","end":"2026-05-19T17:02:01.81686Z","steps":["trace[1723498969] 'read index received' (duration: 863.333798ms)","trace[1723498969] 'applied index is now lower than readState.Index' (duration: 43.781µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:02:01.817063Z","caller":"traceutil/trace.go:171","msg":"trace[1973630221] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"863.966549ms","start":"2026-05-19T17:02:00.953031Z","end":"2026-05-19T17:02:01.816997Z","steps":["trace[1973630221] 'process raft request' (duration: 863.592576ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:02:01.817231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:02:00.953006Z","time spent":"864.097613ms","remote":"127.0.0.1:42686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:02:01.818114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.37694ms","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-05-19T17:02:01.818211Z","caller":"traceutil/trace.go:171","msg":"trace[690572765] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1342; }","duration":"864.771262ms","start":"2026-05-19T17:02:00.953417Z","end":"2026-05-19T17:02:01.818188Z","steps":["trace[690572765] 'agreement among raft nodes before linearized reading' (duration: 863.841934ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:02:01.818421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:02:00.953398Z","time spent":"864.938147ms","remote":"127.0.0.1:42686","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-05-19T17:02:01.890035Z","caller":"traceutil/trace.go:171","msg":"trace[745912371] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"259.657538ms","start":"2026-05-19T17:02:01.630341Z","end":"2026-05-19T17:02:01.889999Z","steps":["trace[745912371] 'process raft request' (duration: 259.450132ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:02:01.890419Z","caller":"traceutil/trace.go:171","msg":"trace[1774038956] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"185.77736ms","start":"2026-05-19T17:02:01.704605Z","end":"2026-05-19T17:02:01.890382Z","steps":["trace[1774038956] 'process raft request' (duration: 185.338086ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:02:01.890799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.149394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-19T17:02:01.890801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.299289ms","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-05-19T17:02:01.890846Z","caller":"traceutil/trace.go:171","msg":"trace[1532172536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1344; }","duration":"736.274858ms","start":"2026-05-19T17:02:01.154558Z","end":"2026-05-19T17:02:01.890833Z","steps":["trace[1532172536] 'agreement among raft nodes before linearized reading' (duration: 736.084702ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:02:01.890862Z","caller":"traceutil/trace.go:171","msg":"trace[537149917] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1344; }","duration":"736.412152ms","start":"2026-05-19T17:02:01.154434Z","end":"2026-05-19T17:02:01.890846Z","steps":["trace[537149917] 'agreement among raft nodes before linearized reading' (duration: 736.180706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:02:01.89088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:02:01.154527Z","time spent":"736.34514ms","remote":"127.0.0.1:42686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T17:02:01.890908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:02:01.154414Z","time spent":"736.481434ms","remote":"127.0.0.1:42686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-19T17:02:01.924094Z","caller":"traceutil/trace.go:171","msg":"trace[472158772] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"100.532703ms","start":"2026-05-19T17:02:01.823531Z","end":"2026-05-19T17:02:01.924064Z","steps":["trace[472158772] 'process raft request' (duration: 100.364488ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:02:02.005139Z","caller":"traceutil/trace.go:171","msg":"trace[1905177898] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"109.199008ms","start":"2026-05-19T17:02:01.895895Z","end":"2026-05-19T17:02:02.005094Z","steps":["trace[1905177898] 'process raft request' (duration: 108.60503ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:02:02.005337Z","caller":"traceutil/trace.go:171","msg":"trace[190383467] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"109.089725ms","start":"2026-05-19T17:02:01.896199Z","end":"2026-05-19T17:02:02.005289Z","steps":["trace[190383467] 'process raft request' (duration: 108.842728ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:02:03.075848Z","caller":"traceutil/trace.go:171","msg":"trace[1620637294] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"121.119366ms","start":"2026-05-19T17:02:02.954697Z","end":"2026-05-19T17:02:03.075816Z","steps":["trace[1620637294] 'process raft request' (duration: 120.874008ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:02:04.272677Z","caller":"traceutil/trace.go:171","msg":"trace[1042055414] linearizableReadLoop","detail":"{readStateIndex:1410; appliedIndex:1409; }","duration":"416.457388ms","start":"2026-05-19T17:02:03.856188Z","end":"2026-05-19T17:02:04.272645Z","steps":["trace[1042055414] 'read index received' (duration: 416.233851ms)","trace[1042055414] 'applied index is now lower than readState.Index' (duration: 222.277µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:02:04.272799Z","caller":"traceutil/trace.go:171","msg":"trace[1411559253] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"446.653783ms","start":"2026-05-19T17:02:03.826096Z","end":"2026-05-19T17:02:04.27275Z","steps":["trace[1411559253] 'process raft request' (duration: 446.355294ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:02:04.27288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.654644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:02:04.272925Z","caller":"traceutil/trace.go:171","msg":"trace[1050517059] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1353; }","duration":"416.786389ms","start":"2026-05-19T17:02:03.856126Z","end":"2026-05-19T17:02:04.272912Z","steps":["trace[1050517059] 'agreement among raft nodes before linearized reading' (duration: 416.671854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:02:04.272958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:02:03.826082Z","time spent":"446.790457ms","remote":"127.0.0.1:42686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:02:04.272966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:02:03.8561Z","time spent":"416.85511ms","remote":"127.0.0.1:52822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-19T17:02:04.457993Z","caller":"traceutil/trace.go:171","msg":"trace[62023398] linearizableReadLoop","detail":"{readStateIndex:1411; appliedIndex:1410; }","duration":"185.190825ms","start":"2026-05-19T17:02:04.272764Z","end":"2026-05-19T17:02:04.457955Z","steps":["trace[62023398] 'read index received' (duration: 126.559247ms)","trace[62023398] 'applied index is now lower than readState.Index' (duration: 58.630098ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:02:04.458266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.130773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-19T17:02:04.458365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.309591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:500 ","response":"range_response_count:6 size:2185"} {"level":"info","ts":"2026-05-19T17:02:04.45842Z","caller":"traceutil/trace.go:171","msg":"trace[580605240] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:6; response_revision:1354; }","duration":"529.462196ms","start":"2026-05-19T17:02:03.928946Z","end":"2026-05-19T17:02:04.458408Z","steps":["trace[580605240] 'agreement among raft nodes before linearized reading' (duration: 529.172737ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:02:04.458433Z","caller":"traceutil/trace.go:171","msg":"trace[318891250] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"560.071693ms","start":"2026-05-19T17:02:03.898346Z","end":"2026-05-19T17:02:04.458417Z","steps":["trace[318891250] 'process raft request' (duration: 501.007062ms)","trace[318891250] 'compare' (duration: 58.403272ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:02:04.458467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:02:03.928921Z","time spent":"529.534327ms","remote":"127.0.0.1:42534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":6,"response size":2209,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:500 "} {"level":"warn","ts":"2026-05-19T17:02:04.458423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.590967ms","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-05-19T17:02:04.458373Z","caller":"traceutil/trace.go:171","msg":"trace[454768726] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1354; }","duration":"448.267318ms","start":"2026-05-19T17:02:04.01007Z","end":"2026-05-19T17:02:04.458337Z","steps":["trace[454768726] 'agreement among raft nodes before linearized reading' (duration: 447.925068ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:02:04.458556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:02:03.898304Z","time spent":"560.172036ms","remote":"127.0.0.1:42686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:02:04.458772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.723673ms","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-05-19T17:02:04.458813Z","caller":"traceutil/trace.go:171","msg":"trace[1564248836] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1354; }","duration":"530.797415ms","start":"2026-05-19T17:02:03.928004Z","end":"2026-05-19T17:02:04.458802Z","steps":["trace[1564248836] 'agreement among raft nodes before linearized reading' (duration: 530.680672ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:02:04.458844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:02:03.927984Z","time spent":"530.852607ms","remote":"127.0.0.1:42686","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":"warn","ts":"2026-05-19T17:02:04.458739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:02:04.010058Z","time spent":"448.598677ms","remote":"127.0.0.1:42686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T17:02:04.458936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.650419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:02:04.459004Z","caller":"traceutil/trace.go:171","msg":"trace[183359710] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1354; }","duration":"128.744972ms","start":"2026-05-19T17:02:04.330235Z","end":"2026-05-19T17:02:04.45898Z","steps":["trace[183359710] 'agreement among raft nodes before linearized reading' (duration: 128.635148ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:02:04.458555Z","caller":"traceutil/trace.go:171","msg":"trace[1578170633] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1354; }","duration":"448.796013ms","start":"2026-05-19T17:02:04.009726Z","end":"2026-05-19T17:02:04.458522Z","steps":["trace[1578170633] 'agreement among raft nodes before linearized reading' (duration: 448.287468ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:02:04.459135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:02:04.0097Z","time spent":"449.421032ms","remote":"127.0.0.1:42686","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-05-19T17:02:04.688975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.578047ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T17:02:04.689435Z","caller":"traceutil/trace.go:171","msg":"trace[910316178] linearizableReadLoop","detail":"{readStateIndex:1414; appliedIndex:1412; }","duration":"166.919604ms","start":"2026-05-19T17:02:04.522493Z","end":"2026-05-19T17:02:04.689413Z","steps":["trace[910316178] 'read index received' (duration: 38.669826ms)","trace[910316178] 'applied index is now lower than readState.Index' (duration: 128.248447ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:02:04.689559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.060027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/default/\" range_end:\"/registry/secrets/default0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:02:04.689566Z","caller":"traceutil/trace.go:171","msg":"trace[369469564] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"224.629513ms","start":"2026-05-19T17:02:04.464917Z","end":"2026-05-19T17:02:04.689546Z","steps":["trace[369469564] 'process raft request' (duration: 96.367906ms)","trace[369469564] 'compare' (duration: 127.437343ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:02:04.689601Z","caller":"traceutil/trace.go:171","msg":"trace[1044590310] range","detail":"{range_begin:/registry/secrets/default/; range_end:/registry/secrets/default0; response_count:0; response_revision:1357; }","duration":"167.154471ms","start":"2026-05-19T17:02:04.522432Z","end":"2026-05-19T17:02:04.689586Z","steps":["trace[1044590310] 'agreement among raft nodes before linearized reading' (duration: 167.073078ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:02:04.689751Z","caller":"traceutil/trace.go:171","msg":"trace[423917347] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"224.043445ms","start":"2026-05-19T17:02:04.465667Z","end":"2026-05-19T17:02:04.689711Z","steps":["trace[423917347] 'process raft request' (duration: 223.435187ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:02:05.321041Z","caller":"traceutil/trace.go:171","msg":"trace[1339021395] linearizableReadLoop","detail":"{readStateIndex:1417; appliedIndex:1416; }","duration":"214.576201ms","start":"2026-05-19T17:02:05.106433Z","end":"2026-05-19T17:02:05.321009Z","steps":["trace[1339021395] 'read index received' (duration: 214.326863ms)","trace[1339021395] 'applied index is now lower than readState.Index' (duration: 247.848µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:02:05.321163Z","caller":"traceutil/trace.go:171","msg":"trace[1441616243] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"233.830189ms","start":"2026-05-19T17:02:05.087294Z","end":"2026-05-19T17:02:05.321125Z","steps":["trace[1441616243] 'process raft request' (duration: 233.498149ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:02:05.321291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.845679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-05-19T17:02:05.321411Z","caller":"traceutil/trace.go:171","msg":"trace[1886123495] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1359; }","duration":"214.980243ms","start":"2026-05-19T17:02:05.106415Z","end":"2026-05-19T17:02:05.321395Z","steps":["trace[1886123495] 'agreement among raft nodes before linearized reading' (duration: 214.760147ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:02:05.322024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.806737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050272"} {"level":"info","ts":"2026-05-19T17:02:05.322175Z","caller":"traceutil/trace.go:171","msg":"trace[1120083804] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:1359; }","duration":"132.044204ms","start":"2026-05-19T17:02:05.190095Z","end":"2026-05-19T17:02:05.322139Z","steps":["trace[1120083804] 'agreement among raft nodes before linearized reading' (duration: 131.159277ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:02:07.469981Z","caller":"traceutil/trace.go:171","msg":"trace[1920206651] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"139.47985ms","start":"2026-05-19T17:02:07.330449Z","end":"2026-05-19T17:02:07.469929Z","steps":["trace[1920206651] 'process raft request' (duration: 139.281524ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:02:16.648255Z","caller":"traceutil/trace.go:171","msg":"trace[1517533230] transaction","detail":"{read_only:false; response_revision:1394; number_of_response:1; }","duration":"112.684711ms","start":"2026-05-19T17:02:16.535533Z","end":"2026-05-19T17:02:16.648218Z","steps":["trace[1517533230] 'process raft request' (duration: 58.011848ms)","trace[1517533230] 'compare' (duration: 54.361505ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:02:24.915287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.548385ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T17:02:24.915482Z","caller":"traceutil/trace.go:171","msg":"trace[1708119274] linearizableReadLoop","detail":"{readStateIndex:1482; appliedIndex:1481; }","duration":"197.759823ms","start":"2026-05-19T17:02:24.717695Z","end":"2026-05-19T17:02:24.915455Z","steps":["trace[1708119274] 'read index received' (duration: 49.731094ms)","trace[1708119274] 'applied index is now lower than readState.Index' (duration: 148.027229ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:02:24.915593Z","caller":"traceutil/trace.go:171","msg":"trace[1523637986] transaction","detail":"{read_only:false; response_revision:1421; number_of_response:1; }","duration":"199.236183ms","start":"2026-05-19T17:02:24.716341Z","end":"2026-05-19T17:02:24.915578Z","steps":["trace[1523637986] 'process raft request' (duration: 51.067531ms)","trace[1523637986] 'compare' (duration: 147.388631ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:02:24.915835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.891215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kube-proxy\" ","response":"range_response_count:1 size:1948"} {"level":"info","ts":"2026-05-19T17:02:24.915918Z","caller":"traceutil/trace.go:171","msg":"trace[1731448140] range","detail":"{range_begin:/registry/configmaps/kube-system/kube-proxy; range_end:; response_count:1; response_revision:1421; }","duration":"177.017429ms","start":"2026-05-19T17:02:24.738879Z","end":"2026-05-19T17:02:24.915896Z","steps":["trace[1731448140] 'agreement among raft nodes before linearized reading' (duration: 176.859655ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:02:24.915954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.229076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kube-apiserver-legacy-service-account-token-tracking\" ","response":"range_response_count:1 size:370"} {"level":"info","ts":"2026-05-19T17:02:24.915998Z","caller":"traceutil/trace.go:171","msg":"trace[2019303127] range","detail":"{range_begin:/registry/configmaps/kube-system/kube-apiserver-legacy-service-account-token-tracking; range_end:; response_count:1; response_revision:1421; }","duration":"198.337398ms","start":"2026-05-19T17:02:24.717648Z","end":"2026-05-19T17:02:24.915985Z","steps":["trace[2019303127] 'agreement among raft nodes before linearized reading' (duration: 198.220625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:02:24.916229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.390953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/\" range_end:\"/registry/roles/kube-system0\" limit:500 ","response":"range_response_count:11 size:5916"} {"level":"info","ts":"2026-05-19T17:02:24.916288Z","caller":"traceutil/trace.go:171","msg":"trace[1845505615] range","detail":"{range_begin:/registry/roles/kube-system/; range_end:/registry/roles/kube-system0; response_count:11; response_revision:1421; }","duration":"125.476815ms","start":"2026-05-19T17:02:24.790796Z","end":"2026-05-19T17:02:24.916273Z","steps":["trace[1845505615] 'agreement among raft nodes before linearized reading' (duration: 125.256859ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:02:25.08599Z","caller":"traceutil/trace.go:171","msg":"trace[691632178] transaction","detail":"{read_only:false; response_revision:1422; number_of_response:1; }","duration":"164.619377ms","start":"2026-05-19T17:02:24.921346Z","end":"2026-05-19T17:02:25.085965Z","steps":["trace[691632178] 'process raft request' (duration: 160.39793ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:02:25.099239Z","caller":"traceutil/trace.go:171","msg":"trace[28615484] transaction","detail":"{read_only:false; response_revision:1423; number_of_response:1; }","duration":"177.288176ms","start":"2026-05-19T17:02:24.921927Z","end":"2026-05-19T17:02:25.099215Z","steps":["trace[28615484] 'process raft request' (duration: 177.029009ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:02:25.099196Z","caller":"traceutil/trace.go:171","msg":"trace[2072381680] linearizableReadLoop","detail":"{readStateIndex:1484; appliedIndex:1482; }","duration":"100.927406ms","start":"2026-05-19T17:02:24.998233Z","end":"2026-05-19T17:02:25.09916Z","steps":["trace[2072381680] 'read index received' (duration: 83.561099ms)","trace[2072381680] 'applied index is now lower than readState.Index' (duration: 17.364797ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:02:25.099565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.215804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/local-path-storage/\" range_end:\"/registry/rolebindings/local-path-storage0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:02:25.099663Z","caller":"traceutil/trace.go:171","msg":"trace[1733118281] range","detail":"{range_begin:/registry/rolebindings/local-path-storage/; range_end:/registry/rolebindings/local-path-storage0; response_count:0; response_revision:1423; }","duration":"101.46083ms","start":"2026-05-19T17:02:24.998187Z","end":"2026-05-19T17:02:25.099648Z","steps":["trace[1733118281] 'agreement among raft nodes before linearized reading' (duration: 101.094821ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:02:30.637736Z","caller":"traceutil/trace.go:171","msg":"trace[99275748] transaction","detail":"{read_only:false; response_revision:1439; number_of_response:1; }","duration":"108.555625ms","start":"2026-05-19T17:02:30.529143Z","end":"2026-05-19T17:02:30.637698Z","steps":["trace[99275748] 'process raft request' (duration: 108.229675ms)"],"step_count":1}