{"level":"warn","ts":"2026-03-14T01:33:07.28168Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-14T01:33:07.281805Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.53: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.53:2380","--initial-cluster=instance=https://199.204.45.53:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.53:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.53: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-03-14T01:33:07.281882Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-14T01:33:07.281899Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-03-14T01:33:07.28192Z","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-03-14T01:33:07.28273Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"]} {"level":"info","ts":"2026-03-14T01:33:07.282829Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.53: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-03-14T01:33:07.287602Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.488926ms"} {"level":"info","ts":"2026-03-14T01:33:07.302121Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c47ca1a7b1fe506b","cluster-id":"95bd21e158fabf07"} {"level":"info","ts":"2026-03-14T01:33:07.302186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=()"} {"level":"info","ts":"2026-03-14T01:33:07.302204Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 0"} {"level":"info","ts":"2026-03-14T01:33:07.302216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c47ca1a7b1fe506b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-14T01:33:07.302225Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 1"} {"level":"info","ts":"2026-03-14T01:33:07.302255Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"warn","ts":"2026-03-14T01:33:07.312627Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-14T01:33:07.313846Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-14T01:33:07.31523Z","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-03-14T01:33:07.317358Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c47ca1a7b1fe506b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-14T01:33:07.317501Z","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-03-14T01:33:07.317585Z","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-03-14T01:33:07.317596Z","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-03-14T01:33:07.321184Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c47ca1a7b1fe506b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-14T01:33:07.323336Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"info","ts":"2026-03-14T01:33:07.323464Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","added-peer-id":"c47ca1a7b1fe506b","added-peer-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-03-14T01:33:07.325086Z","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-03-14T01:33:07.325214Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-03-14T01:33:07.325301Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-03-14T01:33:07.325329Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c47ca1a7b1fe506b","initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-14T01:33:07.325373Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-14T01:33:07.903455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b is starting a new election at term 1"} {"level":"info","ts":"2026-03-14T01:33:07.903519Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became pre-candidate at term 1"} {"level":"info","ts":"2026-03-14T01:33:07.90356Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgPreVoteResp from c47ca1a7b1fe506b at term 1"} {"level":"info","ts":"2026-03-14T01:33:07.903581Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became candidate at term 2"} {"level":"info","ts":"2026-03-14T01:33:07.903593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgVoteResp from c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-03-14T01:33:07.903609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became leader at term 2"} {"level":"info","ts":"2026-03-14T01:33:07.903624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c47ca1a7b1fe506b elected leader c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-03-14T01:33:07.904614Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-14T01:33:07.905336Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c47ca1a7b1fe506b","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.53:2379]}","request-path":"/0/members/c47ca1a7b1fe506b/attributes","cluster-id":"95bd21e158fabf07","publish-timeout":"7s"} {"level":"info","ts":"2026-03-14T01:33:07.90535Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-14T01:33:07.9057Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","cluster-version":"3.5"} {"level":"info","ts":"2026-03-14T01:33:07.905782Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-14T01:33:07.905845Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-14T01:33:07.905844Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-14T01:33:07.905899Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-14T01:33:07.908423Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-14T01:33:07.908604Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-14T01:33:07.912157Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.53:2379"} {"level":"info","ts":"2026-03-14T01:33:34.799105Z","caller":"traceutil/trace.go:171","msg":"trace[1699181824] linearizableReadLoop","detail":"{readStateIndex:485; appliedIndex:484; }","duration":"189.920101ms","start":"2026-03-14T01:33:34.609153Z","end":"2026-03-14T01:33:34.799074Z","steps":["trace[1699181824] 'read index received' (duration: 189.672434ms)","trace[1699181824] 'applied index is now lower than readState.Index' (duration: 246.317µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:33:34.799195Z","caller":"traceutil/trace.go:171","msg":"trace[743940526] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"239.069932ms","start":"2026-03-14T01:33:34.560076Z","end":"2026-03-14T01:33:34.799146Z","steps":["trace[743940526] 'process raft request' (duration: 238.768003ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:33:34.799304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.112957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"warn","ts":"2026-03-14T01:33:34.799352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.17052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-03-14T01:33:34.799438Z","caller":"traceutil/trace.go:171","msg":"trace[918044972] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:464; }","duration":"190.322484ms","start":"2026-03-14T01:33:34.609093Z","end":"2026-03-14T01:33:34.799415Z","steps":["trace[918044972] 'agreement among raft nodes before linearized reading' (duration: 190.118708ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:33:34.799381Z","caller":"traceutil/trace.go:171","msg":"trace[87023334] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:464; }","duration":"190.21069ms","start":"2026-03-14T01:33:34.609153Z","end":"2026-03-14T01:33:34.799364Z","steps":["trace[87023334] 'agreement among raft nodes before linearized reading' (duration: 190.082906ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:33:34.799972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.659054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329948"} {"level":"info","ts":"2026-03-14T01:33:34.800079Z","caller":"traceutil/trace.go:171","msg":"trace[682388398] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:464; }","duration":"187.776998ms","start":"2026-03-14T01:33:34.612276Z","end":"2026-03-14T01:33:34.800053Z","steps":["trace[682388398] 'agreement among raft nodes before linearized reading' (duration: 187.014485ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:33:36.673338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.982936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:33:36.673427Z","caller":"traceutil/trace.go:171","msg":"trace[1595495297] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:470; }","duration":"153.186302ms","start":"2026-03-14T01:33:36.52022Z","end":"2026-03-14T01:33:36.673406Z","steps":["trace[1595495297] 'range keys from in-memory index tree' (duration: 152.819181ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:33:38.965382Z","caller":"traceutil/trace.go:171","msg":"trace[510299403] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"147.388209ms","start":"2026-03-14T01:33:38.817935Z","end":"2026-03-14T01:33:38.965323Z","steps":["trace[510299403] 'process raft request' (duration: 147.270436ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:33:43.119226Z","caller":"traceutil/trace.go:171","msg":"trace[1264918882] linearizableReadLoop","detail":"{readStateIndex:511; appliedIndex:510; }","duration":"139.7324ms","start":"2026-03-14T01:33:42.979445Z","end":"2026-03-14T01:33:43.119177Z","steps":["trace[1264918882] 'read index received' (duration: 139.267066ms)","trace[1264918882] 'applied index is now lower than readState.Index' (duration: 463.614µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:33:43.119344Z","caller":"traceutil/trace.go:171","msg":"trace[214502007] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"322.516448ms","start":"2026-03-14T01:33:42.796782Z","end":"2026-03-14T01:33:43.119299Z","steps":["trace[214502007] 'process raft request' (duration: 322.078395ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:33:43.11961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.105681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-14T01:33:43.119702Z","caller":"traceutil/trace.go:171","msg":"trace[2016601046] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:488; }","duration":"140.287886ms","start":"2026-03-14T01:33:42.979373Z","end":"2026-03-14T01:33:43.119661Z","steps":["trace[2016601046] 'agreement among raft nodes before linearized reading' (duration: 139.975337ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:33:43.120087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:33:42.796764Z","time spent":"322.683663ms","remote":"127.0.0.1:55016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T01:33:43.828632Z","caller":"traceutil/trace.go:171","msg":"trace[1739933118] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"600.110332ms","start":"2026-03-14T01:33:43.228505Z","end":"2026-03-14T01:33:43.828615Z","steps":["trace[1739933118] 'process raft request' (duration: 600.012159ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:33:43.828674Z","caller":"traceutil/trace.go:171","msg":"trace[222368982] linearizableReadLoop","detail":"{readStateIndex:513; appliedIndex:513; }","duration":"446.689113ms","start":"2026-03-14T01:33:43.381938Z","end":"2026-03-14T01:33:43.828627Z","steps":["trace[222368982] 'read index received' (duration: 446.678932ms)","trace[222368982] 'applied index is now lower than readState.Index' (duration: 7.841µs)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:33:43.828736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:33:43.228453Z","time spent":"600.226115ms","remote":"127.0.0.1:55096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T01:33:43.828915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.97007ms","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-03-14T01:33:43.828983Z","caller":"traceutil/trace.go:171","msg":"trace[271425793] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:490; }","duration":"447.088524ms","start":"2026-03-14T01:33:43.381859Z","end":"2026-03-14T01:33:43.828948Z","steps":["trace[271425793] 'agreement among raft nodes before linearized reading' (duration: 446.915559ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:33:43.829025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:33:43.381828Z","time spent":"447.187177ms","remote":"127.0.0.1:55096","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-03-14T01:33:44.328881Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794897874274314839,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-14T01:33:44.362983Z","caller":"traceutil/trace.go:171","msg":"trace[470103475] linearizableReadLoop","detail":"{readStateIndex:515; appliedIndex:513; }","duration":"534.209561ms","start":"2026-03-14T01:33:43.828739Z","end":"2026-03-14T01:33:44.362949Z","steps":["trace[470103475] 'read index received' (duration: 531.430528ms)","trace[470103475] 'applied index is now lower than readState.Index' (duration: 2.777873ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:33:44.363011Z","caller":"traceutil/trace.go:171","msg":"trace[820140720] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"854.291886ms","start":"2026-03-14T01:33:43.508664Z","end":"2026-03-14T01:33:44.362956Z","steps":["trace[820140720] 'process raft request' (duration: 851.487072ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:33:44.363215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"844.341238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:33:44.363285Z","caller":"traceutil/trace.go:171","msg":"trace[1127968631] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:492; }","duration":"844.463102ms","start":"2026-03-14T01:33:43.518802Z","end":"2026-03-14T01:33:44.363266Z","steps":["trace[1127968631] 'agreement among raft nodes before linearized reading' (duration: 844.337579ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:33:44.363304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.298501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-14T01:33:44.363327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:33:43.518779Z","time spent":"844.539074ms","remote":"127.0.0.1:54852","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-03-14T01:33:44.363359Z","caller":"traceutil/trace.go:171","msg":"trace[384214095] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:492; }","duration":"677.411405ms","start":"2026-03-14T01:33:43.685932Z","end":"2026-03-14T01:33:44.363343Z","steps":["trace[384214095] 'agreement among raft nodes before linearized reading' (duration: 677.154097ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:33:44.363409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:33:43.68591Z","time spent":"677.489907ms","remote":"127.0.0.1:55096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-14T01:33:44.363019Z","caller":"traceutil/trace.go:171","msg":"trace[1586302832] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"528.067497ms","start":"2026-03-14T01:33:43.834918Z","end":"2026-03-14T01:33:44.362986Z","steps":["trace[1586302832] 'process raft request' (duration: 527.971344ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:33:44.363625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:33:43.834896Z","time spent":"528.592623ms","remote":"127.0.0.1:55096","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-03-14T01:33:44.3639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:33:43.508631Z","time spent":"854.490232ms","remote":"127.0.0.1:55000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4744,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T01:34:04.736547Z","caller":"traceutil/trace.go:171","msg":"trace[1175155447] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:671; }","duration":"268.701875ms","start":"2026-03-14T01:34:04.467812Z","end":"2026-03-14T01:34:04.736514Z","steps":["trace[1175155447] 'read index received' (duration: 268.494408ms)","trace[1175155447] 'applied index is now lower than readState.Index' (duration: 206.207µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:34:04.736675Z","caller":"traceutil/trace.go:171","msg":"trace[1766411213] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"281.059777ms","start":"2026-03-14T01:34:04.455564Z","end":"2026-03-14T01:34:04.736624Z","steps":["trace[1766411213] 'process raft request' (duration: 280.755948ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:34:04.736792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.950093ms","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-03-14T01:34:04.736871Z","caller":"traceutil/trace.go:171","msg":"trace[632658244] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:643; }","duration":"269.082967ms","start":"2026-03-14T01:34:04.46777Z","end":"2026-03-14T01:34:04.736853Z","steps":["trace[632658244] 'agreement among raft nodes before linearized reading' (duration: 268.854859ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:34:04.736883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.068954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-14T01:34:04.73693Z","caller":"traceutil/trace.go:171","msg":"trace[601625990] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:643; }","duration":"268.161287ms","start":"2026-03-14T01:34:04.46875Z","end":"2026-03-14T01:34:04.736911Z","steps":["trace[601625990] 'agreement among raft nodes before linearized reading' (duration: 267.960981ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:34:05.008257Z","caller":"traceutil/trace.go:171","msg":"trace[1485384390] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"267.405893ms","start":"2026-03-14T01:34:04.740828Z","end":"2026-03-14T01:34:05.008234Z","steps":["trace[1485384390] 'process raft request' (duration: 263.787298ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:34:05.008447Z","caller":"traceutil/trace.go:171","msg":"trace[320991696] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:672; }","duration":"129.061014ms","start":"2026-03-14T01:34:04.879378Z","end":"2026-03-14T01:34:05.008439Z","steps":["trace[320991696] 'read index received' (duration: 125.337326ms)","trace[320991696] 'applied index is now lower than readState.Index' (duration: 3.722668ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:34:05.008539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.151797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:34:05.008566Z","caller":"traceutil/trace.go:171","msg":"trace[661982407] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:645; }","duration":"129.25403ms","start":"2026-03-14T01:34:04.879305Z","end":"2026-03-14T01:34:05.008559Z","steps":["trace[661982407] 'agreement among raft nodes before linearized reading' (duration: 129.165477ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:34:05.008772Z","caller":"traceutil/trace.go:171","msg":"trace[213225520] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"267.133144ms","start":"2026-03-14T01:34:04.741612Z","end":"2026-03-14T01:34:05.008745Z","steps":["trace[213225520] 'process raft request' (duration: 266.527495ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:34:05.622371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.313957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:34:05.622476Z","caller":"traceutil/trace.go:171","msg":"trace[1130992703] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:645; }","duration":"104.461521ms","start":"2026-03-14T01:34:05.517981Z","end":"2026-03-14T01:34:05.622442Z","steps":["trace[1130992703] 'range keys from in-memory index tree' (duration: 104.236504ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:34:05.623008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.155657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-14T01:34:05.623051Z","caller":"traceutil/trace.go:171","msg":"trace[2056050601] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:645; }","duration":"324.230369ms","start":"2026-03-14T01:34:05.29881Z","end":"2026-03-14T01:34:05.623041Z","steps":["trace[2056050601] 'range keys from in-memory index tree' (duration: 323.354232ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:34:05.623081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:34:05.298793Z","time spent":"324.279261ms","remote":"127.0.0.1:55096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-14T01:34:14.945256Z","caller":"traceutil/trace.go:171","msg":"trace[1418844651] linearizableReadLoop","detail":"{readStateIndex:747; appliedIndex:746; }","duration":"169.450597ms","start":"2026-03-14T01:34:14.775779Z","end":"2026-03-14T01:34:14.94523Z","steps":["trace[1418844651] 'read index received' (duration: 169.312792ms)","trace[1418844651] 'applied index is now lower than readState.Index' (duration: 136.535µs)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:34:14.945501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.683345ms","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-03-14T01:34:14.945575Z","caller":"traceutil/trace.go:171","msg":"trace[817632521] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:715; }","duration":"169.835829ms","start":"2026-03-14T01:34:14.775725Z","end":"2026-03-14T01:34:14.945561Z","steps":["trace[817632521] 'agreement among raft nodes before linearized reading' (duration: 169.615022ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:34:14.945314Z","caller":"traceutil/trace.go:171","msg":"trace[471879428] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"534.421302ms","start":"2026-03-14T01:34:14.410865Z","end":"2026-03-14T01:34:14.945287Z","steps":["trace[471879428] 'process raft request' (duration: 534.192495ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:34:14.946049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:34:14.410838Z","time spent":"535.145946ms","remote":"127.0.0.1:55096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T01:34:15.120423Z","caller":"traceutil/trace.go:171","msg":"trace[2116176717] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"170.842283ms","start":"2026-03-14T01:34:14.949559Z","end":"2026-03-14T01:34:15.120402Z","steps":["trace[2116176717] 'process raft request' (duration: 139.348561ms)","trace[2116176717] 'compare' (duration: 31.259484ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:34:15.320642Z","caller":"traceutil/trace.go:171","msg":"trace[1627892307] linearizableReadLoop","detail":"{readStateIndex:750; appliedIndex:749; }","duration":"112.141196ms","start":"2026-03-14T01:34:15.208473Z","end":"2026-03-14T01:34:15.320614Z","steps":["trace[1627892307] 'read index received' (duration: 95.508107ms)","trace[1627892307] 'applied index is now lower than readState.Index' (duration: 16.632179ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:34:15.320755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.263769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:34:15.32078Z","caller":"traceutil/trace.go:171","msg":"trace[58391243] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:718; }","duration":"112.350252ms","start":"2026-03-14T01:34:15.208423Z","end":"2026-03-14T01:34:15.320773Z","steps":["trace[58391243] 'agreement among raft nodes before linearized reading' (duration: 112.27809ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:34:15.320943Z","caller":"traceutil/trace.go:171","msg":"trace[1391329286] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"195.370452ms","start":"2026-03-14T01:34:15.125535Z","end":"2026-03-14T01:34:15.320906Z","steps":["trace[1391329286] 'process raft request' (duration: 178.400683ms)","trace[1391329286] 'compare' (duration: 16.582507ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:34:15.3526Z","caller":"traceutil/trace.go:171","msg":"trace[1172147385] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"123.526418ms","start":"2026-03-14T01:34:15.229059Z","end":"2026-03-14T01:34:15.352585Z","steps":["trace[1172147385] 'process raft request' (duration: 123.411924ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:34:16.247517Z","caller":"traceutil/trace.go:171","msg":"trace[792168045] linearizableReadLoop","detail":"{readStateIndex:752; appliedIndex:751; }","duration":"390.636441ms","start":"2026-03-14T01:34:15.856861Z","end":"2026-03-14T01:34:16.247498Z","steps":["trace[792168045] 'read index received' (duration: 390.461555ms)","trace[792168045] 'applied index is now lower than readState.Index' (duration: 173.956µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:34:16.247617Z","caller":"traceutil/trace.go:171","msg":"trace[1132306148] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"568.734595ms","start":"2026-03-14T01:34:15.678854Z","end":"2026-03-14T01:34:16.247589Z","steps":["trace[1132306148] 'process raft request' (duration: 568.488737ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:34:16.247693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.820847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-14T01:34:16.247715Z","caller":"traceutil/trace.go:171","msg":"trace[1505338496] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:720; }","duration":"390.90632ms","start":"2026-03-14T01:34:15.856802Z","end":"2026-03-14T01:34:16.247709Z","steps":["trace[1505338496] 'agreement among raft nodes before linearized reading' (duration: 390.804636ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:34:16.247738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:34:15.856781Z","time spent":"390.951321ms","remote":"127.0.0.1:55096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-14T01:34:16.247762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:34:15.678822Z","time spent":"568.856429ms","remote":"127.0.0.1:55096","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-03-14T01:34:16.247962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.526414ms","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-03-14T01:34:16.248047Z","caller":"traceutil/trace.go:171","msg":"trace[310417520] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:720; }","duration":"215.687859ms","start":"2026-03-14T01:34:16.032336Z","end":"2026-03-14T01:34:16.248024Z","steps":["trace[310417520] 'agreement among raft nodes before linearized reading' (duration: 215.501703ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:34:16.4086Z","caller":"traceutil/trace.go:171","msg":"trace[1606068825] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"148.454851ms","start":"2026-03-14T01:34:16.260117Z","end":"2026-03-14T01:34:16.408572Z","steps":["trace[1606068825] 'process raft request' (duration: 125.542542ms)","trace[1606068825] 'compare' (duration: 22.796255ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:34:29.327416Z","caller":"traceutil/trace.go:171","msg":"trace[1268434717] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"111.022533ms","start":"2026-03-14T01:34:29.216359Z","end":"2026-03-14T01:34:29.327381Z","steps":["trace[1268434717] 'process raft request' (duration: 110.770705ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:34:29.736151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.064687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:34:29.736246Z","caller":"traceutil/trace.go:171","msg":"trace[247120950] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:761; }","duration":"217.217412ms","start":"2026-03-14T01:34:29.519005Z","end":"2026-03-14T01:34:29.736222Z","steps":["trace[247120950] 'range keys from in-memory index tree' (duration: 216.982794ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:34:29.73648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.191256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-14T01:34:29.736595Z","caller":"traceutil/trace.go:171","msg":"trace[1356655828] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:761; }","duration":"358.378192ms","start":"2026-03-14T01:34:29.37819Z","end":"2026-03-14T01:34:29.736569Z","steps":["trace[1356655828] 'range keys from in-memory index tree' (duration: 357.937048ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:34:29.736669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:34:29.378172Z","time spent":"358.485026ms","remote":"127.0.0.1:55096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-14T01:34:45.037856Z","caller":"traceutil/trace.go:171","msg":"trace[857000799] linearizableReadLoop","detail":"{readStateIndex:905; appliedIndex:904; }","duration":"158.956412ms","start":"2026-03-14T01:34:44.878884Z","end":"2026-03-14T01:34:45.03784Z","steps":["trace[857000799] 'read index received' (duration: 158.879769ms)","trace[857000799] 'applied index is now lower than readState.Index' (duration: 75.983µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:34:45.038003Z","caller":"traceutil/trace.go:171","msg":"trace[625702553] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"479.269412ms","start":"2026-03-14T01:34:44.558695Z","end":"2026-03-14T01:34:45.037964Z","steps":["trace[625702553] 'process raft request' (duration: 479.060985ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:34:45.038607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:34:44.558679Z","time spent":"479.872491ms","remote":"127.0.0.1:55096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T01:34:45.03815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.248471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:34:45.038723Z","caller":"traceutil/trace.go:171","msg":"trace[694210470] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:865; }","duration":"159.86607ms","start":"2026-03-14T01:34:44.878831Z","end":"2026-03-14T01:34:45.038697Z","steps":["trace[694210470] 'agreement among raft nodes before linearized reading' (duration: 159.24431ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:34:45.371337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.178567ms","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-03-14T01:34:45.371424Z","caller":"traceutil/trace.go:171","msg":"trace[491132668] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:865; }","duration":"120.326451ms","start":"2026-03-14T01:34:45.251076Z","end":"2026-03-14T01:34:45.371403Z","steps":["trace[491132668] 'count revisions from in-memory index tree' (duration: 120.045673ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:34:58.057856Z","caller":"traceutil/trace.go:171","msg":"trace[784880025] linearizableReadLoop","detail":"{readStateIndex:952; appliedIndex:951; }","duration":"159.105127ms","start":"2026-03-14T01:34:57.898733Z","end":"2026-03-14T01:34:58.057838Z","steps":["trace[784880025] 'read index received' (duration: 158.982943ms)","trace[784880025] 'applied index is now lower than readState.Index' (duration: 121.484µs)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:34:58.057989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.240591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-14T01:34:58.058007Z","caller":"traceutil/trace.go:171","msg":"trace[1012490071] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:909; }","duration":"159.298133ms","start":"2026-03-14T01:34:57.898702Z","end":"2026-03-14T01:34:58.058Z","steps":["trace[1012490071] 'agreement among raft nodes before linearized reading' (duration: 159.203ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:34:58.058361Z","caller":"traceutil/trace.go:171","msg":"trace[144181412] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"224.362531ms","start":"2026-03-14T01:34:57.83392Z","end":"2026-03-14T01:34:58.058282Z","steps":["trace[144181412] 'process raft request' (duration: 223.790943ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:34:58.249382Z","caller":"traceutil/trace.go:171","msg":"trace[436567414] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"113.622127ms","start":"2026-03-14T01:34:58.135724Z","end":"2026-03-14T01:34:58.249346Z","steps":["trace[436567414] 'process raft request' (duration: 82.298711ms)","trace[436567414] 'compare' (duration: 31.162981ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:34:58.738977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.845848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:34:58.739063Z","caller":"traceutil/trace.go:171","msg":"trace[940551074] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:916; }","duration":"220.967132ms","start":"2026-03-14T01:34:58.518074Z","end":"2026-03-14T01:34:58.739041Z","steps":["trace[940551074] 'range keys from in-memory index tree' (duration: 220.755315ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:35:00.002198Z","caller":"traceutil/trace.go:171","msg":"trace[530896173] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"455.605032ms","start":"2026-03-14T01:34:59.546566Z","end":"2026-03-14T01:35:00.002171Z","steps":["trace[530896173] 'process raft request' (duration: 455.447647ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:35:00.002584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:34:59.546542Z","time spent":"455.915232ms","remote":"127.0.0.1:55096","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-03-14T01:35:00.077016Z","caller":"traceutil/trace.go:171","msg":"trace[892868790] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"176.94289ms","start":"2026-03-14T01:34:59.900022Z","end":"2026-03-14T01:35:00.076965Z","steps":["trace[892868790] 'process raft request' (duration: 176.685651ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:35:00.431566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.326053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-14T01:35:00.431689Z","caller":"traceutil/trace.go:171","msg":"trace[1297352524] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:920; }","duration":"321.521809ms","start":"2026-03-14T01:35:00.110141Z","end":"2026-03-14T01:35:00.431662Z","steps":["trace[1297352524] 'range keys from in-memory index tree' (duration: 321.066844ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:35:00.431746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:35:00.110117Z","time spent":"321.618472ms","remote":"127.0.0.1:55096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-14T01:35:00.639406Z","caller":"traceutil/trace.go:171","msg":"trace[884226628] linearizableReadLoop","detail":"{readStateIndex:965; appliedIndex:964; }","duration":"120.085364ms","start":"2026-03-14T01:35:00.519298Z","end":"2026-03-14T01:35:00.639384Z","steps":["trace[884226628] 'read index received' (duration: 41.787481ms)","trace[884226628] 'applied index is now lower than readState.Index' (duration: 78.296983ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:35:00.639534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.214878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:35:00.639586Z","caller":"traceutil/trace.go:171","msg":"trace[238386042] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:922; }","duration":"120.302971ms","start":"2026-03-14T01:35:00.51927Z","end":"2026-03-14T01:35:00.639573Z","steps":["trace[238386042] 'agreement among raft nodes before linearized reading' (duration: 120.220889ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:35:00.639796Z","caller":"traceutil/trace.go:171","msg":"trace[2035908813] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"136.124569ms","start":"2026-03-14T01:35:00.503657Z","end":"2026-03-14T01:35:00.639781Z","steps":["trace[2035908813] 'process raft request' (duration: 57.488635ms)","trace[2035908813] 'compare' (duration: 78.138768ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:35:01.1005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.885246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:35:01.100575Z","caller":"traceutil/trace.go:171","msg":"trace[1992276315] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:923; }","duration":"129.00991ms","start":"2026-03-14T01:35:00.971548Z","end":"2026-03-14T01:35:01.100558Z","steps":["trace[1992276315] 'count revisions from in-memory index tree' (duration: 128.784703ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:35:01.100229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.704285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.53\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-14T01:35:01.101804Z","caller":"traceutil/trace.go:171","msg":"trace[188763882] range","detail":"{range_begin:/registry/masterleases/199.204.45.53; range_end:; response_count:1; response_revision:923; }","duration":"142.326077ms","start":"2026-03-14T01:35:00.959458Z","end":"2026-03-14T01:35:01.101784Z","steps":["trace[188763882] 'range keys from in-memory index tree' (duration: 140.497188ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:35:01.329569Z","caller":"traceutil/trace.go:171","msg":"trace[1249168755] linearizableReadLoop","detail":"{readStateIndex:970; appliedIndex:969; }","duration":"148.884497ms","start":"2026-03-14T01:35:01.180657Z","end":"2026-03-14T01:35:01.329541Z","steps":["trace[1249168755] 'read index received' (duration: 128.054829ms)","trace[1249168755] 'applied index is now lower than readState.Index' (duration: 20.828338ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:35:01.329785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.124394ms","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-03-14T01:35:01.32989Z","caller":"traceutil/trace.go:171","msg":"trace[174577201] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:926; }","duration":"149.247258ms","start":"2026-03-14T01:35:01.180626Z","end":"2026-03-14T01:35:01.329874Z","steps":["trace[174577201] 'agreement among raft nodes before linearized reading' (duration: 149.019401ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:35:01.330382Z","caller":"traceutil/trace.go:171","msg":"trace[1735176092] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"172.417802ms","start":"2026-03-14T01:35:01.157173Z","end":"2026-03-14T01:35:01.329591Z","steps":["trace[1735176092] 'process raft request' (duration: 151.673087ms)","trace[1735176092] 'compare' (duration: 20.528528ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:35:01.360463Z","caller":"traceutil/trace.go:171","msg":"trace[838240926] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"178.26813ms","start":"2026-03-14T01:35:01.182151Z","end":"2026-03-14T01:35:01.360419Z","steps":["trace[838240926] 'process raft request' (duration: 177.993711ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:35:15.00681Z","caller":"traceutil/trace.go:171","msg":"trace[49091567] linearizableReadLoop","detail":"{readStateIndex:1061; appliedIndex:1060; }","duration":"489.377376ms","start":"2026-03-14T01:35:14.517403Z","end":"2026-03-14T01:35:15.006781Z","steps":["trace[49091567] 'read index received' (duration: 489.215731ms)","trace[49091567] 'applied index is now lower than readState.Index' (duration: 160.805µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:35:15.006927Z","caller":"traceutil/trace.go:171","msg":"trace[1406611082] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"491.787893ms","start":"2026-03-14T01:35:14.515126Z","end":"2026-03-14T01:35:15.006914Z","steps":["trace[1406611082] 'process raft request' (duration: 491.519514ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:35:15.007008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:35:14.515111Z","time spent":"491.833665ms","remote":"127.0.0.1:54950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":606,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T01:35:15.007118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.713966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:35:15.007142Z","caller":"traceutil/trace.go:171","msg":"trace[1062863384] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1015; }","duration":"489.756618ms","start":"2026-03-14T01:35:14.51738Z","end":"2026-03-14T01:35:15.007137Z","steps":["trace[1062863384] 'agreement among raft nodes before linearized reading' (duration: 489.718217ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:35:15.007181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:35:14.51737Z","time spent":"489.782799ms","remote":"127.0.0.1:54852","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-03-14T01:35:15.007465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.698678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-14T01:35:15.007547Z","caller":"traceutil/trace.go:171","msg":"trace[2057278720] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1015; }","duration":"466.806402ms","start":"2026-03-14T01:35:14.54072Z","end":"2026-03-14T01:35:15.007526Z","steps":["trace[2057278720] 'agreement among raft nodes before linearized reading' (duration: 466.610505ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:35:15.007594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:35:14.54071Z","time spent":"466.875454ms","remote":"127.0.0.1:55096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-14T01:35:15.007583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.773168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:35:15.007704Z","caller":"traceutil/trace.go:171","msg":"trace[1447174477] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1015; }","duration":"331.915692ms","start":"2026-03-14T01:35:14.675752Z","end":"2026-03-14T01:35:15.007667Z","steps":["trace[1447174477] 'agreement among raft nodes before linearized reading' (duration: 331.704535ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:35:15.007676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.973619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-14T01:35:15.007787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:35:14.675659Z","time spent":"332.108728ms","remote":"127.0.0.1:55200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"info","ts":"2026-03-14T01:35:15.007817Z","caller":"traceutil/trace.go:171","msg":"trace[1125557137] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1015; }","duration":"301.165445ms","start":"2026-03-14T01:35:14.706619Z","end":"2026-03-14T01:35:15.007785Z","steps":["trace[1125557137] 'agreement among raft nodes before linearized reading' (duration: 300.791413ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:35:15.007887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:35:14.7066Z","time spent":"301.273929ms","remote":"127.0.0.1:55096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-14T01:35:15.008148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.78321ms","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-03-14T01:35:15.00837Z","caller":"traceutil/trace.go:171","msg":"trace[434298544] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1015; }","duration":"233.063259ms","start":"2026-03-14T01:35:14.775236Z","end":"2026-03-14T01:35:15.008299Z","steps":["trace[434298544] 'agreement among raft nodes before linearized reading' (duration: 232.173131ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:38:18.562887Z","caller":"traceutil/trace.go:171","msg":"trace[1245982892] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"252.835227ms","start":"2026-03-14T01:38:18.310023Z","end":"2026-03-14T01:38:18.562858Z","steps":["trace[1245982892] 'process raft request' (duration: 252.467696ms)"],"step_count":1}