{"level":"warn","ts":"2026-03-13T22:59:16.991112Z","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-13T22:59:16.991201Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.209: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.209:2380","--initial-cluster=instance=https://199.204.45.209:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.209:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.209: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-13T22:59:16.991269Z","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-13T22:59:16.991282Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.209:2380"]} {"level":"info","ts":"2026-03-13T22:59:16.991299Z","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-13T22:59:16.991984Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.209:2379"]} {"level":"info","ts":"2026-03-13T22:59:16.992126Z","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.209:2380"],"listen-peer-urls":["https://199.204.45.209:2380"],"advertise-client-urls":["https://199.204.45.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.209: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-13T22:59:16.996072Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.69617ms"} {"level":"info","ts":"2026-03-13T22:59:17.001049Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"adfb641115bf6cbd","cluster-id":"3da6b677d4b80c6"} {"level":"info","ts":"2026-03-13T22:59:17.001131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd switched to configuration voters=()"} {"level":"info","ts":"2026-03-13T22:59:17.001152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became follower at term 0"} {"level":"info","ts":"2026-03-13T22:59:17.001193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft adfb641115bf6cbd [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-13T22:59:17.00125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became follower at term 1"} {"level":"info","ts":"2026-03-13T22:59:17.00132Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd switched to configuration voters=(12536724012257995965)"} {"level":"warn","ts":"2026-03-13T22:59:17.009236Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-13T22:59:17.011907Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-13T22:59:17.013163Z","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-13T22:59:17.016232Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"adfb641115bf6cbd","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-13T22:59:17.016378Z","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-13T22:59:17.016451Z","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-13T22:59:17.016469Z","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-13T22:59:17.016729Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"adfb641115bf6cbd","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-13T22:59:17.019518Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd switched to configuration voters=(12536724012257995965)"} {"level":"info","ts":"2026-03-13T22:59:17.019743Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3da6b677d4b80c6","local-member-id":"adfb641115bf6cbd","added-peer-id":"adfb641115bf6cbd","added-peer-peer-urls":["https://199.204.45.209:2380"]} {"level":"info","ts":"2026-03-13T22:59:17.024169Z","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-13T22:59:17.024334Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.209:2380"} {"level":"info","ts":"2026-03-13T22:59:17.024446Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-13T22:59:17.024459Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.209:2380"} {"level":"info","ts":"2026-03-13T22:59:17.024408Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"adfb641115bf6cbd","initial-advertise-peer-urls":["https://199.204.45.209:2380"],"listen-peer-urls":["https://199.204.45.209:2380"],"advertise-client-urls":["https://199.204.45.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-13T22:59:17.601478Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd is starting a new election at term 1"} {"level":"info","ts":"2026-03-13T22:59:17.601545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became pre-candidate at term 1"} {"level":"info","ts":"2026-03-13T22:59:17.601617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd received MsgPreVoteResp from adfb641115bf6cbd at term 1"} {"level":"info","ts":"2026-03-13T22:59:17.601645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became candidate at term 2"} {"level":"info","ts":"2026-03-13T22:59:17.601698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd received MsgVoteResp from adfb641115bf6cbd at term 2"} {"level":"info","ts":"2026-03-13T22:59:17.601723Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became leader at term 2"} {"level":"info","ts":"2026-03-13T22:59:17.601738Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: adfb641115bf6cbd elected leader adfb641115bf6cbd at term 2"} {"level":"info","ts":"2026-03-13T22:59:17.602684Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"adfb641115bf6cbd","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.209:2379]}","request-path":"/0/members/adfb641115bf6cbd/attributes","cluster-id":"3da6b677d4b80c6","publish-timeout":"7s"} {"level":"info","ts":"2026-03-13T22:59:17.602717Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-13T22:59:17.603099Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-13T22:59:17.60356Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-13T22:59:17.60359Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-13T22:59:17.606184Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.209:2379"} {"level":"info","ts":"2026-03-13T22:59:17.60638Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T22:59:17.606442Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-13T22:59:17.607359Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3da6b677d4b80c6","local-member-id":"adfb641115bf6cbd","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T22:59:17.607466Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T22:59:17.607534Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-03-13T22:59:23.586443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.378724ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-13T22:59:23.586689Z","caller":"traceutil/trace.go:171","msg":"trace[1005565845] linearizableReadLoop","detail":"{readStateIndex:314; appliedIndex:313; }","duration":"852.380463ms","start":"2026-03-13T22:59:22.734282Z","end":"2026-03-13T22:59:23.586662Z","steps":["trace[1005565845] 'read index received' (duration: 349.86352ms)","trace[1005565845] 'applied index is now lower than readState.Index' (duration: 502.515533ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T22:59:23.586719Z","caller":"traceutil/trace.go:171","msg":"trace[1122294211] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"894.869622ms","start":"2026-03-13T22:59:22.69182Z","end":"2026-03-13T22:59:23.58669Z","steps":["trace[1122294211] 'process raft request' (duration: 392.373199ms)","trace[1122294211] 'compare' (duration: 500.262845ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T22:59:23.586833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T22:59:22.691792Z","time spent":"894.996823ms","remote":"127.0.0.1:52414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":170,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-13T22:59:23.587024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.658985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-13T22:59:23.588006Z","caller":"traceutil/trace.go:171","msg":"trace[721469693] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:305; }","duration":"852.878427ms","start":"2026-03-13T22:59:22.734237Z","end":"2026-03-13T22:59:23.587116Z","steps":["trace[721469693] 'agreement among raft nodes before linearized reading' (duration: 852.582655ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T22:59:23.588137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T22:59:22.734217Z","time spent":"853.873666ms","remote":"127.0.0.1:52336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-03-13T22:59:24.087736Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835591453063613367,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-13T22:59:24.244886Z","caller":"traceutil/trace.go:171","msg":"trace[1866294818] transaction","detail":"{read_only:false; number_of_response:0; response_revision:305; }","duration":"982.923491ms","start":"2026-03-13T22:59:23.261936Z","end":"2026-03-13T22:59:24.24486Z","steps":["trace[1866294818] 'process raft request' (duration: 965.73114ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T22:59:24.244992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T22:59:23.261921Z","time spent":"983.020993ms","remote":"127.0.0.1:52394","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-13T22:59:24.998734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000109344s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-13T22:59:24.998829Z","caller":"traceutil/trace.go:171","msg":"trace[1649127261] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000263495s","start":"2026-03-13T22:59:22.998544Z","end":"2026-03-13T22:59:24.998807Z","steps":["trace[1649127261] 'agreement among raft nodes before linearized reading' (duration: 2.000149065s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T22:59:24.998881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T22:59:22.998522Z","time spent":"2.000347426s","remote":"127.0.0.1:52232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/13 22:59:24 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-03-13T22:59:25.096218Z","caller":"traceutil/trace.go:171","msg":"trace[1580114245] transaction","detail":"{read_only:false; number_of_response:0; response_revision:305; }","duration":"1.83413718s","start":"2026-03-13T22:59:23.262051Z","end":"2026-03-13T22:59:25.096188Z","steps":["trace[1580114245] 'process raft request' (duration: 1.83401988s)"],"step_count":1} {"level":"info","ts":"2026-03-13T22:59:25.096333Z","caller":"traceutil/trace.go:171","msg":"trace[645839608] linearizableReadLoop","detail":"{readStateIndex:319; appliedIndex:314; }","duration":"1.509542121s","start":"2026-03-13T22:59:23.58676Z","end":"2026-03-13T22:59:25.096302Z","steps":["trace[645839608] 'read index received' (duration: 640.92182ms)","trace[645839608] 'applied index is now lower than readState.Index' (duration: 868.618921ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T22:59:25.096383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T22:59:23.262023Z","time spent":"1.834264811s","remote":"127.0.0.1:52394","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-13T22:59:25.096412Z","caller":"traceutil/trace.go:171","msg":"trace[1462832549] transaction","detail":"{read_only:false; number_of_response:0; response_revision:305; }","duration":"1.833829527s","start":"2026-03-13T22:59:23.262509Z","end":"2026-03-13T22:59:25.096339Z","steps":["trace[1462832549] 'process raft request' (duration: 1.833636936s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T22:59:25.096552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.836508519s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3980"} {"level":"info","ts":"2026-03-13T22:59:25.096613Z","caller":"traceutil/trace.go:171","msg":"trace[1528460713] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:307; }","duration":"1.836605019s","start":"2026-03-13T22:59:23.259994Z","end":"2026-03-13T22:59:25.096599Z","steps":["trace[1528460713] 'agreement among raft nodes before linearized reading' (duration: 1.836438618s)"],"step_count":1} {"level":"info","ts":"2026-03-13T22:59:25.096631Z","caller":"traceutil/trace.go:171","msg":"trace[421167245] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"1.503635175s","start":"2026-03-13T22:59:23.592979Z","end":"2026-03-13T22:59:25.096615Z","steps":["trace[421167245] 'process raft request' (duration: 1.503189772s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T22:59:25.096655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T22:59:23.259904Z","time spent":"1.83674296s","remote":"127.0.0.1:52394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":4003,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"info","ts":"2026-03-13T22:59:25.096391Z","caller":"traceutil/trace.go:171","msg":"trace[141394170] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"1.502538327s","start":"2026-03-13T22:59:23.593811Z","end":"2026-03-13T22:59:25.096349Z","steps":["trace[141394170] 'process raft request' (duration: 1.502439486s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T22:59:25.096732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T22:59:23.592963Z","time spent":"1.503706096s","remote":"127.0.0.1:52574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":420,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-13T22:59:25.096731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T22:59:23.262462Z","time spent":"1.83408847s","remote":"127.0.0.1:52394","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-13T22:59:25.096564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"850.365192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5897"} {"level":"warn","ts":"2026-03-13T22:59:25.096897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.796671532s","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-03-13T22:59:25.096504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.907339662s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-13T22:59:25.096945Z","caller":"traceutil/trace.go:171","msg":"trace[1322206909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:307; }","duration":"1.796744303s","start":"2026-03-13T22:59:23.300186Z","end":"2026-03-13T22:59:25.096931Z","steps":["trace[1322206909] 'agreement among raft nodes before linearized reading' (duration: 1.796677212s)"],"step_count":1} {"level":"info","ts":"2026-03-13T22:59:25.096953Z","caller":"traceutil/trace.go:171","msg":"trace[649766674] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:307; }","duration":"1.907837365s","start":"2026-03-13T22:59:23.189107Z","end":"2026-03-13T22:59:25.096945Z","steps":["trace[649766674] 'agreement among raft nodes before linearized reading' (duration: 1.907312591s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T22:59:25.096981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T22:59:23.300173Z","time spent":"1.796800883s","remote":"127.0.0.1:52488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-13T22:59:25.096989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T22:59:23.189078Z","time spent":"1.907901325s","remote":"127.0.0.1:52488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-13T22:59:25.097071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.513189869s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T22:59:25.097099Z","caller":"traceutil/trace.go:171","msg":"trace[156266750] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:307; }","duration":"1.513257079s","start":"2026-03-13T22:59:23.583835Z","end":"2026-03-13T22:59:25.097092Z","steps":["trace[156266750] 'agreement among raft nodes before linearized reading' (duration: 1.513216869s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T22:59:25.09713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.793325867s","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-03-13T22:59:25.097176Z","caller":"traceutil/trace.go:171","msg":"trace[199918338] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:307; }","duration":"1.793375337s","start":"2026-03-13T22:59:23.303789Z","end":"2026-03-13T22:59:25.097165Z","steps":["trace[199918338] 'agreement among raft nodes before linearized reading' (duration: 1.793275907s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T22:59:25.097206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T22:59:23.303722Z","time spent":"1.793476878s","remote":"127.0.0.1:52488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-13T22:59:25.0969Z","caller":"traceutil/trace.go:171","msg":"trace[1428922350] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:307; }","duration":"850.721305ms","start":"2026-03-13T22:59:24.246167Z","end":"2026-03-13T22:59:25.096888Z","steps":["trace[1428922350] 'agreement among raft nodes before linearized reading' (duration: 850.371013ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T22:59:25.096826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T22:59:23.593803Z","time spent":"1.50296686s","remote":"127.0.0.1:52414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":201,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-13T22:59:25.097255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T22:59:24.246141Z","time spent":"851.101188ms","remote":"127.0.0.1:52394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":5920,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-03-13T22:59:25.097123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T22:59:23.583811Z","time spent":"1.513307349s","remote":"127.0.0.1:52488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-13T22:59:27.222865Z","caller":"traceutil/trace.go:171","msg":"trace[2134335465] linearizableReadLoop","detail":"{readStateIndex:366; appliedIndex:365; }","duration":"223.701163ms","start":"2026-03-13T22:59:26.99913Z","end":"2026-03-13T22:59:27.222832Z","steps":["trace[2134335465] 'read index received' (duration: 223.415071ms)","trace[2134335465] 'applied index is now lower than readState.Index' (duration: 284.802µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T22:59:27.222914Z","caller":"traceutil/trace.go:171","msg":"trace[1604562704] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"262.383872ms","start":"2026-03-13T22:59:26.960507Z","end":"2026-03-13T22:59:27.222891Z","steps":["trace[1604562704] 'process raft request' (duration: 262.13803ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T22:59:27.223043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.893054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T22:59:27.223088Z","caller":"traceutil/trace.go:171","msg":"trace[2092451202] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:354; }","duration":"223.991974ms","start":"2026-03-13T22:59:26.999082Z","end":"2026-03-13T22:59:27.223074Z","steps":["trace[2092451202] 'agreement among raft nodes before linearized reading' (duration: 223.905934ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T22:59:27.223319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.388255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-03-13T22:59:27.223449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.108141ms","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-03-13T22:59:27.223443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.17188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-03-13T22:59:27.223495Z","caller":"traceutil/trace.go:171","msg":"trace[1125227021] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:354; }","duration":"109.173841ms","start":"2026-03-13T22:59:27.114304Z","end":"2026-03-13T22:59:27.223478Z","steps":["trace[1125227021] 'agreement among raft nodes before linearized reading' (duration: 109.124651ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T22:59:27.223499Z","caller":"traceutil/trace.go:171","msg":"trace[1502332196] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:354; }","duration":"121.278471ms","start":"2026-03-13T22:59:27.102207Z","end":"2026-03-13T22:59:27.223486Z","steps":["trace[1502332196] 'agreement among raft nodes before linearized reading' (duration: 121.183221ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T22:59:27.223539Z","caller":"traceutil/trace.go:171","msg":"trace[1153484946] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:354; }","duration":"221.581576ms","start":"2026-03-13T22:59:27.001863Z","end":"2026-03-13T22:59:27.223444Z","steps":["trace[1153484946] 'agreement among raft nodes before linearized reading' (duration: 221.285084ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T22:59:27.223336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.285074ms","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-03-13T22:59:27.223651Z","caller":"traceutil/trace.go:171","msg":"trace[2045134560] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:354; }","duration":"108.655946ms","start":"2026-03-13T22:59:27.114988Z","end":"2026-03-13T22:59:27.223644Z","steps":["trace[2045134560] 'agreement among raft nodes before linearized reading' (duration: 108.189493ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T22:59:27.223617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.399793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"warn","ts":"2026-03-13T22:59:27.223402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.368132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-13T22:59:27.223711Z","caller":"traceutil/trace.go:171","msg":"trace[100632339] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:354; }","duration":"113.706834ms","start":"2026-03-13T22:59:27.109996Z","end":"2026-03-13T22:59:27.223703Z","steps":["trace[100632339] 'agreement among raft nodes before linearized reading' (duration: 113.351431ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T22:59:27.22372Z","caller":"traceutil/trace.go:171","msg":"trace[1071976899] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:354; }","duration":"171.567875ms","start":"2026-03-13T22:59:27.05213Z","end":"2026-03-13T22:59:27.223698Z","steps":["trace[1071976899] 'agreement among raft nodes before linearized reading' (duration: 171.281123ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T22:59:39.610224Z","caller":"traceutil/trace.go:171","msg":"trace[593490869] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"122.806643ms","start":"2026-03-13T22:59:39.487388Z","end":"2026-03-13T22:59:39.610195Z","steps":["trace[593490869] 'process raft request' (duration: 122.639202ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T22:59:49.258646Z","caller":"traceutil/trace.go:171","msg":"trace[842638847] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:532; }","duration":"260.381162ms","start":"2026-03-13T22:59:48.998241Z","end":"2026-03-13T22:59:49.258623Z","steps":["trace[842638847] 'read index received' (duration: 260.178711ms)","trace[842638847] 'applied index is now lower than readState.Index' (duration: 201.511µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T22:59:49.258713Z","caller":"traceutil/trace.go:171","msg":"trace[307308608] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"353.896806ms","start":"2026-03-13T22:59:48.904798Z","end":"2026-03-13T22:59:49.258695Z","steps":["trace[307308608] 'process raft request' (duration: 353.698255ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T22:59:49.258781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.520463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T22:59:49.258864Z","caller":"traceutil/trace.go:171","msg":"trace[1980331715] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:515; }","duration":"260.648404ms","start":"2026-03-13T22:59:48.99819Z","end":"2026-03-13T22:59:49.258838Z","steps":["trace[1980331715] 'agreement among raft nodes before linearized reading' (duration: 260.515133ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T22:59:49.259092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T22:59:48.90478Z","time spent":"353.994416ms","remote":"127.0.0.1:52390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4567,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T22:59:55.593139Z","caller":"traceutil/trace.go:171","msg":"trace[848148310] linearizableReadLoop","detail":"{readStateIndex:575; appliedIndex:574; }","duration":"206.025762ms","start":"2026-03-13T22:59:55.387087Z","end":"2026-03-13T22:59:55.593113Z","steps":["trace[848148310] 'read index received' (duration: 205.811821ms)","trace[848148310] 'applied index is now lower than readState.Index' (duration: 212.951µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T22:59:55.593214Z","caller":"traceutil/trace.go:171","msg":"trace[220735103] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"234.997165ms","start":"2026-03-13T22:59:55.358176Z","end":"2026-03-13T22:59:55.593173Z","steps":["trace[220735103] 'process raft request' (duration: 234.792003ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T22:59:55.593424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.309977ms","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-13T22:59:55.593474Z","caller":"traceutil/trace.go:171","msg":"trace[999525110] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:556; }","duration":"206.407232ms","start":"2026-03-13T22:59:55.387054Z","end":"2026-03-13T22:59:55.593461Z","steps":["trace[999525110] 'agreement among raft nodes before linearized reading' (duration: 206.224793ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T22:59:55.593598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.132212ms","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-03-13T22:59:55.593634Z","caller":"traceutil/trace.go:171","msg":"trace[612275696] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:556; }","duration":"194.211736ms","start":"2026-03-13T22:59:55.399411Z","end":"2026-03-13T22:59:55.593623Z","steps":["trace[612275696] 'agreement among raft nodes before linearized reading' (duration: 194.041958ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T22:59:55.593737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.520804ms","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-13T22:59:55.593755Z","caller":"traceutil/trace.go:171","msg":"trace[1646619435] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:556; }","duration":"179.538085ms","start":"2026-03-13T22:59:55.414211Z","end":"2026-03-13T22:59:55.593749Z","steps":["trace[1646619435] 'agreement among raft nodes before linearized reading' (duration: 179.486992ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T22:59:55.593912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.695959ms","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-03-13T22:59:55.593974Z","caller":"traceutil/trace.go:171","msg":"trace[1769114219] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:556; }","duration":"192.773223ms","start":"2026-03-13T22:59:55.401184Z","end":"2026-03-13T22:59:55.593957Z","steps":["trace[1769114219] 'agreement among raft nodes before linearized reading' (duration: 192.612885ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T22:59:57.074424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.153234s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T22:59:57.075054Z","caller":"traceutil/trace.go:171","msg":"trace[1485185840] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"1.475196371s","start":"2026-03-13T22:59:55.599785Z","end":"2026-03-13T22:59:57.074981Z","steps":["trace[1485185840] 'process raft request' (duration: 318.48865ms)","trace[1485185840] 'compare' (duration: 1.152979918s)"],"step_count":2} {"level":"warn","ts":"2026-03-13T22:59:57.075306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T22:59:55.59975Z","time spent":"1.475415423s","remote":"127.0.0.1:52488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T22:59:57.075465Z","caller":"traceutil/trace.go:171","msg":"trace[1040355120] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"1.474878275s","start":"2026-03-13T22:59:55.600543Z","end":"2026-03-13T22:59:57.075421Z","steps":["trace[1040355120] 'process raft request' (duration: 1.474059944s)"],"step_count":1} {"level":"info","ts":"2026-03-13T22:59:57.075513Z","caller":"traceutil/trace.go:171","msg":"trace[425221664] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"1.472540477s","start":"2026-03-13T22:59:55.602922Z","end":"2026-03-13T22:59:57.075463Z","steps":["trace[425221664] 'process raft request' (duration: 1.472049632s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T22:59:57.075683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T22:59:55.600487Z","time spent":"1.475085656s","remote":"127.0.0.1:52488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T22:59:57.075759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T22:59:55.602909Z","time spent":"1.472724476s","remote":"127.0.0.1:52488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T22:59:57.076105Z","caller":"traceutil/trace.go:171","msg":"trace[424366716] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:576; }","duration":"1.076558233s","start":"2026-03-13T22:59:55.999473Z","end":"2026-03-13T22:59:57.076031Z","steps":["trace[424366716] 'read index received' (duration: 118.906µs)","trace[424366716] 'applied index is now lower than readState.Index' (duration: 1.076036998s)"],"step_count":2} {"level":"warn","ts":"2026-03-13T22:59:57.264756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.699994ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-03-13T22:59:57.264798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.265265397s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T22:59:57.265686Z","caller":"traceutil/trace.go:171","msg":"trace[810159210] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:560; }","duration":"1.266203044s","start":"2026-03-13T22:59:55.999416Z","end":"2026-03-13T22:59:57.265619Z","steps":["trace[810159210] 'agreement among raft nodes before linearized reading' (duration: 1.076740252s)","trace[810159210] 'range keys from in-memory index tree' (duration: 188.257422ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T22:59:57.265787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T22:59:55.999387Z","time spent":"1.266361472s","remote":"127.0.0.1:52224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-13T22:59:58.369427Z","caller":"traceutil/trace.go:171","msg":"trace[115508787] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"700.218789ms","start":"2026-03-13T22:59:57.669171Z","end":"2026-03-13T22:59:58.36939Z","steps":["trace[115508787] 'process raft request' (duration: 699.939145ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T22:59:58.369636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T22:59:57.669137Z","time spent":"700.385086ms","remote":"127.0.0.1:52488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T22:59:59.46855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.910042ms","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-03-13T22:59:59.468639Z","caller":"traceutil/trace.go:171","msg":"trace[429658009] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:561; }","duration":"388.048128ms","start":"2026-03-13T22:59:59.080572Z","end":"2026-03-13T22:59:59.46862Z","steps":["trace[429658009] 'range keys from in-memory index tree' (duration: 387.740544ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T22:59:59.468685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T22:59:59.080548Z","time spent":"388.127403ms","remote":"127.0.0.1:52488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-13T22:59:59.468843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.383316ms","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-13T22:59:59.468558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.781025ms","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-13T22:59:59.468909Z","caller":"traceutil/trace.go:171","msg":"trace[1617212864] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:561; }","duration":"387.505832ms","start":"2026-03-13T22:59:59.081384Z","end":"2026-03-13T22:59:59.46889Z","steps":["trace[1617212864] 'range keys from in-memory index tree' (duration: 387.24491ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T22:59:59.468942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T22:59:59.081363Z","time spent":"387.571695ms","remote":"127.0.0.1:52488","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-03-13T22:59:59.468942Z","caller":"traceutil/trace.go:171","msg":"trace[1495909941] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:561; }","duration":"388.182175ms","start":"2026-03-13T22:59:59.080744Z","end":"2026-03-13T22:59:59.468926Z","steps":["trace[1495909941] 'range keys from in-memory index tree' (duration: 387.621058ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T22:59:59.46898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T22:59:59.080737Z","time spent":"388.233778ms","remote":"127.0.0.1:52488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-13T22:59:59.468953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.304465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T22:59:59.469099Z","caller":"traceutil/trace.go:171","msg":"trace[516217166] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:561; }","duration":"470.460252ms","start":"2026-03-13T22:59:58.998586Z","end":"2026-03-13T22:59:59.469046Z","steps":["trace[516217166] 'range keys from in-memory index tree' (duration: 470.156758ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T22:59:59.469131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.137398ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T22:59:59.46917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T22:59:58.998456Z","time spent":"470.697654ms","remote":"127.0.0.1:52232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-13T22:59:59.4692Z","caller":"traceutil/trace.go:171","msg":"trace[1892043580] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:561; }","duration":"610.207421ms","start":"2026-03-13T22:59:58.858982Z","end":"2026-03-13T22:59:59.46919Z","steps":["trace[1892043580] 'range keys from in-memory index tree' (duration: 610.127488ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T22:59:59.583246Z","caller":"traceutil/trace.go:171","msg":"trace[535593110] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"110.68108ms","start":"2026-03-13T22:59:59.472528Z","end":"2026-03-13T22:59:59.583209Z","steps":["trace[535593110] 'process raft request' (duration: 110.432188ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T22:59:59.583491Z","caller":"traceutil/trace.go:171","msg":"trace[463884862] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"110.377435ms","start":"2026-03-13T22:59:59.473081Z","end":"2026-03-13T22:59:59.583459Z","steps":["trace[463884862] 'process raft request' (duration: 110.060219ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:00:04.710485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.944628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-03-13T23:00:04.710591Z","caller":"traceutil/trace.go:171","msg":"trace[66840983] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:635; }","duration":"134.108245ms","start":"2026-03-13T23:00:04.576458Z","end":"2026-03-13T23:00:04.710567Z","steps":["trace[66840983] 'range keys from in-memory index tree' (duration: 133.748078ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:00:41.15477Z","caller":"traceutil/trace.go:171","msg":"trace[837156626] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"133.740545ms","start":"2026-03-13T23:00:41.020998Z","end":"2026-03-13T23:00:41.154738Z","steps":["trace[837156626] 'process raft request' (duration: 133.537359ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:00:49.990685Z","caller":"traceutil/trace.go:171","msg":"trace[1441236752] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"126.799297ms","start":"2026-03-13T23:00:49.863863Z","end":"2026-03-13T23:00:49.990662Z","steps":["trace[1441236752] 'process raft request' (duration: 126.669304ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:01:17.828608Z","caller":"traceutil/trace.go:171","msg":"trace[2025893599] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"148.19921ms","start":"2026-03-13T23:01:17.680389Z","end":"2026-03-13T23:01:17.828588Z","steps":["trace[2025893599] 'process raft request' (duration: 148.051887ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:01:17.828764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.458114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-eb281899-7914-45ec-9d06-7d94c0f2e109\" ","response":"range_response_count:1 size:3579"} {"level":"info","ts":"2026-03-13T23:01:17.828595Z","caller":"traceutil/trace.go:171","msg":"trace[381020161] linearizableReadLoop","detail":"{readStateIndex:984; appliedIndex:983; }","duration":"147.289781ms","start":"2026-03-13T23:01:17.681283Z","end":"2026-03-13T23:01:17.828573Z","steps":["trace[381020161] 'read index received' (duration: 147.143358ms)","trace[381020161] 'applied index is now lower than readState.Index' (duration: 145.693µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:01:17.828812Z","caller":"traceutil/trace.go:171","msg":"trace[1355623424] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-eb281899-7914-45ec-9d06-7d94c0f2e109; range_end:; response_count:1; response_revision:945; }","duration":"147.543067ms","start":"2026-03-13T23:01:17.681255Z","end":"2026-03-13T23:01:17.828798Z","steps":["trace[1355623424] 'agreement among raft nodes before linearized reading' (duration: 147.390233ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:01:32.455528Z","caller":"traceutil/trace.go:171","msg":"trace[1866231844] linearizableReadLoop","detail":"{readStateIndex:1052; appliedIndex:1051; }","duration":"456.340782ms","start":"2026-03-13T23:01:31.999133Z","end":"2026-03-13T23:01:32.455474Z","steps":["trace[1866231844] 'read index received' (duration: 456.130818ms)","trace[1866231844] 'applied index is now lower than readState.Index' (duration: 208.764µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:01:32.455648Z","caller":"traceutil/trace.go:171","msg":"trace[846031959] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"502.06943ms","start":"2026-03-13T23:01:31.953543Z","end":"2026-03-13T23:01:32.455613Z","steps":["trace[846031959] 'process raft request' (duration: 501.641782ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:01:32.455766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.583067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T23:01:32.455843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:01:31.95352Z","time spent":"502.225764ms","remote":"127.0.0.1:52488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:01:32.45587Z","caller":"traceutil/trace.go:171","msg":"trace[1798252152] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1010; }","duration":"456.754261ms","start":"2026-03-13T23:01:31.999099Z","end":"2026-03-13T23:01:32.455853Z","steps":["trace[1798252152] 'agreement among raft nodes before linearized reading' (duration: 456.533976ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:01:32.455921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:01:31.999089Z","time spent":"456.824642ms","remote":"127.0.0.1:52224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-13T23:01:32.468327Z","caller":"traceutil/trace.go:171","msg":"trace[73179417] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"463.870118ms","start":"2026-03-13T23:01:32.004445Z","end":"2026-03-13T23:01:32.468316Z","steps":["trace[73179417] 'process raft request' (duration: 463.761274ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:01:32.468389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:01:32.004438Z","time spent":"463.918518ms","remote":"127.0.0.1:52488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:01:32.468588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.302961ms","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-03-13T23:01:32.468613Z","caller":"traceutil/trace.go:171","msg":"trace[694253101] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1011; }","duration":"218.384732ms","start":"2026-03-13T23:01:32.250221Z","end":"2026-03-13T23:01:32.468606Z","steps":["trace[694253101] 'agreement among raft nodes before linearized reading' (duration: 218.29223ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:01:32.468687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.516001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-03-13T23:01:32.468778Z","caller":"traceutil/trace.go:171","msg":"trace[1155577822] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1011; }","duration":"209.652124ms","start":"2026-03-13T23:01:32.259106Z","end":"2026-03-13T23:01:32.468758Z","steps":["trace[1155577822] 'agreement among raft nodes before linearized reading' (duration: 209.395209ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:01:32.468842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.68037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-13T23:01:32.468877Z","caller":"traceutil/trace.go:171","msg":"trace[489697474] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1011; }","duration":"271.757922ms","start":"2026-03-13T23:01:32.197109Z","end":"2026-03-13T23:01:32.468867Z","steps":["trace[489697474] 'agreement among raft nodes before linearized reading' (duration: 271.346734ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:01:50.903815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.020585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-03-13T23:01:50.903881Z","caller":"traceutil/trace.go:171","msg":"trace[1240233588] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1068; }","duration":"100.130747ms","start":"2026-03-13T23:01:50.803734Z","end":"2026-03-13T23:01:50.903865Z","steps":["trace[1240233588] 'range keys from in-memory index tree' (duration: 99.946963ms)"],"step_count":1}