{"level":"warn","ts":"2026-02-04T01:55:38.813997Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-04T01:55:38.820265Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.30:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.30:2380","--initial-cluster=instance=https://199.204.45.30:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.30:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.30:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-04T01:55:38.82051Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-04T01:55:38.820651Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.30:2380"]} {"level":"info","ts":"2026-02-04T01:55:38.820828Z","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-02-04T01:55:38.822098Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"]} {"level":"info","ts":"2026-02-04T01:55:38.82236Z","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":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.30:2380"],"listen-peer-urls":["https://199.204.45.30:2380"],"advertise-client-urls":["https://199.204.45.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.30:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-04T01:55:38.916628Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"93.833741ms"} {"level":"info","ts":"2026-02-04T01:55:38.98743Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c6cf68dde442ffa5","cluster-id":"98b7ecdca9045526"} {"level":"info","ts":"2026-02-04T01:55:38.987603Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=()"} {"level":"info","ts":"2026-02-04T01:55:38.98768Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became follower at term 0"} {"level":"info","ts":"2026-02-04T01:55:38.98776Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c6cf68dde442ffa5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-04T01:55:38.987804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became follower at term 1"} {"level":"info","ts":"2026-02-04T01:55:38.987883Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=(14325784241915494309)"} {"level":"warn","ts":"2026-02-04T01:55:39.01585Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-04T01:55:39.033081Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-04T01:55:39.044767Z","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-02-04T01:55:39.107843Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c6cf68dde442ffa5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-04T01:55:39.109891Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c6cf68dde442ffa5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-04T01:55:39.110072Z","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-02-04T01:55:39.110139Z","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-02-04T01:55:39.110171Z","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-02-04T01:55:39.136395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=(14325784241915494309)"} {"level":"info","ts":"2026-02-04T01:55:39.137096Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"98b7ecdca9045526","local-member-id":"c6cf68dde442ffa5","added-peer-id":"c6cf68dde442ffa5","added-peer-peer-urls":["https://199.204.45.30:2380"]} {"level":"info","ts":"2026-02-04T01:55:39.137726Z","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-02-04T01:55:39.137933Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c6cf68dde442ffa5","initial-advertise-peer-urls":["https://199.204.45.30:2380"],"listen-peer-urls":["https://199.204.45.30:2380"],"advertise-client-urls":["https://199.204.45.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-04T01:55:39.138437Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-04T01:55:39.138561Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.30:2380"} {"level":"info","ts":"2026-02-04T01:55:39.138668Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.30:2380"} {"level":"info","ts":"2026-02-04T01:55:39.193253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 is starting a new election at term 1"} {"level":"info","ts":"2026-02-04T01:55:39.193326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-04T01:55:39.194829Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 received MsgPreVoteResp from c6cf68dde442ffa5 at term 1"} {"level":"info","ts":"2026-02-04T01:55:39.194982Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became candidate at term 2"} {"level":"info","ts":"2026-02-04T01:55:39.195002Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 received MsgVoteResp from c6cf68dde442ffa5 at term 2"} {"level":"info","ts":"2026-02-04T01:55:39.195053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became leader at term 2"} {"level":"info","ts":"2026-02-04T01:55:39.195081Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c6cf68dde442ffa5 elected leader c6cf68dde442ffa5 at term 2"} {"level":"info","ts":"2026-02-04T01:55:39.196337Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T01:55:39.197002Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c6cf68dde442ffa5","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.30:2379]}","request-path":"/0/members/c6cf68dde442ffa5/attributes","cluster-id":"98b7ecdca9045526","publish-timeout":"7s"} {"level":"info","ts":"2026-02-04T01:55:39.197352Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-04T01:55:39.197583Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"98b7ecdca9045526","local-member-id":"c6cf68dde442ffa5","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T01:55:39.197873Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T01:55:39.197927Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T01:55:39.197605Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-04T01:55:39.197628Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-04T01:55:39.200933Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-04T01:55:39.202319Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-04T01:55:39.209328Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.30:2379"} {"level":"info","ts":"2026-02-04T01:55:42.797116Z","caller":"traceutil/trace.go:171","msg":"trace[978062457] transaction","detail":"{read_only:false; response_revision:117; number_of_response:1; }","duration":"114.035948ms","start":"2026-02-04T01:55:42.683053Z","end":"2026-02-04T01:55:42.797089Z","steps":["trace[978062457] 'process raft request' (duration: 45.26634ms)","trace[978062457] 'compare' (duration: 68.630665ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:55:43.370467Z","caller":"traceutil/trace.go:171","msg":"trace[1560130811] transaction","detail":"{read_only:false; response_revision:166; number_of_response:1; }","duration":"152.573254ms","start":"2026-02-04T01:55:43.217872Z","end":"2026-02-04T01:55:43.370445Z","steps":["trace[1560130811] 'process raft request' (duration: 95.020487ms)","trace[1560130811] 'compare' (duration: 57.410904ms)"],"step_count":2} 2026/02/04 01:55:45 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-02-04T01:55:46.803707Z","caller":"traceutil/trace.go:171","msg":"trace[1464368632] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"128.826259ms","start":"2026-02-04T01:55:46.674862Z","end":"2026-02-04T01:55:46.803688Z","steps":["trace[1464368632] 'process raft request' (duration: 128.710207ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:55:47.573527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.042355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-04T01:55:47.573614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.125056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:55:47.573653Z","caller":"traceutil/trace.go:171","msg":"trace[1499837258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:285; }","duration":"451.210888ms","start":"2026-02-04T01:55:47.122419Z","end":"2026-02-04T01:55:47.57363Z","steps":["trace[1499837258] 'range keys from in-memory index tree' (duration: 450.960653ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:55:47.573662Z","caller":"traceutil/trace.go:171","msg":"trace[641181627] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:0; response_revision:285; }","duration":"699.211037ms","start":"2026-02-04T01:55:46.874436Z","end":"2026-02-04T01:55:47.573647Z","steps":["trace[641181627] 'range keys from in-memory index tree' (duration: 699.035254ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:55:47.573703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:55:47.122403Z","time spent":"451.2861ms","remote":"127.0.0.1:58326","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-02-04T01:55:47.573708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:55:46.874415Z","time spent":"699.281829ms","remote":"127.0.0.1:58244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" "} {"level":"warn","ts":"2026-02-04T01:55:47.574006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.756446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:5491"} {"level":"info","ts":"2026-02-04T01:55:47.574037Z","caller":"traceutil/trace.go:171","msg":"trace[79494816] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:285; }","duration":"576.810298ms","start":"2026-02-04T01:55:46.997217Z","end":"2026-02-04T01:55:47.574027Z","steps":["trace[79494816] 'range keys from in-memory index tree' (duration: 576.667004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:55:47.573527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.419131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"warn","ts":"2026-02-04T01:55:47.574063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:55:46.997206Z","time spent":"576.850148ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":5515,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"info","ts":"2026-02-04T01:55:47.574096Z","caller":"traceutil/trace.go:171","msg":"trace[532709874] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:285; }","duration":"646.326473ms","start":"2026-02-04T01:55:46.927747Z","end":"2026-02-04T01:55:47.574074Z","steps":["trace[532709874] 'range keys from in-memory index tree' (duration: 644.32641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:55:47.574136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:55:46.927715Z","time spent":"646.409504ms","remote":"127.0.0.1:58244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":240,"request content":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" "} {"level":"warn","ts":"2026-02-04T01:55:47.574257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.751996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-04T01:55:47.574276Z","caller":"traceutil/trace.go:171","msg":"trace[1325610699] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:285; }","duration":"454.806557ms","start":"2026-02-04T01:55:47.119462Z","end":"2026-02-04T01:55:47.574268Z","steps":["trace[1325610699] 'range keys from in-memory index tree' (duration: 454.583032ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:55:47.574292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:55:47.119434Z","time spent":"454.853968ms","remote":"127.0.0.1:58326","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-02-04T01:55:47.727366Z","caller":"traceutil/trace.go:171","msg":"trace[1271257463] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"144.231833ms","start":"2026-02-04T01:55:47.583117Z","end":"2026-02-04T01:55:47.727349Z","steps":["trace[1271257463] 'process raft request' (duration: 144.13238ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:55:47.746874Z","caller":"traceutil/trace.go:171","msg":"trace[432144052] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"160.914254ms","start":"2026-02-04T01:55:47.585942Z","end":"2026-02-04T01:55:47.746857Z","steps":["trace[432144052] 'process raft request' (duration: 160.809152ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:55:47.747092Z","caller":"traceutil/trace.go:171","msg":"trace[1309840431] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"160.127307ms","start":"2026-02-04T01:55:47.58696Z","end":"2026-02-04T01:55:47.747087Z","steps":["trace[1309840431] 'process raft request' (duration: 159.877491ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:55:47.747234Z","caller":"traceutil/trace.go:171","msg":"trace[604187358] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"150.365875ms","start":"2026-02-04T01:55:47.596858Z","end":"2026-02-04T01:55:47.747224Z","steps":["trace[604187358] 'process raft request' (duration: 150.12632ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:55:47.879063Z","caller":"traceutil/trace.go:171","msg":"trace[985116435] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"118.020295ms","start":"2026-02-04T01:55:47.761011Z","end":"2026-02-04T01:55:47.879031Z","steps":["trace[985116435] 'process raft request' (duration: 27.492796ms)","trace[985116435] 'compare' (duration: 90.124581ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:55:47.878717Z","caller":"traceutil/trace.go:171","msg":"trace[288647864] linearizableReadLoop","detail":"{readStateIndex:302; appliedIndex:301; }","duration":"102.255983ms","start":"2026-02-04T01:55:47.776444Z","end":"2026-02-04T01:55:47.8787Z","steps":["trace[288647864] 'read index received' (duration: 12.01387ms)","trace[288647864] 'applied index is now lower than readState.Index' (duration: 90.241503ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:55:47.879167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.707443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/attachdetach-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:55:47.8792Z","caller":"traceutil/trace.go:171","msg":"trace[551430802] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/attachdetach-controller; range_end:; response_count:0; response_revision:291; }","duration":"102.781365ms","start":"2026-02-04T01:55:47.776411Z","end":"2026-02-04T01:55:47.879192Z","steps":["trace[551430802] 'agreement among raft nodes before linearized reading' (duration: 102.719573ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:55:48.161685Z","caller":"traceutil/trace.go:171","msg":"trace[1971206406] linearizableReadLoop","detail":"{readStateIndex:304; appliedIndex:303; }","duration":"232.629676ms","start":"2026-02-04T01:55:47.929034Z","end":"2026-02-04T01:55:48.161663Z","steps":["trace[1971206406] 'read index received' (duration: 172.25669ms)","trace[1971206406] 'applied index is now lower than readState.Index' (duration: 60.372036ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:55:48.161796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.740928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-04T01:55:48.161822Z","caller":"traceutil/trace.go:171","msg":"trace[971803253] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:293; }","duration":"232.80853ms","start":"2026-02-04T01:55:47.929007Z","end":"2026-02-04T01:55:48.161815Z","steps":["trace[971803253] 'agreement among raft nodes before linearized reading' (duration: 232.731469ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:55:48.161938Z","caller":"traceutil/trace.go:171","msg":"trace[1575771081] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"273.884529ms","start":"2026-02-04T01:55:47.888031Z","end":"2026-02-04T01:55:48.161916Z","steps":["trace[1575771081] 'process raft request' (duration: 213.296048ms)","trace[1575771081] 'compare' (duration: 60.247043ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:55:53.908683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.620271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-04T01:55:53.908798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.025751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-04T01:55:53.908814Z","caller":"traceutil/trace.go:171","msg":"trace[12709689] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:369; }","duration":"120.783455ms","start":"2026-02-04T01:55:53.788008Z","end":"2026-02-04T01:55:53.908791Z","steps":["trace[12709689] 'range keys from in-memory index tree' (duration: 120.54662ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:55:53.908936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.12842ms","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-02-04T01:55:53.908953Z","caller":"traceutil/trace.go:171","msg":"trace[419911701] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:369; }","duration":"126.177411ms","start":"2026-02-04T01:55:53.782772Z","end":"2026-02-04T01:55:53.90895Z","steps":["trace[419911701] 'range keys from in-memory index tree' (duration: 126.076429ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:55:53.908844Z","caller":"traceutil/trace.go:171","msg":"trace[276667113] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:369; }","duration":"109.102702ms","start":"2026-02-04T01:55:53.79973Z","end":"2026-02-04T01:55:53.908833Z","steps":["trace[276667113] 'range keys from in-memory index tree' (duration: 108.978059ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:55:54.350841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.925743ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:55:54.351096Z","caller":"traceutil/trace.go:171","msg":"trace[605538630] linearizableReadLoop","detail":"{readStateIndex:385; appliedIndex:382; }","duration":"410.178241ms","start":"2026-02-04T01:55:53.940896Z","end":"2026-02-04T01:55:54.351075Z","steps":["trace[605538630] 'read index received' (duration: 96.470669ms)","trace[605538630] 'applied index is now lower than readState.Index' (duration: 313.706172ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:55:54.353001Z","caller":"traceutil/trace.go:171","msg":"trace[458955408] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"441.019798ms","start":"2026-02-04T01:55:53.911942Z","end":"2026-02-04T01:55:54.352962Z","steps":["trace[458955408] 'process raft request' (duration: 198.220052ms)","trace[458955408] 'compare' (duration: 239.73741ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:55:54.353115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:55:53.911921Z","time spent":"441.1428ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:55:54.353288Z","caller":"traceutil/trace.go:171","msg":"trace[383560465] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"438.330699ms","start":"2026-02-04T01:55:53.914945Z","end":"2026-02-04T01:55:54.353276Z","steps":["trace[383560465] 'process raft request' (duration: 436.02461ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:55:54.353354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:55:53.914939Z","time spent":"438.38298ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:55:54.37329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.364272ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:55:54.373381Z","caller":"traceutil/trace.go:171","msg":"trace[1174014832] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:372; }","duration":"432.475805ms","start":"2026-02-04T01:55:53.940891Z","end":"2026-02-04T01:55:54.373367Z","steps":["trace[1174014832] 'agreement among raft nodes before linearized reading' (duration: 432.328592ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:55:54.373621Z","caller":"traceutil/trace.go:171","msg":"trace[1571488611] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"438.425871ms","start":"2026-02-04T01:55:53.915027Z","end":"2026-02-04T01:55:54.353453Z","steps":["trace[1571488611] 'process raft request' (duration: 436.00726ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:55:54.373722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:55:53.915023Z","time spent":"458.62839ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:55:54.384065Z","caller":"traceutil/trace.go:171","msg":"trace[451292646] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"345.248794ms","start":"2026-02-04T01:55:54.038785Z","end":"2026-02-04T01:55:54.384034Z","steps":["trace[451292646] 'process raft request' (duration: 345.084101ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:55:54.384192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:55:54.038747Z","time spent":"345.361637ms","remote":"127.0.0.1:58102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:55:54.384286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.744386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6894"} {"level":"info","ts":"2026-02-04T01:55:54.384403Z","caller":"traceutil/trace.go:171","msg":"trace[286944183] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:373; }","duration":"339.92586ms","start":"2026-02-04T01:55:54.044456Z","end":"2026-02-04T01:55:54.384382Z","steps":["trace[286944183] 'agreement among raft nodes before linearized reading' (duration: 339.573201ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:55:54.384464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:55:54.044445Z","time spent":"340.008522ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6918,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"info","ts":"2026-02-04T01:55:54.528722Z","caller":"traceutil/trace.go:171","msg":"trace[1811427859] linearizableReadLoop","detail":"{readStateIndex:387; appliedIndex:386; }","duration":"133.417939ms","start":"2026-02-04T01:55:54.395268Z","end":"2026-02-04T01:55:54.528686Z","steps":["trace[1811427859] 'read index received' (duration: 131.427645ms)","trace[1811427859] 'applied index is now lower than readState.Index' (duration: 1.989134ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:55:54.528947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.633063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-02-04T01:55:54.528985Z","caller":"traceutil/trace.go:171","msg":"trace[1217170950] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:374; }","duration":"133.735076ms","start":"2026-02-04T01:55:54.39524Z","end":"2026-02-04T01:55:54.528975Z","steps":["trace[1217170950] 'agreement among raft nodes before linearized reading' (duration: 133.596523ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:55:54.52923Z","caller":"traceutil/trace.go:171","msg":"trace[1719496014] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"134.468381ms","start":"2026-02-04T01:55:54.394748Z","end":"2026-02-04T01:55:54.529216Z","steps":["trace[1719496014] 'process raft request' (duration: 131.905805ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:55:56.478837Z","caller":"traceutil/trace.go:171","msg":"trace[1383993068] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"139.457249ms","start":"2026-02-04T01:55:56.339362Z","end":"2026-02-04T01:55:56.478819Z","steps":["trace[1383993068] 'process raft request' (duration: 139.318236ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:55:59.414477Z","caller":"traceutil/trace.go:171","msg":"trace[1285331719] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"204.679741ms","start":"2026-02-04T01:55:59.20978Z","end":"2026-02-04T01:55:59.41446Z","steps":["trace[1285331719] 'process raft request' (duration: 204.62532ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:55:59.414735Z","caller":"traceutil/trace.go:171","msg":"trace[1235269730] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"205.000318ms","start":"2026-02-04T01:55:59.209727Z","end":"2026-02-04T01:55:59.414727Z","steps":["trace[1235269730] 'process raft request' (duration: 153.005553ms)","trace[1235269730] 'compare' (duration: 51.585846ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:56:12.837238Z","caller":"traceutil/trace.go:171","msg":"trace[1948648065] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"238.175557ms","start":"2026-02-04T01:56:12.598992Z","end":"2026-02-04T01:56:12.837167Z","steps":["trace[1948648065] 'process raft request' (duration: 238.014273ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:12.837924Z","caller":"traceutil/trace.go:171","msg":"trace[745064996] linearizableReadLoop","detail":"{readStateIndex:501; appliedIndex:501; }","duration":"238.655417ms","start":"2026-02-04T01:56:12.599223Z","end":"2026-02-04T01:56:12.837878Z","steps":["trace[745064996] 'read index received' (duration: 238.650857ms)","trace[745064996] 'applied index is now lower than readState.Index' (duration: 3.5µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:12.838025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.78441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:56:12.838072Z","caller":"traceutil/trace.go:171","msg":"trace[2120564191] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:485; }","duration":"238.834411ms","start":"2026-02-04T01:56:12.59921Z","end":"2026-02-04T01:56:12.838044Z","steps":["trace[2120564191] 'agreement among raft nodes before linearized reading' (duration: 238.779159ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:12.839168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.842905ms","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-02-04T01:56:12.839317Z","caller":"traceutil/trace.go:171","msg":"trace[1122745394] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:485; }","duration":"144.920537ms","start":"2026-02-04T01:56:12.694275Z","end":"2026-02-04T01:56:12.839196Z","steps":["trace[1122745394] 'agreement among raft nodes before linearized reading' (duration: 144.404096ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:12.839589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.61153ms","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-02-04T01:56:12.839659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.362549ms","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-02-04T01:56:12.839682Z","caller":"traceutil/trace.go:171","msg":"trace[10079398] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:485; }","duration":"140.401189ms","start":"2026-02-04T01:56:12.699273Z","end":"2026-02-04T01:56:12.839674Z","steps":["trace[10079398] 'agreement among raft nodes before linearized reading' (duration: 140.256766ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:12.839723Z","caller":"traceutil/trace.go:171","msg":"trace[1743432384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:485; }","duration":"138.657702ms","start":"2026-02-04T01:56:12.700954Z","end":"2026-02-04T01:56:12.839611Z","steps":["trace[1743432384] 'agreement among raft nodes before linearized reading' (duration: 138.487108ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:14.108773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.728628ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:56:14.108861Z","caller":"traceutil/trace.go:171","msg":"trace[573710381] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"1.263647119s","start":"2026-02-04T01:56:12.845197Z","end":"2026-02-04T01:56:14.108844Z","steps":["trace[573710381] 'process raft request' (duration: 563.740166ms)","trace[573710381] 'compare' (duration: 699.394031ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:14.108914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:12.845176Z","time spent":"1.26371291s","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:56:14.118475Z","caller":"traceutil/trace.go:171","msg":"trace[946577817] linearizableReadLoop","detail":"{readStateIndex:505; appliedIndex:502; }","duration":"454.714544ms","start":"2026-02-04T01:56:13.663746Z","end":"2026-02-04T01:56:14.11846Z","steps":["trace[946577817] 'read index received' (duration: 454.565301ms)","trace[946577817] 'applied index is now lower than readState.Index' (duration: 148.733µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:56:14.118681Z","caller":"traceutil/trace.go:171","msg":"trace[638280763] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"1.272180692s","start":"2026-02-04T01:56:12.846494Z","end":"2026-02-04T01:56:14.118675Z","steps":["trace[638280763] 'process raft request' (duration: 1.271858016s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:14.118745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:12.846485Z","time spent":"1.272214744s","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:56:14.11884Z","caller":"traceutil/trace.go:171","msg":"trace[1427994942] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"1.27159174s","start":"2026-02-04T01:56:12.847244Z","end":"2026-02-04T01:56:14.118836Z","steps":["trace[1427994942] 'process raft request' (duration: 1.271176151s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:14.118869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:12.847234Z","time spent":"1.271621201s","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:56:14.118924Z","caller":"traceutil/trace.go:171","msg":"trace[1770765227] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"1.136072386s","start":"2026-02-04T01:56:12.982849Z","end":"2026-02-04T01:56:14.118921Z","steps":["trace[1770765227] 'process raft request' (duration: 1.135590045s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:14.118948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:12.982816Z","time spent":"1.136121996s","remote":"127.0.0.1:58146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":828,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-04T01:56:14.119029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.287987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4018"} {"level":"info","ts":"2026-02-04T01:56:14.119048Z","caller":"traceutil/trace.go:171","msg":"trace[675218027] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:489; }","duration":"455.331187ms","start":"2026-02-04T01:56:13.663712Z","end":"2026-02-04T01:56:14.119043Z","steps":["trace[675218027] 'agreement among raft nodes before linearized reading' (duration: 455.297097ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:14.119063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:13.663686Z","time spent":"455.372449ms","remote":"127.0.0.1:58220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4042,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-04T01:56:14.11914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.194311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.30\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-04T01:56:14.119156Z","caller":"traceutil/trace.go:171","msg":"trace[93255832] range","detail":"{range_begin:/registry/masterleases/199.204.45.30; range_end:; response_count:1; response_revision:489; }","duration":"283.233542ms","start":"2026-02-04T01:56:13.835919Z","end":"2026-02-04T01:56:14.119152Z","steps":["trace[93255832] 'agreement among raft nodes before linearized reading' (duration: 283.207081ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:18.270977Z","caller":"traceutil/trace.go:171","msg":"trace[628381556] linearizableReadLoop","detail":"{readStateIndex:516; appliedIndex:515; }","duration":"110.894456ms","start":"2026-02-04T01:56:18.160068Z","end":"2026-02-04T01:56:18.270962Z","steps":["trace[628381556] 'read index received' (duration: 110.761813ms)","trace[628381556] 'applied index is now lower than readState.Index' (duration: 132.043µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:56:18.271163Z","caller":"traceutil/trace.go:171","msg":"trace[1350589198] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"150.579639ms","start":"2026-02-04T01:56:18.120576Z","end":"2026-02-04T01:56:18.271156Z","steps":["trace[1350589198] 'process raft request' (duration: 150.304483ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:18.271286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.196743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-04T01:56:18.271304Z","caller":"traceutil/trace.go:171","msg":"trace[828659434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:499; }","duration":"111.267985ms","start":"2026-02-04T01:56:18.160029Z","end":"2026-02-04T01:56:18.271297Z","steps":["trace[828659434] 'agreement among raft nodes before linearized reading' (duration: 111.217523ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:18.271375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.744753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-04T01:56:18.271387Z","caller":"traceutil/trace.go:171","msg":"trace[1160105156] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:499; }","duration":"110.756573ms","start":"2026-02-04T01:56:18.160626Z","end":"2026-02-04T01:56:18.271383Z","steps":["trace[1160105156] 'agreement among raft nodes before linearized reading' (duration: 110.735683ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:18.548561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.297681ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:56:18.548666Z","caller":"traceutil/trace.go:171","msg":"trace[953399598] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"273.31659ms","start":"2026-02-04T01:56:18.275334Z","end":"2026-02-04T01:56:18.54865Z","steps":["trace[953399598] 'process raft request' (duration: 136.870945ms)","trace[953399598] 'compare' (duration: 136.188229ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:56:18.836607Z","caller":"traceutil/trace.go:171","msg":"trace[1712739591] linearizableReadLoop","detail":"{readStateIndex:518; appliedIndex:517; }","duration":"239.942397ms","start":"2026-02-04T01:56:18.596642Z","end":"2026-02-04T01:56:18.836585Z","steps":["trace[1712739591] 'read index received' (duration: 195.886857ms)","trace[1712739591] 'applied index is now lower than readState.Index' (duration: 44.05468ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:18.836761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.06473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:56:18.836792Z","caller":"traceutil/trace.go:171","msg":"trace[1122155143] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:501; }","duration":"240.170721ms","start":"2026-02-04T01:56:18.596611Z","end":"2026-02-04T01:56:18.836782Z","steps":["trace[1122155143] 'agreement among raft nodes before linearized reading' (duration: 240.051419ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:18.837072Z","caller":"traceutil/trace.go:171","msg":"trace[1513648599] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"561.551771ms","start":"2026-02-04T01:56:18.275507Z","end":"2026-02-04T01:56:18.837059Z","steps":["trace[1513648599] 'process raft request' (duration: 517.076022ms)","trace[1513648599] 'compare' (duration: 43.915857ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:18.837147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:18.275484Z","time spent":"561.612813ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:56:19.402816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.564231ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:56:19.403037Z","caller":"traceutil/trace.go:171","msg":"trace[573372042] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"545.35499ms","start":"2026-02-04T01:56:18.857669Z","end":"2026-02-04T01:56:19.403024Z","steps":["trace[573372042] 'process raft request' (duration: 340.511303ms)","trace[573372042] 'compare' (duration: 204.494979ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:19.403103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:18.857653Z","time spent":"545.431742ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:56:19.940126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.17746ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-04T01:56:19.940217Z","caller":"traceutil/trace.go:171","msg":"trace[1514042564] linearizableReadLoop","detail":"{readStateIndex:520; appliedIndex:519; }","duration":"341.091828ms","start":"2026-02-04T01:56:19.599112Z","end":"2026-02-04T01:56:19.940204Z","steps":["trace[1514042564] 'read index received' (duration: 28.321µs)","trace[1514042564] 'applied index is now lower than readState.Index' (duration: 341.062517ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:19.940274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.161289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:56:19.940296Z","caller":"traceutil/trace.go:171","msg":"trace[2058094038] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:502; }","duration":"341.228131ms","start":"2026-02-04T01:56:19.599061Z","end":"2026-02-04T01:56:19.940289Z","steps":["trace[2058094038] 'agreement among raft nodes before linearized reading' (duration: 341.17665ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:19.940321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:19.599042Z","time spent":"341.273691ms","remote":"127.0.0.1:58078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:56:23.762475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.84498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:56:23.762555Z","caller":"traceutil/trace.go:171","msg":"trace[635824286] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:514; }","duration":"165.974123ms","start":"2026-02-04T01:56:23.596561Z","end":"2026-02-04T01:56:23.762535Z","steps":["trace[635824286] 'range keys from in-memory index tree' (duration: 165.761769ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:24.649211Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421301539407754361,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:56:25.034191Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.058247472s","expected-duration":"1s"} {"level":"info","ts":"2026-02-04T01:56:25.034544Z","caller":"traceutil/trace.go:171","msg":"trace[312406050] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:532; }","duration":"885.493318ms","start":"2026-02-04T01:56:24.149023Z","end":"2026-02-04T01:56:25.034516Z","steps":["trace[312406050] 'read index received' (duration: 885.320824ms)","trace[312406050] 'applied index is now lower than readState.Index' (duration: 171.044µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:25.034828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:23.83737Z","time spent":"1.197454339s","remote":"127.0.0.1:58102","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-04T01:56:25.035176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.130512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-hs548\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-04T01:56:25.035212Z","caller":"traceutil/trace.go:171","msg":"trace[338068956] range","detail":"{range_begin:/registry/pods/kube-system/cilium-hs548; range_end:; response_count:1; response_revision:514; }","duration":"886.213934ms","start":"2026-02-04T01:56:24.148986Z","end":"2026-02-04T01:56:25.0352Z","steps":["trace[338068956] 'agreement among raft nodes before linearized reading' (duration: 886.112962ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:25.035242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:24.148969Z","time spent":"886.267055ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16676,"request content":"key:\"/registry/pods/kube-system/cilium-hs548\" "} {"level":"warn","ts":"2026-02-04T01:56:25.0353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.416657ms","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-02-04T01:56:25.035364Z","caller":"traceutil/trace.go:171","msg":"trace[2111508312] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:514; }","duration":"747.520109ms","start":"2026-02-04T01:56:24.287828Z","end":"2026-02-04T01:56:25.035348Z","steps":["trace[2111508312] 'agreement among raft nodes before linearized reading' (duration: 747.323995ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:25.035402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:24.287814Z","time spent":"747.58073ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-04T01:56:25.035636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.493986ms","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-02-04T01:56:25.035668Z","caller":"traceutil/trace.go:171","msg":"trace[1238636607] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:514; }","duration":"180.555847ms","start":"2026-02-04T01:56:24.855102Z","end":"2026-02-04T01:56:25.035658Z","steps":["trace[1238636607] 'agreement among raft nodes before linearized reading' (duration: 180.477995ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:25.035773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.372618ms","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-02-04T01:56:25.035808Z","caller":"traceutil/trace.go:171","msg":"trace[2037422043] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:514; }","duration":"468.4409ms","start":"2026-02-04T01:56:24.567357Z","end":"2026-02-04T01:56:25.035798Z","steps":["trace[2037422043] 'agreement among raft nodes before linearized reading' (duration: 468.323348ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:25.035835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:24.567336Z","time spent":"468.493102ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-04T01:56:25.035918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.517437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:56:25.035939Z","caller":"traceutil/trace.go:171","msg":"trace[1030344745] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:514; }","duration":"439.536987ms","start":"2026-02-04T01:56:24.596395Z","end":"2026-02-04T01:56:25.035932Z","steps":["trace[1030344745] 'agreement among raft nodes before linearized reading' (duration: 439.504226ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:25.035961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:24.596351Z","time spent":"439.605149ms","remote":"127.0.0.1:58078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:56:25.348411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.919083ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:56:25.348529Z","caller":"traceutil/trace.go:171","msg":"trace[657743731] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"309.670139ms","start":"2026-02-04T01:56:25.038835Z","end":"2026-02-04T01:56:25.348505Z","steps":["trace[657743731] 'process raft request' (duration: 169.544022ms)","trace[657743731] 'compare' (duration: 139.447481ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:25.348609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:25.038812Z","time spent":"309.756331ms","remote":"127.0.0.1:58102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:56:25.353107Z","caller":"traceutil/trace.go:171","msg":"trace[1886002650] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"312.105173ms","start":"2026-02-04T01:56:25.040991Z","end":"2026-02-04T01:56:25.353096Z","steps":["trace[1886002650] 'process raft request' (duration: 312.00327ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:25.353171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:25.040977Z","time spent":"312.162715ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:56:25.353295Z","caller":"traceutil/trace.go:171","msg":"trace[522302729] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"310.439516ms","start":"2026-02-04T01:56:25.042842Z","end":"2026-02-04T01:56:25.353282Z","steps":["trace[522302729] 'process raft request' (duration: 310.230761ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:25.353373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:25.042834Z","time spent":"310.514917ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:56:25.353556Z","caller":"traceutil/trace.go:171","msg":"trace[1406139889] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"310.647451ms","start":"2026-02-04T01:56:25.042886Z","end":"2026-02-04T01:56:25.353533Z","steps":["trace[1406139889] 'process raft request' (duration: 310.358944ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:25.353599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:25.042881Z","time spent":"310.694912ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:56:25.353743Z","caller":"traceutil/trace.go:171","msg":"trace[3991391] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"299.989054ms","start":"2026-02-04T01:56:25.053747Z","end":"2026-02-04T01:56:25.353736Z","steps":["trace[3991391] 'process raft request' (duration: 299.708866ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:25.353779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:25.053718Z","time spent":"300.041696ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:56:27.580539Z","caller":"traceutil/trace.go:171","msg":"trace[1835609015] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"292.353883ms","start":"2026-02-04T01:56:27.288116Z","end":"2026-02-04T01:56:27.58047Z","steps":["trace[1835609015] 'process raft request' (duration: 291.664948ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:27.662105Z","caller":"traceutil/trace.go:171","msg":"trace[1540133772] linearizableReadLoop","detail":"{readStateIndex:561; appliedIndex:559; }","duration":"300.481775ms","start":"2026-02-04T01:56:27.361603Z","end":"2026-02-04T01:56:27.662084Z","steps":["trace[1540133772] 'read index received' (duration: 218.236504ms)","trace[1540133772] 'applied index is now lower than readState.Index' (duration: 82.244471ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:27.66253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.903595ms","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-02-04T01:56:27.662565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.868354ms","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-02-04T01:56:27.662596Z","caller":"traceutil/trace.go:171","msg":"trace[1522816649] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:543; }","duration":"301.016857ms","start":"2026-02-04T01:56:27.361566Z","end":"2026-02-04T01:56:27.662583Z","steps":["trace[1522816649] 'agreement among raft nodes before linearized reading' (duration: 300.782671ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:27.662605Z","caller":"traceutil/trace.go:171","msg":"trace[178310789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:543; }","duration":"300.934955ms","start":"2026-02-04T01:56:27.36166Z","end":"2026-02-04T01:56:27.662595Z","steps":["trace[178310789] 'agreement among raft nodes before linearized reading' (duration: 300.846103ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:27.66263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:27.361519Z","time spent":"301.104838ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-04T01:56:27.662689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.015854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-04T01:56:27.662714Z","caller":"traceutil/trace.go:171","msg":"trace[580239198] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:543; }","duration":"233.058454ms","start":"2026-02-04T01:56:27.429648Z","end":"2026-02-04T01:56:27.662707Z","steps":["trace[580239198] 'agreement among raft nodes before linearized reading' (duration: 233.018363ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:27.662904Z","caller":"traceutil/trace.go:171","msg":"trace[1201581522] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"365.230934ms","start":"2026-02-04T01:56:27.297664Z","end":"2026-02-04T01:56:27.662895Z","steps":["trace[1201581522] 'process raft request' (duration: 364.283584ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:27.66297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:27.297651Z","time spent":"365.272745ms","remote":"127.0.0.1:58110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-04T01:56:27.663011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.668999ms","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-02-04T01:56:27.663043Z","caller":"traceutil/trace.go:171","msg":"trace[428678559] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:543; }","duration":"300.721909ms","start":"2026-02-04T01:56:27.362312Z","end":"2026-02-04T01:56:27.663034Z","steps":["trace[428678559] 'agreement among raft nodes before linearized reading' (duration: 300.490884ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:27.663051Z","caller":"traceutil/trace.go:171","msg":"trace[665951825] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"166.786361ms","start":"2026-02-04T01:56:27.496257Z","end":"2026-02-04T01:56:27.663044Z","steps":["trace[665951825] 'process raft request' (duration: 165.793829ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:27.662639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:27.361654Z","time spent":"300.977396ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-04T01:56:27.663078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:27.362302Z","time spent":"300.76782ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-04T01:56:30.538447Z","caller":"traceutil/trace.go:171","msg":"trace[1994569632] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"207.613929ms","start":"2026-02-04T01:56:30.330821Z","end":"2026-02-04T01:56:30.538435Z","steps":["trace[1994569632] 'process raft request' (duration: 207.317222ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:39.68444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.603628ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:56:39.684717Z","caller":"traceutil/trace.go:171","msg":"trace[2070957849] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"339.228906ms","start":"2026-02-04T01:56:39.345476Z","end":"2026-02-04T01:56:39.684705Z","steps":["trace[2070957849] 'process raft request' (duration: 216.313341ms)","trace[2070957849] 'compare' (duration: 122.357113ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:39.684784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:39.345459Z","time spent":"339.309159ms","remote":"127.0.0.1:60540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1238,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T01:56:39.949931Z","caller":"traceutil/trace.go:171","msg":"trace[1050091734] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"220.363373ms","start":"2026-02-04T01:56:39.72955Z","end":"2026-02-04T01:56:39.949913Z","steps":["trace[1050091734] 'process raft request' (duration: 151.00108ms)","trace[1050091734] 'compare' (duration: 69.276031ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:56:40.163492Z","caller":"traceutil/trace.go:171","msg":"trace[1491522349] linearizableReadLoop","detail":"{readStateIndex:677; appliedIndex:674; }","duration":"420.051605ms","start":"2026-02-04T01:56:39.743418Z","end":"2026-02-04T01:56:40.16347Z","steps":["trace[1491522349] 'read index received' (duration: 137.769095ms)","trace[1491522349] 'applied index is now lower than readState.Index' (duration: 282.2816ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:56:40.163616Z","caller":"traceutil/trace.go:171","msg":"trace[58691964] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"433.810891ms","start":"2026-02-04T01:56:39.729796Z","end":"2026-02-04T01:56:40.163606Z","steps":["trace[58691964] 'process raft request' (duration: 433.625346ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:40.163624Z","caller":"traceutil/trace.go:171","msg":"trace[1667978654] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"433.824501ms","start":"2026-02-04T01:56:39.729768Z","end":"2026-02-04T01:56:40.163592Z","steps":["trace[1667978654] 'process raft request' (duration: 409.604623ms)","trace[1667978654] 'compare' (duration: 23.903871ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:40.163737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:39.729792Z","time spent":"433.850951ms","remote":"127.0.0.1:60520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2260,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-04T01:56:40.163757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:39.729762Z","time spent":"433.930573ms","remote":"127.0.0.1:60520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2261,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-04T01:56:40.164023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.596356ms","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-02-04T01:56:40.16405Z","caller":"traceutil/trace.go:171","msg":"trace[1289685413] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:655; }","duration":"420.653837ms","start":"2026-02-04T01:56:39.743387Z","end":"2026-02-04T01:56:40.164041Z","steps":["trace[1289685413] 'agreement among raft nodes before linearized reading' (duration: 420.516654ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:40.164069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:39.743372Z","time spent":"420.692729ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-04T01:56:40.164305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.848682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-04T01:56:40.164323Z","caller":"traceutil/trace.go:171","msg":"trace[1094651850] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:655; }","duration":"420.873212ms","start":"2026-02-04T01:56:39.743444Z","end":"2026-02-04T01:56:40.164317Z","steps":["trace[1094651850] 'agreement among raft nodes before linearized reading' (duration: 420.820451ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:40.164343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:39.743441Z","time spent":"420.898134ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-04T01:56:40.164425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.16301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-04T01:56:40.164451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.553812ms","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-02-04T01:56:40.164462Z","caller":"traceutil/trace.go:171","msg":"trace[762932829] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:655; }","duration":"390.231172ms","start":"2026-02-04T01:56:39.77422Z","end":"2026-02-04T01:56:40.164451Z","steps":["trace[762932829] 'agreement among raft nodes before linearized reading' (duration: 390.104958ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:40.16447Z","caller":"traceutil/trace.go:171","msg":"trace[1163010867] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:655; }","duration":"409.593862ms","start":"2026-02-04T01:56:39.754871Z","end":"2026-02-04T01:56:40.164465Z","steps":["trace[1163010867] 'agreement among raft nodes before linearized reading' (duration: 409.542681ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:40.164488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:39.754861Z","time spent":"409.623363ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-04T01:56:40.164495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:39.774205Z","time spent":"390.280803ms","remote":"127.0.0.1:58326","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-02-04T01:56:40.517454Z","caller":"traceutil/trace.go:171","msg":"trace[1426978987] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:678; }","duration":"202.287211ms","start":"2026-02-04T01:56:40.315142Z","end":"2026-02-04T01:56:40.517429Z","steps":["trace[1426978987] 'read index received' (duration: 121.202458ms)","trace[1426978987] 'applied index is now lower than readState.Index' (duration: 81.083603ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:56:40.51773Z","caller":"traceutil/trace.go:171","msg":"trace[1224242614] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"346.321565ms","start":"2026-02-04T01:56:40.171396Z","end":"2026-02-04T01:56:40.517718Z","steps":["trace[1224242614] 'process raft request' (duration: 264.993976ms)","trace[1224242614] 'compare' (duration: 80.687394ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:40.517826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:40.171388Z","time spent":"346.371656ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:56:40.517994Z","caller":"traceutil/trace.go:171","msg":"trace[1629992279] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"345.68602ms","start":"2026-02-04T01:56:40.172298Z","end":"2026-02-04T01:56:40.517984Z","steps":["trace[1629992279] 'process raft request' (duration: 345.007406ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:40.518044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:40.17229Z","time spent":"345.725941ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:56:40.518166Z","caller":"traceutil/trace.go:171","msg":"trace[989345941] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"344.453032ms","start":"2026-02-04T01:56:40.173702Z","end":"2026-02-04T01:56:40.518155Z","steps":["trace[989345941] 'process raft request' (duration: 343.681695ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:40.518227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:40.173693Z","time spent":"344.500544ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:56:40.518382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.245642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-04T01:56:40.51842Z","caller":"traceutil/trace.go:171","msg":"trace[1402037560] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:659; }","duration":"203.321814ms","start":"2026-02-04T01:56:40.315088Z","end":"2026-02-04T01:56:40.51841Z","steps":["trace[1402037560] 'agreement among raft nodes before linearized reading' (duration: 203.253912ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:40.52755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.064944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-04T01:56:40.527574Z","caller":"traceutil/trace.go:171","msg":"trace[795157687] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:659; }","duration":"157.119915ms","start":"2026-02-04T01:56:40.370447Z","end":"2026-02-04T01:56:40.527567Z","steps":["trace[795157687] 'agreement among raft nodes before linearized reading' (duration: 157.061484ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:42.052947Z","caller":"traceutil/trace.go:171","msg":"trace[382855005] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"795.342193ms","start":"2026-02-04T01:56:41.257585Z","end":"2026-02-04T01:56:42.052927Z","steps":["trace[382855005] 'process raft request' (duration: 795.22777ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:42.053068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:41.257572Z","time spent":"795.432204ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:56:42.053411Z","caller":"traceutil/trace.go:171","msg":"trace[453608588] linearizableReadLoop","detail":"{readStateIndex:683; appliedIndex:683; }","duration":"457.697592ms","start":"2026-02-04T01:56:41.595706Z","end":"2026-02-04T01:56:42.053404Z","steps":["trace[453608588] 'read index received' (duration: 457.694732ms)","trace[453608588] 'applied index is now lower than readState.Index' (duration: 2.37µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:42.053449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.740593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:56:42.053467Z","caller":"traceutil/trace.go:171","msg":"trace[1038729263] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:660; }","duration":"457.788464ms","start":"2026-02-04T01:56:41.595673Z","end":"2026-02-04T01:56:42.053461Z","steps":["trace[1038729263] 'agreement among raft nodes before linearized reading' (duration: 457.755964ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:42.05349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:41.595656Z","time spent":"457.829745ms","remote":"127.0.0.1:58082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-04T01:56:42.073656Z","caller":"traceutil/trace.go:171","msg":"trace[14143753] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"139.310929ms","start":"2026-02-04T01:56:41.934329Z","end":"2026-02-04T01:56:42.07364Z","steps":["trace[14143753] 'process raft request' (duration: 139.223477ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:42.536168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.443399ms","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-02-04T01:56:42.536221Z","caller":"traceutil/trace.go:171","msg":"trace[280413639] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:661; }","duration":"298.553331ms","start":"2026-02-04T01:56:42.237652Z","end":"2026-02-04T01:56:42.536206Z","steps":["trace[280413639] 'range keys from in-memory index tree' (duration: 298.357417ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:49.583444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.055659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:56:49.583497Z","caller":"traceutil/trace.go:171","msg":"trace[2135543906] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:721; }","duration":"337.128521ms","start":"2026-02-04T01:56:49.246356Z","end":"2026-02-04T01:56:49.583485Z","steps":["trace[2135543906] 'count revisions from in-memory index tree' (duration: 337.026027ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:49.583528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:49.246354Z","time spent":"337.166811ms","remote":"127.0.0.1:54048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-02-04T01:56:49.583735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.407357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:56:49.583757Z","caller":"traceutil/trace.go:171","msg":"trace[1373871972] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:721; }","duration":"337.448597ms","start":"2026-02-04T01:56:49.246302Z","end":"2026-02-04T01:56:49.583751Z","steps":["trace[1373871972] 'range keys from in-memory index tree' (duration: 337.308243ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:49.583777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:49.24629Z","time spent":"337.481748ms","remote":"127.0.0.1:54048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 "} {"level":"warn","ts":"2026-02-04T01:56:49.583913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.502198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:56:49.583935Z","caller":"traceutil/trace.go:171","msg":"trace[712958804] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:721; }","duration":"337.530558ms","start":"2026-02-04T01:56:49.246397Z","end":"2026-02-04T01:56:49.583927Z","steps":["trace[712958804] 'range keys from in-memory index tree' (duration: 337.478417ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:49.583954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:49.246394Z","time spent":"337.554649ms","remote":"127.0.0.1:54048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 "} {"level":"warn","ts":"2026-02-04T01:56:49.584091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.448149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:56:49.584115Z","caller":"traceutil/trace.go:171","msg":"trace[296301684] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:721; }","duration":"329.48123ms","start":"2026-02-04T01:56:49.254627Z","end":"2026-02-04T01:56:49.584109Z","steps":["trace[296301684] 'range keys from in-memory index tree' (duration: 329.428509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:49.584135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:49.254624Z","time spent":"329.505311ms","remote":"127.0.0.1:54054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-02-04T01:56:49.584281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.681394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:56:49.584301Z","caller":"traceutil/trace.go:171","msg":"trace[1480627293] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:721; }","duration":"329.704654ms","start":"2026-02-04T01:56:49.254591Z","end":"2026-02-04T01:56:49.584295Z","steps":["trace[1480627293] 'range keys from in-memory index tree' (duration: 329.594003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:49.58432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:49.254575Z","time spent":"329.739896ms","remote":"127.0.0.1:54054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 "} {"level":"warn","ts":"2026-02-04T01:56:49.584473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.799237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:56:49.584494Z","caller":"traceutil/trace.go:171","msg":"trace[1100113483] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:721; }","duration":"329.830248ms","start":"2026-02-04T01:56:49.254658Z","end":"2026-02-04T01:56:49.584488Z","steps":["trace[1100113483] 'count revisions from in-memory index tree' (duration: 329.777906ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:49.584514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:49.254656Z","time spent":"329.852178ms","remote":"127.0.0.1:54054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-04T01:56:49.584704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.503808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:56:49.584753Z","caller":"traceutil/trace.go:171","msg":"trace[1523902287] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:721; }","duration":"319.534139ms","start":"2026-02-04T01:56:49.265185Z","end":"2026-02-04T01:56:49.584719Z","steps":["trace[1523902287] 'range keys from in-memory index tree' (duration: 319.484937ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:49.584775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:49.265182Z","time spent":"319.58591ms","remote":"127.0.0.1:54062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 "} {"level":"warn","ts":"2026-02-04T01:56:49.584782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.60999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:56:49.584805Z","caller":"traceutil/trace.go:171","msg":"trace[1877339407] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:721; }","duration":"319.64391ms","start":"2026-02-04T01:56:49.265155Z","end":"2026-02-04T01:56:49.584799Z","steps":["trace[1877339407] 'range keys from in-memory index tree' (duration: 319.536429ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:49.584821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:49.26515Z","time spent":"319.666011ms","remote":"127.0.0.1:54062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 "} {"level":"warn","ts":"2026-02-04T01:56:49.584876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.650281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:56:49.584888Z","caller":"traceutil/trace.go:171","msg":"trace[2124092273] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:721; }","duration":"319.668191ms","start":"2026-02-04T01:56:49.265216Z","end":"2026-02-04T01:56:49.584884Z","steps":["trace[2124092273] 'count revisions from in-memory index tree' (duration: 319.630541ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:49.5849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:49.265213Z","time spent":"319.682892ms","remote":"127.0.0.1:54062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-02-04T01:56:49.584952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.451243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:56:49.584963Z","caller":"traceutil/trace.go:171","msg":"trace[1441673186] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:721; }","duration":"264.477373ms","start":"2026-02-04T01:56:49.320482Z","end":"2026-02-04T01:56:49.58496Z","steps":["trace[1441673186] 'count revisions from in-memory index tree' (duration: 264.420982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:49.585052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.1412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:56:49.585078Z","caller":"traceutil/trace.go:171","msg":"trace[1900500967] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:721; }","duration":"239.18725ms","start":"2026-02-04T01:56:49.345887Z","end":"2026-02-04T01:56:49.585074Z","steps":["trace[1900500967] 'count revisions from in-memory index tree' (duration: 239.104899ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:50.701053Z","caller":"traceutil/trace.go:171","msg":"trace[259102106] linearizableReadLoop","detail":"{readStateIndex:749; appliedIndex:748; }","duration":"123.794423ms","start":"2026-02-04T01:56:50.577243Z","end":"2026-02-04T01:56:50.701038Z","steps":["trace[259102106] 'read index received' (duration: 123.66417ms)","trace[259102106] 'applied index is now lower than readState.Index' (duration: 129.573µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:56:50.701128Z","caller":"traceutil/trace.go:171","msg":"trace[1405850386] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"128.175991ms","start":"2026-02-04T01:56:50.572945Z","end":"2026-02-04T01:56:50.70112Z","steps":["trace[1405850386] 'process raft request' (duration: 128.001557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:50.701248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.168291ms","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-02-04T01:56:50.701274Z","caller":"traceutil/trace.go:171","msg":"trace[831478854] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:723; }","duration":"119.211162ms","start":"2026-02-04T01:56:50.582057Z","end":"2026-02-04T01:56:50.701268Z","steps":["trace[831478854] 'agreement among raft nodes before linearized reading' (duration: 119.11677ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:50.701384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.138662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-04T01:56:50.701396Z","caller":"traceutil/trace.go:171","msg":"trace[2001780891] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:723; }","duration":"124.165082ms","start":"2026-02-04T01:56:50.577227Z","end":"2026-02-04T01:56:50.701392Z","steps":["trace[2001780891] 'agreement among raft nodes before linearized reading' (duration: 124.129941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:50.701519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.219412ms","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-02-04T01:56:50.701532Z","caller":"traceutil/trace.go:171","msg":"trace[1035591485] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:723; }","duration":"105.241982ms","start":"2026-02-04T01:56:50.596286Z","end":"2026-02-04T01:56:50.701528Z","steps":["trace[1035591485] 'agreement among raft nodes before linearized reading' (duration: 105.208601ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:50.868457Z","caller":"traceutil/trace.go:171","msg":"trace[1551009808] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"135.452944ms","start":"2026-02-04T01:56:50.73299Z","end":"2026-02-04T01:56:50.868443Z","steps":["trace[1551009808] 'process raft request' (duration: 135.425123ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:50.86865Z","caller":"traceutil/trace.go:171","msg":"trace[1758754323] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"137.150411ms","start":"2026-02-04T01:56:50.731492Z","end":"2026-02-04T01:56:50.868643Z","steps":["trace[1758754323] 'process raft request' (duration: 82.212699ms)","trace[1758754323] 'compare' (duration: 54.617574ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:56:52.251075Z","caller":"traceutil/trace.go:171","msg":"trace[1419439811] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"159.071269ms","start":"2026-02-04T01:56:52.091988Z","end":"2026-02-04T01:56:52.251059Z","steps":["trace[1419439811] 'process raft request' (duration: 158.987927ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:52.448391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.723701ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:56:52.44849Z","caller":"traceutil/trace.go:171","msg":"trace[1599452529] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"300.159278ms","start":"2026-02-04T01:56:52.148314Z","end":"2026-02-04T01:56:52.448473Z","steps":["trace[1599452529] 'process raft request' (duration: 185.275133ms)","trace[1599452529] 'compare' (duration: 114.515166ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:52.448547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:52.148298Z","time spent":"300.223009ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:56:52.93577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.841192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:56:52.935826Z","caller":"traceutil/trace.go:171","msg":"trace[481378468] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:728; }","duration":"340.935125ms","start":"2026-02-04T01:56:52.594877Z","end":"2026-02-04T01:56:52.935812Z","steps":["trace[481378468] 'range keys from in-memory index tree' (duration: 340.78541ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:52.935853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:52.594861Z","time spent":"340.985876ms","remote":"127.0.0.1:58078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:56:52.936086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.79393ms","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-02-04T01:56:52.936106Z","caller":"traceutil/trace.go:171","msg":"trace[1601015682] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:728; }","duration":"206.812ms","start":"2026-02-04T01:56:52.729286Z","end":"2026-02-04T01:56:52.936098Z","steps":["trace[1601015682] 'range keys from in-memory index tree' (duration: 206.698829ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:52.936172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.051352ms","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-02-04T01:56:52.936216Z","caller":"traceutil/trace.go:171","msg":"trace[582351518] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:728; }","duration":"178.122543ms","start":"2026-02-04T01:56:52.758083Z","end":"2026-02-04T01:56:52.936205Z","steps":["trace[582351518] 'range keys from in-memory index tree' (duration: 177.98241ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:03.646592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.872184ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:57:03.649628Z","caller":"traceutil/trace.go:171","msg":"trace[59303327] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"514.367303ms","start":"2026-02-04T01:57:03.135211Z","end":"2026-02-04T01:57:03.649578Z","steps":["trace[59303327] 'process raft request' (duration: 410.39322ms)","trace[59303327] 'compare' (duration: 100.782492ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:57:03.65063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:03.135194Z","time spent":"514.884924ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:57:04.700069Z","caller":"traceutil/trace.go:171","msg":"trace[254115186] linearizableReadLoop","detail":"{readStateIndex:796; appliedIndex:795; }","duration":"102.994391ms","start":"2026-02-04T01:57:04.597051Z","end":"2026-02-04T01:57:04.700046Z","steps":["trace[254115186] 'read index received' (duration: 102.781746ms)","trace[254115186] 'applied index is now lower than readState.Index' (duration: 211.575µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:57:04.700724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.636135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:57:04.70202Z","caller":"traceutil/trace.go:171","msg":"trace[59535522] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:767; }","duration":"105.052527ms","start":"2026-02-04T01:57:04.596959Z","end":"2026-02-04T01:57:04.702012Z","steps":["trace[59535522] 'agreement among raft nodes before linearized reading' (duration: 103.488512ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:04.701952Z","caller":"traceutil/trace.go:171","msg":"trace[2021865647] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"413.415958ms","start":"2026-02-04T01:57:04.28852Z","end":"2026-02-04T01:57:04.701936Z","steps":["trace[2021865647] 'process raft request' (duration: 411.374322ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:04.702595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:04.288509Z","time spent":"413.792656ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:57:05.108644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.517711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:57:05.108762Z","caller":"traceutil/trace.go:171","msg":"trace[1918948955] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:767; }","duration":"111.676484ms","start":"2026-02-04T01:57:04.99704Z","end":"2026-02-04T01:57:05.108716Z","steps":["trace[1918948955] 'count revisions from in-memory index tree' (duration: 111.425479ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:05.10892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.990302ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:57:05.109008Z","caller":"traceutil/trace.go:171","msg":"trace[1138098184] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:767; }","duration":"166.089864ms","start":"2026-02-04T01:57:04.942904Z","end":"2026-02-04T01:57:05.108994Z","steps":["trace[1138098184] 'range keys from in-memory index tree' (duration: 165.975962ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:05.633871Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421301539407754908,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-04T01:57:05.676939Z","caller":"traceutil/trace.go:171","msg":"trace[1221232998] linearizableReadLoop","detail":"{readStateIndex:797; appliedIndex:796; }","duration":"543.876259ms","start":"2026-02-04T01:57:05.133046Z","end":"2026-02-04T01:57:05.676922Z","steps":["trace[1221232998] 'read index received' (duration: 543.664114ms)","trace[1221232998] 'applied index is now lower than readState.Index' (duration: 209.704µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:57:05.677143Z","caller":"traceutil/trace.go:171","msg":"trace[1785427553] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"562.440412ms","start":"2026-02-04T01:57:05.114695Z","end":"2026-02-04T01:57:05.677135Z","steps":["trace[1785427553] 'process raft request' (duration: 562.093735ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:05.677247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:05.114678Z","time spent":"562.509233ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:57:05.677852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.744357ms","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-02-04T01:57:05.678063Z","caller":"traceutil/trace.go:171","msg":"trace[2045425156] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:768; }","duration":"544.919251ms","start":"2026-02-04T01:57:05.133042Z","end":"2026-02-04T01:57:05.677961Z","steps":["trace[2045425156] 'agreement among raft nodes before linearized reading' (duration: 544.538293ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:05.678248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:05.133009Z","time spent":"545.219748ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-04T01:57:05.678798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.688671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-04T01:57:05.67904Z","caller":"traceutil/trace.go:171","msg":"trace[1136511804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:768; }","duration":"448.813213ms","start":"2026-02-04T01:57:05.230026Z","end":"2026-02-04T01:57:05.67884Z","steps":["trace[1136511804] 'agreement among raft nodes before linearized reading' (duration: 448.540497ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:05.679181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:05.230011Z","time spent":"449.153491ms","remote":"127.0.0.1:58326","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-02-04T01:57:05.680407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.758272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-04T01:57:05.680546Z","caller":"traceutil/trace.go:171","msg":"trace[444830196] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:768; }","duration":"252.965217ms","start":"2026-02-04T01:57:05.427562Z","end":"2026-02-04T01:57:05.680527Z","steps":["trace[444830196] 'agreement among raft nodes before linearized reading' (duration: 252.748282ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:05.868099Z","caller":"traceutil/trace.go:171","msg":"trace[874657394] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"180.184697ms","start":"2026-02-04T01:57:05.687885Z","end":"2026-02-04T01:57:05.86807Z","steps":["trace[874657394] 'process raft request' (duration: 131.802121ms)","trace[874657394] 'compare' (duration: 47.822294ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:57:06.107137Z","caller":"traceutil/trace.go:171","msg":"trace[2138626038] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"419.014661ms","start":"2026-02-04T01:57:05.688097Z","end":"2026-02-04T01:57:06.107111Z","steps":["trace[2138626038] 'process raft request' (duration: 341.394665ms)","trace[2138626038] 'compare' (duration: 76.974042ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:57:06.107286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:05.688076Z","time spent":"419.137363ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:57:06.107592Z","caller":"traceutil/trace.go:171","msg":"trace[896422587] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"418.371327ms","start":"2026-02-04T01:57:05.689205Z","end":"2026-02-04T01:57:06.107577Z","steps":["trace[896422587] 'process raft request' (duration: 417.855146ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:06.108158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:05.689198Z","time spent":"418.91602ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:57:18.662743Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421301539407755098,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-04T01:57:18.914939Z","caller":"traceutil/trace.go:171","msg":"trace[951416478] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"999.171207ms","start":"2026-02-04T01:57:17.915754Z","end":"2026-02-04T01:57:18.914925Z","steps":["trace[951416478] 'process raft request' (duration: 999.095885ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:18.915118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:17.915745Z","time spent":"999.300429ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:57:19.354222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.064969ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:57:19.354466Z","caller":"traceutil/trace.go:171","msg":"trace[2121598560] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"1.097256089s","start":"2026-02-04T01:57:18.257196Z","end":"2026-02-04T01:57:19.354452Z","steps":["trace[2121598560] 'process raft request' (duration: 777.901454ms)","trace[2121598560] 'compare' (duration: 318.945606ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:57:19.354515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:18.257171Z","time spent":"1.097320151s","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:57:19.354706Z","caller":"traceutil/trace.go:171","msg":"trace[635345293] linearizableReadLoop","detail":"{readStateIndex:887; appliedIndex:886; }","duration":"1.192852246s","start":"2026-02-04T01:57:18.161846Z","end":"2026-02-04T01:57:19.354698Z","steps":["trace[635345293] 'read index received' (duration: 753.671546ms)","trace[635345293] 'applied index is now lower than readState.Index' (duration: 439.1798ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:57:19.360083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.192914458s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-04T01:57:19.360112Z","caller":"traceutil/trace.go:171","msg":"trace[1487199245] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:855; }","duration":"1.192957318s","start":"2026-02-04T01:57:18.167146Z","end":"2026-02-04T01:57:19.360103Z","steps":["trace[1487199245] 'agreement among raft nodes before linearized reading' (duration: 1.192871066s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:19.360133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:18.167138Z","time spent":"1.192990319s","remote":"127.0.0.1:58326","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-02-04T01:57:19.360297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.1984476s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-04T01:57:19.360315Z","caller":"traceutil/trace.go:171","msg":"trace[1832639161] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:855; }","duration":"1.198489242s","start":"2026-02-04T01:57:18.161821Z","end":"2026-02-04T01:57:19.36031Z","steps":["trace[1832639161] 'agreement among raft nodes before linearized reading' (duration: 1.192945878s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:19.360332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:18.16181Z","time spent":"1.198517792s","remote":"127.0.0.1:58326","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-02-04T01:57:19.360415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.237265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-04T01:57:19.360432Z","caller":"traceutil/trace.go:171","msg":"trace[1655840140] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:855; }","duration":"454.280886ms","start":"2026-02-04T01:57:18.906145Z","end":"2026-02-04T01:57:19.360426Z","steps":["trace[1655840140] 'agreement among raft nodes before linearized reading' (duration: 454.251556ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:19.360448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:18.906129Z","time spent":"454.314067ms","remote":"127.0.0.1:58162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":4,"response size":31,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-02-04T01:57:19.360556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.696847ms","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-02-04T01:57:19.360574Z","caller":"traceutil/trace.go:171","msg":"trace[316238735] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:855; }","duration":"504.738738ms","start":"2026-02-04T01:57:18.855829Z","end":"2026-02-04T01:57:19.360567Z","steps":["trace[316238735] 'agreement among raft nodes before linearized reading' (duration: 504.692297ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:19.360607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:18.855814Z","time spent":"504.788439ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-04T01:57:19.360681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.247553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:57:19.360697Z","caller":"traceutil/trace.go:171","msg":"trace[18461432] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:855; }","duration":"765.307835ms","start":"2026-02-04T01:57:18.595383Z","end":"2026-02-04T01:57:19.360691Z","steps":["trace[18461432] 'agreement among raft nodes before linearized reading' (duration: 765.284345ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:19.360711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:18.595365Z","time spent":"765.342905ms","remote":"127.0.0.1:58078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:57:19.36082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.077356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:57:19.360838Z","caller":"traceutil/trace.go:171","msg":"trace[2012152583] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:855; }","duration":"826.133638ms","start":"2026-02-04T01:57:18.534699Z","end":"2026-02-04T01:57:19.360833Z","steps":["trace[2012152583] 'agreement among raft nodes before linearized reading' (duration: 826.103238ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:19.360854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:18.534679Z","time spent":"826.171229ms","remote":"127.0.0.1:58148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"info","ts":"2026-02-04T01:57:21.620549Z","caller":"traceutil/trace.go:171","msg":"trace[1811929754] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"187.936928ms","start":"2026-02-04T01:57:21.432589Z","end":"2026-02-04T01:57:21.620526Z","steps":["trace[1811929754] 'process raft request' (duration: 155.490515ms)","trace[1811929754] 'compare' (duration: 32.30373ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:57:25.850208Z","caller":"traceutil/trace.go:171","msg":"trace[1822937467] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"407.682257ms","start":"2026-02-04T01:57:25.442519Z","end":"2026-02-04T01:57:25.850201Z","steps":["trace[1822937467] 'process raft request' (duration: 407.303679ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:25.850301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:25.442504Z","time spent":"407.744209ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:57:25.850169Z","caller":"traceutil/trace.go:171","msg":"trace[210008592] linearizableReadLoop","detail":"{readStateIndex:907; appliedIndex:906; }","duration":"374.101629ms","start":"2026-02-04T01:57:25.476044Z","end":"2026-02-04T01:57:25.850146Z","steps":["trace[210008592] 'read index received' (duration: 373.710181ms)","trace[210008592] 'applied index is now lower than readState.Index' (duration: 390.648µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:57:25.850607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.567679ms","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-02-04T01:57:25.850631Z","caller":"traceutil/trace.go:171","msg":"trace[1020511901] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:873; }","duration":"374.625291ms","start":"2026-02-04T01:57:25.475997Z","end":"2026-02-04T01:57:25.850622Z","steps":["trace[1020511901] 'agreement among raft nodes before linearized reading' (duration: 374.543559ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:25.850651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:25.475988Z","time spent":"374.658621ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-04T01:57:25.852264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.627693ms","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-02-04T01:57:25.852291Z","caller":"traceutil/trace.go:171","msg":"trace[394890366] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:873; }","duration":"219.987694ms","start":"2026-02-04T01:57:25.632295Z","end":"2026-02-04T01:57:25.852283Z","steps":["trace[394890366] 'agreement among raft nodes before linearized reading' (duration: 218.611253ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:32.099086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.624337ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:57:32.099147Z","caller":"traceutil/trace.go:171","msg":"trace[975737015] linearizableReadLoop","detail":"{readStateIndex:925; appliedIndex:924; }","duration":"160.360555ms","start":"2026-02-04T01:57:31.938777Z","end":"2026-02-04T01:57:32.099138Z","steps":["trace[975737015] 'read index received' (duration: 44.524933ms)","trace[975737015] 'applied index is now lower than readState.Index' (duration: 115.834942ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:57:32.099196Z","caller":"traceutil/trace.go:171","msg":"trace[1163158238] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"224.635377ms","start":"2026-02-04T01:57:31.874555Z","end":"2026-02-04T01:57:32.09919Z","steps":["trace[1163158238] 'process raft request' (duration: 108.787115ms)","trace[1163158238] 'compare' (duration: 115.544375ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:57:32.099318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.546269ms","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-02-04T01:57:32.099331Z","caller":"traceutil/trace.go:171","msg":"trace[1520770200] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:890; }","duration":"160.578779ms","start":"2026-02-04T01:57:31.938747Z","end":"2026-02-04T01:57:32.099326Z","steps":["trace[1520770200] 'agreement among raft nodes before linearized reading' (duration: 160.510968ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:32.099414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.438887ms","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-02-04T01:57:32.099425Z","caller":"traceutil/trace.go:171","msg":"trace[1284968010] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:890; }","duration":"130.474258ms","start":"2026-02-04T01:57:31.968947Z","end":"2026-02-04T01:57:32.099422Z","steps":["trace[1284968010] 'agreement among raft nodes before linearized reading' (duration: 130.437978ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:32.270281Z","caller":"traceutil/trace.go:171","msg":"trace[1694861133] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"166.595454ms","start":"2026-02-04T01:57:32.10367Z","end":"2026-02-04T01:57:32.270265Z","steps":["trace[1694861133] 'process raft request' (duration: 154.424792ms)","trace[1694861133] 'compare' (duration: 12.09531ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:57:33.468093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"874.993682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:57:33.468137Z","caller":"traceutil/trace.go:171","msg":"trace[163307011] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:892; }","duration":"875.069164ms","start":"2026-02-04T01:57:32.593057Z","end":"2026-02-04T01:57:33.468126Z","steps":["trace[163307011] 'range keys from in-memory index tree' (duration: 874.955582ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:33.468158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:32.593043Z","time spent":"875.111275ms","remote":"127.0.0.1:58078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:57:33.468339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.805079ms","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-02-04T01:57:33.46843Z","caller":"traceutil/trace.go:171","msg":"trace[24151389] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:892; }","duration":"311.930252ms","start":"2026-02-04T01:57:33.156474Z","end":"2026-02-04T01:57:33.468404Z","steps":["trace[24151389] 'range keys from in-memory index tree' (duration: 311.600475ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:33.468468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:33.156456Z","time spent":"312.003254ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-04T01:57:33.561734Z","caller":"traceutil/trace.go:171","msg":"trace[613042939] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"402.971962ms","start":"2026-02-04T01:57:33.158748Z","end":"2026-02-04T01:57:33.56172Z","steps":["trace[613042939] 'process raft request' (duration: 402.694866ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:33.561841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:33.158731Z","time spent":"403.044973ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:57:33.668596Z","caller":"traceutil/trace.go:171","msg":"trace[1035606305] linearizableReadLoop","detail":"{readStateIndex:929; appliedIndex:927; }","duration":"194.23211ms","start":"2026-02-04T01:57:33.474347Z","end":"2026-02-04T01:57:33.668579Z","steps":["trace[1035606305] 'read index received' (duration: 87.144413ms)","trace[1035606305] 'applied index is now lower than readState.Index' (duration: 107.087197ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:57:33.66869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.330102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:57:33.668705Z","caller":"traceutil/trace.go:171","msg":"trace[91000479] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:894; }","duration":"194.371584ms","start":"2026-02-04T01:57:33.474329Z","end":"2026-02-04T01:57:33.668701Z","steps":["trace[91000479] 'agreement among raft nodes before linearized reading' (duration: 194.304892ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:33.668884Z","caller":"traceutil/trace.go:171","msg":"trace[1216031762] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"194.590469ms","start":"2026-02-04T01:57:33.474287Z","end":"2026-02-04T01:57:33.668877Z","steps":["trace[1216031762] 'process raft request' (duration: 139.172733ms)","trace[1216031762] 'compare' (duration: 55.058288ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:57:34.381307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.842455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.30\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-04T01:57:34.381377Z","caller":"traceutil/trace.go:171","msg":"trace[909310129] range","detail":"{range_begin:/registry/masterleases/199.204.45.30; range_end:; response_count:1; response_revision:894; }","duration":"540.939168ms","start":"2026-02-04T01:57:33.84042Z","end":"2026-02-04T01:57:34.38136Z","steps":["trace[909310129] 'range keys from in-memory index tree' (duration: 540.701702ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:34.381873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:33.840407Z","time spent":"541.449669ms","remote":"127.0.0.1:58102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.30\" "} {"level":"warn","ts":"2026-02-04T01:57:34.385393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.358995ms","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-02-04T01:57:34.385421Z","caller":"traceutil/trace.go:171","msg":"trace[1664797787] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:894; }","duration":"446.088094ms","start":"2026-02-04T01:57:33.939324Z","end":"2026-02-04T01:57:34.385413Z","steps":["trace[1664797787] 'range keys from in-memory index tree' (duration: 444.225602ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:34.385466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:33.939312Z","time spent":"446.125435ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-04T01:57:34.385733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.96824ms","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-02-04T01:57:34.385789Z","caller":"traceutil/trace.go:171","msg":"trace[680971008] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:894; }","duration":"280.027222ms","start":"2026-02-04T01:57:34.105726Z","end":"2026-02-04T01:57:34.385753Z","steps":["trace[680971008] 'range keys from in-memory index tree' (duration: 279.784737ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:34.386254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.958036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-04T01:57:34.386278Z","caller":"traceutil/trace.go:171","msg":"trace[1613279939] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:894; }","duration":"252.024117ms","start":"2026-02-04T01:57:34.134247Z","end":"2026-02-04T01:57:34.386271Z","steps":["trace[1613279939] 'count revisions from in-memory index tree' (duration: 251.915005ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:34.386766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.508367ms","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-02-04T01:57:34.38679Z","caller":"traceutil/trace.go:171","msg":"trace[1760075376] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:894; }","duration":"190.557198ms","start":"2026-02-04T01:57:34.196226Z","end":"2026-02-04T01:57:34.386783Z","steps":["trace[1760075376] 'range keys from in-memory index tree' (duration: 190.402735ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:34.387242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.47838ms","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-02-04T01:57:34.387264Z","caller":"traceutil/trace.go:171","msg":"trace[540008105] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:894; }","duration":"113.50316ms","start":"2026-02-04T01:57:34.273755Z","end":"2026-02-04T01:57:34.387258Z","steps":["trace[540008105] 'range keys from in-memory index tree' (duration: 113.386727ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:34.947439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.738953ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:57:34.947595Z","caller":"traceutil/trace.go:171","msg":"trace[1619150652] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"554.431998ms","start":"2026-02-04T01:57:34.393143Z","end":"2026-02-04T01:57:34.947575Z","steps":["trace[1619150652] 'process raft request' (duration: 554.357066ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:34.947631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:34.393137Z","time spent":"554.478659ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:57:34.947683Z","caller":"traceutil/trace.go:171","msg":"trace[1586704160] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"555.215735ms","start":"2026-02-04T01:57:34.392454Z","end":"2026-02-04T01:57:34.94767Z","steps":["trace[1586704160] 'process raft request' (duration: 206.195946ms)","trace[1586704160] 'compare' (duration: 348.641731ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:57:34.94778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:34.39244Z","time spent":"555.313067ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:57:34.947855Z","caller":"traceutil/trace.go:171","msg":"trace[928058697] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"552.940244ms","start":"2026-02-04T01:57:34.39491Z","end":"2026-02-04T01:57:34.94785Z","steps":["trace[928058697] 'process raft request' (duration: 552.649848ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:34.947885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:34.394904Z","time spent":"552.966805ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:57:34.948009Z","caller":"traceutil/trace.go:171","msg":"trace[617110837] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"553.063307ms","start":"2026-02-04T01:57:34.39494Z","end":"2026-02-04T01:57:34.948003Z","steps":["trace[617110837] 'process raft request' (duration: 552.889413ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:34.948038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:34.394937Z","time spent":"553.087489ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:57:35.10082Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421301539407755209,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:57:36.125539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"985.809913ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:57:36.125624Z","caller":"traceutil/trace.go:171","msg":"trace[861310393] linearizableReadLoop","detail":"{readStateIndex:935; appliedIndex:934; }","duration":"1.525078934s","start":"2026-02-04T01:57:34.600531Z","end":"2026-02-04T01:57:36.12561Z","steps":["trace[861310393] 'read index received' (duration: 539.078627ms)","trace[861310393] 'applied index is now lower than readState.Index' (duration: 985.999117ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:57:36.127247Z","caller":"traceutil/trace.go:171","msg":"trace[99845676] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"1.680237811s","start":"2026-02-04T01:57:34.446994Z","end":"2026-02-04T01:57:36.127231Z","steps":["trace[99845676] 'process raft request' (duration: 692.6774ms)","trace[99845676] 'compare' (duration: 984.695907ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:57:36.127422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:34.446945Z","time spent":"1.680343493s","remote":"127.0.0.1:58102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:57:36.127849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.527310964s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:57:36.12789Z","caller":"traceutil/trace.go:171","msg":"trace[260411075] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:899; }","duration":"1.527388826s","start":"2026-02-04T01:57:34.600491Z","end":"2026-02-04T01:57:36.12788Z","steps":["trace[260411075] 'agreement among raft nodes before linearized reading' (duration: 1.527279984s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:36.128174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:34.600473Z","time spent":"1.52760059s","remote":"127.0.0.1:58082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:57:36.1285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.384139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:57:36.128538Z","caller":"traceutil/trace.go:171","msg":"trace[2058701988] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:899; }","duration":"559.45232ms","start":"2026-02-04T01:57:35.569076Z","end":"2026-02-04T01:57:36.128528Z","steps":["trace[2058701988] 'agreement among raft nodes before linearized reading' (duration: 559.387269ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:36.12883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:35.56906Z","time spent":"559.645825ms","remote":"127.0.0.1:58078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:57:36.129153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.039634ms","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-02-04T01:57:36.129182Z","caller":"traceutil/trace.go:171","msg":"trace[794686451] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:899; }","duration":"451.165876ms","start":"2026-02-04T01:57:35.678007Z","end":"2026-02-04T01:57:36.129173Z","steps":["trace[794686451] 'agreement among raft nodes before linearized reading' (duration: 451.060824ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:36.129209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:35.677989Z","time spent":"451.214097ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-04T01:57:36.640599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.074543ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:57:36.640979Z","caller":"traceutil/trace.go:171","msg":"trace[1379284337] linearizableReadLoop","detail":"{readStateIndex:936; appliedIndex:935; }","duration":"505.032806ms","start":"2026-02-04T01:57:36.13593Z","end":"2026-02-04T01:57:36.640962Z","steps":["trace[1379284337] 'read index received' (duration: 333.469912ms)","trace[1379284337] 'applied index is now lower than readState.Index' (duration: 171.561194ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:57:36.641074Z","caller":"traceutil/trace.go:171","msg":"trace[2030050374] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"506.53388ms","start":"2026-02-04T01:57:36.134516Z","end":"2026-02-04T01:57:36.64105Z","steps":["trace[2030050374] 'process raft request' (duration: 334.934466ms)","trace[2030050374] 'compare' (duration: 170.825467ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:57:36.64119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:36.134509Z","time spent":"506.618463ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:57:36.641276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.334403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:57:36.641343Z","caller":"traceutil/trace.go:171","msg":"trace[2130707142] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:900; }","duration":"505.413155ms","start":"2026-02-04T01:57:36.135918Z","end":"2026-02-04T01:57:36.641331Z","steps":["trace[2130707142] 'agreement among raft nodes before linearized reading' (duration: 505.270312ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:36.641423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:36.135913Z","time spent":"505.498427ms","remote":"127.0.0.1:58082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:57:36.641547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.005846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-04T01:57:36.641588Z","caller":"traceutil/trace.go:171","msg":"trace[70772944] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:900; }","duration":"505.057257ms","start":"2026-02-04T01:57:36.136523Z","end":"2026-02-04T01:57:36.64158Z","steps":["trace[70772944] 'agreement among raft nodes before linearized reading' (duration: 504.982135ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:36.641615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:36.136518Z","time spent":"505.092107ms","remote":"127.0.0.1:58342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-02-04T01:57:37.36491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.799881ms","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-02-04T01:57:37.364978Z","caller":"traceutil/trace.go:171","msg":"trace[1651737918] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:900; }","duration":"412.907223ms","start":"2026-02-04T01:57:36.952054Z","end":"2026-02-04T01:57:37.364961Z","steps":["trace[1651737918] 'range keys from in-memory index tree' (duration: 412.644687ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:37.365028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:36.952034Z","time spent":"412.973665ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-04T01:57:37.365252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.31102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-04T01:57:37.365282Z","caller":"traceutil/trace.go:171","msg":"trace[510276742] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:900; }","duration":"412.363122ms","start":"2026-02-04T01:57:36.952909Z","end":"2026-02-04T01:57:37.365272Z","steps":["trace[510276742] 'range keys from in-memory index tree' (duration: 412.220018ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:37.365309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:36.9529Z","time spent":"412.402362ms","remote":"127.0.0.1:58326","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-02-04T01:57:37.365474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.869608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-04T01:57:37.3655Z","caller":"traceutil/trace.go:171","msg":"trace[87138949] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:900; }","duration":"410.913609ms","start":"2026-02-04T01:57:36.954579Z","end":"2026-02-04T01:57:37.365492Z","steps":["trace[87138949] 'range keys from in-memory index tree' (duration: 410.786216ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:37.365526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:36.954569Z","time spent":"410.951209ms","remote":"127.0.0.1:58326","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-02-04T01:57:37.365678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.978571ms","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-02-04T01:57:37.365704Z","caller":"traceutil/trace.go:171","msg":"trace[1068944569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:900; }","duration":"411.003441ms","start":"2026-02-04T01:57:36.954692Z","end":"2026-02-04T01:57:37.365696Z","steps":["trace[1068944569] 'range keys from in-memory index tree' (duration: 410.873559ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:37.365732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:36.95467Z","time spent":"411.056133ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-04T01:57:37.365939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.713289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-04T01:57:37.365968Z","caller":"traceutil/trace.go:171","msg":"trace[1381689422] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:900; }","duration":"259.769981ms","start":"2026-02-04T01:57:37.10619Z","end":"2026-02-04T01:57:37.36596Z","steps":["trace[1381689422] 'count revisions from in-memory index tree' (duration: 259.650978ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:37.777374Z","caller":"traceutil/trace.go:171","msg":"trace[1709315436] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"402.452341ms","start":"2026-02-04T01:57:37.374903Z","end":"2026-02-04T01:57:37.777356Z","steps":["trace[1709315436] 'process raft request' (duration: 402.346748ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:37.777502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:37.374882Z","time spent":"402.561353ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:57:38.293855Z","caller":"traceutil/trace.go:171","msg":"trace[765581946] linearizableReadLoop","detail":"{readStateIndex:940; appliedIndex:937; }","duration":"699.031612ms","start":"2026-02-04T01:57:37.594809Z","end":"2026-02-04T01:57:38.29384Z","steps":["trace[765581946] 'read index received' (duration: 183.105612ms)","trace[765581946] 'applied index is now lower than readState.Index' (duration: 515.92534ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:57:38.293949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.127364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:57:38.293964Z","caller":"traceutil/trace.go:171","msg":"trace[1132375057] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:904; }","duration":"699.182615ms","start":"2026-02-04T01:57:37.594777Z","end":"2026-02-04T01:57:38.29396Z","steps":["trace[1132375057] 'agreement among raft nodes before linearized reading' (duration: 699.126263ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:38.293981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:37.594762Z","time spent":"699.215116ms","remote":"127.0.0.1:58082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-04T01:57:38.294131Z","caller":"traceutil/trace.go:171","msg":"trace[832800382] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"919.206499ms","start":"2026-02-04T01:57:37.374918Z","end":"2026-02-04T01:57:38.294124Z","steps":["trace[832800382] 'process raft request' (duration: 820.849457ms)","trace[832800382] 'compare' (duration: 97.946813ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:57:38.294174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:37.374869Z","time spent":"919.274331ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:57:38.294236Z","caller":"traceutil/trace.go:171","msg":"trace[463183459] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"919.195309ms","start":"2026-02-04T01:57:37.375035Z","end":"2026-02-04T01:57:38.294231Z","steps":["trace[463183459] 'process raft request' (duration: 918.766259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:38.294258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:37.375025Z","time spent":"919.220209ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:57:38.294313Z","caller":"traceutil/trace.go:171","msg":"trace[1488629938] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"919.171828ms","start":"2026-02-04T01:57:37.375137Z","end":"2026-02-04T01:57:38.294309Z","steps":["trace[1488629938] 'process raft request' (duration: 918.684198ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:38.294336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:37.37512Z","time spent":"919.201509ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:57:38.909863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.159207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:57:38.909934Z","caller":"traceutil/trace.go:171","msg":"trace[651167036] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:904; }","duration":"316.2563ms","start":"2026-02-04T01:57:38.593661Z","end":"2026-02-04T01:57:38.909918Z","steps":["trace[651167036] 'range keys from in-memory index tree' (duration: 316.093086ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:38.90997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:38.593648Z","time spent":"316.314381ms","remote":"127.0.0.1:58078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:57:38.910306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.683415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-04T01:57:38.910334Z","caller":"traceutil/trace.go:171","msg":"trace[1353912338] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:904; }","duration":"262.746007ms","start":"2026-02-04T01:57:38.647579Z","end":"2026-02-04T01:57:38.910325Z","steps":["trace[1353912338] 'range keys from in-memory index tree' (duration: 262.548913ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:42.670443Z","caller":"traceutil/trace.go:171","msg":"trace[848182319] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"113.915328ms","start":"2026-02-04T01:57:42.556514Z","end":"2026-02-04T01:57:42.67043Z","steps":["trace[848182319] 'process raft request' (duration: 107.177538ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:42.673319Z","caller":"traceutil/trace.go:171","msg":"trace[797235056] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"116.199319ms","start":"2026-02-04T01:57:42.557112Z","end":"2026-02-04T01:57:42.673312Z","steps":["trace[797235056] 'process raft request' (duration: 115.981775ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:42.673441Z","caller":"traceutil/trace.go:171","msg":"trace[1631398181] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"114.561684ms","start":"2026-02-04T01:57:42.558874Z","end":"2026-02-04T01:57:42.673436Z","steps":["trace[1631398181] 'process raft request' (duration: 114.263508ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:43.910994Z","caller":"traceutil/trace.go:171","msg":"trace[1091082209] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"115.642277ms","start":"2026-02-04T01:57:43.795336Z","end":"2026-02-04T01:57:43.910978Z","steps":["trace[1091082209] 'process raft request' (duration: 115.420232ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:44.054796Z","caller":"traceutil/trace.go:171","msg":"trace[1245942531] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"139.873918ms","start":"2026-02-04T01:57:43.914903Z","end":"2026-02-04T01:57:44.054777Z","steps":["trace[1245942531] 'process raft request' (duration: 139.708824ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:51.022985Z","caller":"traceutil/trace.go:171","msg":"trace[245103779] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"108.834765ms","start":"2026-02-04T01:57:50.914126Z","end":"2026-02-04T01:57:51.022961Z","steps":["trace[245103779] 'process raft request' (duration: 73.964528ms)","trace[245103779] 'compare' (duration: 34.54683ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:57:51.023301Z","caller":"traceutil/trace.go:171","msg":"trace[2100367677] linearizableReadLoop","detail":"{readStateIndex:1028; appliedIndex:1027; }","duration":"109.040151ms","start":"2026-02-04T01:57:50.91425Z","end":"2026-02-04T01:57:51.023291Z","steps":["trace[2100367677] 'read index received' (duration: 73.849586ms)","trace[2100367677] 'applied index is now lower than readState.Index' (duration: 35.189105ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:57:51.023616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.301816ms","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:527"} {"level":"info","ts":"2026-02-04T01:57:51.023652Z","caller":"traceutil/trace.go:171","msg":"trace[716437408] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:989; }","duration":"109.408938ms","start":"2026-02-04T01:57:50.914231Z","end":"2026-02-04T01:57:51.02364Z","steps":["trace[716437408] 'agreement among raft nodes before linearized reading' (duration: 109.234025ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:56.31871Z","caller":"traceutil/trace.go:171","msg":"trace[563043800] linearizableReadLoop","detail":"{readStateIndex:1060; appliedIndex:1059; }","duration":"124.520455ms","start":"2026-02-04T01:57:56.194173Z","end":"2026-02-04T01:57:56.318694Z","steps":["trace[563043800] 'read index received' (duration: 124.371782ms)","trace[563043800] 'applied index is now lower than readState.Index' (duration: 148.033µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:57:56.318895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.699679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-04T01:57:56.318919Z","caller":"traceutil/trace.go:171","msg":"trace[181063487] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1019; }","duration":"124.7704ms","start":"2026-02-04T01:57:56.194141Z","end":"2026-02-04T01:57:56.318912Z","steps":["trace[181063487] 'agreement among raft nodes before linearized reading' (duration: 124.637957ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:56.319154Z","caller":"traceutil/trace.go:171","msg":"trace[1330474176] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"218.134792ms","start":"2026-02-04T01:57:56.101011Z","end":"2026-02-04T01:57:56.319145Z","steps":["trace[1330474176] 'process raft request' (duration: 217.58552ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:56.914506Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421301539407755465,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:57:57.41527Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421301539407755465,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:57:57.915879Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421301539407755465,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:57:58.416671Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421301539407755465,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:57:58.59746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.0004521s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-04T01:57:58.801483Z","caller":"traceutil/trace.go:171","msg":"trace[1716642795] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.204533419s","start":"2026-02-04T01:57:56.596918Z","end":"2026-02-04T01:57:58.801452Z","steps":["trace[1716642795] 'agreement among raft nodes before linearized reading' (duration: 2.000485241s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:58.802826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:56.596893Z","time spent":"2.205900639s","remote":"127.0.0.1:58078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:57:58.972668Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421301539407755465,"retry-timeout":"500ms"} 2026/02/04 01:57:58 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-04T01:57:58.735912Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.412720218s","expected-duration":"1s"} {"level":"info","ts":"2026-02-04T01:57:58.977015Z","caller":"traceutil/trace.go:171","msg":"trace[239428904] linearizableReadLoop","detail":"{readStateIndex:1061; appliedIndex:1060; }","duration":"2.563472329s","start":"2026-02-04T01:57:56.413524Z","end":"2026-02-04T01:57:58.976996Z","steps":["trace[239428904] 'read index received' (duration: 2.563289975s)","trace[239428904] 'applied index is now lower than readState.Index' (duration: 181.524µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:57:58.977435Z","caller":"traceutil/trace.go:171","msg":"trace[380385301] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"2.654371025s","start":"2026-02-04T01:57:56.323046Z","end":"2026-02-04T01:57:58.977417Z","steps":["trace[380385301] 'process raft request' (duration: 2.653827343s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:58.977543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:56.323031Z","time spent":"2.654445467s","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:57:58.978622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.565102856s","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-02-04T01:57:58.978668Z","caller":"traceutil/trace.go:171","msg":"trace[106044983] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1020; }","duration":"2.565185618s","start":"2026-02-04T01:57:56.41347Z","end":"2026-02-04T01:57:58.978656Z","steps":["trace[106044983] 'agreement among raft nodes before linearized reading' (duration: 2.565050785s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:58.978809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:56.41345Z","time spent":"2.565338432s","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-04T01:57:58.979191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.548016635s","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-02-04T01:57:58.979233Z","caller":"traceutil/trace.go:171","msg":"trace[838179053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1020; }","duration":"2.548097287s","start":"2026-02-04T01:57:56.431125Z","end":"2026-02-04T01:57:58.979222Z","steps":["trace[838179053] 'agreement among raft nodes before linearized reading' (duration: 2.547995295s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:58.979262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:56.431105Z","time spent":"2.548150678s","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-04T01:57:58.983919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.744779301s","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:532"} {"level":"info","ts":"2026-02-04T01:57:58.983977Z","caller":"traceutil/trace.go:171","msg":"trace[1662440854] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1020; }","duration":"1.744878143s","start":"2026-02-04T01:57:57.239085Z","end":"2026-02-04T01:57:58.983963Z","steps":["trace[1662440854] 'agreement among raft nodes before linearized reading' (duration: 1.744485934s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:58.984136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:57.239061Z","time spent":"1.745060368s","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-04T01:57:58.984428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.780811854s","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-02-04T01:57:58.984458Z","caller":"traceutil/trace.go:171","msg":"trace[1076292282] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1020; }","duration":"1.780885656s","start":"2026-02-04T01:57:57.203566Z","end":"2026-02-04T01:57:58.984452Z","steps":["trace[1076292282] 'agreement among raft nodes before linearized reading' (duration: 1.780784014s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:58.984479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:57.203545Z","time spent":"1.780930357s","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-04T01:57:58.984703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.210026151s","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:510"} {"level":"info","ts":"2026-02-04T01:57:58.98475Z","caller":"traceutil/trace.go:171","msg":"trace[1629245844] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1020; }","duration":"2.210107823s","start":"2026-02-04T01:57:56.774635Z","end":"2026-02-04T01:57:58.984743Z","steps":["trace[1629245844] 'agreement among raft nodes before linearized reading' (duration: 2.210022481s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:58.98483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:56.774599Z","time spent":"2.210223486s","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":534,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-04T01:57:58.985057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.553653221s","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-02-04T01:57:58.985081Z","caller":"traceutil/trace.go:171","msg":"trace[242759660] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1020; }","duration":"2.553708973s","start":"2026-02-04T01:57:56.431366Z","end":"2026-02-04T01:57:58.985075Z","steps":["trace[242759660] 'agreement among raft nodes before linearized reading' (duration: 2.553647501s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:58.9851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:56.431344Z","time spent":"2.553752073s","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-04T02:01:06.802807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.920749ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T02:01:06.802937Z","caller":"traceutil/trace.go:171","msg":"trace[1763002369] transaction","detail":"{read_only:false; response_revision:1817; number_of_response:1; }","duration":"130.731487ms","start":"2026-02-04T02:01:06.672192Z","end":"2026-02-04T02:01:06.802924Z","steps":["trace[1763002369] 'process raft request' (duration: 130.692486ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T02:01:06.803161Z","caller":"traceutil/trace.go:171","msg":"trace[1442156206] transaction","detail":"{read_only:false; response_revision:1816; number_of_response:1; }","duration":"288.430442ms","start":"2026-02-04T02:01:06.514723Z","end":"2026-02-04T02:01:06.803153Z","steps":["trace[1442156206] 'process raft request' (duration: 178.103174ms)","trace[1442156206] 'compare' (duration: 109.846428ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T02:01:06.803237Z","caller":"traceutil/trace.go:171","msg":"trace[1854537782] linearizableReadLoop","detail":"{readStateIndex:1896; appliedIndex:1894; }","duration":"266.702285ms","start":"2026-02-04T02:01:06.536529Z","end":"2026-02-04T02:01:06.803231Z","steps":["trace[1854537782] 'read index received' (duration: 60.468198ms)","trace[1854537782] 'applied index is now lower than readState.Index' (duration: 206.233437ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T02:01:06.803397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.875129ms","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-02-04T02:01:06.803417Z","caller":"traceutil/trace.go:171","msg":"trace[1022516466] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1817; }","duration":"266.929409ms","start":"2026-02-04T02:01:06.53648Z","end":"2026-02-04T02:01:06.80341Z","steps":["trace[1022516466] 'agreement among raft nodes before linearized reading' (duration: 266.850918ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T02:01:06.8035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.55015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T02:01:06.803516Z","caller":"traceutil/trace.go:171","msg":"trace[1668622211] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1817; }","duration":"210.565471ms","start":"2026-02-04T02:01:06.592945Z","end":"2026-02-04T02:01:06.803511Z","steps":["trace[1668622211] 'agreement among raft nodes before linearized reading' (duration: 210.54283ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T02:01:06.955847Z","caller":"traceutil/trace.go:171","msg":"trace[2002020366] linearizableReadLoop","detail":"{readStateIndex:1898; appliedIndex:1897; }","duration":"117.160675ms","start":"2026-02-04T02:01:06.838663Z","end":"2026-02-04T02:01:06.955824Z","steps":["trace[2002020366] 'read index received' (duration: 85.759041ms)","trace[2002020366] 'applied index is now lower than readState.Index' (duration: 31.400634ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T02:01:06.956038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.348689ms","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:527"} {"level":"info","ts":"2026-02-04T02:01:06.956075Z","caller":"traceutil/trace.go:171","msg":"trace[324253628] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1818; }","duration":"117.42768ms","start":"2026-02-04T02:01:06.838635Z","end":"2026-02-04T02:01:06.956063Z","steps":["trace[324253628] 'agreement among raft nodes before linearized reading' (duration: 117.280877ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T02:01:06.956096Z","caller":"traceutil/trace.go:171","msg":"trace[1741744725] transaction","detail":"{read_only:false; response_revision:1818; number_of_response:1; }","duration":"147.874064ms","start":"2026-02-04T02:01:06.808199Z","end":"2026-02-04T02:01:06.956073Z","steps":["trace[1741744725] 'process raft request' (duration: 116.273225ms)","trace[1741744725] 'compare' (duration: 31.235631ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T02:01:07.772665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.638614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T02:01:07.772792Z","caller":"traceutil/trace.go:171","msg":"trace[1466742500] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1819; }","duration":"178.803167ms","start":"2026-02-04T02:01:07.593965Z","end":"2026-02-04T02:01:07.772768Z","steps":["trace[1466742500] 'range keys from in-memory index tree' (duration: 178.571713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T02:01:09.553951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.729439ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"}