{"level":"warn","ts":"2026-07-01T10:13:28.463485Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-01T10:13:28.463614Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.223: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.223:2380","--initial-cluster=instance=https://199.204.45.223:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.223:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.223:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-01T10:13:28.464419Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-01T10:13:28.464443Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-07-01T10:13:28.464492Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-01T10:13:28.465518Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"]} {"level":"info","ts":"2026-07-01T10:13:28.465851Z","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.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.223:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-01T10:13:28.470855Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.685307ms"} {"level":"info","ts":"2026-07-01T10:13:28.476912Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"11f26225a5bb9fe3","cluster-id":"75c25101f01fd4c6"} {"level":"info","ts":"2026-07-01T10:13:28.477005Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=()"} {"level":"info","ts":"2026-07-01T10:13:28.477028Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 0"} {"level":"info","ts":"2026-07-01T10:13:28.477041Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 11f26225a5bb9fe3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-01T10:13:28.477082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 1"} {"level":"info","ts":"2026-07-01T10:13:28.477127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"warn","ts":"2026-07-01T10:13:28.483161Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-01T10:13:28.488004Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-01T10:13:28.489169Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-01T10:13:28.491205Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"11f26225a5bb9fe3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-01T10:13:28.491434Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"11f26225a5bb9fe3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-01T10:13:28.49151Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-01T10:13:28.491582Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-01T10:13:28.491594Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-01T10:13:28.492415Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"info","ts":"2026-07-01T10:13:28.497401Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","added-peer-id":"11f26225a5bb9fe3","added-peer-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-07-01T10:13:28.500199Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-01T10:13:28.500419Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-07-01T10:13:28.501464Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-07-01T10:13:28.501735Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"11f26225a5bb9fe3","initial-advertise-peer-urls":["https://199.204.45.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-01T10:13:28.501855Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-01T10:13:29.478223Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 is starting a new election at term 1"} {"level":"info","ts":"2026-07-01T10:13:29.478286Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-01T10:13:29.478321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgPreVoteResp from 11f26225a5bb9fe3 at term 1"} {"level":"info","ts":"2026-07-01T10:13:29.47834Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became candidate at term 2"} {"level":"info","ts":"2026-07-01T10:13:29.47835Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgVoteResp from 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-07-01T10:13:29.478371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became leader at term 2"} {"level":"info","ts":"2026-07-01T10:13:29.478384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 11f26225a5bb9fe3 elected leader 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-07-01T10:13:29.479413Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T10:13:29.480328Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T10:13:29.48042Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T10:13:29.480336Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"11f26225a5bb9fe3","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.223:2379]}","request-path":"/0/members/11f26225a5bb9fe3/attributes","cluster-id":"75c25101f01fd4c6","publish-timeout":"7s"} {"level":"info","ts":"2026-07-01T10:13:29.480733Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-01T10:13:29.480875Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-01T10:13:29.481089Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T10:13:29.481307Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T10:13:29.481405Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T10:13:29.483801Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.223:2379"} {"level":"info","ts":"2026-07-01T10:13:29.484512Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-01T10:13:31.986844Z","caller":"traceutil/trace.go:171","msg":"trace[1351275599] linearizableReadLoop","detail":"{readStateIndex:131; appliedIndex:130; }","duration":"193.159571ms","start":"2026-07-01T10:13:31.793654Z","end":"2026-07-01T10:13:31.986814Z","steps":["trace[1351275599] 'read index received' (duration: 186.844543ms)","trace[1351275599] 'applied index is now lower than readState.Index' (duration: 6.314068ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:13:31.986935Z","caller":"traceutil/trace.go:171","msg":"trace[477538260] transaction","detail":"{read_only:false; response_revision:126; number_of_response:1; }","duration":"221.537921ms","start":"2026-07-01T10:13:31.765374Z","end":"2026-07-01T10:13:31.986911Z","steps":["trace[477538260] 'process raft request' (duration: 215.155481ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:13:31.987128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.45115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-01T10:13:31.987194Z","caller":"traceutil/trace.go:171","msg":"trace[89675362] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:126; }","duration":"193.554723ms","start":"2026-07-01T10:13:31.793627Z","end":"2026-07-01T10:13:31.987182Z","steps":["trace[89675362] 'agreement among raft nodes before linearized reading' (duration: 193.365737ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:13:32.317877Z","caller":"traceutil/trace.go:171","msg":"trace[1403522987] transaction","detail":"{read_only:false; response_revision:134; number_of_response:1; }","duration":"267.393281ms","start":"2026-07-01T10:13:32.050457Z","end":"2026-07-01T10:13:32.31785Z","steps":["trace[1403522987] 'process raft request' (duration: 267.262877ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:13:38.187059Z","caller":"traceutil/trace.go:171","msg":"trace[431603739] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"605.571813ms","start":"2026-07-01T10:13:37.58146Z","end":"2026-07-01T10:13:38.187031Z","steps":["trace[431603739] 'process raft request' (duration: 605.413858ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:13:38.187528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:13:37.581429Z","time spent":"605.711677ms","remote":"127.0.0.1:34388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":186,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-01T10:13:38.815968Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521227219374120948,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-01T10:13:39.278371Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.030466282s","expected-duration":"1s"} {"level":"info","ts":"2026-07-01T10:13:39.362565Z","caller":"traceutil/trace.go:171","msg":"trace[700524283] linearizableReadLoop","detail":"{readStateIndex:343; appliedIndex:342; }","duration":"1.047308181s","start":"2026-07-01T10:13:38.315239Z","end":"2026-07-01T10:13:39.362547Z","steps":["trace[700524283] 'read index received' (duration: 963.265844ms)","trace[700524283] 'applied index is now lower than readState.Index' (duration: 84.041657ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:13:39.362653Z","caller":"traceutil/trace.go:171","msg":"trace[825691245] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"1.160001357s","start":"2026-07-01T10:13:38.202611Z","end":"2026-07-01T10:13:39.362613Z","steps":["trace[825691245] 'process raft request' (duration: 1.076025702s)","trace[825691245] 'compare' (duration: 83.81902ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:13:39.362705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.047465656s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-01T10:13:39.362728Z","caller":"traceutil/trace.go:171","msg":"trace[722845191] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:333; }","duration":"1.047555739s","start":"2026-07-01T10:13:38.315166Z","end":"2026-07-01T10:13:39.362722Z","steps":["trace[722845191] 'agreement among raft nodes before linearized reading' (duration: 1.047476926s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:13:39.362762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:13:38.315137Z","time spent":"1.04761017s","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-01T10:13:39.362889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.858363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-07-01T10:13:39.362928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.63448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T10:13:39.362989Z","caller":"traceutil/trace.go:171","msg":"trace[514089286] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:333; }","duration":"523.723032ms","start":"2026-07-01T10:13:38.839244Z","end":"2026-07-01T10:13:39.362967Z","steps":["trace[514089286] 'agreement among raft nodes before linearized reading' (duration: 523.599398ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:13:39.362983Z","caller":"traceutil/trace.go:171","msg":"trace[460424283] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:333; }","duration":"214.966726ms","start":"2026-07-01T10:13:39.147994Z","end":"2026-07-01T10:13:39.362961Z","steps":["trace[460424283] 'agreement among raft nodes before linearized reading' (duration: 214.812661ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:13:39.363028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:13:38.839224Z","time spent":"523.797215ms","remote":"127.0.0.1:34174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-01T10:13:39.363158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.077057ms","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-07-01T10:13:39.36279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:13:38.2026Z","time spent":"1.160128222s","remote":"127.0.0.1:34388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":186,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T10:13:39.363358Z","caller":"traceutil/trace.go:171","msg":"trace[2091051850] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:333; }","duration":"316.348047ms","start":"2026-07-01T10:13:39.046985Z","end":"2026-07-01T10:13:39.363333Z","steps":["trace[2091051850] 'agreement among raft nodes before linearized reading' (duration: 315.8068ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:13:39.3634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:13:39.046964Z","time spent":"316.426958ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-01T10:13:39.363702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.436606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-01T10:13:39.363763Z","caller":"traceutil/trace.go:171","msg":"trace[934634581] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:333; }","duration":"765.534229ms","start":"2026-07-01T10:13:38.598213Z","end":"2026-07-01T10:13:39.363747Z","steps":["trace[934634581] 'agreement among raft nodes before linearized reading' (duration: 764.505086ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:13:39.363797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:13:38.598193Z","time spent":"765.59681ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-01T10:13:39.867635Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521227219374120951,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-01T10:13:40.179767Z","caller":"traceutil/trace.go:171","msg":"trace[452589762] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"812.3657ms","start":"2026-07-01T10:13:39.367379Z","end":"2026-07-01T10:13:40.179745Z","steps":["trace[452589762] 'process raft request' (duration: 812.204255ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:13:40.180135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:13:39.367357Z","time spent":"812.473234ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T10:13:40.180822Z","caller":"traceutil/trace.go:171","msg":"trace[1470655896] linearizableReadLoop","detail":"{readStateIndex:347; appliedIndex:343; }","duration":"813.333601ms","start":"2026-07-01T10:13:39.36747Z","end":"2026-07-01T10:13:40.180803Z","steps":["trace[1470655896] 'read index received' (duration: 812.120643ms)","trace[1470655896] 'applied index is now lower than readState.Index' (duration: 1.211988ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:13:40.181013Z","caller":"traceutil/trace.go:171","msg":"trace[1720138918] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"813.519196ms","start":"2026-07-01T10:13:39.367477Z","end":"2026-07-01T10:13:40.180996Z","steps":["trace[1720138918] 'process raft request' (duration: 813.153605ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:13:40.181149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:13:39.367462Z","time spent":"813.63012ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T10:13:40.182565Z","caller":"traceutil/trace.go:171","msg":"trace[1277932482] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"814.728415ms","start":"2026-07-01T10:13:39.367816Z","end":"2026-07-01T10:13:40.182545Z","steps":["trace[1277932482] 'process raft request' (duration: 812.949099ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:13:40.182645Z","caller":"traceutil/trace.go:171","msg":"trace[672917564] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"815.040074ms","start":"2026-07-01T10:13:39.367543Z","end":"2026-07-01T10:13:40.182583Z","steps":["trace[672917564] 'process raft request' (duration: 813.174785ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:13:40.182688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:13:39.367804Z","time spent":"814.820477ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T10:13:40.182664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.705244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7303"} {"level":"info","ts":"2026-07-01T10:13:40.182771Z","caller":"traceutil/trace.go:171","msg":"trace[415860032] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:337; }","duration":"814.853188ms","start":"2026-07-01T10:13:39.367899Z","end":"2026-07-01T10:13:40.182752Z","steps":["trace[415860032] 'agreement among raft nodes before linearized reading' (duration: 814.706284ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:13:40.182911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:13:39.367893Z","time spent":"815.000523ms","remote":"127.0.0.1:34370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7325,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-07-01T10:13:40.182924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.413246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pv-protection-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T10:13:40.182878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:13:39.367538Z","time spent":"815.160198ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T10:13:40.182977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.73535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T10:13:40.183077Z","caller":"traceutil/trace.go:171","msg":"trace[1523350112] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:337; }","duration":"507.116393ms","start":"2026-07-01T10:13:39.675938Z","end":"2026-07-01T10:13:40.183055Z","steps":["trace[1523350112] 'agreement among raft nodes before linearized reading' (duration: 506.747111ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:13:40.183128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:13:39.675922Z","time spent":"507.192885ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-01T10:13:40.183013Z","caller":"traceutil/trace.go:171","msg":"trace[1789254153] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pv-protection-controller; range_end:; response_count:0; response_revision:337; }","duration":"815.553651ms","start":"2026-07-01T10:13:39.367434Z","end":"2026-07-01T10:13:40.182987Z","steps":["trace[1789254153] 'agreement among raft nodes before linearized reading' (duration: 814.151027ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:13:40.183185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:13:39.367414Z","time spent":"815.756796ms","remote":"127.0.0.1:34388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/kube-system/pv-protection-controller\" "} {"level":"info","ts":"2026-07-01T10:14:09.339828Z","caller":"traceutil/trace.go:171","msg":"trace[718494208] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"275.461882ms","start":"2026-07-01T10:14:09.064344Z","end":"2026-07-01T10:14:09.339806Z","steps":["trace[718494208] 'process raft request' (duration: 275.306847ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:14:09.347631Z","caller":"traceutil/trace.go:171","msg":"trace[1301981474] transaction","detail":"{read_only:false; number_of_response:1; response_revision:537; }","duration":"282.099583ms","start":"2026-07-01T10:14:09.065512Z","end":"2026-07-01T10:14:09.347612Z","steps":["trace[1301981474] 'process raft request' (duration: 281.866996ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:14:09.348015Z","caller":"traceutil/trace.go:171","msg":"trace[289526223] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"278.577091ms","start":"2026-07-01T10:14:09.069412Z","end":"2026-07-01T10:14:09.347989Z","steps":["trace[289526223] 'process raft request' (duration: 278.164998ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:14:09.348986Z","caller":"traceutil/trace.go:171","msg":"trace[43481358] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"142.298546ms","start":"2026-07-01T10:14:09.20667Z","end":"2026-07-01T10:14:09.348969Z","steps":["trace[43481358] 'process raft request' (duration: 142.243835ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:14:09.349214Z","caller":"traceutil/trace.go:171","msg":"trace[1037637147] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"268.721368ms","start":"2026-07-01T10:14:09.08048Z","end":"2026-07-01T10:14:09.349201Z","steps":["trace[1037637147] 'process raft request' (duration: 268.009965ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:14:09.349196Z","caller":"traceutil/trace.go:171","msg":"trace[1612280852] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"268.512101ms","start":"2026-07-01T10:14:09.080643Z","end":"2026-07-01T10:14:09.349155Z","steps":["trace[1612280852] 'process raft request' (duration: 267.963964ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:14:09.349558Z","caller":"traceutil/trace.go:171","msg":"trace[1719081525] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"269.183143ms","start":"2026-07-01T10:14:09.080364Z","end":"2026-07-01T10:14:09.349547Z","steps":["trace[1719081525] 'process raft request' (duration: 267.539ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:14:09.3496Z","caller":"traceutil/trace.go:171","msg":"trace[1693343171] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"268.973246ms","start":"2026-07-01T10:14:09.080615Z","end":"2026-07-01T10:14:09.349588Z","steps":["trace[1693343171] 'process raft request' (duration: 267.961784ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:14:09.349789Z","caller":"traceutil/trace.go:171","msg":"trace[811165911] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"260.168166ms","start":"2026-07-01T10:14:09.089603Z","end":"2026-07-01T10:14:09.349772Z","steps":["trace[811165911] 'process raft request' (duration: 259.170834ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:14:09.349794Z","caller":"traceutil/trace.go:171","msg":"trace[283784306] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"260.202067ms","start":"2026-07-01T10:14:09.089568Z","end":"2026-07-01T10:14:09.34977Z","steps":["trace[283784306] 'process raft request' (duration: 259.152663ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:14:09.350614Z","caller":"traceutil/trace.go:171","msg":"trace[716977753] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"246.407368ms","start":"2026-07-01T10:14:09.104192Z","end":"2026-07-01T10:14:09.350599Z","steps":["trace[716977753] 'process raft request' (duration: 244.639032ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:14:09.352015Z","caller":"traceutil/trace.go:171","msg":"trace[1471139789] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"266.776156ms","start":"2026-07-01T10:14:09.085223Z","end":"2026-07-01T10:14:09.351999Z","steps":["trace[1471139789] 'process raft request' (duration: 263.410579ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:14:09.352879Z","caller":"traceutil/trace.go:171","msg":"trace[1535685079] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"267.548591ms","start":"2026-07-01T10:14:09.085322Z","end":"2026-07-01T10:14:09.35287Z","steps":["trace[1535685079] 'process raft request' (duration: 263.350487ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:14:15.270381Z","caller":"traceutil/trace.go:171","msg":"trace[1599391538] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"148.453473ms","start":"2026-07-01T10:14:15.121901Z","end":"2026-07-01T10:14:15.270355Z","steps":["trace[1599391538] 'process raft request' (duration: 69.126799ms)","trace[1599391538] 'compare' (duration: 79.230411ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:14:15.56722Z","caller":"traceutil/trace.go:171","msg":"trace[366244270] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"195.83188ms","start":"2026-07-01T10:14:15.37135Z","end":"2026-07-01T10:14:15.567182Z","steps":["trace[366244270] 'process raft request' (duration: 134.954323ms)","trace[366244270] 'compare' (duration: 60.704151ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:14:16.098858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.141335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-x6mwj\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-01T10:14:16.098955Z","caller":"traceutil/trace.go:171","msg":"trace[60981669] range","detail":"{range_begin:/registry/pods/kube-system/cilium-x6mwj; range_end:; response_count:1; response_revision:637; }","duration":"304.273919ms","start":"2026-07-01T10:14:15.794657Z","end":"2026-07-01T10:14:16.098931Z","steps":["trace[60981669] 'range keys from in-memory index tree' (duration: 303.961009ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:14:16.099005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:14:15.794609Z","time spent":"304.383432ms","remote":"127.0.0.1:34370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-x6mwj\" "} {"level":"warn","ts":"2026-07-01T10:14:16.099308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.785557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T10:14:16.099341Z","caller":"traceutil/trace.go:171","msg":"trace[415510826] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"410.87897ms","start":"2026-07-01T10:14:15.688453Z","end":"2026-07-01T10:14:16.099332Z","steps":["trace[415510826] 'range keys from in-memory index tree' (duration: 410.694313ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:14:16.099362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:14:15.688434Z","time spent":"410.923341ms","remote":"127.0.0.1:34174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-01T10:14:16.099462Z","caller":"traceutil/trace.go:171","msg":"trace[460103912] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"135.907184ms","start":"2026-07-01T10:14:15.963518Z","end":"2026-07-01T10:14:16.099426Z","steps":["trace[460103912] 'process raft request' (duration: 119.219263ms)","trace[460103912] 'compare' (duration: 16.145353ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:14:16.865879Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521227219374121612,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-01T10:14:17.366576Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521227219374121612,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-01T10:14:17.645295Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.459472604s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-01T10:14:18.225592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.831263ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-01T10:14:18.2257Z","caller":"traceutil/trace.go:171","msg":"trace[2051356572] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"2.039923487s","start":"2026-07-01T10:14:16.185748Z","end":"2026-07-01T10:14:18.225671Z","steps":["trace[2051356572] 'process raft request' (duration: 1.459826745s)","trace[2051356572] 'compare' (duration: 578.77059ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:14:18.225832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:14:16.185732Z","time spent":"2.040040371s","remote":"127.0.0.1:34370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T10:14:18.225808Z","caller":"traceutil/trace.go:171","msg":"trace[2021088364] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:663; }","duration":"1.860485019s","start":"2026-07-01T10:14:16.365291Z","end":"2026-07-01T10:14:18.225776Z","steps":["trace[2021088364] 'read index received' (duration: 1.28015961s)","trace[2021088364] 'applied index is now lower than readState.Index' (duration: 580.323419ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:14:18.241166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.875860058s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-01T10:14:18.241233Z","caller":"traceutil/trace.go:171","msg":"trace[1340724168] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:641; }","duration":"1.875965611s","start":"2026-07-01T10:14:16.365248Z","end":"2026-07-01T10:14:18.241213Z","steps":["trace[1340724168] 'agreement among raft nodes before linearized reading' (duration: 1.860676205s)","trace[1340724168] 'range keys from in-memory index tree' (duration: 15.136941ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:14:18.241262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:14:16.365229Z","time spent":"1.876026984s","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-01T10:14:18.241261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.869256869s","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-07-01T10:14:18.241286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.864390794s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-01T10:14:18.241325Z","caller":"traceutil/trace.go:171","msg":"trace[613268649] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:641; }","duration":"1.869368862s","start":"2026-07-01T10:14:16.371944Z","end":"2026-07-01T10:14:18.241313Z","steps":["trace[613268649] 'agreement among raft nodes before linearized reading' (duration: 1.869207837s)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:14:18.241337Z","caller":"traceutil/trace.go:171","msg":"trace[2035080447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:641; }","duration":"1.864477077s","start":"2026-07-01T10:14:16.376847Z","end":"2026-07-01T10:14:18.241324Z","steps":["trace[2035080447] 'agreement among raft nodes before linearized reading' (duration: 1.864311052s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:14:18.241358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:14:16.371924Z","time spent":"1.869428635s","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-01T10:14:18.241372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:14:16.376833Z","time spent":"1.864532198s","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-01T10:14:18.241524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.868744162s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-01T10:14:18.241552Z","caller":"traceutil/trace.go:171","msg":"trace[1413449816] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:641; }","duration":"1.868788564s","start":"2026-07-01T10:14:16.372756Z","end":"2026-07-01T10:14:18.241545Z","steps":["trace[1413449816] 'agreement among raft nodes before linearized reading' (duration: 1.868697621s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:14:18.24157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:14:16.372747Z","time spent":"1.868818215s","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-01T10:14:18.241524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.552543714s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T10:14:18.241717Z","caller":"traceutil/trace.go:171","msg":"trace[1397075714] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"1.552824814s","start":"2026-07-01T10:14:16.688866Z","end":"2026-07-01T10:14:18.241691Z","steps":["trace[1397075714] 'agreement among raft nodes before linearized reading' (duration: 1.552579186s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:14:18.241832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:14:16.688844Z","time spent":"1.552969297s","remote":"127.0.0.1:34174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-01T10:14:23.095715Z","caller":"traceutil/trace.go:171","msg":"trace[299772369] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"115.045109ms","start":"2026-07-01T10:14:22.980641Z","end":"2026-07-01T10:14:23.095686Z","steps":["trace[299772369] 'process raft request' (duration: 114.375138ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:14:33.869576Z","caller":"traceutil/trace.go:171","msg":"trace[685011907] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"301.533972ms","start":"2026-07-01T10:14:33.568019Z","end":"2026-07-01T10:14:33.869553Z","steps":["trace[685011907] 'process raft request' (duration: 301.404448ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:14:33.869722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:14:33.567983Z","time spent":"301.668646ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T10:14:34.03439Z","caller":"traceutil/trace.go:171","msg":"trace[75135445] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"132.125782ms","start":"2026-07-01T10:14:33.902237Z","end":"2026-07-01T10:14:34.034363Z","steps":["trace[75135445] 'process raft request' (duration: 131.889095ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:15:03.029336Z","caller":"traceutil/trace.go:171","msg":"trace[2032047684] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"114.243471ms","start":"2026-07-01T10:15:02.915069Z","end":"2026-07-01T10:15:03.029312Z","steps":["trace[2032047684] 'process raft request' (duration: 113.720075ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:15:09.114714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.423947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T10:15:09.114792Z","caller":"traceutil/trace.go:171","msg":"trace[1180336972] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1023; }","duration":"428.546951ms","start":"2026-07-01T10:15:08.686225Z","end":"2026-07-01T10:15:09.114772Z","steps":["trace[1180336972] 'range keys from in-memory index tree' (duration: 428.347675ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:15:09.11483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:15:08.686205Z","time spent":"428.616473ms","remote":"127.0.0.1:34174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-01T10:15:09.61628Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521227219374122396,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-01T10:15:09.801502Z","caller":"traceutil/trace.go:171","msg":"trace[448181719] linearizableReadLoop","detail":"{readStateIndex:1060; appliedIndex:1059; }","duration":"685.828852ms","start":"2026-07-01T10:15:09.115638Z","end":"2026-07-01T10:15:09.801466Z","steps":["trace[448181719] 'read index received' (duration: 685.550714ms)","trace[448181719] 'applied index is now lower than readState.Index' (duration: 276.628µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:15:09.801586Z","caller":"traceutil/trace.go:171","msg":"trace[935509881] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"749.178812ms","start":"2026-07-01T10:15:09.052366Z","end":"2026-07-01T10:15:09.801545Z","steps":["trace[935509881] 'process raft request' (duration: 748.881122ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:15:09.801694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.024409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T10:15:09.801744Z","caller":"traceutil/trace.go:171","msg":"trace[928623573] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1024; }","duration":"686.131482ms","start":"2026-07-01T10:15:09.1156Z","end":"2026-07-01T10:15:09.801732Z","steps":["trace[928623573] 'agreement among raft nodes before linearized reading' (duration: 685.999138ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:15:09.80177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:15:09.052334Z","time spent":"749.336847ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T10:15:09.801788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:15:09.115582Z","time spent":"686.197533ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-01T10:15:32.814555Z","caller":"traceutil/trace.go:171","msg":"trace[388084238] linearizableReadLoop","detail":"{readStateIndex:1138; appliedIndex:1137; }","duration":"187.89068ms","start":"2026-07-01T10:15:32.626638Z","end":"2026-07-01T10:15:32.814529Z","steps":["trace[388084238] 'read index received' (duration: 187.658372ms)","trace[388084238] 'applied index is now lower than readState.Index' (duration: 231.268µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:15:32.814612Z","caller":"traceutil/trace.go:171","msg":"trace[1123189870] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"223.429113ms","start":"2026-07-01T10:15:32.591131Z","end":"2026-07-01T10:15:32.814561Z","steps":["trace[1123189870] 'process raft request' (duration: 223.252477ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:15:32.814825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.155638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-01T10:15:32.814874Z","caller":"traceutil/trace.go:171","msg":"trace[1542621829] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1098; }","duration":"188.251351ms","start":"2026-07-01T10:15:32.626609Z","end":"2026-07-01T10:15:32.81486Z","steps":["trace[1542621829] 'agreement among raft nodes before linearized reading' (duration: 188.076106ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:15:32.81499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.869081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-01T10:15:32.815073Z","caller":"traceutil/trace.go:171","msg":"trace[1677887572] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1098; }","duration":"183.977494ms","start":"2026-07-01T10:15:32.631073Z","end":"2026-07-01T10:15:32.81505Z","steps":["trace[1677887572] 'agreement among raft nodes before linearized reading' (duration: 183.775748ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:15:32.815207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.395509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T10:15:32.815212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.54496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-01T10:15:32.815261Z","caller":"traceutil/trace.go:171","msg":"trace[105150588] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1098; }","duration":"188.642534ms","start":"2026-07-01T10:15:32.626605Z","end":"2026-07-01T10:15:32.815248Z","steps":["trace[105150588] 'agreement among raft nodes before linearized reading' (duration: 188.453808ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:15:32.815242Z","caller":"traceutil/trace.go:171","msg":"trace[918395847] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1098; }","duration":"126.471441ms","start":"2026-07-01T10:15:32.688761Z","end":"2026-07-01T10:15:32.815233Z","steps":["trace[918395847] 'agreement among raft nodes before linearized reading' (duration: 126.415179ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:15:33.403983Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521227219374122601,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-01T10:15:33.556831Z","caller":"traceutil/trace.go:171","msg":"trace[1989229523] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"738.454789ms","start":"2026-07-01T10:15:32.818349Z","end":"2026-07-01T10:15:33.556804Z","steps":["trace[1989229523] 'process raft request' (duration: 738.305864ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:15:33.557016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:15:32.818325Z","time spent":"738.590803ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T10:15:33.578317Z","caller":"traceutil/trace.go:171","msg":"trace[904867690] linearizableReadLoop","detail":"{readStateIndex:1142; appliedIndex:1139; }","duration":"675.067418ms","start":"2026-07-01T10:15:32.903223Z","end":"2026-07-01T10:15:33.578291Z","steps":["trace[904867690] 'read index received' (duration: 653.584503ms)","trace[904867690] 'applied index is now lower than readState.Index' (duration: 21.481965ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:15:33.578389Z","caller":"traceutil/trace.go:171","msg":"trace[1749718244] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"758.618521ms","start":"2026-07-01T10:15:32.819744Z","end":"2026-07-01T10:15:33.578363Z","steps":["trace[1749718244] 'process raft request' (duration: 758.339782ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:15:33.578472Z","caller":"traceutil/trace.go:171","msg":"trace[1808353001] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"758.58648ms","start":"2026-07-01T10:15:32.819867Z","end":"2026-07-01T10:15:33.578453Z","steps":["trace[1808353001] 'process raft request' (duration: 758.326572ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:15:33.578516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.280985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.223\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-01T10:15:33.578544Z","caller":"traceutil/trace.go:171","msg":"trace[1856418933] range","detail":"{range_begin:/registry/masterleases/199.204.45.223; range_end:; response_count:1; response_revision:1102; }","duration":"675.344937ms","start":"2026-07-01T10:15:32.903191Z","end":"2026-07-01T10:15:33.578536Z","steps":["trace[1856418933] 'agreement among raft nodes before linearized reading' (duration: 675.199762ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:15:33.578545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:15:32.819722Z","time spent":"758.723735ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T10:15:33.578571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:15:32.903176Z","time spent":"675.389689ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.223\" "} {"level":"warn","ts":"2026-07-01T10:15:33.578573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:15:32.819856Z","time spent":"758.672484ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T10:15:33.578556Z","caller":"traceutil/trace.go:171","msg":"trace[1631958478] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"499.211793ms","start":"2026-07-01T10:15:33.079287Z","end":"2026-07-01T10:15:33.578499Z","steps":["trace[1631958478] 'process raft request' (duration: 498.955524ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:15:33.57881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:15:33.079249Z","time spent":"499.402578ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T10:15:34.724275Z","caller":"traceutil/trace.go:171","msg":"trace[43851974] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"565.203767ms","start":"2026-07-01T10:15:34.159039Z","end":"2026-07-01T10:15:34.724242Z","steps":["trace[43851974] 'process raft request' (duration: 565.048162ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:15:34.724444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:15:34.159007Z","time spent":"565.355371ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T10:15:34.972435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.155652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-01T10:15:34.972597Z","caller":"traceutil/trace.go:171","msg":"trace[1715989445] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1105; }","duration":"153.37819ms","start":"2026-07-01T10:15:34.819198Z","end":"2026-07-01T10:15:34.972576Z","steps":["trace[1715989445] 'range keys from in-memory index tree' (duration: 152.971136ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:15:36.080203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.918341ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T10:15:36.080447Z","caller":"traceutil/trace.go:171","msg":"trace[164407750] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"419.72816ms","start":"2026-07-01T10:15:35.660696Z","end":"2026-07-01T10:15:36.080424Z","steps":["trace[164407750] 'process raft request' (duration: 419.625377ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:15:36.080458Z","caller":"traceutil/trace.go:171","msg":"trace[1474600449] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"420.117823ms","start":"2026-07-01T10:15:35.660257Z","end":"2026-07-01T10:15:36.080375Z","steps":["trace[1474600449] 'process raft request' (duration: 92.903672ms)","trace[1474600449] 'compare' (duration: 326.721545ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:15:36.080526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:15:35.660668Z","time spent":"419.823293ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T10:15:36.080683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:15:35.660242Z","time spent":"420.304018ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T10:15:36.266954Z","caller":"traceutil/trace.go:171","msg":"trace[1119206533] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"331.528538ms","start":"2026-07-01T10:15:35.935387Z","end":"2026-07-01T10:15:36.266915Z","steps":["trace[1119206533] 'process raft request' (duration: 301.574773ms)","trace[1119206533] 'compare' (duration: 29.79231ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:15:36.267226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:15:35.935349Z","time spent":"331.693843ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T10:15:36.874974Z","caller":"traceutil/trace.go:171","msg":"trace[974808571] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"142.471282ms","start":"2026-07-01T10:15:36.732472Z","end":"2026-07-01T10:15:36.874943Z","steps":["trace[974808571] 'process raft request' (duration: 142.284466ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:15:37.116655Z","caller":"traceutil/trace.go:171","msg":"trace[1411208751] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"112.31311ms","start":"2026-07-01T10:15:37.004317Z","end":"2026-07-01T10:15:37.11663Z","steps":["trace[1411208751] 'process raft request' (duration: 112.109823ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:15:53.499921Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521227219374122864,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-01T10:15:54.001095Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521227219374122864,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-01T10:15:54.488086Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.582305149s","expected-duration":"1s"} {"level":"info","ts":"2026-07-01T10:15:54.488652Z","caller":"traceutil/trace.go:171","msg":"trace[458133877] linearizableReadLoop","detail":"{readStateIndex:1260; appliedIndex:1259; }","duration":"1.488926613s","start":"2026-07-01T10:15:52.999696Z","end":"2026-07-01T10:15:54.488622Z","steps":["trace[458133877] 'read index received' (duration: 1.488736477s)","trace[458133877] 'applied index is now lower than readState.Index' (duration: 188.836µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:15:54.488758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:15:52.905649Z","time spent":"1.583104755s","remote":"127.0.0.1:34228","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-01T10:15:54.489282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.489561853s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-01T10:15:54.489345Z","caller":"traceutil/trace.go:171","msg":"trace[227929505] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1213; }","duration":"1.489667756s","start":"2026-07-01T10:15:52.999662Z","end":"2026-07-01T10:15:54.48933Z","steps":["trace[227929505] 'agreement among raft nodes before linearized reading' (duration: 1.48946862s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:15:54.489393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:15:52.999647Z","time spent":"1.489735078s","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-01T10:15:54.984556Z","caller":"traceutil/trace.go:171","msg":"trace[1768336731] linearizableReadLoop","detail":"{readStateIndex:1262; appliedIndex:1260; }","duration":"495.816334ms","start":"2026-07-01T10:15:54.488712Z","end":"2026-07-01T10:15:54.984528Z","steps":["trace[1768336731] 'read index received' (duration: 495.69447ms)","trace[1768336731] 'applied index is now lower than readState.Index' (duration: 120.794µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:15:54.984791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.146970712s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-01T10:15:54.984787Z","caller":"traceutil/trace.go:171","msg":"trace[1216366650] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"627.816223ms","start":"2026-07-01T10:15:54.356946Z","end":"2026-07-01T10:15:54.984762Z","steps":["trace[1216366650] 'process raft request' (duration: 627.527823ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:15:54.984826Z","caller":"traceutil/trace.go:171","msg":"trace[837447570] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1215; }","duration":"1.147064874s","start":"2026-07-01T10:15:53.837751Z","end":"2026-07-01T10:15:54.984816Z","steps":["trace[837447570] 'agreement among raft nodes before linearized reading' (duration: 1.146900579s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:15:54.984805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.835612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-07-01T10:15:54.984865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:15:53.837731Z","time spent":"1.147125877s","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-01T10:15:54.984909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:15:54.356927Z","time spent":"627.922955ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T10:15:54.984908Z","caller":"traceutil/trace.go:171","msg":"trace[1030484051] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1215; }","duration":"802.991827ms","start":"2026-07-01T10:15:54.181893Z","end":"2026-07-01T10:15:54.984885Z","steps":["trace[1030484051] 'agreement among raft nodes before linearized reading' (duration: 802.697497ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:15:54.984964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:15:54.181873Z","time spent":"803.07896ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-01T10:15:54.984991Z","caller":"traceutil/trace.go:171","msg":"trace[559728522] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"943.786085ms","start":"2026-07-01T10:15:54.0412Z","end":"2026-07-01T10:15:54.984986Z","steps":["trace[559728522] 'process raft request' (duration: 943.080512ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:15:54.984977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.297478729s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T10:15:54.985019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:15:54.041184Z","time spent":"943.821115ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T10:15:54.985027Z","caller":"traceutil/trace.go:171","msg":"trace[416559337] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1215; }","duration":"1.297570993s","start":"2026-07-01T10:15:53.687443Z","end":"2026-07-01T10:15:54.985014Z","steps":["trace[416559337] 'agreement among raft nodes before linearized reading' (duration: 1.29748701s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:15:54.985094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:15:53.687424Z","time spent":"1.297661895s","remote":"127.0.0.1:34174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-01T10:15:54.985118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.774911689s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-01T10:15:54.985152Z","caller":"traceutil/trace.go:171","msg":"trace[1791087364] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1215; }","duration":"1.77496902s","start":"2026-07-01T10:15:53.210172Z","end":"2026-07-01T10:15:54.985141Z","steps":["trace[1791087364] 'agreement among raft nodes before linearized reading' (duration: 1.774821595s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:15:54.985164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.254860641s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-01T10:15:54.985185Z","caller":"traceutil/trace.go:171","msg":"trace[2012714117] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1215; }","duration":"1.254926863s","start":"2026-07-01T10:15:53.730253Z","end":"2026-07-01T10:15:54.98518Z","steps":["trace[2012714117] 'agreement among raft nodes before linearized reading' (duration: 1.254870391s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:15:54.98518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:15:53.210159Z","time spent":"1.775014762s","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-01T10:15:54.985201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:15:53.730233Z","time spent":"1.254964085s","remote":"127.0.0.1:34686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":29,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-07-01T10:15:54.985179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.818767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-01T10:15:54.985284Z","caller":"traceutil/trace.go:171","msg":"trace[1157956782] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1215; }","duration":"773.962971ms","start":"2026-07-01T10:15:54.21129Z","end":"2026-07-01T10:15:54.985253Z","steps":["trace[1157956782] 'agreement among raft nodes before linearized reading' (duration: 773.61783ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:15:54.985343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:15:54.211269Z","time spent":"774.057365ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-01T10:15:54.985788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.371272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T10:15:54.985861Z","caller":"traceutil/trace.go:171","msg":"trace[951003170] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1217; }","duration":"155.499867ms","start":"2026-07-01T10:15:54.830343Z","end":"2026-07-01T10:15:54.985843Z","steps":["trace[951003170] 'agreement among raft nodes before linearized reading' (duration: 155.358533ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:15:54.985908Z","caller":"traceutil/trace.go:171","msg":"trace[204828476] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"492.801059ms","start":"2026-07-01T10:15:54.493098Z","end":"2026-07-01T10:15:54.985899Z","steps":["trace[204828476] 'process raft request' (duration: 492.558711ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:15:54.985988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:15:54.493077Z","time spent":"492.85547ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T10:15:54.985862Z","caller":"traceutil/trace.go:171","msg":"trace[1878570788] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"495.402061ms","start":"2026-07-01T10:15:54.490445Z","end":"2026-07-01T10:15:54.985847Z","steps":["trace[1878570788] 'process raft request' (duration: 495.112322ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:15:54.986113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:15:54.490422Z","time spent":"495.637589ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T10:16:07.387861Z","caller":"traceutil/trace.go:171","msg":"trace[152055618] linearizableReadLoop","detail":"{readStateIndex:1304; appliedIndex:1303; }","duration":"261.372717ms","start":"2026-07-01T10:16:07.126461Z","end":"2026-07-01T10:16:07.387834Z","steps":["trace[152055618] 'read index received' (duration: 261.08216ms)","trace[152055618] 'applied index is now lower than readState.Index' (duration: 289.357µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:16:07.387929Z","caller":"traceutil/trace.go:171","msg":"trace[1714715792] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"322.324174ms","start":"2026-07-01T10:16:07.065561Z","end":"2026-07-01T10:16:07.387885Z","steps":["trace[1714715792] 'process raft request' (duration: 322.019947ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:07.388149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:07.065543Z","time spent":"322.494538ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T10:16:07.388144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.660729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T10:16:07.3882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.072338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-01T10:16:07.388213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.874143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-01T10:16:07.38824Z","caller":"traceutil/trace.go:171","msg":"trace[2141846415] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1255; }","duration":"226.16695ms","start":"2026-07-01T10:16:07.162062Z","end":"2026-07-01T10:16:07.388229Z","steps":["trace[2141846415] 'agreement among raft nodes before linearized reading' (duration: 226.034727ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:16:07.388239Z","caller":"traceutil/trace.go:171","msg":"trace[507587308] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1255; }","duration":"236.819993ms","start":"2026-07-01T10:16:07.151394Z","end":"2026-07-01T10:16:07.388214Z","steps":["trace[507587308] 'agreement among raft nodes before linearized reading' (duration: 236.651559ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:16:07.388267Z","caller":"traceutil/trace.go:171","msg":"trace[39902078] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1255; }","duration":"217.959976ms","start":"2026-07-01T10:16:07.170293Z","end":"2026-07-01T10:16:07.388253Z","steps":["trace[39902078] 'agreement among raft nodes before linearized reading' (duration: 217.784842ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:07.388282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.506316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-01T10:16:07.388455Z","caller":"traceutil/trace.go:171","msg":"trace[1072561355] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1255; }","duration":"204.69248ms","start":"2026-07-01T10:16:07.183742Z","end":"2026-07-01T10:16:07.388434Z","steps":["trace[1072561355] 'agreement among raft nodes before linearized reading' (duration: 204.325711ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:07.388481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.505944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-01T10:16:07.388519Z","caller":"traceutil/trace.go:171","msg":"trace[505338538] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1255; }","duration":"142.561846ms","start":"2026-07-01T10:16:07.245934Z","end":"2026-07-01T10:16:07.388496Z","steps":["trace[505338538] 'agreement among raft nodes before linearized reading' (duration: 142.439353ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:07.388671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.211366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-01T10:16:07.388771Z","caller":"traceutil/trace.go:171","msg":"trace[1311141700] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1255; }","duration":"262.33719ms","start":"2026-07-01T10:16:07.126423Z","end":"2026-07-01T10:16:07.38876Z","steps":["trace[1311141700] 'agreement among raft nodes before linearized reading' (duration: 261.560091ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:11.218278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.472264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T10:16:11.218356Z","caller":"traceutil/trace.go:171","msg":"trace[1643981166] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1276; }","duration":"530.579508ms","start":"2026-07-01T10:16:10.687757Z","end":"2026-07-01T10:16:11.218337Z","steps":["trace[1643981166] 'range keys from in-memory index tree' (duration: 530.426943ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:11.218393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:10.687745Z","time spent":"530.64113ms","remote":"127.0.0.1:34174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-01T10:16:11.765935Z","caller":"traceutil/trace.go:171","msg":"trace[910500746] linearizableReadLoop","detail":"{readStateIndex:1327; appliedIndex:1326; }","duration":"353.342461ms","start":"2026-07-01T10:16:11.412568Z","end":"2026-07-01T10:16:11.76591Z","steps":["trace[910500746] 'read index received' (duration: 353.140814ms)","trace[910500746] 'applied index is now lower than readState.Index' (duration: 200.717µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:16:11.765981Z","caller":"traceutil/trace.go:171","msg":"trace[1112114271] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"363.713571ms","start":"2026-07-01T10:16:11.402236Z","end":"2026-07-01T10:16:11.76595Z","steps":["trace[1112114271] 'process raft request' (duration: 363.526255ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:11.76611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:11.40222Z","time spent":"363.823825ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T10:16:11.766163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.246875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-01T10:16:11.766187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.84516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-01T10:16:11.766192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.600139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-01T10:16:11.766226Z","caller":"traceutil/trace.go:171","msg":"trace[1908242667] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1277; }","duration":"350.901112ms","start":"2026-07-01T10:16:11.415314Z","end":"2026-07-01T10:16:11.766215Z","steps":["trace[1908242667] 'agreement among raft nodes before linearized reading' (duration: 350.797279ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:11.76626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:11.415306Z","time spent":"350.947434ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-01T10:16:11.766264Z","caller":"traceutil/trace.go:171","msg":"trace[1481772624] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1277; }","duration":"353.705301ms","start":"2026-07-01T10:16:11.41254Z","end":"2026-07-01T10:16:11.766245Z","steps":["trace[1481772624] 'agreement among raft nodes before linearized reading' (duration: 353.495195ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:11.766312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:11.412528Z","time spent":"353.773474ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-01T10:16:11.766211Z","caller":"traceutil/trace.go:171","msg":"trace[1837894686] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1277; }","duration":"352.356189ms","start":"2026-07-01T10:16:11.413839Z","end":"2026-07-01T10:16:11.766196Z","steps":["trace[1837894686] 'agreement among raft nodes before linearized reading' (duration: 352.198734ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:11.766328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.384077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-01T10:16:11.766364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:11.413816Z","time spent":"352.540545ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-01T10:16:11.766378Z","caller":"traceutil/trace.go:171","msg":"trace[951527030] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1277; }","duration":"351.45858ms","start":"2026-07-01T10:16:11.414906Z","end":"2026-07-01T10:16:11.766364Z","steps":["trace[951527030] 'agreement among raft nodes before linearized reading' (duration: 351.331936ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:11.766415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.520962ms","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-07-01T10:16:11.766428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:11.414898Z","time spent":"351.520232ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-01T10:16:11.766495Z","caller":"traceutil/trace.go:171","msg":"trace[991370755] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1277; }","duration":"351.630196ms","start":"2026-07-01T10:16:11.414846Z","end":"2026-07-01T10:16:11.766476Z","steps":["trace[991370755] 'agreement among raft nodes before linearized reading' (duration: 351.407109ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:11.766609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:11.414832Z","time spent":"351.761629ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-01T10:16:13.078759Z","caller":"traceutil/trace.go:171","msg":"trace[1728315768] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"146.877249ms","start":"2026-07-01T10:16:12.931844Z","end":"2026-07-01T10:16:13.078721Z","steps":["trace[1728315768] 'process raft request' (duration: 49.089407ms)","trace[1728315768] 'compare' (duration: 97.44033ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:16:13.078775Z","caller":"traceutil/trace.go:171","msg":"trace[1664127882] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"133.452861ms","start":"2026-07-01T10:16:12.945301Z","end":"2026-07-01T10:16:13.078754Z","steps":["trace[1664127882] 'process raft request' (duration: 133.312386ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:16:13.257911Z","caller":"traceutil/trace.go:171","msg":"trace[1006543836] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"101.149539ms","start":"2026-07-01T10:16:13.156734Z","end":"2026-07-01T10:16:13.257883Z","steps":["trace[1006543836] 'process raft request' (duration: 25.573466ms)","trace[1006543836] 'compare' (duration: 75.445119ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:16:14.207891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.7454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T10:16:14.207961Z","caller":"traceutil/trace.go:171","msg":"trace[1041978465] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1286; }","duration":"518.869024ms","start":"2026-07-01T10:16:13.689075Z","end":"2026-07-01T10:16:14.207944Z","steps":["trace[1041978465] 'range keys from in-memory index tree' (duration: 518.666278ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:14.207989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:13.689056Z","time spent":"518.927435ms","remote":"127.0.0.1:34174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-01T10:16:14.208356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.612167ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T10:16:14.208433Z","caller":"traceutil/trace.go:171","msg":"trace[2112109989] linearizableReadLoop","detail":"{readStateIndex:1338; appliedIndex:1337; }","duration":"427.759595ms","start":"2026-07-01T10:16:13.78066Z","end":"2026-07-01T10:16:14.20842Z","steps":["trace[2112109989] 'read index received' (duration: 75.028535ms)","trace[2112109989] 'applied index is now lower than readState.Index' (duration: 352.72681ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:16:14.208594Z","caller":"traceutil/trace.go:171","msg":"trace[1039982076] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"437.788136ms","start":"2026-07-01T10:16:13.770797Z","end":"2026-07-01T10:16:14.208585Z","steps":["trace[1039982076] 'process raft request' (duration: 84.868449ms)","trace[1039982076] 'compare' (duration: 352.246935ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:16:14.208658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:13.770772Z","time spent":"437.849248ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T10:16:14.208841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.180109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-01T10:16:14.208865Z","caller":"traceutil/trace.go:171","msg":"trace[1905800743] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1287; }","duration":"428.23449ms","start":"2026-07-01T10:16:13.780625Z","end":"2026-07-01T10:16:14.208859Z","steps":["trace[1905800743] 'agreement among raft nodes before linearized reading' (duration: 428.147728ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:14.208886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:13.780612Z","time spent":"428.268442ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-01T10:16:14.20901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.304053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5331"} {"level":"info","ts":"2026-07-01T10:16:14.209034Z","caller":"traceutil/trace.go:171","msg":"trace[2014787138] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1287; }","duration":"371.411307ms","start":"2026-07-01T10:16:13.837618Z","end":"2026-07-01T10:16:14.209029Z","steps":["trace[2014787138] 'agreement among raft nodes before linearized reading' (duration: 371.348445ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:14.209083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:13.837596Z","time spent":"371.481538ms","remote":"127.0.0.1:34360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5353,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-07-01T10:16:14.209234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.386222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-01T10:16:14.20926Z","caller":"traceutil/trace.go:171","msg":"trace[422794316] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1287; }","duration":"338.447924ms","start":"2026-07-01T10:16:13.870806Z","end":"2026-07-01T10:16:14.209254Z","steps":["trace[422794316] 'agreement among raft nodes before linearized reading' (duration: 338.382412ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:14.209279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:13.870788Z","time spent":"338.487136ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-01T10:16:14.209335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.351421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-01T10:16:14.209389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.407447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T10:16:14.209436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.903589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-01T10:16:14.20946Z","caller":"traceutil/trace.go:171","msg":"trace[619168927] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1287; }","duration":"338.976571ms","start":"2026-07-01T10:16:13.870477Z","end":"2026-07-01T10:16:14.209453Z","steps":["trace[619168927] 'agreement among raft nodes before linearized reading' (duration: 338.885638ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:16:14.209451Z","caller":"traceutil/trace.go:171","msg":"trace[940683283] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1287; }","duration":"288.50678ms","start":"2026-07-01T10:16:13.92093Z","end":"2026-07-01T10:16:14.209437Z","steps":["trace[940683283] 'agreement among raft nodes before linearized reading' (duration: 288.404177ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:14.209485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:13.870389Z","time spent":"339.091635ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-01T10:16:14.209472Z","caller":"traceutil/trace.go:171","msg":"trace[1433936642] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1287; }","duration":"338.469585ms","start":"2026-07-01T10:16:13.870949Z","end":"2026-07-01T10:16:14.209418Z","steps":["trace[1433936642] 'agreement among raft nodes before linearized reading' (duration: 338.186696ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:14.209587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:13.870943Z","time spent":"338.63143ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-01T10:16:14.209921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.863418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-01T10:16:14.210141Z","caller":"traceutil/trace.go:171","msg":"trace[778645072] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1287; }","duration":"339.052644ms","start":"2026-07-01T10:16:13.870945Z","end":"2026-07-01T10:16:14.209998Z","steps":["trace[778645072] 'agreement among raft nodes before linearized reading' (duration: 338.431244ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:14.210221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:13.870925Z","time spent":"339.277601ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-01T10:16:14.995923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.228645ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T10:16:14.996028Z","caller":"traceutil/trace.go:171","msg":"trace[930764573] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"781.55773ms","start":"2026-07-01T10:16:14.214451Z","end":"2026-07-01T10:16:14.996009Z","steps":["trace[930764573] 'process raft request' (duration: 533.16542ms)","trace[930764573] 'compare' (duration: 248.095751ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:16:14.996085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:14.214432Z","time spent":"781.627153ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T10:16:15.047051Z","caller":"traceutil/trace.go:171","msg":"trace[2017013199] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"832.197887ms","start":"2026-07-01T10:16:14.214833Z","end":"2026-07-01T10:16:15.047031Z","steps":["trace[2017013199] 'process raft request' (duration: 832.038171ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:15.047179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:14.214825Z","time spent":"832.287479ms","remote":"127.0.0.1:34360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5457,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T10:16:15.047307Z","caller":"traceutil/trace.go:171","msg":"trace[2113240465] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"832.30709ms","start":"2026-07-01T10:16:14.214982Z","end":"2026-07-01T10:16:15.047289Z","steps":["trace[2113240465] 'process raft request' (duration: 832.01232ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:15.047425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:14.214961Z","time spent":"832.417784ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T10:16:15.047596Z","caller":"traceutil/trace.go:171","msg":"trace[1718200173] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"832.493556ms","start":"2026-07-01T10:16:14.21509Z","end":"2026-07-01T10:16:15.047584Z","steps":["trace[1718200173] 'process raft request' (duration: 832.139665ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:15.04767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:14.215082Z","time spent":"832.554648ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T10:16:15.047695Z","caller":"traceutil/trace.go:171","msg":"trace[1296666274] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"832.540137ms","start":"2026-07-01T10:16:14.215145Z","end":"2026-07-01T10:16:15.047685Z","steps":["trace[1296666274] 'process raft request' (duration: 832.389373ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:15.047732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:14.21514Z","time spent":"832.572328ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T10:16:15.047813Z","caller":"traceutil/trace.go:171","msg":"trace[1871824750] linearizableReadLoop","detail":"{readStateIndex:1344; appliedIndex:1338; }","duration":"357.262385ms","start":"2026-07-01T10:16:14.690543Z","end":"2026-07-01T10:16:15.047806Z","steps":["trace[1871824750] 'read index received' (duration: 57.083723ms)","trace[1871824750] 'applied index is now lower than readState.Index' (duration: 300.178052ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:16:15.047877Z","caller":"traceutil/trace.go:171","msg":"trace[1965241490] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"831.750172ms","start":"2026-07-01T10:16:14.216121Z","end":"2026-07-01T10:16:15.047871Z","steps":["trace[1965241490] 'process raft request' (duration: 831.536336ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:15.047909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:14.216112Z","time spent":"831.779523ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T10:16:15.047995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.452531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T10:16:15.048018Z","caller":"traceutil/trace.go:171","msg":"trace[1597249970] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1293; }","duration":"357.503373ms","start":"2026-07-01T10:16:14.690508Z","end":"2026-07-01T10:16:15.048011Z","steps":["trace[1597249970] 'agreement among raft nodes before linearized reading' (duration: 357.469292ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:15.048046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:14.690491Z","time spent":"357.550804ms","remote":"127.0.0.1:34174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-01T10:16:15.048112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.824879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T10:16:15.048151Z","caller":"traceutil/trace.go:171","msg":"trace[2057971826] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1293; }","duration":"355.880911ms","start":"2026-07-01T10:16:14.692258Z","end":"2026-07-01T10:16:15.048139Z","steps":["trace[2057971826] 'agreement among raft nodes before linearized reading' (duration: 355.820269ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:15.048187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:14.692251Z","time spent":"355.924602ms","remote":"127.0.0.1:59696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-07-01T10:16:17.190483Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521227219374123048,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-01T10:16:17.360227Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.143970959s","expected-duration":"1s"} {"level":"info","ts":"2026-07-01T10:16:17.36069Z","caller":"traceutil/trace.go:171","msg":"trace[1322665299] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"1.144546277s","start":"2026-07-01T10:16:16.216119Z","end":"2026-07-01T10:16:17.360666Z","steps":["trace[1322665299] 'process raft request' (duration: 1.144392602s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:17.360899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:16.216092Z","time spent":"1.144687432s","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T10:16:17.45109Z","caller":"traceutil/trace.go:171","msg":"trace[1199358543] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"345.330474ms","start":"2026-07-01T10:16:17.105739Z","end":"2026-07-01T10:16:17.45107Z","steps":["trace[1199358543] 'process raft request' (duration: 296.593259ms)","trace[1199358543] 'compare' (duration: 48.532179ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:16:17.45122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.780018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T10:16:17.451234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:17.105719Z","time spent":"345.453297ms","remote":"127.0.0.1:34276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":763,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T10:16:17.451272Z","caller":"traceutil/trace.go:171","msg":"trace[599776380] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1297; }","duration":"761.870191ms","start":"2026-07-01T10:16:16.689388Z","end":"2026-07-01T10:16:17.451258Z","steps":["trace[599776380] 'agreement among raft nodes before linearized reading' (duration: 761.753537ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:17.451321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:16.689372Z","time spent":"761.939034ms","remote":"127.0.0.1:34174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-01T10:16:17.451033Z","caller":"traceutil/trace.go:171","msg":"trace[274325503] linearizableReadLoop","detail":"{readStateIndex:1348; appliedIndex:1346; }","duration":"761.586662ms","start":"2026-07-01T10:16:16.689422Z","end":"2026-07-01T10:16:17.451008Z","steps":["trace[274325503] 'read index received' (duration: 671.032072ms)","trace[274325503] 'applied index is now lower than readState.Index' (duration: 90.55356ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:16:17.451799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.292227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-01T10:16:17.451841Z","caller":"traceutil/trace.go:171","msg":"trace[1564979138] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1297; }","duration":"451.369379ms","start":"2026-07-01T10:16:17.000461Z","end":"2026-07-01T10:16:17.45183Z","steps":["trace[1564979138] 'agreement among raft nodes before linearized reading' (duration: 451.229545ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:17.451871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:17.000413Z","time spent":"451.452382ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-01T10:16:17.451804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.574073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-01T10:16:17.451899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.588464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-01T10:16:17.451931Z","caller":"traceutil/trace.go:171","msg":"trace[1653443548] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1297; }","duration":"398.628085ms","start":"2026-07-01T10:16:17.053294Z","end":"2026-07-01T10:16:17.451922Z","steps":["trace[1653443548] 'agreement among raft nodes before linearized reading' (duration: 398.542963ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:16:17.451928Z","caller":"traceutil/trace.go:171","msg":"trace[1269252724] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1297; }","duration":"398.725338ms","start":"2026-07-01T10:16:17.053188Z","end":"2026-07-01T10:16:17.451913Z","steps":["trace[1269252724] 'agreement among raft nodes before linearized reading' (duration: 398.532852ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:17.451956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:17.053288Z","time spent":"398.661206ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-01T10:16:17.451964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:17.05316Z","time spent":"398.79358ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-01T10:16:17.451954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.835792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-01T10:16:17.452035Z","caller":"traceutil/trace.go:171","msg":"trace[613989779] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1297; }","duration":"398.915145ms","start":"2026-07-01T10:16:17.053074Z","end":"2026-07-01T10:16:17.451989Z","steps":["trace[613989779] 'agreement among raft nodes before linearized reading' (duration: 398.78453ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:17.452071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:17.053026Z","time spent":"399.035889ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-01T10:16:17.452111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.78011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-01T10:16:17.452222Z","caller":"traceutil/trace.go:171","msg":"trace[1593353828] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1297; }","duration":"398.990367ms","start":"2026-07-01T10:16:17.053186Z","end":"2026-07-01T10:16:17.452176Z","steps":["trace[1593353828] 'agreement among raft nodes before linearized reading' (duration: 398.638576ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:17.452285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:17.05318Z","time spent":"399.06924ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-01T10:16:17.804349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.371155ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-01T10:16:17.804452Z","caller":"traceutil/trace.go:171","msg":"trace[2007648613] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"347.731341ms","start":"2026-07-01T10:16:17.456703Z","end":"2026-07-01T10:16:17.804434Z","steps":["trace[2007648613] 'process raft request' (duration: 106.18338ms)","trace[2007648613] 'compare' (duration: 241.170839ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:16:17.804503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:17.456691Z","time spent":"347.791683ms","remote":"127.0.0.1:34276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":763,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T10:16:17.858457Z","caller":"traceutil/trace.go:171","msg":"trace[1441631996] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"401.530518ms","start":"2026-07-01T10:16:17.456901Z","end":"2026-07-01T10:16:17.858432Z","steps":["trace[1441631996] 'process raft request' (duration: 401.365213ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:17.858615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:17.456876Z","time spent":"401.666092ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T10:16:17.858955Z","caller":"traceutil/trace.go:171","msg":"trace[1945632187] linearizableReadLoop","detail":"{readStateIndex:1354; appliedIndex:1349; }","duration":"162.614571ms","start":"2026-07-01T10:16:17.696311Z","end":"2026-07-01T10:16:17.858926Z","steps":["trace[1945632187] 'read index received' (duration: 161.889808ms)","trace[1945632187] 'applied index is now lower than readState.Index' (duration: 723.853µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:16:17.858969Z","caller":"traceutil/trace.go:171","msg":"trace[415799032] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"401.768166ms","start":"2026-07-01T10:16:17.457174Z","end":"2026-07-01T10:16:17.858942Z","steps":["trace[415799032] 'process raft request' (duration: 401.567419ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:16:17.859034Z","caller":"traceutil/trace.go:171","msg":"trace[854905513] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"402.006543ms","start":"2026-07-01T10:16:17.457019Z","end":"2026-07-01T10:16:17.859025Z","steps":["trace[854905513] 'process raft request' (duration: 401.372473ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:17.85912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:17.457162Z","time spent":"401.880299ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T10:16:17.859159Z","caller":"traceutil/trace.go:171","msg":"trace[1285745856] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"400.914038ms","start":"2026-07-01T10:16:17.458232Z","end":"2026-07-01T10:16:17.859146Z","steps":["trace[1285745856] 'process raft request' (duration: 400.633639ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:17.859168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:17.45701Z","time spent":"402.070955ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T10:16:17.85923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:17.45822Z","time spent":"400.9727ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T10:16:17.859312Z","caller":"traceutil/trace.go:171","msg":"trace[1613016736] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"402.099456ms","start":"2026-07-01T10:16:17.457189Z","end":"2026-07-01T10:16:17.859288Z","steps":["trace[1613016736] 'process raft request' (duration: 401.643441ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:17.859393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:17.457172Z","time spent":"402.179069ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T10:16:17.859403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.080346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-f58fj\" ","response":"range_response_count:1 size:11061"} {"level":"info","ts":"2026-07-01T10:16:17.859457Z","caller":"traceutil/trace.go:171","msg":"trace[1016951453] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-f58fj; range_end:; response_count:1; response_revision:1303; }","duration":"163.167129ms","start":"2026-07-01T10:16:17.696275Z","end":"2026-07-01T10:16:17.859442Z","steps":["trace[1016951453] 'agreement among raft nodes before linearized reading' (duration: 162.967482ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:16:18.098924Z","caller":"traceutil/trace.go:171","msg":"trace[205082964] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"228.68604ms","start":"2026-07-01T10:16:17.869914Z","end":"2026-07-01T10:16:18.0986Z","steps":["trace[205082964] 'process raft request' (duration: 228.518705ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:22.134921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.426786ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T10:16:22.135089Z","caller":"traceutil/trace.go:171","msg":"trace[517365967] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"193.640321ms","start":"2026-07-01T10:16:21.94143Z","end":"2026-07-01T10:16:22.13507Z","steps":["trace[517365967] 'process raft request' (duration: 19.975427ms)","trace[517365967] 'compare' (duration: 173.309063ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:16:22.135716Z","caller":"traceutil/trace.go:171","msg":"trace[666083186] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"192.64939ms","start":"2026-07-01T10:16:21.943045Z","end":"2026-07-01T10:16:22.135695Z","steps":["trace[666083186] 'process raft request' (duration: 191.975088ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:16:22.135852Z","caller":"traceutil/trace.go:171","msg":"trace[515978025] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"192.300868ms","start":"2026-07-01T10:16:21.943535Z","end":"2026-07-01T10:16:22.135836Z","steps":["trace[515978025] 'process raft request' (duration: 192.097482ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:24.190101Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521227219374123123,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-01T10:16:24.65229Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.234707286s","expected-duration":"1s"} {"level":"info","ts":"2026-07-01T10:16:24.652722Z","caller":"traceutil/trace.go:171","msg":"trace[764631109] linearizableReadLoop","detail":"{readStateIndex:1389; appliedIndex:1388; }","duration":"963.631032ms","start":"2026-07-01T10:16:23.689065Z","end":"2026-07-01T10:16:24.652696Z","steps":["trace[764631109] 'read index received' (duration: 963.354593ms)","trace[764631109] 'applied index is now lower than readState.Index' (duration: 275.489µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:16:24.65285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"963.800628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T10:16:24.652843Z","caller":"traceutil/trace.go:171","msg":"trace[1017081225] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"1.235327166s","start":"2026-07-01T10:16:23.417484Z","end":"2026-07-01T10:16:24.652811Z","steps":["trace[1017081225] 'process raft request' (duration: 1.235044997s)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:16:24.652889Z","caller":"traceutil/trace.go:171","msg":"trace[1229475533] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1336; }","duration":"963.888851ms","start":"2026-07-01T10:16:23.68899Z","end":"2026-07-01T10:16:24.652879Z","steps":["trace[1229475533] 'agreement among raft nodes before linearized reading' (duration: 963.816879ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:24.652922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:23.688969Z","time spent":"963.945823ms","remote":"127.0.0.1:34174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-01T10:16:24.652997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.704343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-01T10:16:24.653027Z","caller":"traceutil/trace.go:171","msg":"trace[379727539] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1336; }","duration":"713.771746ms","start":"2026-07-01T10:16:23.939248Z","end":"2026-07-01T10:16:24.653019Z","steps":["trace[379727539] 'agreement among raft nodes before linearized reading' (duration: 713.668873ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:24.653002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:23.417464Z","time spent":"1.23545341s","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T10:16:24.653073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:23.939228Z","time spent":"713.835628ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-01T10:16:24.653215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.684343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-07-01T10:16:24.653239Z","caller":"traceutil/trace.go:171","msg":"trace[665260747] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1336; }","duration":"292.751865ms","start":"2026-07-01T10:16:24.360481Z","end":"2026-07-01T10:16:24.653233Z","steps":["trace[665260747] 'agreement among raft nodes before linearized reading' (duration: 292.691723ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:24.653314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.274504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-01T10:16:24.653334Z","caller":"traceutil/trace.go:171","msg":"trace[1649566703] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1336; }","duration":"710.323195ms","start":"2026-07-01T10:16:23.943006Z","end":"2026-07-01T10:16:24.653329Z","steps":["trace[1649566703] 'agreement among raft nodes before linearized reading' (duration: 710.274404ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:24.653339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.090649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-01T10:16:24.653373Z","caller":"traceutil/trace.go:171","msg":"trace[1418722002] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1336; }","duration":"513.154621ms","start":"2026-07-01T10:16:24.140209Z","end":"2026-07-01T10:16:24.653363Z","steps":["trace[1418722002] 'agreement among raft nodes before linearized reading' (duration: 513.057868ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:24.6534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.730356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-01T10:16:24.653416Z","caller":"traceutil/trace.go:171","msg":"trace[1271110115] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1336; }","duration":"511.758127ms","start":"2026-07-01T10:16:24.141654Z","end":"2026-07-01T10:16:24.653412Z","steps":["trace[1271110115] 'agreement among raft nodes before linearized reading' (duration: 511.713075ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:24.653349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:23.942993Z","time spent":"710.352787ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-01T10:16:24.653433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:24.141646Z","time spent":"511.783657ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-01T10:16:24.653411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:24.140192Z","time spent":"513.212154ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-01T10:16:24.653533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.242315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-01T10:16:24.653553Z","caller":"traceutil/trace.go:171","msg":"trace[434539451] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1336; }","duration":"513.290736ms","start":"2026-07-01T10:16:24.140257Z","end":"2026-07-01T10:16:24.653548Z","steps":["trace[434539451] 'agreement among raft nodes before linearized reading' (duration: 513.241925ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:24.653569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:24.14024Z","time spent":"513.325287ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-01T10:16:25.264351Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521227219374123127,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-01T10:16:25.606318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.01299ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T10:16:25.606523Z","caller":"traceutil/trace.go:171","msg":"trace[699735460] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"947.739805ms","start":"2026-07-01T10:16:24.658771Z","end":"2026-07-01T10:16:25.606511Z","steps":["trace[699735460] 'process raft request' (duration: 947.686583ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:16:25.606523Z","caller":"traceutil/trace.go:171","msg":"trace[500572757] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"948.197219ms","start":"2026-07-01T10:16:24.658288Z","end":"2026-07-01T10:16:25.606485Z","steps":["trace[500572757] 'process raft request' (duration: 828.946452ms)","trace[500572757] 'compare' (duration: 118.496313ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:16:25.606573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:24.658754Z","time spent":"947.799336ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T10:16:25.606586Z","caller":"traceutil/trace.go:171","msg":"trace[1707171173] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"947.839058ms","start":"2026-07-01T10:16:24.65873Z","end":"2026-07-01T10:16:25.606569Z","steps":["trace[1707171173] 'process raft request' (duration: 947.705294ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:25.606694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:24.65827Z","time spent":"948.327264ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T10:16:25.606744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:24.658708Z","time spent":"947.944272ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T10:16:25.606566Z","caller":"traceutil/trace.go:171","msg":"trace[1692686680] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"947.831388ms","start":"2026-07-01T10:16:24.658702Z","end":"2026-07-01T10:16:25.606534Z","steps":["trace[1692686680] 'process raft request' (duration: 947.694303ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:25.606863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:24.65868Z","time spent":"948.160499ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T10:16:25.607021Z","caller":"traceutil/trace.go:171","msg":"trace[1126438390] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"947.717034ms","start":"2026-07-01T10:16:24.659285Z","end":"2026-07-01T10:16:25.607002Z","steps":["trace[1126438390] 'process raft request' (duration: 947.201197ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:16:25.607057Z","caller":"traceutil/trace.go:171","msg":"trace[452888939] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"947.411185ms","start":"2026-07-01T10:16:24.659624Z","end":"2026-07-01T10:16:25.607035Z","steps":["trace[452888939] 'process raft request' (duration: 947.138526ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:25.607116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:24.659267Z","time spent":"947.805137ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T10:16:25.607052Z","caller":"traceutil/trace.go:171","msg":"trace[453969364] linearizableReadLoop","detail":"{readStateIndex:1395; appliedIndex:1389; }","duration":"843.319751ms","start":"2026-07-01T10:16:24.763703Z","end":"2026-07-01T10:16:25.607023Z","steps":["trace[453969364] 'read index received' (duration: 723.587369ms)","trace[453969364] 'applied index is now lower than readState.Index' (duration: 119.731222ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:16:25.607176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:24.659608Z","time spent":"947.511108ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T10:16:25.607246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.544009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-01T10:16:25.608246Z","caller":"traceutil/trace.go:171","msg":"trace[417915086] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1342; }","duration":"844.589172ms","start":"2026-07-01T10:16:24.763641Z","end":"2026-07-01T10:16:25.608231Z","steps":["trace[417915086] 'agreement among raft nodes before linearized reading' (duration: 843.552859ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:16:25.608369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:16:24.763617Z","time spent":"844.711296ms","remote":"127.0.0.1:34704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":3,"response size":29,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"info","ts":"2026-07-01T10:18:46.479423Z","caller":"traceutil/trace.go:171","msg":"trace[1887040228] transaction","detail":"{read_only:false; response_revision:2009; number_of_response:1; }","duration":"124.805345ms","start":"2026-07-01T10:18:46.354578Z","end":"2026-07-01T10:18:46.479383Z","steps":["trace[1887040228] 'process raft request' (duration: 93.835876ms)","trace[1887040228] 'compare' (duration: 30.828454ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:18:46.484407Z","caller":"traceutil/trace.go:171","msg":"trace[458966219] transaction","detail":"{read_only:false; response_revision:2010; number_of_response:1; }","duration":"114.32699ms","start":"2026-07-01T10:18:46.370064Z","end":"2026-07-01T10:18:46.484391Z","steps":["trace[458966219] 'process raft request' (duration: 114.153734ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:18:46.484408Z","caller":"traceutil/trace.go:171","msg":"trace[547173339] transaction","detail":"{read_only:false; response_revision:2012; number_of_response:1; }","duration":"111.994066ms","start":"2026-07-01T10:18:46.37239Z","end":"2026-07-01T10:18:46.484384Z","steps":["trace[547173339] 'process raft request' (duration: 111.960314ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:18:46.484484Z","caller":"traceutil/trace.go:171","msg":"trace[724424434] transaction","detail":"{read_only:false; response_revision:2011; number_of_response:1; }","duration":"114.236347ms","start":"2026-07-01T10:18:46.370207Z","end":"2026-07-01T10:18:46.484444Z","steps":["trace[724424434] 'process raft request' (duration: 114.111553ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:18:54.63189Z","caller":"traceutil/trace.go:171","msg":"trace[1255469576] transaction","detail":"{read_only:false; response_revision:2049; number_of_response:1; }","duration":"108.711942ms","start":"2026-07-01T10:18:54.523152Z","end":"2026-07-01T10:18:54.631864Z","steps":["trace[1255469576] 'process raft request' (duration: 108.66068ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:18:54.631939Z","caller":"traceutil/trace.go:171","msg":"trace[1536899259] transaction","detail":"{read_only:false; response_revision:2048; number_of_response:1; }","duration":"108.830886ms","start":"2026-07-01T10:18:54.523071Z","end":"2026-07-01T10:18:54.631902Z","steps":["trace[1536899259] 'process raft request' (duration: 106.13963ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:18:56.652132Z","caller":"traceutil/trace.go:171","msg":"trace[1722367761] transaction","detail":"{read_only:false; response_revision:2055; number_of_response:1; }","duration":"124.859968ms","start":"2026-07-01T10:18:56.527251Z","end":"2026-07-01T10:18:56.652111Z","steps":["trace[1722367761] 'process raft request' (duration: 70.843733ms)","trace[1722367761] 'compare' (duration: 53.87064ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:18:56.652118Z","caller":"traceutil/trace.go:171","msg":"trace[1998744245] linearizableReadLoop","detail":"{readStateIndex:2138; appliedIndex:2137; }","duration":"106.7662ms","start":"2026-07-01T10:18:56.54531Z","end":"2026-07-01T10:18:56.652076Z","steps":["trace[1998744245] 'read index received' (duration: 52.731044ms)","trace[1998744245] 'applied index is now lower than readState.Index' (duration: 54.033876ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:18:56.652291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.965966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-01T10:18:56.652354Z","caller":"traceutil/trace.go:171","msg":"trace[922617957] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2055; }","duration":"107.085369ms","start":"2026-07-01T10:18:56.545256Z","end":"2026-07-01T10:18:56.652341Z","steps":["trace[922617957] 'agreement among raft nodes before linearized reading' (duration: 106.921054ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:18:58.143473Z","caller":"traceutil/trace.go:171","msg":"trace[1238081542] transaction","detail":"{read_only:false; response_revision:2061; number_of_response:1; }","duration":"177.139378ms","start":"2026-07-01T10:18:57.966307Z","end":"2026-07-01T10:18:58.143447Z","steps":["trace[1238081542] 'process raft request' (duration: 176.978972ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:18:58.883981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.663848ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T10:18:58.884096Z","caller":"traceutil/trace.go:171","msg":"trace[1599298670] linearizableReadLoop","detail":"{readStateIndex:2150; appliedIndex:2149; }","duration":"196.756934ms","start":"2026-07-01T10:18:58.68732Z","end":"2026-07-01T10:18:58.884077Z","steps":["trace[1599298670] 'read index received' (duration: 57.783546ms)","trace[1599298670] 'applied index is now lower than readState.Index' (duration: 138.971718ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:18:58.884202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.887018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T10:18:58.88419Z","caller":"traceutil/trace.go:171","msg":"trace[2013775468] transaction","detail":"{read_only:false; response_revision:2066; number_of_response:1; }","duration":"211.159754ms","start":"2026-07-01T10:18:58.673006Z","end":"2026-07-01T10:18:58.884166Z","steps":["trace[2013775468] 'process raft request' (duration: 72.213806ms)","trace[2013775468] 'compare' (duration: 138.528414ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:18:58.884229Z","caller":"traceutil/trace.go:171","msg":"trace[749605578] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2066; }","duration":"196.95272ms","start":"2026-07-01T10:18:58.687265Z","end":"2026-07-01T10:18:58.884217Z","steps":["trace[749605578] 'agreement among raft nodes before linearized reading' (duration: 196.906329ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:18:58.884496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.82182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-01T10:18:58.884524Z","caller":"traceutil/trace.go:171","msg":"trace[1230821164] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2066; }","duration":"193.890432ms","start":"2026-07-01T10:18:58.690625Z","end":"2026-07-01T10:18:58.884516Z","steps":["trace[1230821164] 'agreement among raft nodes before linearized reading' (duration: 193.778328ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:18:58.884636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.499169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-01T10:18:58.884678Z","caller":"traceutil/trace.go:171","msg":"trace[2096112854] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2066; }","duration":"193.557002ms","start":"2026-07-01T10:18:58.691109Z","end":"2026-07-01T10:18:58.884666Z","steps":["trace[2096112854] 'agreement among raft nodes before linearized reading' (duration: 193.454318ms)"],"step_count":1}