{"level":"warn","ts":"2026-06-17T03:04:17.404507Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-17T03:04:17.404613Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.205: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.205:2380","--initial-cluster=instance=https://199.204.45.205:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.205:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.205: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-06-17T03:04:17.404679Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-17T03:04:17.404692Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-06-17T03:04:17.404712Z","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-06-17T03:04:17.405302Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"]} {"level":"info","ts":"2026-06-17T03:04:17.405381Z","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.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.205: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-06-17T03:04:17.409476Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.884265ms"} {"level":"info","ts":"2026-06-17T03:04:17.41476Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"71d28e9ac1bc4e5d","cluster-id":"4a4ae660c7a9a6a7"} {"level":"info","ts":"2026-06-17T03:04:17.414834Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=()"} {"level":"info","ts":"2026-06-17T03:04:17.414861Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 0"} {"level":"info","ts":"2026-06-17T03:04:17.414875Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 71d28e9ac1bc4e5d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-17T03:04:17.414884Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 1"} {"level":"info","ts":"2026-06-17T03:04:17.414928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"warn","ts":"2026-06-17T03:04:17.42926Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-17T03:04:17.430907Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-17T03:04:17.432353Z","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-06-17T03:04:17.435821Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"71d28e9ac1bc4e5d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-17T03:04:17.440652Z","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-06-17T03:04:17.441448Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"71d28e9ac1bc4e5d","initial-advertise-peer-urls":["https://199.204.45.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-17T03:04:17.441433Z","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-06-17T03:04:17.441537Z","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-06-17T03:04:17.441553Z","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-06-17T03:04:17.441704Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-06-17T03:04:17.441718Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-06-17T03:04:17.441605Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"71d28e9ac1bc4e5d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-17T03:04:17.441486Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-17T03:04:17.442575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"info","ts":"2026-06-17T03:04:17.442673Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","added-peer-id":"71d28e9ac1bc4e5d","added-peer-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-06-17T03:04:17.915416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d is starting a new election at term 1"} {"level":"info","ts":"2026-06-17T03:04:17.915685Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became pre-candidate at term 1"} {"level":"info","ts":"2026-06-17T03:04:17.915722Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgPreVoteResp from 71d28e9ac1bc4e5d at term 1"} {"level":"info","ts":"2026-06-17T03:04:17.915742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became candidate at term 2"} {"level":"info","ts":"2026-06-17T03:04:17.91575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgVoteResp from 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-06-17T03:04:17.915763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became leader at term 2"} {"level":"info","ts":"2026-06-17T03:04:17.915776Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 71d28e9ac1bc4e5d elected leader 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-06-17T03:04:17.916728Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"71d28e9ac1bc4e5d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.205:2379]}","request-path":"/0/members/71d28e9ac1bc4e5d/attributes","cluster-id":"4a4ae660c7a9a6a7","publish-timeout":"7s"} {"level":"info","ts":"2026-06-17T03:04:17.916937Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-17T03:04:17.917097Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-17T03:04:17.917322Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-17T03:04:17.917433Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-17T03:04:17.917455Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-17T03:04:17.919312Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.205:2379"} {"level":"info","ts":"2026-06-17T03:04:17.919564Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-17T03:04:17.924475Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","cluster-version":"3.5"} {"level":"info","ts":"2026-06-17T03:04:17.924537Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-17T03:04:17.924555Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-06-17T03:04:53.753938Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646844139172858730,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-17T03:04:53.828892Z","caller":"traceutil/trace.go:171","msg":"trace[180369452] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"696.663698ms","start":"2026-06-17T03:04:53.132199Z","end":"2026-06-17T03:04:53.828863Z","steps":["trace[180369452] 'process raft request' (duration: 694.208142ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:04:53.829549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:04:53.13218Z","time spent":"696.809412ms","remote":"127.0.0.1:43660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65199,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T03:04:53.831914Z","caller":"traceutil/trace.go:171","msg":"trace[551113781] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:539; }","duration":"578.34856ms","start":"2026-06-17T03:04:53.253555Z","end":"2026-06-17T03:04:53.831904Z","steps":["trace[551113781] 'read index received' (duration: 572.932907ms)","trace[551113781] 'applied index is now lower than readState.Index' (duration: 5.414943ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T03:04:53.83198Z","caller":"traceutil/trace.go:171","msg":"trace[935913018] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"456.069486ms","start":"2026-06-17T03:04:53.375903Z","end":"2026-06-17T03:04:53.831972Z","steps":["trace[935913018] 'process raft request' (duration: 455.967675ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:04:53.832021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:04:53.375888Z","time spent":"456.106857ms","remote":"127.0.0.1:43886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T03:04:53.831975Z","caller":"traceutil/trace.go:171","msg":"trace[1534080770] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"696.717031ms","start":"2026-06-17T03:04:53.135208Z","end":"2026-06-17T03:04:53.831925Z","steps":["trace[1534080770] 'process raft request' (duration: 696.328942ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:04:53.832095Z","caller":"traceutil/trace.go:171","msg":"trace[1469522536] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"689.465012ms","start":"2026-06-17T03:04:53.14257Z","end":"2026-06-17T03:04:53.832035Z","steps":["trace[1469522536] 'process raft request' (duration: 689.180816ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:04:53.832196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:04:53.135191Z","time spent":"696.879833ms","remote":"127.0.0.1:43660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59607,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T03:04:53.832289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.261384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-17T03:04:53.832239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.060558ms","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-06-17T03:04:53.832316Z","caller":"traceutil/trace.go:171","msg":"trace[506695055] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:522; }","duration":"155.285875ms","start":"2026-06-17T03:04:53.677022Z","end":"2026-06-17T03:04:53.832308Z","steps":["trace[506695055] 'agreement among raft nodes before linearized reading' (duration: 155.231244ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:04:53.83232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:04:53.142552Z","time spent":"689.647126ms","remote":"127.0.0.1:43660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-17T03:04:53.832356Z","caller":"traceutil/trace.go:171","msg":"trace[536716716] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:522; }","duration":"410.18914ms","start":"2026-06-17T03:04:53.422141Z","end":"2026-06-17T03:04:53.83233Z","steps":["trace[536716716] 'agreement among raft nodes before linearized reading' (duration: 410.047667ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:04:53.832149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.574124ms","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-06-17T03:04:53.832446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:04:53.422126Z","time spent":"410.294833ms","remote":"127.0.0.1:43886","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-06-17T03:04:53.832508Z","caller":"traceutil/trace.go:171","msg":"trace[1976246889] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:522; }","duration":"578.959352ms","start":"2026-06-17T03:04:53.253511Z","end":"2026-06-17T03:04:53.83247Z","steps":["trace[1976246889] 'agreement among raft nodes before linearized reading' (duration: 578.585565ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:04:53.832612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:04:53.253494Z","time spent":"579.097844ms","remote":"127.0.0.1:43886","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":"warn","ts":"2026-06-17T03:05:01.19365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.129854638s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-17T03:05:01.193887Z","caller":"traceutil/trace.go:171","msg":"trace[21416583] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"1.388383933s","start":"2026-06-17T03:04:59.805486Z","end":"2026-06-17T03:05:01.19387Z","steps":["trace[21416583] 'process raft request' (duration: 1.388283202s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:05:01.193963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:04:59.805463Z","time spent":"1.388456134s","remote":"127.0.0.1:43886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T03:05:01.193935Z","caller":"traceutil/trace.go:171","msg":"trace[859483414] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"1.535210637s","start":"2026-06-17T03:04:59.658677Z","end":"2026-06-17T03:05:01.193887Z","steps":["trace[859483414] 'process raft request' (duration: 404.91899ms)","trace[859483414] 'compare' (duration: 1.129524082s)"],"step_count":2} {"level":"warn","ts":"2026-06-17T03:05:01.194139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:04:59.658652Z","time spent":"1.535382019s","remote":"127.0.0.1:44076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-17T03:05:01.194247Z","caller":"traceutil/trace.go:171","msg":"trace[203330270] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"1.44637552s","start":"2026-06-17T03:04:59.747847Z","end":"2026-06-17T03:05:01.194223Z","steps":["trace[203330270] 'read index received' (duration: 315.838198ms)","trace[203330270] 'applied index is now lower than readState.Index' (duration: 1.130534912s)"],"step_count":2} {"level":"warn","ts":"2026-06-17T03:05:01.194414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.408983106s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T03:05:01.194508Z","caller":"traceutil/trace.go:171","msg":"trace[713433120] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:589; }","duration":"1.409126059s","start":"2026-06-17T03:04:59.785364Z","end":"2026-06-17T03:05:01.19449Z","steps":["trace[713433120] 'agreement among raft nodes before linearized reading' (duration: 1.409009147s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:05:01.194549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:04:59.785343Z","time spent":"1.40919854s","remote":"127.0.0.1:43608","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-06-17T03:05:01.194541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.446681625s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-629wm\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-06-17T03:05:01.194548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.545528ms","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-06-17T03:05:01.194601Z","caller":"traceutil/trace.go:171","msg":"trace[1791677022] range","detail":"{range_begin:/registry/pods/kube-system/cilium-629wm; range_end:; response_count:1; response_revision:589; }","duration":"1.446813918s","start":"2026-06-17T03:04:59.74777Z","end":"2026-06-17T03:05:01.194584Z","steps":["trace[1791677022] 'agreement among raft nodes before linearized reading' (duration: 1.446582683s)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:05:01.194606Z","caller":"traceutil/trace.go:171","msg":"trace[1009685647] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:589; }","duration":"343.64059ms","start":"2026-06-17T03:05:00.850951Z","end":"2026-06-17T03:05:01.194592Z","steps":["trace[1009685647] 'agreement among raft nodes before linearized reading' (duration: 343.462946ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:05:01.194615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.323121848s","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-06-17T03:05:01.194732Z","caller":"traceutil/trace.go:171","msg":"trace[1739628044] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:589; }","duration":"1.323286121s","start":"2026-06-17T03:04:59.871417Z","end":"2026-06-17T03:05:01.194704Z","steps":["trace[1739628044] 'agreement among raft nodes before linearized reading' (duration: 1.322978035s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:05:01.194793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.324064044s","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-06-17T03:05:01.194811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:04:59.871383Z","time spent":"1.323397932s","remote":"127.0.0.1:43886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-17T03:05:01.194839Z","caller":"traceutil/trace.go:171","msg":"trace[1025796079] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:589; }","duration":"1.324131426s","start":"2026-06-17T03:04:59.870699Z","end":"2026-06-17T03:05:01.19483Z","steps":["trace[1025796079] 'agreement among raft nodes before linearized reading' (duration: 1.323978623s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:05:01.194831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.324605415s","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-06-17T03:05:01.194874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:04:59.870669Z","time spent":"1.324198677s","remote":"127.0.0.1:43886","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-06-17T03:05:01.194652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:04:59.747747Z","time spent":"1.44689409s","remote":"127.0.0.1:43802","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-629wm\" "} {"level":"info","ts":"2026-06-17T03:05:01.194902Z","caller":"traceutil/trace.go:171","msg":"trace[1024251545] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:589; }","duration":"1.324727278s","start":"2026-06-17T03:04:59.87015Z","end":"2026-06-17T03:05:01.194878Z","steps":["trace[1024251545] 'agreement among raft nodes before linearized reading' (duration: 1.324542785s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:05:01.194959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:04:59.870137Z","time spent":"1.324808439s","remote":"127.0.0.1:43886","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-06-17T03:05:01.194617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.603992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.205\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-17T03:05:01.195061Z","caller":"traceutil/trace.go:171","msg":"trace[1808003931] range","detail":"{range_begin:/registry/masterleases/199.204.45.205; range_end:; response_count:1; response_revision:589; }","duration":"505.107032ms","start":"2026-06-17T03:05:00.689944Z","end":"2026-06-17T03:05:01.195051Z","steps":["trace[1808003931] 'agreement among raft nodes before linearized reading' (duration: 504.604132ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:05:01.195094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:05:00.689915Z","time spent":"505.170554ms","remote":"127.0.0.1:43650","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.205\" "} {"level":"warn","ts":"2026-06-17T03:05:01.194652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:05:00.850931Z","time spent":"343.710121ms","remote":"127.0.0.1:43886","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-06-17T03:05:03.178352Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.955456318s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-17T03:05:03.204335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999939318s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-06-17T03:05:03.204412Z","caller":"traceutil/trace.go:171","msg":"trace[1538001261] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00004601s","start":"2026-06-17T03:05:01.204343Z","end":"2026-06-17T03:05:03.204389Z","steps":["trace[1538001261] 'agreement among raft nodes before linearized reading' (duration: 1.999955658s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:05:03.204485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:05:01.204335Z","time spent":"2.000137822s","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-17T03:05:03.204884Z","caller":"traceutil/trace.go:171","msg":"trace[1497828531] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"2.005408712s","start":"2026-06-17T03:05:01.199448Z","end":"2026-06-17T03:05:03.204856Z","steps":["trace[1497828531] 'process raft request' (duration: 2.00532644s)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:05:03.205028Z","caller":"traceutil/trace.go:171","msg":"trace[183585443] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:613; }","duration":"2.000646722s","start":"2026-06-17T03:05:01.204366Z","end":"2026-06-17T03:05:03.205013Z","steps":["trace[183585443] 'read index received' (duration: 18.506653ms)","trace[183585443] 'applied index is now lower than readState.Index' (duration: 1.982139179s)"],"step_count":2} {"level":"warn","ts":"2026-06-17T03:05:03.205062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:05:01.199439Z","time spent":"2.005519714s","remote":"127.0.0.1:43886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T03:05:03.205147Z","caller":"traceutil/trace.go:171","msg":"trace[1820210739] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"2.001324084s","start":"2026-06-17T03:05:01.203816Z","end":"2026-06-17T03:05:03.20514Z","steps":["trace[1820210739] 'process raft request' (duration: 2.001004488s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:05:03.205223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:05:01.203797Z","time spent":"2.001368324s","remote":"127.0.0.1:43886","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-06-17T03:05:03.205262Z","caller":"traceutil/trace.go:171","msg":"trace[1965137052] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"2.005968792s","start":"2026-06-17T03:05:01.199276Z","end":"2026-06-17T03:05:03.205245Z","steps":["trace[1965137052] 'process raft request' (duration: 1.979404405s)","trace[1965137052] 'compare' (duration: 25.944785ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T03:05:03.205318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:05:01.199257Z","time spent":"2.006029834s","remote":"127.0.0.1:43886","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-06-17T03:05:03.205327Z","caller":"traceutil/trace.go:171","msg":"trace[120344954] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"2.001431615s","start":"2026-06-17T03:05:01.203889Z","end":"2026-06-17T03:05:03.20532Z","steps":["trace[120344954] 'process raft request' (duration: 2.000999297s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:05:03.205361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:05:01.203882Z","time spent":"2.001461147s","remote":"127.0.0.1:43886","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-06-17T03:05:03.205492Z","caller":"traceutil/trace.go:171","msg":"trace[383581139] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"1.997468819s","start":"2026-06-17T03:05:01.208017Z","end":"2026-06-17T03:05:03.205485Z","steps":["trace[383581139] 'process raft request' (duration: 1.996929539s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:05:03.205549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:05:01.207995Z","time spent":"1.997534761s","remote":"127.0.0.1:43802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T03:05:03.218751Z","caller":"traceutil/trace.go:171","msg":"trace[819358128] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"1.994990803s","start":"2026-06-17T03:05:01.223732Z","end":"2026-06-17T03:05:03.218723Z","steps":["trace[819358128] 'process raft request' (duration: 1.994891821s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:05:03.218794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.012278312s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T03:05:03.218849Z","caller":"traceutil/trace.go:171","msg":"trace[1733875241] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:595; }","duration":"2.012378174s","start":"2026-06-17T03:05:01.206457Z","end":"2026-06-17T03:05:03.218835Z","steps":["trace[1733875241] 'agreement among raft nodes before linearized reading' (duration: 2.012271392s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:05:03.218862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:05:01.22371Z","time spent":"1.995083445s","remote":"127.0.0.1:43650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T03:05:03.218874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:05:01.206452Z","time spent":"2.012416585s","remote":"127.0.0.1:43850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-06-17T03:05:05.160108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.845116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T03:05:05.160247Z","caller":"traceutil/trace.go:171","msg":"trace[584596895] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:631; }","duration":"198.073471ms","start":"2026-06-17T03:05:04.962146Z","end":"2026-06-17T03:05:05.160219Z","steps":["trace[584596895] 'range keys from in-memory index tree' (duration: 197.687343ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:05:19.13418Z","caller":"traceutil/trace.go:171","msg":"trace[337066738] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"499.115029ms","start":"2026-06-17T03:05:18.63504Z","end":"2026-06-17T03:05:19.134155Z","steps":["trace[337066738] 'process raft request' (duration: 498.965386ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:05:19.134341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:05:18.635019Z","time spent":"499.23594ms","remote":"127.0.0.1:43886","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-06-17T03:05:19.454407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.571159ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-17T03:05:19.454526Z","caller":"traceutil/trace.go:171","msg":"trace[2048436134] linearizableReadLoop","detail":"{readStateIndex:784; appliedIndex:783; }","duration":"174.592063ms","start":"2026-06-17T03:05:19.279918Z","end":"2026-06-17T03:05:19.45451Z","steps":["trace[2048436134] 'read index received' (duration: 45.671µs)","trace[2048436134] 'applied index is now lower than readState.Index' (duration: 174.545162ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T03:05:19.454645Z","caller":"traceutil/trace.go:171","msg":"trace[999545189] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"595.277252ms","start":"2026-06-17T03:05:18.859321Z","end":"2026-06-17T03:05:19.454598Z","steps":["trace[999545189] 'process raft request' (duration: 362.431658ms)","trace[999545189] 'compare' (duration: 232.467478ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T03:05:19.454805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:05:18.859297Z","time spent":"595.421976ms","remote":"127.0.0.1:43850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T03:05:19.454831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.086916ms","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-06-17T03:05:19.454872Z","caller":"traceutil/trace.go:171","msg":"trace[1898636865] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:756; }","duration":"171.149236ms","start":"2026-06-17T03:05:19.283711Z","end":"2026-06-17T03:05:19.45486Z","steps":["trace[1898636865] 'agreement among raft nodes before linearized reading' (duration: 171.018534ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:05:19.454884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.9616ms","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-06-17T03:05:19.454912Z","caller":"traceutil/trace.go:171","msg":"trace[712662656] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:756; }","duration":"175.023691ms","start":"2026-06-17T03:05:19.27988Z","end":"2026-06-17T03:05:19.454904Z","steps":["trace[712662656] 'agreement among raft nodes before linearized reading' (duration: 174.811777ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:05:19.455083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.043995ms","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-06-17T03:05:19.45512Z","caller":"traceutil/trace.go:171","msg":"trace[1677223322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:756; }","duration":"171.101446ms","start":"2026-06-17T03:05:19.28401Z","end":"2026-06-17T03:05:19.455112Z","steps":["trace[1677223322] 'agreement among raft nodes before linearized reading' (duration: 171.013994ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:05:19.455145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.157116ms","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-06-17T03:05:19.455191Z","caller":"traceutil/trace.go:171","msg":"trace[1674133156] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:756; }","duration":"171.216468ms","start":"2026-06-17T03:05:19.283963Z","end":"2026-06-17T03:05:19.45518Z","steps":["trace[1674133156] 'agreement among raft nodes before linearized reading' (duration: 171.070515ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:05:49.904709Z","caller":"traceutil/trace.go:171","msg":"trace[1614600090] linearizableReadLoop","detail":"{readStateIndex:988; appliedIndex:987; }","duration":"158.910869ms","start":"2026-06-17T03:05:49.745776Z","end":"2026-06-17T03:05:49.904687Z","steps":["trace[1614600090] 'read index received' (duration: 158.712855ms)","trace[1614600090] 'applied index is now lower than readState.Index' (duration: 197.104µs)"],"step_count":2} {"level":"warn","ts":"2026-06-17T03:05:49.90492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.131714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-17T03:05:49.904927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.317089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T03:05:49.904999Z","caller":"traceutil/trace.go:171","msg":"trace[67420628] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:952; }","duration":"118.440392ms","start":"2026-06-17T03:05:49.786544Z","end":"2026-06-17T03:05:49.904985Z","steps":["trace[67420628] 'agreement among raft nodes before linearized reading' (duration: 118.308139ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:05:49.904996Z","caller":"traceutil/trace.go:171","msg":"trace[1780143431] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:952; }","duration":"159.242416ms","start":"2026-06-17T03:05:49.745735Z","end":"2026-06-17T03:05:49.904977Z","steps":["trace[1780143431] 'agreement among raft nodes before linearized reading' (duration: 159.043302ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:05:49.90526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.085512ms","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-06-17T03:05:49.905292Z","caller":"traceutil/trace.go:171","msg":"trace[1639709970] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:952; }","duration":"136.143363ms","start":"2026-06-17T03:05:49.76914Z","end":"2026-06-17T03:05:49.905284Z","steps":["trace[1639709970] 'agreement among raft nodes before linearized reading' (duration: 135.669824ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:05:49.904826Z","caller":"traceutil/trace.go:171","msg":"trace[127947741] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"208.688666ms","start":"2026-06-17T03:05:49.696094Z","end":"2026-06-17T03:05:49.904783Z","steps":["trace[127947741] 'process raft request' (duration: 208.453031ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:05:55.18245Z","caller":"traceutil/trace.go:171","msg":"trace[469874650] linearizableReadLoop","detail":"{readStateIndex:1011; appliedIndex:1010; }","duration":"396.881293ms","start":"2026-06-17T03:05:54.785549Z","end":"2026-06-17T03:05:55.182431Z","steps":["trace[469874650] 'read index received' (duration: 396.70971ms)","trace[469874650] 'applied index is now lower than readState.Index' (duration: 170.863µs)"],"step_count":2} {"level":"warn","ts":"2026-06-17T03:05:55.182583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.026226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T03:05:55.182607Z","caller":"traceutil/trace.go:171","msg":"trace[174207863] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:974; }","duration":"397.089848ms","start":"2026-06-17T03:05:54.785509Z","end":"2026-06-17T03:05:55.182599Z","steps":["trace[174207863] 'agreement among raft nodes before linearized reading' (duration: 397.039017ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:05:55.182683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:05:54.785498Z","time spent":"397.179019ms","remote":"127.0.0.1:43602","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-06-17T03:05:55.182846Z","caller":"traceutil/trace.go:171","msg":"trace[1613949485] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"445.519619ms","start":"2026-06-17T03:05:54.737316Z","end":"2026-06-17T03:05:55.182836Z","steps":["trace[1613949485] 'process raft request' (duration: 445.011578ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:05:55.182905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:05:54.737295Z","time spent":"445.56804ms","remote":"127.0.0.1:43886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T03:05:56.022491Z","caller":"traceutil/trace.go:171","msg":"trace[1077379481] linearizableReadLoop","detail":"{readStateIndex:1012; appliedIndex:1011; }","duration":"249.814337ms","start":"2026-06-17T03:05:55.772621Z","end":"2026-06-17T03:05:56.022435Z","steps":["trace[1077379481] 'read index received' (duration: 249.48492ms)","trace[1077379481] 'applied index is now lower than readState.Index' (duration: 328.237µs)"],"step_count":2} {"level":"info","ts":"2026-06-17T03:05:56.022567Z","caller":"traceutil/trace.go:171","msg":"trace[279708403] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"637.782078ms","start":"2026-06-17T03:05:55.384744Z","end":"2026-06-17T03:05:56.022526Z","steps":["trace[279708403] 'process raft request' (duration: 637.440432ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:05:56.022723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:05:55.38471Z","time spent":"637.935571ms","remote":"127.0.0.1:43886","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-06-17T03:05:56.022826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.170393ms","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-06-17T03:05:56.022881Z","caller":"traceutil/trace.go:171","msg":"trace[816550426] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:975; }","duration":"250.288526ms","start":"2026-06-17T03:05:55.772575Z","end":"2026-06-17T03:05:56.022864Z","steps":["trace[816550426] 'agreement among raft nodes before linearized reading' (duration: 250.074662ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:05:56.022889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.005967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-17T03:05:56.023034Z","caller":"traceutil/trace.go:171","msg":"trace[267077180] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:975; }","duration":"197.226702ms","start":"2026-06-17T03:05:55.825777Z","end":"2026-06-17T03:05:56.023004Z","steps":["trace[267077180] 'agreement among raft nodes before linearized reading' (duration: 196.915495ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:05:57.276903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.552082ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-17T03:05:57.276979Z","caller":"traceutil/trace.go:171","msg":"trace[484650911] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"1.24873255s","start":"2026-06-17T03:05:56.028233Z","end":"2026-06-17T03:05:57.276965Z","steps":["trace[484650911] 'process raft request' (duration: 593.063535ms)","trace[484650911] 'compare' (duration: 655.341468ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T03:05:57.277017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:05:56.028189Z","time spent":"1.248810251s","remote":"127.0.0.1:43886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T03:05:57.285703Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646844139172859739,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-17T03:05:57.786725Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646844139172859739,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-17T03:05:57.95383Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.33237407s","expected-duration":"1s"} {"level":"info","ts":"2026-06-17T03:05:58.009504Z","caller":"traceutil/trace.go:171","msg":"trace[1151371852] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"1.980871477s","start":"2026-06-17T03:05:56.028529Z","end":"2026-06-17T03:05:58.0094Z","steps":["trace[1151371852] 'process raft request' (duration: 1.925451354s)","trace[1151371852] 'compare' (duration: 54.858152ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T03:05:58.009607Z","caller":"traceutil/trace.go:171","msg":"trace[500898954] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"1.981026489s","start":"2026-06-17T03:05:56.028563Z","end":"2026-06-17T03:05:58.009589Z","steps":["trace[500898954] 'process raft request' (duration: 1.980779605s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:05:58.00966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:05:56.028516Z","time spent":"1.98106065s","remote":"127.0.0.1:43886","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-06-17T03:05:58.009697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:05:56.028543Z","time spent":"1.981105012s","remote":"127.0.0.1:43886","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-06-17T03:05:58.010026Z","caller":"traceutil/trace.go:171","msg":"trace[1355024228] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"1.981271706s","start":"2026-06-17T03:05:56.028743Z","end":"2026-06-17T03:05:58.010015Z","steps":["trace[1355024228] 'process raft request' (duration: 1.980794065s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:05:58.010094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:05:56.028732Z","time spent":"1.981328927s","remote":"127.0.0.1:43886","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-06-17T03:05:58.226337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.468455ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-17T03:05:58.226572Z","caller":"traceutil/trace.go:171","msg":"trace[1937086643] linearizableReadLoop","detail":"{readStateIndex:1018; appliedIndex:1013; }","duration":"1.440891578s","start":"2026-06-17T03:05:56.78566Z","end":"2026-06-17T03:05:58.226552Z","steps":["trace[1937086643] 'read index received' (duration: 1.168270935s)","trace[1937086643] 'applied index is now lower than readState.Index' (duration: 272.619443ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T03:05:58.226814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.104939101s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T03:05:58.226899Z","caller":"traceutil/trace.go:171","msg":"trace[437424023] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:980; }","duration":"1.105036853s","start":"2026-06-17T03:05:57.121842Z","end":"2026-06-17T03:05:58.226879Z","steps":["trace[437424023] 'agreement among raft nodes before linearized reading' (duration: 1.10489759s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:05:58.226898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.441212515s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T03:05:58.226904Z","caller":"traceutil/trace.go:171","msg":"trace[787989293] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"1.899479784s","start":"2026-06-17T03:05:56.327401Z","end":"2026-06-17T03:05:58.226881Z","steps":["trace[787989293] 'process raft request' (duration: 1.899032484s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:05:58.226939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.380477ms","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-06-17T03:05:58.226947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:05:57.121825Z","time spent":"1.105110994s","remote":"127.0.0.1:38786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"info","ts":"2026-06-17T03:05:58.226973Z","caller":"traceutil/trace.go:171","msg":"trace[14157134] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:980; }","duration":"200.454448ms","start":"2026-06-17T03:05:58.026509Z","end":"2026-06-17T03:05:58.226964Z","steps":["trace[14157134] 'agreement among raft nodes before linearized reading' (duration: 200.325016ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:05:58.226969Z","caller":"traceutil/trace.go:171","msg":"trace[457711415] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:980; }","duration":"1.441328617s","start":"2026-06-17T03:05:56.785622Z","end":"2026-06-17T03:05:58.226951Z","steps":["trace[457711415] 'agreement among raft nodes before linearized reading' (duration: 1.441191824s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:05:58.227015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:05:56.785601Z","time spent":"1.441406259s","remote":"127.0.0.1:43602","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-06-17T03:05:58.227072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.052926856s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T03:05:58.227111Z","caller":"traceutil/trace.go:171","msg":"trace[1545558785] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:980; }","duration":"1.052996927s","start":"2026-06-17T03:05:57.174102Z","end":"2026-06-17T03:05:58.227099Z","steps":["trace[1545558785] 'agreement among raft nodes before linearized reading' (duration: 1.052927496s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:05:58.227156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:05:57.174091Z","time spent":"1.053055339s","remote":"127.0.0.1:43834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-06-17T03:05:58.227379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:05:56.327379Z","time spent":"1.899586106s","remote":"127.0.0.1:43886","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-06-17T03:06:02.288952Z","caller":"traceutil/trace.go:171","msg":"trace[17179905] linearizableReadLoop","detail":"{readStateIndex:1030; appliedIndex:1029; }","duration":"231.413281ms","start":"2026-06-17T03:06:02.05752Z","end":"2026-06-17T03:06:02.288933Z","steps":["trace[17179905] 'read index received' (duration: 213.025465ms)","trace[17179905] 'applied index is now lower than readState.Index' (duration: 18.386696ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T03:06:02.288984Z","caller":"traceutil/trace.go:171","msg":"trace[1212913136] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"247.791215ms","start":"2026-06-17T03:06:02.041159Z","end":"2026-06-17T03:06:02.28895Z","steps":["trace[1212913136] 'process raft request' (duration: 229.43047ms)","trace[1212913136] 'compare' (duration: 18.0936ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T03:06:02.289143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.616895ms","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-06-17T03:06:02.289176Z","caller":"traceutil/trace.go:171","msg":"trace[685734391] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:991; }","duration":"231.699627ms","start":"2026-06-17T03:06:02.057466Z","end":"2026-06-17T03:06:02.289166Z","steps":["trace[685734391] 'agreement among raft nodes before linearized reading' (duration: 231.565724ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:06:05.42684Z","caller":"traceutil/trace.go:171","msg":"trace[886192327] linearizableReadLoop","detail":"{readStateIndex:1041; appliedIndex:1040; }","duration":"120.588705ms","start":"2026-06-17T03:06:05.306227Z","end":"2026-06-17T03:06:05.426815Z","steps":["trace[886192327] 'read index received' (duration: 120.397721ms)","trace[886192327] 'applied index is now lower than readState.Index' (duration: 190.114µs)"],"step_count":2} {"level":"info","ts":"2026-06-17T03:06:05.426953Z","caller":"traceutil/trace.go:171","msg":"trace[1009471723] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"133.535329ms","start":"2026-06-17T03:06:05.293407Z","end":"2026-06-17T03:06:05.426942Z","steps":["trace[1009471723] 'process raft request' (duration: 133.248154ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:06:05.427177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.930552ms","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-06-17T03:06:05.427236Z","caller":"traceutil/trace.go:171","msg":"trace[1033162653] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1002; }","duration":"121.025854ms","start":"2026-06-17T03:06:05.306194Z","end":"2026-06-17T03:06:05.42722Z","steps":["trace[1033162653] 'agreement among raft nodes before linearized reading' (duration: 120.81583ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:06:05.573832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.567587ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-17T03:06:05.573932Z","caller":"traceutil/trace.go:171","msg":"trace[1187285936] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"142.555324ms","start":"2026-06-17T03:06:05.431357Z","end":"2026-06-17T03:06:05.573913Z","steps":["trace[1187285936] 'process raft request' (duration: 40.834054ms)","trace[1187285936] 'compare' (duration: 101.472575ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T03:06:10.886232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.130307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T03:06:10.886332Z","caller":"traceutil/trace.go:171","msg":"trace[925004891] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1021; }","duration":"101.28361ms","start":"2026-06-17T03:06:10.785033Z","end":"2026-06-17T03:06:10.886317Z","steps":["trace[925004891] 'range keys from in-memory index tree' (duration: 101.046526ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:06:20.571103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.643107ms","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-06-17T03:06:20.571191Z","caller":"traceutil/trace.go:171","msg":"trace[2112073623] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1049; }","duration":"188.77986ms","start":"2026-06-17T03:06:20.382393Z","end":"2026-06-17T03:06:20.571173Z","steps":["trace[2112073623] 'range keys from in-memory index tree' (duration: 188.455092ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:06:21.017045Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646844139172859935,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-17T03:06:21.055058Z","caller":"traceutil/trace.go:171","msg":"trace[399176686] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"633.034162ms","start":"2026-06-17T03:06:20.42201Z","end":"2026-06-17T03:06:21.055044Z","steps":["trace[399176686] 'process raft request' (duration: 632.94758ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:06:21.055138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:06:20.421987Z","time spent":"633.100743ms","remote":"127.0.0.1:43886","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-06-17T03:06:21.777215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.19354ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-17T03:06:21.777304Z","caller":"traceutil/trace.go:171","msg":"trace[2106885824] linearizableReadLoop","detail":"{readStateIndex:1093; appliedIndex:1092; }","duration":"1.260586382s","start":"2026-06-17T03:06:20.516703Z","end":"2026-06-17T03:06:21.777289Z","steps":["trace[2106885824] 'read index received' (duration: 538.351326ms)","trace[2106885824] 'applied index is now lower than readState.Index' (duration: 722.234116ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T03:06:21.777508Z","caller":"traceutil/trace.go:171","msg":"trace[776857498] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"1.203140807s","start":"2026-06-17T03:06:20.574283Z","end":"2026-06-17T03:06:21.777424Z","steps":["trace[776857498] 'process raft request' (duration: 1.075659461s)","trace[776857498] 'compare' (duration: 127.092038ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T03:06:21.777592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:06:20.574264Z","time spent":"1.203290611s","remote":"127.0.0.1:43886","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-06-17T03:06:21.77761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.260908419s","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-06-17T03:06:21.777657Z","caller":"traceutil/trace.go:171","msg":"trace[978266673] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1051; }","duration":"1.260993641s","start":"2026-06-17T03:06:20.516649Z","end":"2026-06-17T03:06:21.777643Z","steps":["trace[978266673] 'agreement among raft nodes before linearized reading' (duration: 1.260829158s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:06:21.77769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:06:20.516628Z","time spent":"1.261055252s","remote":"127.0.0.1:43886","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-06-17T03:06:21.778028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"992.994192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-17T03:06:21.77807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.69951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-17T03:06:21.778209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.083197325s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.205\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-17T03:06:21.77826Z","caller":"traceutil/trace.go:171","msg":"trace[1579234745] range","detail":"{range_begin:/registry/masterleases/199.204.45.205; range_end:; response_count:1; response_revision:1051; }","duration":"1.083280276s","start":"2026-06-17T03:06:20.694967Z","end":"2026-06-17T03:06:21.778247Z","steps":["trace[1579234745] 'agreement among raft nodes before linearized reading' (duration: 1.083131753s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:06:21.778287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:06:20.694935Z","time spent":"1.083347068s","remote":"127.0.0.1:43650","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.205\" "} {"level":"info","ts":"2026-06-17T03:06:21.778217Z","caller":"traceutil/trace.go:171","msg":"trace[1392250236] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1051; }","duration":"194.897544ms","start":"2026-06-17T03:06:21.583299Z","end":"2026-06-17T03:06:21.778197Z","steps":["trace[1392250236] 'agreement among raft nodes before linearized reading' (duration: 194.68206ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:06:21.778078Z","caller":"traceutil/trace.go:171","msg":"trace[1979059512] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1051; }","duration":"993.045383ms","start":"2026-06-17T03:06:20.785023Z","end":"2026-06-17T03:06:21.778068Z","steps":["trace[1979059512] 'agreement among raft nodes before linearized reading' (duration: 992.975621ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:06:21.778508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:06:20.784977Z","time spent":"993.525013ms","remote":"127.0.0.1:43602","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-06-17T03:06:21.778085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.701394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-17T03:06:21.778587Z","caller":"traceutil/trace.go:171","msg":"trace[620162899] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1051; }","duration":"338.205455ms","start":"2026-06-17T03:06:21.44037Z","end":"2026-06-17T03:06:21.778576Z","steps":["trace[620162899] 'agreement among raft nodes before linearized reading' (duration: 337.646393ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:06:21.778631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:06:21.440321Z","time spent":"338.294517ms","remote":"127.0.0.1:43814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":43,"response size":29,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-06-17T03:06:21.778761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.2594796s","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-06-17T03:06:21.778799Z","caller":"traceutil/trace.go:171","msg":"trace[1912679897] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1051; }","duration":"1.259567722s","start":"2026-06-17T03:06:20.51922Z","end":"2026-06-17T03:06:21.778787Z","steps":["trace[1912679897] 'agreement among raft nodes before linearized reading' (duration: 1.258688384s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:06:21.778862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:06:20.519105Z","time spent":"1.259746585s","remote":"127.0.0.1:43886","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-06-17T03:06:21.936653Z","caller":"traceutil/trace.go:171","msg":"trace[902699169] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"154.961098ms","start":"2026-06-17T03:06:21.781672Z","end":"2026-06-17T03:06:21.936633Z","steps":["trace[902699169] 'process raft request' (duration: 154.773833ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:06:21.937049Z","caller":"traceutil/trace.go:171","msg":"trace[1294244933] linearizableReadLoop","detail":"{readStateIndex:1097; appliedIndex:1093; }","duration":"151.492907ms","start":"2026-06-17T03:06:21.785533Z","end":"2026-06-17T03:06:21.937026Z","steps":["trace[1294244933] 'read index received' (duration: 44.128621ms)","trace[1294244933] 'applied index is now lower than readState.Index' (duration: 107.363406ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T03:06:21.937113Z","caller":"traceutil/trace.go:171","msg":"trace[1990311167] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"154.719364ms","start":"2026-06-17T03:06:21.782365Z","end":"2026-06-17T03:06:21.937084Z","steps":["trace[1990311167] 'process raft request' (duration: 154.216883ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:06:21.937179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.626879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T03:06:21.937217Z","caller":"traceutil/trace.go:171","msg":"trace[1132428667] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1054; }","duration":"151.69081ms","start":"2026-06-17T03:06:21.785517Z","end":"2026-06-17T03:06:21.937207Z","steps":["trace[1132428667] 'agreement among raft nodes before linearized reading' (duration: 151.620159ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:06:21.937157Z","caller":"traceutil/trace.go:171","msg":"trace[1943423776] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"153.825545ms","start":"2026-06-17T03:06:21.7833Z","end":"2026-06-17T03:06:21.937126Z","steps":["trace[1943423776] 'process raft request' (duration: 153.645621ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:06:21.938127Z","caller":"traceutil/trace.go:171","msg":"trace[1800000476] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"107.245573ms","start":"2026-06-17T03:06:21.83087Z","end":"2026-06-17T03:06:21.938115Z","steps":["trace[1800000476] 'process raft request' (duration: 106.582429ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:06:23.196152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.853491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T03:06:23.196236Z","caller":"traceutil/trace.go:171","msg":"trace[724046545] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1056; }","duration":"727.998114ms","start":"2026-06-17T03:06:22.468218Z","end":"2026-06-17T03:06:23.196216Z","steps":["trace[724046545] 'count revisions from in-memory index tree' (duration: 727.76328ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:06:23.196281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:06:22.4682Z","time spent":"728.069835ms","remote":"127.0.0.1:38966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-17T03:06:23.196353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.657985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T03:06:23.196473Z","caller":"traceutil/trace.go:171","msg":"trace[1120729375] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1056; }","duration":"410.812569ms","start":"2026-06-17T03:06:22.785642Z","end":"2026-06-17T03:06:23.196454Z","steps":["trace[1120729375] 'range keys from in-memory index tree' (duration: 410.587704ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:06:23.196519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:06:22.785627Z","time spent":"410.88334ms","remote":"127.0.0.1:43608","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-06-17T03:06:23.660029Z","caller":"traceutil/trace.go:171","msg":"trace[1397070077] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"792.758478ms","start":"2026-06-17T03:06:22.867244Z","end":"2026-06-17T03:06:23.660003Z","steps":["trace[1397070077] 'process raft request' (duration: 792.541333ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:06:23.660241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:06:22.867211Z","time spent":"792.92705ms","remote":"127.0.0.1:43886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T03:06:23.747926Z","caller":"traceutil/trace.go:171","msg":"trace[2073527292] linearizableReadLoop","detail":"{readStateIndex:1101; appliedIndex:1099; }","duration":"259.483925ms","start":"2026-06-17T03:06:23.488405Z","end":"2026-06-17T03:06:23.747889Z","steps":["trace[2073527292] 'read index received' (duration: 171.378073ms)","trace[2073527292] 'applied index is now lower than readState.Index' (duration: 88.104732ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T03:06:23.748155Z","caller":"traceutil/trace.go:171","msg":"trace[1397993685] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"687.410284ms","start":"2026-06-17T03:06:23.060706Z","end":"2026-06-17T03:06:23.748117Z","steps":["trace[1397993685] 'process raft request' (duration: 641.076407ms)","trace[1397993685] 'compare' (duration: 45.945679ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T03:06:23.748349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:06:23.060689Z","time spent":"687.573797ms","remote":"127.0.0.1:43886","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-06-17T03:06:23.748398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.953804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T03:06:23.748462Z","caller":"traceutil/trace.go:171","msg":"trace[261412140] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1058; }","duration":"260.087938ms","start":"2026-06-17T03:06:23.488364Z","end":"2026-06-17T03:06:23.748452Z","steps":["trace[261412140] 'agreement among raft nodes before linearized reading' (duration: 259.649868ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:06:24.049537Z","caller":"traceutil/trace.go:171","msg":"trace[1601382483] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"103.67491ms","start":"2026-06-17T03:06:23.945839Z","end":"2026-06-17T03:06:24.049514Z","steps":["trace[1601382483] 'process raft request' (duration: 103.536436ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:06:24.083021Z","caller":"traceutil/trace.go:171","msg":"trace[1862056242] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"136.98482ms","start":"2026-06-17T03:06:23.946012Z","end":"2026-06-17T03:06:24.082997Z","steps":["trace[1862056242] 'process raft request' (duration: 136.810916ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:06:24.083158Z","caller":"traceutil/trace.go:171","msg":"trace[827340687] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"137.104923ms","start":"2026-06-17T03:06:23.946022Z","end":"2026-06-17T03:06:24.083127Z","steps":["trace[827340687] 'process raft request' (duration: 136.936139ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:06:24.673208Z","caller":"traceutil/trace.go:171","msg":"trace[1617493072] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"367.238107ms","start":"2026-06-17T03:06:24.305947Z","end":"2026-06-17T03:06:24.673185Z","steps":["trace[1617493072] 'process raft request' (duration: 367.118505ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:06:24.673362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:06:24.305925Z","time spent":"367.354489ms","remote":"127.0.0.1:43886","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-06-17T03:06:24.904404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.556295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T03:06:24.904513Z","caller":"traceutil/trace.go:171","msg":"trace[454890467] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1063; }","duration":"118.730477ms","start":"2026-06-17T03:06:24.785762Z","end":"2026-06-17T03:06:24.904493Z","steps":["trace[454890467] 'range keys from in-memory index tree' (duration: 118.455291ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:06:26.316919Z","caller":"traceutil/trace.go:171","msg":"trace[1647515418] linearizableReadLoop","detail":"{readStateIndex:1110; appliedIndex:1109; }","duration":"228.885048ms","start":"2026-06-17T03:06:26.088007Z","end":"2026-06-17T03:06:26.316892Z","steps":["trace[1647515418] 'read index received' (duration: 228.684214ms)","trace[1647515418] 'applied index is now lower than readState.Index' (duration: 199.584µs)"],"step_count":2} {"level":"info","ts":"2026-06-17T03:06:26.316956Z","caller":"traceutil/trace.go:171","msg":"trace[262517244] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"258.846782ms","start":"2026-06-17T03:06:26.058079Z","end":"2026-06-17T03:06:26.316926Z","steps":["trace[262517244] 'process raft request' (duration: 258.626018ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:06:26.317262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.240176ms","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-06-17T03:06:26.317337Z","caller":"traceutil/trace.go:171","msg":"trace[1270994464] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1066; }","duration":"229.36756ms","start":"2026-06-17T03:06:26.087951Z","end":"2026-06-17T03:06:26.317319Z","steps":["trace[1270994464] 'agreement among raft nodes before linearized reading' (duration: 229.058604ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:06:26.330605Z","caller":"traceutil/trace.go:171","msg":"trace[867639871] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"121.654387ms","start":"2026-06-17T03:06:26.20893Z","end":"2026-06-17T03:06:26.330584Z","steps":["trace[867639871] 'process raft request' (duration: 121.513405ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:06:26.330645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.476686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T03:06:26.330682Z","caller":"traceutil/trace.go:171","msg":"trace[1176732544] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1067; }","duration":"216.524187ms","start":"2026-06-17T03:06:26.114148Z","end":"2026-06-17T03:06:26.330673Z","steps":["trace[1176732544] 'agreement among raft nodes before linearized reading' (duration: 216.453616ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:06:26.330725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.457847ms","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-06-17T03:06:26.330808Z","caller":"traceutil/trace.go:171","msg":"trace[1558035800] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1067; }","duration":"242.590829ms","start":"2026-06-17T03:06:26.088199Z","end":"2026-06-17T03:06:26.33079Z","steps":["trace[1558035800] 'agreement among raft nodes before linearized reading' (duration: 242.367265ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:06:26.43801Z","caller":"traceutil/trace.go:171","msg":"trace[972037053] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"116.473991ms","start":"2026-06-17T03:06:26.32151Z","end":"2026-06-17T03:06:26.437984Z","steps":["trace[972037053] 'process raft request' (duration: 22.875689ms)","trace[972037053] 'compare' (duration: 93.360808ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T03:06:26.438128Z","caller":"traceutil/trace.go:171","msg":"trace[443071991] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"102.891224ms","start":"2026-06-17T03:06:26.335208Z","end":"2026-06-17T03:06:26.4381Z","steps":["trace[443071991] 'process raft request' (duration: 102.685709ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:06:38.332967Z","caller":"traceutil/trace.go:171","msg":"trace[1790304299] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1105; }","duration":"110.038122ms","start":"2026-06-17T03:06:38.222912Z","end":"2026-06-17T03:06:38.33295Z","steps":["trace[1790304299] 'process raft request' (duration: 109.96748ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:06:38.497408Z","caller":"traceutil/trace.go:171","msg":"trace[1543560769] linearizableReadLoop","detail":"{readStateIndex:1153; appliedIndex:1152; }","duration":"132.7331ms","start":"2026-06-17T03:06:38.364657Z","end":"2026-06-17T03:06:38.49739Z","steps":["trace[1543560769] 'read index received' (duration: 132.575276ms)","trace[1543560769] 'applied index is now lower than readState.Index' (duration: 156.944µs)"],"step_count":2} {"level":"info","ts":"2026-06-17T03:06:38.497507Z","caller":"traceutil/trace.go:171","msg":"trace[798363577] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"141.667251ms","start":"2026-06-17T03:06:38.355829Z","end":"2026-06-17T03:06:38.497497Z","steps":["trace[798363577] 'process raft request' (duration: 141.446385ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:06:38.49772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.051126ms","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-06-17T03:06:38.497758Z","caller":"traceutil/trace.go:171","msg":"trace[1935022383] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1106; }","duration":"133.128798ms","start":"2026-06-17T03:06:38.36462Z","end":"2026-06-17T03:06:38.497749Z","steps":["trace[1935022383] 'agreement among raft nodes before linearized reading' (duration: 132.978325ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:06:40.342711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.738574ms","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-06-17T03:06:40.34277Z","caller":"traceutil/trace.go:171","msg":"trace[2041561523] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1161; }","duration":"369.843566ms","start":"2026-06-17T03:06:39.972914Z","end":"2026-06-17T03:06:40.342757Z","steps":["trace[2041561523] 'range keys from in-memory index tree' (duration: 369.584641ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:06:40.342793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:06:39.972896Z","time spent":"369.892378ms","remote":"127.0.0.1:43886","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-06-17T03:06:40.553654Z","caller":"traceutil/trace.go:171","msg":"trace[1438351515] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"207.110291ms","start":"2026-06-17T03:06:40.346506Z","end":"2026-06-17T03:06:40.553616Z","steps":["trace[1438351515] 'process raft request' (duration: 206.825086ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:06:42.259136Z","caller":"traceutil/trace.go:171","msg":"trace[1521736047] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"387.615645ms","start":"2026-06-17T03:06:41.871502Z","end":"2026-06-17T03:06:42.259118Z","steps":["trace[1521736047] 'process raft request' (duration: 387.487253ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:06:42.259255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:06:41.871469Z","time spent":"387.724798ms","remote":"127.0.0.1:43886","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-06-17T03:06:43.135353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.627817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T03:06:43.135456Z","caller":"traceutil/trace.go:171","msg":"trace[1724245278] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1168; }","duration":"344.783399ms","start":"2026-06-17T03:06:42.79065Z","end":"2026-06-17T03:06:43.135434Z","steps":["trace[1724245278] 'range keys from in-memory index tree' (duration: 344.537735ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:06:43.13551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:06:42.790634Z","time spent":"344.866962ms","remote":"127.0.0.1:43602","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-06-17T03:06:43.136155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.430857ms","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-06-17T03:06:43.136206Z","caller":"traceutil/trace.go:171","msg":"trace[1679281254] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1168; }","duration":"578.504319ms","start":"2026-06-17T03:06:42.557688Z","end":"2026-06-17T03:06:43.136192Z","steps":["trace[1679281254] 'range keys from in-memory index tree' (duration: 576.976027ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:06:43.136248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:06:42.557672Z","time spent":"578.56854ms","remote":"127.0.0.1:43886","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-06-17T03:06:43.136426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.266663ms","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-06-17T03:06:43.13647Z","caller":"traceutil/trace.go:171","msg":"trace[2025088283] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1168; }","duration":"570.316383ms","start":"2026-06-17T03:06:42.566142Z","end":"2026-06-17T03:06:43.136458Z","steps":["trace[2025088283] 'range keys from in-memory index tree' (duration: 568.777291ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:06:43.136508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:06:42.566138Z","time spent":"570.362964ms","remote":"127.0.0.1:43886","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-06-17T03:06:43.136674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.380114ms","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-06-17T03:06:43.13671Z","caller":"traceutil/trace.go:171","msg":"trace[1878988124] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1168; }","duration":"569.453725ms","start":"2026-06-17T03:06:42.567247Z","end":"2026-06-17T03:06:43.136701Z","steps":["trace[1878988124] 'range keys from in-memory index tree' (duration: 567.615078ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:06:43.136743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:06:42.567227Z","time spent":"569.509007ms","remote":"127.0.0.1:43886","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-06-17T03:06:43.136916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.933964ms","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-06-17T03:06:43.136955Z","caller":"traceutil/trace.go:171","msg":"trace[1191916574] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1168; }","duration":"568.987386ms","start":"2026-06-17T03:06:42.567956Z","end":"2026-06-17T03:06:43.136943Z","steps":["trace[1191916574] 'range keys from in-memory index tree' (duration: 566.935554ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:06:43.136989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:06:42.567948Z","time spent":"569.034727ms","remote":"127.0.0.1:43886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-17T03:06:43.137222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.43567ms","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-06-17T03:06:43.137261Z","caller":"traceutil/trace.go:171","msg":"trace[1921252446] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1168; }","duration":"385.474991ms","start":"2026-06-17T03:06:42.751776Z","end":"2026-06-17T03:06:43.137251Z","steps":["trace[1921252446] 'range keys from in-memory index tree' (duration: 383.328998ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:06:43.13729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:06:42.751683Z","time spent":"385.599904ms","remote":"127.0.0.1:43886","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-06-17T03:06:44.355086Z","caller":"traceutil/trace.go:171","msg":"trace[1418589740] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"157.06758ms","start":"2026-06-17T03:06:44.197991Z","end":"2026-06-17T03:06:44.355059Z","steps":["trace[1418589740] 'process raft request' (duration: 156.910197ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:06:44.385925Z","caller":"traceutil/trace.go:171","msg":"trace[1458495031] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"121.252407ms","start":"2026-06-17T03:06:44.264648Z","end":"2026-06-17T03:06:44.3859Z","steps":["trace[1458495031] 'process raft request' (duration: 121.118194ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:07:01.114823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.748284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.205\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-17T03:07:01.114883Z","caller":"traceutil/trace.go:171","msg":"trace[269640615] range","detail":"{range_begin:/registry/masterleases/199.204.45.205; range_end:; response_count:1; response_revision:1247; }","duration":"418.814815ms","start":"2026-06-17T03:07:00.696054Z","end":"2026-06-17T03:07:01.114868Z","steps":["trace[269640615] 'range keys from in-memory index tree' (duration: 418.547359ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:07:01.11491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:07:00.695957Z","time spent":"418.946867ms","remote":"127.0.0.1:43650","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.205\" "} {"level":"warn","ts":"2026-06-17T03:07:01.114999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.394309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T03:07:01.115073Z","caller":"traceutil/trace.go:171","msg":"trace[305244086] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1247; }","duration":"329.504791ms","start":"2026-06-17T03:07:00.785549Z","end":"2026-06-17T03:07:01.115054Z","steps":["trace[305244086] 'range keys from in-memory index tree' (duration: 329.317958ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:07:01.115117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:07:00.785535Z","time spent":"329.567342ms","remote":"127.0.0.1:43602","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-06-17T03:07:01.11518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.885945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T03:07:01.115208Z","caller":"traceutil/trace.go:171","msg":"trace[1267808802] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1247; }","duration":"302.951226ms","start":"2026-06-17T03:07:00.812246Z","end":"2026-06-17T03:07:01.115197Z","steps":["trace[1267808802] 'count revisions from in-memory index tree' (duration: 302.786184ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:07:01.115226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:07:00.812207Z","time spent":"303.013958ms","remote":"127.0.0.1:40478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-06-17T03:07:01.616682Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646844139172860381,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-17T03:07:01.676307Z","caller":"traceutil/trace.go:171","msg":"trace[1143032494] linearizableReadLoop","detail":"{readStateIndex:1300; appliedIndex:1299; }","duration":"559.975254ms","start":"2026-06-17T03:07:01.116308Z","end":"2026-06-17T03:07:01.676284Z","steps":["trace[1143032494] 'read index received' (duration: 559.892282ms)","trace[1143032494] 'applied index is now lower than readState.Index' (duration: 81.752µs)"],"step_count":2} {"level":"warn","ts":"2026-06-17T03:07:01.676376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:07:01.11624Z","time spent":"560.128797ms","remote":"127.0.0.1:43650","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-17T03:07:01.676468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.134647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T03:07:01.676506Z","caller":"traceutil/trace.go:171","msg":"trace[824813185] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1247; }","duration":"560.230589ms","start":"2026-06-17T03:07:01.11627Z","end":"2026-06-17T03:07:01.6765Z","steps":["trace[824813185] 'agreement among raft nodes before linearized reading' (duration: 560.140848ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:07:01.676527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:07:01.11625Z","time spent":"560.27224ms","remote":"127.0.0.1:43608","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-06-17T03:07:01.676684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.141055ms","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-06-17T03:07:01.676775Z","caller":"traceutil/trace.go:171","msg":"trace[1933499127] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1247; }","duration":"440.281838ms","start":"2026-06-17T03:07:01.23647Z","end":"2026-06-17T03:07:01.676751Z","steps":["trace[1933499127] 'agreement among raft nodes before linearized reading' (duration: 440.020512ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:07:01.676803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.042471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-17T03:07:01.676889Z","caller":"traceutil/trace.go:171","msg":"trace[2060487074] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1247; }","duration":"430.166034ms","start":"2026-06-17T03:07:01.246703Z","end":"2026-06-17T03:07:01.676869Z","steps":["trace[2060487074] 'agreement among raft nodes before linearized reading' (duration: 429.889138ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:07:01.676898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.345913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-17T03:07:01.676936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.977648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-17T03:07:01.676946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.317509ms","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-06-17T03:07:01.676941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:07:01.246694Z","time spent":"430.232506ms","remote":"127.0.0.1:43886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-17T03:07:01.676953Z","caller":"traceutil/trace.go:171","msg":"trace[890455372] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1247; }","duration":"302.488387ms","start":"2026-06-17T03:07:01.374443Z","end":"2026-06-17T03:07:01.676931Z","steps":["trace[890455372] 'agreement among raft nodes before linearized reading' (duration: 302.388505ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:07:01.676981Z","caller":"traceutil/trace.go:171","msg":"trace[540351863] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1247; }","duration":"420.026829ms","start":"2026-06-17T03:07:01.256936Z","end":"2026-06-17T03:07:01.676963Z","steps":["trace[540351863] 'agreement among raft nodes before linearized reading' (duration: 419.930287ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:07:01.67699Z","caller":"traceutil/trace.go:171","msg":"trace[1373107908] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1247; }","duration":"439.38226ms","start":"2026-06-17T03:07:01.237597Z","end":"2026-06-17T03:07:01.67698Z","steps":["trace[1373107908] 'agreement among raft nodes before linearized reading' (duration: 439.266727ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:07:01.676832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:07:01.236448Z","time spent":"440.371631ms","remote":"127.0.0.1:43886","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-06-17T03:07:01.677008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:07:01.374423Z","time spent":"302.575788ms","remote":"127.0.0.1:38950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-17T03:07:01.677014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:07:01.256928Z","time spent":"420.07985ms","remote":"127.0.0.1:43886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-17T03:07:01.677027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:07:01.237588Z","time spent":"439.43072ms","remote":"127.0.0.1:43886","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":"warn","ts":"2026-06-17T03:07:01.676825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.019822ms","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-06-17T03:07:01.6771Z","caller":"traceutil/trace.go:171","msg":"trace[2105164590] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1247; }","duration":"437.318958ms","start":"2026-06-17T03:07:01.239769Z","end":"2026-06-17T03:07:01.677088Z","steps":["trace[2105164590] 'agreement among raft nodes before linearized reading' (duration: 436.91696ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:07:01.677149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:07:01.23976Z","time spent":"437.37951ms","remote":"127.0.0.1:43886","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-06-17T03:07:02.375225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.460584ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-17T03:07:02.375306Z","caller":"traceutil/trace.go:171","msg":"trace[2006141853] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"696.053501ms","start":"2026-06-17T03:07:01.67923Z","end":"2026-06-17T03:07:02.375284Z","steps":["trace[2006141853] 'process raft request' (duration: 557.480734ms)","trace[2006141853] 'compare' (duration: 138.26625ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T03:07:02.375337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:07:01.679207Z","time spent":"696.117203ms","remote":"127.0.0.1:43650","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-06-17T03:07:02.418288Z","caller":"traceutil/trace.go:171","msg":"trace[1133237765] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"736.220702ms","start":"2026-06-17T03:07:01.682048Z","end":"2026-06-17T03:07:02.418269Z","steps":["trace[1133237765] 'process raft request' (duration: 736.08937ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:07:02.418411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:07:01.682027Z","time spent":"736.310523ms","remote":"127.0.0.1:43886","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-06-17T03:07:02.418562Z","caller":"traceutil/trace.go:171","msg":"trace[769320909] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"736.151669ms","start":"2026-06-17T03:07:01.682386Z","end":"2026-06-17T03:07:02.418538Z","steps":["trace[769320909] 'process raft request' (duration: 735.853834ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:07:02.418725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:07:01.682362Z","time spent":"736.281242ms","remote":"127.0.0.1:43886","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-06-17T03:07:02.418875Z","caller":"traceutil/trace.go:171","msg":"trace[1872100945] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"736.446587ms","start":"2026-06-17T03:07:01.682418Z","end":"2026-06-17T03:07:02.418865Z","steps":["trace[1872100945] 'process raft request' (duration: 736.008807ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:07:02.418933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:07:01.682409Z","time spent":"736.494447ms","remote":"127.0.0.1:43886","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-06-17T03:07:02.418978Z","caller":"traceutil/trace.go:171","msg":"trace[771758406] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"736.510968ms","start":"2026-06-17T03:07:01.68245Z","end":"2026-06-17T03:07:02.418961Z","steps":["trace[771758406] 'process raft request' (duration: 736.231382ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:07:02.419073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:07:01.682444Z","time spent":"736.585749ms","remote":"127.0.0.1:43886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T03:07:02.419118Z","caller":"traceutil/trace.go:171","msg":"trace[1086101866] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"736.504937ms","start":"2026-06-17T03:07:01.682603Z","end":"2026-06-17T03:07:02.419108Z","steps":["trace[1086101866] 'process raft request' (duration: 736.13003ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:07:02.419177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:07:01.682597Z","time spent":"736.552528ms","remote":"127.0.0.1:43886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T03:07:04.314934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.339721ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-17T03:07:04.315194Z","caller":"traceutil/trace.go:171","msg":"trace[684428980] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"1.001899146s","start":"2026-06-17T03:07:03.313274Z","end":"2026-06-17T03:07:04.315173Z","steps":["trace[684428980] 'process raft request' (duration: 1.001762823s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:07:04.315288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:07:03.313266Z","time spent":"1.001971507s","remote":"127.0.0.1:43886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-17T03:07:04.315534Z","caller":"traceutil/trace.go:171","msg":"trace[1801321410] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"1.00462326s","start":"2026-06-17T03:07:03.310896Z","end":"2026-06-17T03:07:04.315519Z","steps":["trace[1801321410] 'process raft request' (duration: 290.628466ms)","trace[1801321410] 'compare' (duration: 712.906941ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T03:07:04.315588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:07:03.31088Z","time spent":"1.004680062s","remote":"127.0.0.1:43692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-17T03:07:04.668488Z","caller":"traceutil/trace.go:171","msg":"trace[762204070] linearizableReadLoop","detail":"{readStateIndex:1314; appliedIndex:1312; }","duration":"244.430283ms","start":"2026-06-17T03:07:04.424037Z","end":"2026-06-17T03:07:04.668468Z","steps":["trace[762204070] 'read index received' (duration: 158.781804ms)","trace[762204070] 'applied index is now lower than readState.Index' (duration: 85.647609ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T03:07:04.668562Z","caller":"traceutil/trace.go:171","msg":"trace[2018888530] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"347.137179ms","start":"2026-06-17T03:07:04.321404Z","end":"2026-06-17T03:07:04.668542Z","steps":["trace[2018888530] 'process raft request' (duration: 347.011436ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:07:04.668678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:07:04.32139Z","time spent":"347.21557ms","remote":"127.0.0.1:43886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T03:07:04.668897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.645827ms","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-06-17T03:07:04.668947Z","caller":"traceutil/trace.go:171","msg":"trace[1106351142] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1261; }","duration":"244.902403ms","start":"2026-06-17T03:07:04.424032Z","end":"2026-06-17T03:07:04.668935Z","steps":["trace[1106351142] 'agreement among raft nodes before linearized reading' (duration: 244.545705ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:07:04.66902Z","caller":"traceutil/trace.go:171","msg":"trace[893250406] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"350.068787ms","start":"2026-06-17T03:07:04.318925Z","end":"2026-06-17T03:07:04.668994Z","steps":["trace[893250406] 'process raft request' (duration: 263.905408ms)","trace[893250406] 'compare' (duration: 85.463245ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T03:07:04.669313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:07:04.318906Z","time spent":"350.289451ms","remote":"127.0.0.1:43692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-17T03:07:04.716201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.780737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-17T03:07:04.71624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.994064ms","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-06-17T03:07:04.716284Z","caller":"traceutil/trace.go:171","msg":"trace[988805955] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1262; }","duration":"166.908949ms","start":"2026-06-17T03:07:04.549357Z","end":"2026-06-17T03:07:04.716266Z","steps":["trace[988805955] 'agreement among raft nodes before linearized reading' (duration: 166.739135ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:07:04.716314Z","caller":"traceutil/trace.go:171","msg":"trace[100029287] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1262; }","duration":"292.098995ms","start":"2026-06-17T03:07:04.424197Z","end":"2026-06-17T03:07:04.716296Z","steps":["trace[100029287] 'agreement among raft nodes before linearized reading' (duration: 291.84075ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:07:04.716586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.902233ms","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-06-17T03:07:04.716623Z","caller":"traceutil/trace.go:171","msg":"trace[688153317] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1262; }","duration":"291.951604ms","start":"2026-06-17T03:07:04.424662Z","end":"2026-06-17T03:07:04.716613Z","steps":["trace[688153317] 'agreement among raft nodes before linearized reading' (duration: 291.858342ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:07:04.716812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.691707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-17T03:07:04.716856Z","caller":"traceutil/trace.go:171","msg":"trace[451125744] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1262; }","duration":"291.747199ms","start":"2026-06-17T03:07:04.425098Z","end":"2026-06-17T03:07:04.716845Z","steps":["trace[451125744] 'agreement among raft nodes before linearized reading' (duration: 291.600916ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:07:04.716215Z","caller":"traceutil/trace.go:171","msg":"trace[1112385490] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"128.230608ms","start":"2026-06-17T03:07:04.587959Z","end":"2026-06-17T03:07:04.71619Z","steps":["trace[1112385490] 'process raft request' (duration: 127.875491ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:07:04.716865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.070446ms","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-06-17T03:07:04.717105Z","caller":"traceutil/trace.go:171","msg":"trace[98299092] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1262; }","duration":"292.30044ms","start":"2026-06-17T03:07:04.424765Z","end":"2026-06-17T03:07:04.717066Z","steps":["trace[98299092] 'agreement among raft nodes before linearized reading' (duration: 291.576016ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:07:04.862999Z","caller":"traceutil/trace.go:171","msg":"trace[502135297] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"146.783793ms","start":"2026-06-17T03:07:04.716191Z","end":"2026-06-17T03:07:04.862974Z","steps":["trace[502135297] 'process raft request' (duration: 146.560499ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:07:04.863236Z","caller":"traceutil/trace.go:171","msg":"trace[1266083841] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"142.633158ms","start":"2026-06-17T03:07:04.720566Z","end":"2026-06-17T03:07:04.863199Z","steps":["trace[1266083841] 'process raft request' (duration: 142.330752ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:07:04.863441Z","caller":"traceutil/trace.go:171","msg":"trace[1590747196] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"142.564658ms","start":"2026-06-17T03:07:04.720866Z","end":"2026-06-17T03:07:04.863431Z","steps":["trace[1590747196] 'process raft request' (duration: 142.305922ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:07:04.863483Z","caller":"traceutil/trace.go:171","msg":"trace[443530973] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"142.506007ms","start":"2026-06-17T03:07:04.720964Z","end":"2026-06-17T03:07:04.86347Z","steps":["trace[443530973] 'process raft request' (duration: 142.319782ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:07:04.863537Z","caller":"traceutil/trace.go:171","msg":"trace[1006458426] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"142.137649ms","start":"2026-06-17T03:07:04.721391Z","end":"2026-06-17T03:07:04.863528Z","steps":["trace[1006458426] 'process raft request' (duration: 141.995126ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:07:06.211643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.898827ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-17T03:07:06.850285Z","caller":"traceutil/trace.go:171","msg":"trace[1694372092] linearizableReadLoop","detail":"{readStateIndex:1323; appliedIndex:1322; }","duration":"114.44001ms","start":"2026-06-17T03:07:06.735826Z","end":"2026-06-17T03:07:06.850266Z","steps":["trace[1694372092] 'read index received' (duration: 114.298257ms)","trace[1694372092] 'applied index is now lower than readState.Index' (duration: 140.893µs)"],"step_count":2} {"level":"info","ts":"2026-06-17T03:07:06.850384Z","caller":"traceutil/trace.go:171","msg":"trace[1952056625] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"127.537075ms","start":"2026-06-17T03:07:06.722818Z","end":"2026-06-17T03:07:06.850355Z","steps":["trace[1952056625] 'process raft request' (duration: 127.320881ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:07:06.850635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.795238ms","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-06-17T03:07:06.850706Z","caller":"traceutil/trace.go:171","msg":"trace[485027753] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1269; }","duration":"114.909229ms","start":"2026-06-17T03:07:06.73578Z","end":"2026-06-17T03:07:06.850689Z","steps":["trace[485027753] 'agreement among raft nodes before linearized reading' (duration: 114.590793ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:07:07.073083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.380875ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-17T03:07:07.073191Z","caller":"traceutil/trace.go:171","msg":"trace[401027714] linearizableReadLoop","detail":"{readStateIndex:1325; appliedIndex:1324; }","duration":"198.459256ms","start":"2026-06-17T03:07:06.874716Z","end":"2026-06-17T03:07:07.073175Z","steps":["trace[401027714] 'read index received' (duration: 49.859237ms)","trace[401027714] 'applied index is now lower than readState.Index' (duration: 148.598959ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T03:07:07.073231Z","caller":"traceutil/trace.go:171","msg":"trace[261465099] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"218.398849ms","start":"2026-06-17T03:07:06.854801Z","end":"2026-06-17T03:07:07.0732Z","steps":["trace[261465099] 'process raft request' (duration: 69.82539ms)","trace[261465099] 'compare' (duration: 148.268393ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T03:07:07.073291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.440008ms","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-06-17T03:07:07.073322Z","caller":"traceutil/trace.go:171","msg":"trace[2061012089] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1271; }","duration":"205.484398ms","start":"2026-06-17T03:07:06.867831Z","end":"2026-06-17T03:07:07.073315Z","steps":["trace[2061012089] 'agreement among raft nodes before linearized reading' (duration: 205.379316ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:07:07.073343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.997119ms","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-06-17T03:07:07.073378Z","caller":"traceutil/trace.go:171","msg":"trace[92191712] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1271; }","duration":"206.063919ms","start":"2026-06-17T03:07:06.867306Z","end":"2026-06-17T03:07:07.07337Z","steps":["trace[92191712] 'agreement among raft nodes before linearized reading' (duration: 205.952747ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:07:07.07347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.154882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-17T03:07:07.073495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.929056ms","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-06-17T03:07:07.073528Z","caller":"traceutil/trace.go:171","msg":"trace[1864930160] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1271; }","duration":"206.266093ms","start":"2026-06-17T03:07:06.86725Z","end":"2026-06-17T03:07:07.073516Z","steps":["trace[1864930160] 'agreement among raft nodes before linearized reading' (duration: 206.036939ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:07:07.073542Z","caller":"traceutil/trace.go:171","msg":"trace[379254185] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1271; }","duration":"204.992447ms","start":"2026-06-17T03:07:06.868537Z","end":"2026-06-17T03:07:07.073529Z","steps":["trace[379254185] 'agreement among raft nodes before linearized reading' (duration: 204.769593ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:07:07.074056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.314132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T03:07:07.074145Z","caller":"traceutil/trace.go:171","msg":"trace[110858717] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1271; }","duration":"198.424526ms","start":"2026-06-17T03:07:06.875709Z","end":"2026-06-17T03:07:07.074133Z","steps":["trace[110858717] 'agreement among raft nodes before linearized reading' (duration: 198.268932ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:07:12.341294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.452493ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-17T03:07:12.341407Z","caller":"traceutil/trace.go:171","msg":"trace[568355162] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"485.913391ms","start":"2026-06-17T03:07:11.855473Z","end":"2026-06-17T03:07:12.341387Z","steps":["trace[568355162] 'process raft request' (duration: 42.297285ms)","trace[568355162] 'compare' (duration: 443.161457ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T03:07:12.341457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:07:11.855457Z","time spent":"485.980443ms","remote":"127.0.0.1:43886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-17T03:07:13.066262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.127787ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-17T03:07:13.066556Z","caller":"traceutil/trace.go:171","msg":"trace[756737796] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"178.84253ms","start":"2026-06-17T03:07:12.887695Z","end":"2026-06-17T03:07:13.066537Z","steps":["trace[756737796] 'process raft request' (duration: 178.792719ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:07:13.066783Z","caller":"traceutil/trace.go:171","msg":"trace[1036631410] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"718.624849ms","start":"2026-06-17T03:07:12.348147Z","end":"2026-06-17T03:07:13.066772Z","steps":["trace[1036631410] 'process raft request' (duration: 483.889009ms)","trace[1036631410] 'compare' (duration: 233.944503ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T03:07:13.066912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:07:12.348129Z","time spent":"718.687309ms","remote":"127.0.0.1:43692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":911,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-17T03:07:13.066944Z","caller":"traceutil/trace.go:171","msg":"trace[1275257591] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"710.456063ms","start":"2026-06-17T03:07:12.356441Z","end":"2026-06-17T03:07:13.066897Z","steps":["trace[1275257591] 'process raft request' (duration: 709.931442ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:07:13.067008Z","caller":"traceutil/trace.go:171","msg":"trace[1326708825] linearizableReadLoop","detail":"{readStateIndex:1365; appliedIndex:1363; }","duration":"280.616756ms","start":"2026-06-17T03:07:12.786291Z","end":"2026-06-17T03:07:13.066908Z","steps":["trace[1326708825] 'read index received' (duration: 45.737184ms)","trace[1326708825] 'applied index is now lower than readState.Index' (duration: 234.876152ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T03:07:13.067122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:07:12.356387Z","time spent":"710.646687ms","remote":"127.0.0.1:43886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T03:07:13.067296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.016364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T03:07:13.067436Z","caller":"traceutil/trace.go:171","msg":"trace[1525661931] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1311; }","duration":"281.184248ms","start":"2026-06-17T03:07:12.786209Z","end":"2026-06-17T03:07:13.067394Z","steps":["trace[1525661931] 'agreement among raft nodes before linearized reading' (duration: 281.007204ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:09:41.944141Z","caller":"traceutil/trace.go:171","msg":"trace[1347940947] linearizableReadLoop","detail":"{readStateIndex:2092; appliedIndex:2091; }","duration":"159.017475ms","start":"2026-06-17T03:09:41.785105Z","end":"2026-06-17T03:09:41.944123Z","steps":["trace[1347940947] 'read index received' (duration: 158.847562ms)","trace[1347940947] 'applied index is now lower than readState.Index' (duration: 169.003µs)"],"step_count":2} {"level":"info","ts":"2026-06-17T03:09:41.944599Z","caller":"traceutil/trace.go:171","msg":"trace[1797119326] transaction","detail":"{read_only:false; response_revision:2007; number_of_response:1; }","duration":"193.422548ms","start":"2026-06-17T03:09:41.751162Z","end":"2026-06-17T03:09:41.944585Z","steps":["trace[1797119326] 'process raft request' (duration: 192.839526ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:09:41.944943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.815821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T03:09:41.944998Z","caller":"traceutil/trace.go:171","msg":"trace[1000397772] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2007; }","duration":"159.902103ms","start":"2026-06-17T03:09:41.785081Z","end":"2026-06-17T03:09:41.944983Z","steps":["trace[1000397772] 'agreement among raft nodes before linearized reading' (duration: 159.804471ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:09:42.10602Z","caller":"traceutil/trace.go:171","msg":"trace[1089249180] transaction","detail":"{read_only:false; response_revision:2011; number_of_response:1; }","duration":"154.94985ms","start":"2026-06-17T03:09:41.95105Z","end":"2026-06-17T03:09:42.106Z","steps":["trace[1089249180] 'process raft request' (duration: 154.91083ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:09:42.106049Z","caller":"traceutil/trace.go:171","msg":"trace[584620151] transaction","detail":"{read_only:false; response_revision:2009; number_of_response:1; }","duration":"155.344988ms","start":"2026-06-17T03:09:41.950646Z","end":"2026-06-17T03:09:42.105991Z","steps":["trace[584620151] 'process raft request' (duration: 79.642876ms)","trace[584620151] 'compare' (duration: 75.457098ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T03:09:42.106176Z","caller":"traceutil/trace.go:171","msg":"trace[170185506] transaction","detail":"{read_only:false; response_revision:2010; number_of_response:1; }","duration":"155.426079ms","start":"2026-06-17T03:09:41.950732Z","end":"2026-06-17T03:09:42.106158Z","steps":["trace[170185506] 'process raft request' (duration: 155.165324ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:09:42.106101Z","caller":"traceutil/trace.go:171","msg":"trace[940671906] transaction","detail":"{read_only:false; response_revision:2012; number_of_response:1; }","duration":"153.763888ms","start":"2026-06-17T03:09:41.952321Z","end":"2026-06-17T03:09:42.106084Z","steps":["trace[940671906] 'process raft request' (duration: 153.658256ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:09:43.484754Z","caller":"traceutil/trace.go:171","msg":"trace[716441622] transaction","detail":"{read_only:false; response_revision:2013; number_of_response:1; }","duration":"115.577126ms","start":"2026-06-17T03:09:43.369149Z","end":"2026-06-17T03:09:43.484727Z","steps":["trace[716441622] 'process raft request' (duration: 115.378203ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:09:44.373274Z","caller":"traceutil/trace.go:171","msg":"trace[1583932859] transaction","detail":"{read_only:false; response_revision:2019; number_of_response:1; }","duration":"198.340798ms","start":"2026-06-17T03:09:44.174899Z","end":"2026-06-17T03:09:44.37324Z","steps":["trace[1583932859] 'process raft request' (duration: 187.932395ms)","trace[1583932859] 'compare' (duration: 10.120518ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T03:09:44.373317Z","caller":"traceutil/trace.go:171","msg":"trace[2145300131] transaction","detail":"{read_only:false; response_revision:2021; number_of_response:1; }","duration":"197.915332ms","start":"2026-06-17T03:09:44.17538Z","end":"2026-06-17T03:09:44.373296Z","steps":["trace[2145300131] 'process raft request' (duration: 197.870561ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:09:44.373445Z","caller":"traceutil/trace.go:171","msg":"trace[974151403] transaction","detail":"{read_only:false; response_revision:2020; number_of_response:1; }","duration":"198.279728ms","start":"2026-06-17T03:09:44.175105Z","end":"2026-06-17T03:09:44.373385Z","steps":["trace[974151403] 'process raft request' (duration: 198.074874ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:09:45.797665Z","caller":"traceutil/trace.go:171","msg":"trace[739501957] transaction","detail":"{read_only:false; response_revision:2024; number_of_response:1; }","duration":"221.029343ms","start":"2026-06-17T03:09:45.576618Z","end":"2026-06-17T03:09:45.797648Z","steps":["trace[739501957] 'process raft request' (duration: 220.823128ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:09:46.53411Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646844139172862233,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-17T03:09:46.636078Z","caller":"traceutil/trace.go:171","msg":"trace[400313067] transaction","detail":"{read_only:false; response_revision:2025; number_of_response:1; }","duration":"632.66409ms","start":"2026-06-17T03:09:46.003388Z","end":"2026-06-17T03:09:46.636053Z","steps":["trace[400313067] 'process raft request' (duration: 632.517577ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:09:46.636253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:09:46.003373Z","time spent":"632.787313ms","remote":"127.0.0.1:43886","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-06-17T03:09:46.919683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.031828ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-17T03:09:46.91982Z","caller":"traceutil/trace.go:171","msg":"trace[1955923428] linearizableReadLoop","detail":"{readStateIndex:2111; appliedIndex:2109; }","duration":"886.388294ms","start":"2026-06-17T03:09:46.033391Z","end":"2026-06-17T03:09:46.91978Z","steps":["trace[1955923428] 'read index received' (duration: 602.502948ms)","trace[1955923428] 'applied index is now lower than readState.Index' (duration: 283.882886ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T03:09:46.92007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.979978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-17T03:09:46.920097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.831991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-17T03:09:46.920117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.231273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-06-17T03:09:46.920155Z","caller":"traceutil/trace.go:171","msg":"trace[533879160] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:2025; }","duration":"216.08613ms","start":"2026-06-17T03:09:46.704049Z","end":"2026-06-17T03:09:46.920135Z","steps":["trace[533879160] 'agreement among raft nodes before linearized reading' (duration: 215.915837ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:09:46.920165Z","caller":"traceutil/trace.go:171","msg":"trace[286461473] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:2025; }","duration":"657.954563ms","start":"2026-06-17T03:09:46.262192Z","end":"2026-06-17T03:09:46.920146Z","steps":["trace[286461473] 'agreement among raft nodes before linearized reading' (duration: 657.837251ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:09:46.920175Z","caller":"traceutil/trace.go:171","msg":"trace[489317501] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2025; }","duration":"543.318965ms","start":"2026-06-17T03:09:46.376842Z","end":"2026-06-17T03:09:46.920161Z","steps":["trace[489317501] 'agreement among raft nodes before linearized reading' (duration: 543.144872ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:09:46.920224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:09:46.37683Z","time spent":"543.380106ms","remote":"127.0.0.1:43886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":552,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-06-17T03:09:46.920251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.856752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-17T03:09:46.920302Z","caller":"traceutil/trace.go:171","msg":"trace[1731349207] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2025; }","duration":"886.926423ms","start":"2026-06-17T03:09:46.033361Z","end":"2026-06-17T03:09:46.920288Z","steps":["trace[1731349207] 'agreement among raft nodes before linearized reading' (duration: 886.579717ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:09:46.92031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.854687ms","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-06-17T03:09:46.920349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:09:46.03335Z","time spent":"886.987675ms","remote":"127.0.0.1:43886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-17T03:09:46.920351Z","caller":"traceutil/trace.go:171","msg":"trace[1331651803] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2025; }","duration":"704.967059ms","start":"2026-06-17T03:09:46.215372Z","end":"2026-06-17T03:09:46.920339Z","steps":["trace[1331651803] 'agreement among raft nodes before linearized reading' (duration: 704.586482ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:09:46.92018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.679382ms","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-06-17T03:09:46.920399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:09:46.215353Z","time spent":"705.032392ms","remote":"127.0.0.1:43886","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-06-17T03:09:46.920438Z","caller":"traceutil/trace.go:171","msg":"trace[484986395] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2025; }","duration":"542.965808ms","start":"2026-06-17T03:09:46.37746Z","end":"2026-06-17T03:09:46.920426Z","steps":["trace[484986395] 'agreement among raft nodes before linearized reading' (duration: 542.54516ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:09:46.920093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.628751ms","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-06-17T03:09:46.920516Z","caller":"traceutil/trace.go:171","msg":"trace[163663348] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2025; }","duration":"543.108961ms","start":"2026-06-17T03:09:46.377394Z","end":"2026-06-17T03:09:46.920503Z","steps":["trace[163663348] 'agreement among raft nodes before linearized reading' (duration: 542.56121ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:09:46.920563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:09:46.377373Z","time spent":"543.178762ms","remote":"127.0.0.1:43886","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-06-17T03:09:46.92022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:09:46.262176Z","time spent":"658.029054ms","remote":"127.0.0.1:43956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-06-17T03:09:46.920479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:09:46.377438Z","time spent":"543.030619ms","remote":"127.0.0.1:43886","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-06-17T03:09:47.249865Z","caller":"traceutil/trace.go:171","msg":"trace[1771997091] transaction","detail":"{read_only:false; response_revision:2027; number_of_response:1; }","duration":"324.541204ms","start":"2026-06-17T03:09:46.925301Z","end":"2026-06-17T03:09:47.249842Z","steps":["trace[1771997091] 'process raft request' (duration: 324.362321ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:09:47.250007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:09:46.925293Z","time spent":"324.635096ms","remote":"127.0.0.1:43886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T03:09:47.250257Z","caller":"traceutil/trace.go:171","msg":"trace[1715250815] transaction","detail":"{read_only:false; response_revision:2028; number_of_response:1; }","duration":"324.836249ms","start":"2026-06-17T03:09:46.925397Z","end":"2026-06-17T03:09:47.250233Z","steps":["trace[1715250815] 'process raft request' (duration: 324.409391ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:09:47.251208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:09:46.925381Z","time spent":"325.762837ms","remote":"127.0.0.1:43886","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-06-17T03:09:47.250355Z","caller":"traceutil/trace.go:171","msg":"trace[295506327] transaction","detail":"{read_only:false; response_revision:2030; number_of_response:1; }","duration":"324.930551ms","start":"2026-06-17T03:09:46.925397Z","end":"2026-06-17T03:09:47.250328Z","steps":["trace[295506327] 'process raft request' (duration: 324.88253ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:09:47.25138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:09:46.925366Z","time spent":"325.981981ms","remote":"127.0.0.1:43886","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-06-17T03:09:47.250387Z","caller":"traceutil/trace.go:171","msg":"trace[1945231460] transaction","detail":"{read_only:false; response_revision:2029; number_of_response:1; }","duration":"324.995703ms","start":"2026-06-17T03:09:46.925384Z","end":"2026-06-17T03:09:47.25038Z","steps":["trace[1945231460] 'process raft request' (duration: 324.767699ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:09:47.251514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:09:46.925351Z","time spent":"326.134383ms","remote":"127.0.0.1:43886","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-06-17T03:09:48.005128Z","caller":"traceutil/trace.go:171","msg":"trace[1838937779] transaction","detail":"{read_only:false; response_revision:2031; number_of_response:1; }","duration":"202.839054ms","start":"2026-06-17T03:09:47.802259Z","end":"2026-06-17T03:09:48.005098Z","steps":["trace[1838937779] 'process raft request' (duration: 202.6421ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:09:48.365582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.570647ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-17T03:09:48.365709Z","caller":"traceutil/trace.go:171","msg":"trace[756456454] transaction","detail":"{read_only:false; response_revision:2032; number_of_response:1; }","duration":"316.470613ms","start":"2026-06-17T03:09:48.049209Z","end":"2026-06-17T03:09:48.365679Z","steps":["trace[756456454] 'process raft request' (duration: 104.717653ms)","trace[756456454] 'compare' (duration: 211.444965ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T03:09:48.365806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:09:48.049179Z","time spent":"316.581925ms","remote":"127.0.0.1:43886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T03:09:48.629821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.652043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-06-17T03:09:48.62991Z","caller":"traceutil/trace.go:171","msg":"trace[1466661051] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2032; }","duration":"140.779746ms","start":"2026-06-17T03:09:48.489105Z","end":"2026-06-17T03:09:48.629884Z","steps":["trace[1466661051] 'range keys from in-memory index tree' (duration: 140.46159ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:09:48.629968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.153547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-06-17T03:09:48.630059Z","caller":"traceutil/trace.go:171","msg":"trace[555900909] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2032; }","duration":"101.32747ms","start":"2026-06-17T03:09:48.52871Z","end":"2026-06-17T03:09:48.630038Z","steps":["trace[555900909] 'range keys from in-memory index tree' (duration: 100.794151ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:09:48.735999Z","caller":"traceutil/trace.go:171","msg":"trace[597665590] transaction","detail":"{read_only:false; response_revision:2033; number_of_response:1; }","duration":"102.056074ms","start":"2026-06-17T03:09:48.633921Z","end":"2026-06-17T03:09:48.735977Z","steps":["trace[597665590] 'process raft request' (duration: 101.881991ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:09:48.929277Z","caller":"traceutil/trace.go:171","msg":"trace[2091873370] linearizableReadLoop","detail":"{readStateIndex:2120; appliedIndex:2118; }","duration":"290.222582ms","start":"2026-06-17T03:09:48.63902Z","end":"2026-06-17T03:09:48.929243Z","steps":["trace[2091873370] 'read index received' (duration: 96.784215ms)","trace[2091873370] 'applied index is now lower than readState.Index' (duration: 193.437187ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T03:09:48.929449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.69264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T03:09:48.929491Z","caller":"traceutil/trace.go:171","msg":"trace[1585093250] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2034; }","duration":"143.74325ms","start":"2026-06-17T03:09:48.785735Z","end":"2026-06-17T03:09:48.929478Z","steps":["trace[1585093250] 'agreement among raft nodes before linearized reading' (duration: 143.667199ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:09:48.929516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.485928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-17T03:09:48.929597Z","caller":"traceutil/trace.go:171","msg":"trace[1158320584] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2034; }","duration":"290.59244ms","start":"2026-06-17T03:09:48.638984Z","end":"2026-06-17T03:09:48.929576Z","steps":["trace[1158320584] 'agreement among raft nodes before linearized reading' (duration: 290.356275ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:09:48.929784Z","caller":"traceutil/trace.go:171","msg":"trace[2119439761] transaction","detail":"{read_only:false; response_revision:2034; number_of_response:1; }","duration":"295.389949ms","start":"2026-06-17T03:09:48.633947Z","end":"2026-06-17T03:09:48.929337Z","steps":["trace[2119439761] 'process raft request' (duration: 249.33919ms)","trace[2119439761] 'compare' (duration: 45.800654ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T03:09:49.161381Z","caller":"traceutil/trace.go:171","msg":"trace[207317263] linearizableReadLoop","detail":"{readStateIndex:2121; appliedIndex:2120; }","duration":"167.262019ms","start":"2026-06-17T03:09:48.994102Z","end":"2026-06-17T03:09:49.161365Z","steps":["trace[207317263] 'read index received' (duration: 138.949341ms)","trace[207317263] 'applied index is now lower than readState.Index' (duration: 28.311938ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T03:09:49.161456Z","caller":"traceutil/trace.go:171","msg":"trace[57701338] transaction","detail":"{read_only:false; response_revision:2035; number_of_response:1; }","duration":"227.399381ms","start":"2026-06-17T03:09:48.934029Z","end":"2026-06-17T03:09:49.161429Z","steps":["trace[57701338] 'process raft request' (duration: 198.981112ms)","trace[57701338] 'compare' (duration: 28.273477ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T03:09:49.161522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.407742ms","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-06-17T03:09:49.161547Z","caller":"traceutil/trace.go:171","msg":"trace[158796820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2035; }","duration":"167.441972ms","start":"2026-06-17T03:09:48.994098Z","end":"2026-06-17T03:09:49.16154Z","steps":["trace[158796820] 'agreement among raft nodes before linearized reading' (duration: 167.32891ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:09:50.198169Z","caller":"traceutil/trace.go:171","msg":"trace[1409385353] linearizableReadLoop","detail":"{readStateIndex:2127; appliedIndex:2126; }","duration":"150.150439ms","start":"2026-06-17T03:09:50.048001Z","end":"2026-06-17T03:09:50.198152Z","steps":["trace[1409385353] 'read index received' (duration: 150.054246ms)","trace[1409385353] 'applied index is now lower than readState.Index' (duration: 95.473µs)"],"step_count":2} {"level":"info","ts":"2026-06-17T03:09:50.198212Z","caller":"traceutil/trace.go:171","msg":"trace[1326098326] transaction","detail":"{read_only:false; response_revision:2041; number_of_response:1; }","duration":"188.030985ms","start":"2026-06-17T03:09:50.010152Z","end":"2026-06-17T03:09:50.198183Z","steps":["trace[1326098326] 'process raft request' (duration: 187.898902ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:09:50.198308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.291201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-06-17T03:09:50.198335Z","caller":"traceutil/trace.go:171","msg":"trace[560788720] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2041; }","duration":"150.361953ms","start":"2026-06-17T03:09:50.047967Z","end":"2026-06-17T03:09:50.198329Z","steps":["trace[560788720] 'agreement among raft nodes before linearized reading' (duration: 150.252321ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:09:50.532884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.533001ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-17T03:09:50.532955Z","caller":"traceutil/trace.go:171","msg":"trace[1701512345] linearizableReadLoop","detail":"{readStateIndex:2128; appliedIndex:2127; }","duration":"272.571161ms","start":"2026-06-17T03:09:50.260374Z","end":"2026-06-17T03:09:50.532945Z","steps":["trace[1701512345] 'read index received' (duration: 110.901348ms)","trace[1701512345] 'applied index is now lower than readState.Index' (duration: 161.669113ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T03:09:50.533006Z","caller":"traceutil/trace.go:171","msg":"trace[1942026845] transaction","detail":"{read_only:false; response_revision:2042; number_of_response:1; }","duration":"330.514682ms","start":"2026-06-17T03:09:50.202484Z","end":"2026-06-17T03:09:50.532999Z","steps":["trace[1942026845] 'process raft request' (duration: 168.809498ms)","trace[1942026845] 'compare' (duration: 161.359917ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T03:09:50.533035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:09:50.202443Z","time spent":"330.577434ms","remote":"127.0.0.1:43886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T03:09:50.533131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.755045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T03:09:50.533152Z","caller":"traceutil/trace.go:171","msg":"trace[1418674390] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:2042; }","duration":"272.805596ms","start":"2026-06-17T03:09:50.260339Z","end":"2026-06-17T03:09:50.533145Z","steps":["trace[1418674390] 'agreement among raft nodes before linearized reading' (duration: 272.764695ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:09:50.533293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.592121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-06-17T03:09:50.533311Z","caller":"traceutil/trace.go:171","msg":"trace[934518788] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2042; }","duration":"142.645692ms","start":"2026-06-17T03:09:50.39066Z","end":"2026-06-17T03:09:50.533306Z","steps":["trace[934518788] 'agreement among raft nodes before linearized reading' (duration: 142.573321ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:09:51.173127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.179247ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-17T03:09:51.173395Z","caller":"traceutil/trace.go:171","msg":"trace[2144123636] transaction","detail":"{read_only:false; response_revision:2044; number_of_response:1; }","duration":"284.400516ms","start":"2026-06-17T03:09:50.888967Z","end":"2026-06-17T03:09:51.173368Z","steps":["trace[2144123636] 'process raft request' (duration: 126.900923ms)","trace[2144123636] 'compare' (duration: 156.880642ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T03:09:51.349454Z","caller":"traceutil/trace.go:171","msg":"trace[1484124886] transaction","detail":"{read_only:false; response_revision:2047; number_of_response:1; }","duration":"104.11378ms","start":"2026-06-17T03:09:51.245305Z","end":"2026-06-17T03:09:51.349419Z","steps":["trace[1484124886] 'process raft request' (duration: 62.157773ms)","trace[1484124886] 'compare' (duration: 41.658612ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T03:09:51.550209Z","caller":"traceutil/trace.go:171","msg":"trace[37696336] transaction","detail":"{read_only:false; response_revision:2051; number_of_response:1; }","duration":"103.873255ms","start":"2026-06-17T03:09:51.446303Z","end":"2026-06-17T03:09:51.550176Z","steps":["trace[37696336] 'process raft request' (duration: 43.341434ms)","trace[37696336] 'compare' (duration: 60.416119ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T03:09:51.995954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.285638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T03:09:51.996038Z","caller":"traceutil/trace.go:171","msg":"trace[1054063835] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2051; }","duration":"210.40638ms","start":"2026-06-17T03:09:51.785615Z","end":"2026-06-17T03:09:51.996021Z","steps":["trace[1054063835] 'range keys from in-memory index tree' (duration: 210.226087ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:10:00.53753Z","caller":"traceutil/trace.go:171","msg":"trace[1835358499] transaction","detail":"{read_only:false; response_revision:2087; number_of_response:1; }","duration":"249.646165ms","start":"2026-06-17T03:10:00.287857Z","end":"2026-06-17T03:10:00.537504Z","steps":["trace[1835358499] 'process raft request' (duration: 249.481402ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:10:00.716007Z","caller":"traceutil/trace.go:171","msg":"trace[200440046] linearizableReadLoop","detail":"{readStateIndex:2176; appliedIndex:2175; }","duration":"106.156918ms","start":"2026-06-17T03:10:00.609823Z","end":"2026-06-17T03:10:00.71598Z","steps":["trace[200440046] 'read index received' (duration: 60.778485ms)","trace[200440046] 'applied index is now lower than readState.Index' (duration: 45.376643ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T03:10:00.716147Z","caller":"traceutil/trace.go:171","msg":"trace[1530741567] transaction","detail":"{read_only:false; response_revision:2088; number_of_response:1; }","duration":"165.779592ms","start":"2026-06-17T03:10:00.550313Z","end":"2026-06-17T03:10:00.716092Z","steps":["trace[1530741567] 'process raft request' (duration: 120.362858ms)","trace[1530741567] 'compare' (duration: 45.173888ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T03:10:00.716264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.417193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-06-17T03:10:00.716309Z","caller":"traceutil/trace.go:171","msg":"trace[1280913966] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2088; }","duration":"106.536755ms","start":"2026-06-17T03:10:00.609761Z","end":"2026-06-17T03:10:00.716298Z","steps":["trace[1280913966] 'agreement among raft nodes before linearized reading' (duration: 106.364552ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:10:00.85745Z","caller":"traceutil/trace.go:171","msg":"trace[2092152346] linearizableReadLoop","detail":"{readStateIndex:2178; appliedIndex:2176; }","duration":"135.146159ms","start":"2026-06-17T03:10:00.722282Z","end":"2026-06-17T03:10:00.857428Z","steps":["trace[2092152346] 'read index received' (duration: 53.552847ms)","trace[2092152346] 'applied index is now lower than readState.Index' (duration: 81.592352ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T03:10:00.857508Z","caller":"traceutil/trace.go:171","msg":"trace[506968146] transaction","detail":"{read_only:false; response_revision:2089; number_of_response:1; }","duration":"135.383854ms","start":"2026-06-17T03:10:00.722072Z","end":"2026-06-17T03:10:00.857456Z","steps":["trace[506968146] 'process raft request' (duration: 95.76656ms)","trace[506968146] 'compare' (duration: 39.426739ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T03:10:00.85803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.711839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:6772"} {"level":"info","ts":"2026-06-17T03:10:00.858139Z","caller":"traceutil/trace.go:171","msg":"trace[647732338] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:2090; }","duration":"135.854203ms","start":"2026-06-17T03:10:00.722256Z","end":"2026-06-17T03:10:00.858111Z","steps":["trace[647732338] 'agreement among raft nodes before linearized reading' (duration: 135.254692ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:11:24.164084Z","caller":"traceutil/trace.go:171","msg":"trace[659121288] transaction","detail":"{read_only:false; response_revision:2507; number_of_response:1; }","duration":"245.624969ms","start":"2026-06-17T03:11:23.91843Z","end":"2026-06-17T03:11:24.164055Z","steps":["trace[659121288] 'process raft request' (duration: 245.481347ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:11:24.164076Z","caller":"traceutil/trace.go:171","msg":"trace[2130359435] linearizableReadLoop","detail":"{readStateIndex:2614; appliedIndex:2612; }","duration":"211.815688ms","start":"2026-06-17T03:11:23.952222Z","end":"2026-06-17T03:11:24.164038Z","steps":["trace[2130359435] 'read index received' (duration: 202.830407ms)","trace[2130359435] 'applied index is now lower than readState.Index' (duration: 8.984041ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T03:11:24.16411Z","caller":"traceutil/trace.go:171","msg":"trace[1308037332] transaction","detail":"{read_only:false; response_revision:2506; number_of_response:1; }","duration":"246.045247ms","start":"2026-06-17T03:11:23.918026Z","end":"2026-06-17T03:11:24.164071Z","steps":["trace[1308037332] 'process raft request' (duration: 237.017155ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:11:24.164501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.262976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/envoy-gateway-system/\" range_end:\"/registry/events/envoy-gateway-system0\" limit:500 ","response":"range_response_count:15 size:12285"} {"level":"info","ts":"2026-06-17T03:11:24.164549Z","caller":"traceutil/trace.go:171","msg":"trace[1801209467] range","detail":"{range_begin:/registry/events/envoy-gateway-system/; range_end:/registry/events/envoy-gateway-system0; response_count:15; response_revision:2507; }","duration":"212.354028ms","start":"2026-06-17T03:11:23.952185Z","end":"2026-06-17T03:11:24.164539Z","steps":["trace[1801209467] 'agreement among raft nodes before linearized reading' (duration: 211.984342ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:11:24.164555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.995837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"warn","ts":"2026-06-17T03:11:24.164583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.10712ms","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-06-17T03:11:24.164593Z","caller":"traceutil/trace.go:171","msg":"trace[1144131053] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2507; }","duration":"197.071638ms","start":"2026-06-17T03:11:23.96751Z","end":"2026-06-17T03:11:24.164581Z","steps":["trace[1144131053] 'agreement among raft nodes before linearized reading' (duration: 196.922195ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:11:24.164625Z","caller":"traceutil/trace.go:171","msg":"trace[2030649552] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2507; }","duration":"157.186311ms","start":"2026-06-17T03:11:24.007427Z","end":"2026-06-17T03:11:24.164614Z","steps":["trace[2030649552] 'agreement among raft nodes before linearized reading' (duration: 157.057538ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:11:24.165455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.475873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" ","response":"range_response_count:1 size:1036317"} {"level":"info","ts":"2026-06-17T03:11:24.165496Z","caller":"traceutil/trace.go:171","msg":"trace[1597061849] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1; range_end:; response_count:1; response_revision:2507; }","duration":"181.530773ms","start":"2026-06-17T03:11:23.983953Z","end":"2026-06-17T03:11:24.165484Z","steps":["trace[1597061849] 'agreement among raft nodes before linearized reading' (duration: 180.723378ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:11:40.555256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.397073ms","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-06-17T03:11:40.555354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.380916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-06-17T03:11:40.555415Z","caller":"traceutil/trace.go:171","msg":"trace[2136991680] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2578; }","duration":"312.443347ms","start":"2026-06-17T03:11:40.242957Z","end":"2026-06-17T03:11:40.5554Z","steps":["trace[2136991680] 'range keys from in-memory index tree' (duration: 312.200852ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:11:40.555401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.848238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-629wm\" ","response":"range_response_count:1 size:18525"} {"level":"warn","ts":"2026-06-17T03:11:40.555453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:11:40.242904Z","time spent":"312.540319ms","remote":"127.0.0.1:43886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-06-17T03:11:40.555462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.910409ms","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-06-17T03:11:40.555478Z","caller":"traceutil/trace.go:171","msg":"trace[6986564] range","detail":"{range_begin:/registry/pods/kube-system/cilium-629wm; range_end:; response_count:1; response_revision:2578; }","duration":"531.94851ms","start":"2026-06-17T03:11:40.023509Z","end":"2026-06-17T03:11:40.555458Z","steps":["trace[6986564] 'range keys from in-memory index tree' (duration: 531.691625ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T03:11:40.555491Z","caller":"traceutil/trace.go:171","msg":"trace[1625539677] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2578; }","duration":"319.98256ms","start":"2026-06-17T03:11:40.235499Z","end":"2026-06-17T03:11:40.555482Z","steps":["trace[1625539677] 'range keys from in-memory index tree' (duration: 319.721746ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:11:40.555521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:11:40.235475Z","time spent":"320.039271ms","remote":"127.0.0.1:43886","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-06-17T03:11:40.555519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:11:40.023501Z","time spent":"532.009861ms","remote":"127.0.0.1:43802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18547,"request content":"key:\"/registry/pods/kube-system/cilium-629wm\" "} {"level":"info","ts":"2026-06-17T03:11:40.555364Z","caller":"traceutil/trace.go:171","msg":"trace[977830250] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2578; }","duration":"315.540456ms","start":"2026-06-17T03:11:40.2398Z","end":"2026-06-17T03:11:40.55534Z","steps":["trace[977830250] 'range keys from in-memory index tree' (duration: 315.175428ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:11:40.555585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T03:11:40.239788Z","time spent":"315.776961ms","remote":"127.0.0.1:43886","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-06-17T03:11:41.262111Z","caller":"traceutil/trace.go:171","msg":"trace[1314892954] linearizableReadLoop","detail":"{readStateIndex:2696; appliedIndex:2695; }","duration":"207.117778ms","start":"2026-06-17T03:11:41.05497Z","end":"2026-06-17T03:11:41.262088Z","steps":["trace[1314892954] 'read index received' (duration: 203.00843ms)","trace[1314892954] 'applied index is now lower than readState.Index' (duration: 4.108428ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T03:11:41.262122Z","caller":"traceutil/trace.go:171","msg":"trace[1329126873] transaction","detail":"{read_only:false; response_revision:2585; number_of_response:1; }","duration":"223.244033ms","start":"2026-06-17T03:11:41.038849Z","end":"2026-06-17T03:11:41.262093Z","steps":["trace[1329126873] 'process raft request' (duration: 219.209238ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:11:41.262289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.314853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-sj82b\" ","response":"range_response_count:1 size:4694"} {"level":"info","ts":"2026-06-17T03:11:41.262316Z","caller":"traceutil/trace.go:171","msg":"trace[1908191946] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-sj82b; range_end:; response_count:1; response_revision:2585; }","duration":"207.383494ms","start":"2026-06-17T03:11:41.054922Z","end":"2026-06-17T03:11:41.262306Z","steps":["trace[1908191946] 'agreement among raft nodes before linearized reading' (duration: 207.270491ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T03:11:41.262349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.503908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-06-17T03:11:41.26243Z","caller":"traceutil/trace.go:171","msg":"trace[507755039] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2585; }","duration":"138.608779ms","start":"2026-06-17T03:11:41.123793Z","end":"2026-06-17T03:11:41.262402Z","steps":["trace[507755039] 'agreement among raft nodes before linearized reading' (duration: 138.429095ms)"],"step_count":1}