{"level":"warn","ts":"2026-04-28T06:05:14.658022Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T06:05:14.658185Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.57: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.57:2380","--initial-cluster=instance=https://199.204.45.57:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.57:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.57: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-04-28T06:05:14.658378Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T06:05:14.658502Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-04-28T06:05:14.658631Z","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-04-28T06:05:14.659635Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"]} {"level":"info","ts":"2026-04-28T06:05:14.659744Z","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.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.57: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-04-28T06:05:14.681723Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"21.749019ms"} {"level":"info","ts":"2026-04-28T06:05:14.704581Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b6478f0ac5aeb8e1","cluster-id":"6c58cff80d4a5b62"} {"level":"info","ts":"2026-04-28T06:05:14.704688Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=()"} {"level":"info","ts":"2026-04-28T06:05:14.704718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 0"} {"level":"info","ts":"2026-04-28T06:05:14.704742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b6478f0ac5aeb8e1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-28T06:05:14.704762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 1"} {"level":"info","ts":"2026-04-28T06:05:14.704818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"warn","ts":"2026-04-28T06:05:14.716907Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-28T06:05:14.726402Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-28T06:05:14.729367Z","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-04-28T06:05:14.746894Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b6478f0ac5aeb8e1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-28T06:05:14.747625Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b6478f0ac5aeb8e1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-28T06:05:14.747761Z","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-04-28T06:05:14.747828Z","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-04-28T06:05:14.747841Z","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-04-28T06:05:14.750886Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"info","ts":"2026-04-28T06:05:14.751107Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","added-peer-id":"b6478f0ac5aeb8e1","added-peer-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-04-28T06:05:14.752898Z","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-04-28T06:05:14.753142Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-04-28T06:05:14.753201Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-04-28T06:05:14.753196Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b6478f0ac5aeb8e1","initial-advertise-peer-urls":["https://199.204.45.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-28T06:05:14.753257Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-28T06:05:15.205695Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 is starting a new election at term 1"} {"level":"info","ts":"2026-04-28T06:05:15.205878Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-28T06:05:15.205952Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgPreVoteResp from b6478f0ac5aeb8e1 at term 1"} {"level":"info","ts":"2026-04-28T06:05:15.206032Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became candidate at term 2"} {"level":"info","ts":"2026-04-28T06:05:15.20608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgVoteResp from b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-04-28T06:05:15.206156Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became leader at term 2"} {"level":"info","ts":"2026-04-28T06:05:15.20626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b6478f0ac5aeb8e1 elected leader b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-04-28T06:05:15.208162Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T06:05:15.208247Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:05:15.208289Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T06:05:15.208899Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-28T06:05:15.208924Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-28T06:05:15.208131Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b6478f0ac5aeb8e1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.57:2379]}","request-path":"/0/members/b6478f0ac5aeb8e1/attributes","cluster-id":"6c58cff80d4a5b62","publish-timeout":"7s"} {"level":"info","ts":"2026-04-28T06:05:15.212378Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-28T06:05:15.21253Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.57:2379"} {"level":"info","ts":"2026-04-28T06:05:15.305047Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:05:15.305156Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:05:15.305189Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:05:17.534629Z","caller":"traceutil/trace.go:171","msg":"trace[284130201] linearizableReadLoop","detail":"{readStateIndex:80; appliedIndex:79; }","duration":"101.481159ms","start":"2026-04-28T06:05:17.433124Z","end":"2026-04-28T06:05:17.534606Z","steps":["trace[284130201] 'read index received' (duration: 101.307765ms)","trace[284130201] 'applied index is now lower than readState.Index' (duration: 172.444µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:05:17.534685Z","caller":"traceutil/trace.go:171","msg":"trace[1559999442] transaction","detail":"{read_only:false; response_revision:76; number_of_response:1; }","duration":"102.869536ms","start":"2026-04-28T06:05:17.43177Z","end":"2026-04-28T06:05:17.53464Z","steps":["trace[1559999442] 'process raft request' (duration: 102.713893ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:05:17.53487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.728252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T06:05:17.534996Z","caller":"traceutil/trace.go:171","msg":"trace[1226057457] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:76; }","duration":"101.896863ms","start":"2026-04-28T06:05:17.433081Z","end":"2026-04-28T06:05:17.534978Z","steps":["trace[1226057457] 'agreement among raft nodes before linearized reading' (duration: 101.729961ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:05:17.694728Z","caller":"traceutil/trace.go:171","msg":"trace[947517624] linearizableReadLoop","detail":"{readStateIndex:81; appliedIndex:80; }","duration":"153.112428ms","start":"2026-04-28T06:05:17.541592Z","end":"2026-04-28T06:05:17.694704Z","steps":["trace[947517624] 'read index received' (duration: 127.416584ms)","trace[947517624] 'applied index is now lower than readState.Index' (duration: 25.694744ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:05:17.694866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.26099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T06:05:17.694898Z","caller":"traceutil/trace.go:171","msg":"trace[692322781] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:0; response_revision:77; }","duration":"153.347381ms","start":"2026-04-28T06:05:17.54154Z","end":"2026-04-28T06:05:17.694887Z","steps":["trace[692322781] 'agreement among raft nodes before linearized reading' (duration: 153.25004ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:05:17.694992Z","caller":"traceutil/trace.go:171","msg":"trace[647824695] transaction","detail":"{read_only:false; response_revision:77; number_of_response:1; }","duration":"155.048283ms","start":"2026-04-28T06:05:17.539906Z","end":"2026-04-28T06:05:17.694955Z","steps":["trace[647824695] 'process raft request' (duration: 129.125057ms)","trace[647824695] 'compare' (duration: 25.574342ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:05:18.232576Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322102700978491874,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:05:20.590466Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.644285178s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-28T06:05:20.633226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999778889s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-28T06:05:20.633391Z","caller":"traceutil/trace.go:171","msg":"trace[228409217] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000011682s","start":"2026-04-28T06:05:18.633353Z","end":"2026-04-28T06:05:20.633364Z","steps":["trace[228409217] 'agreement among raft nodes before linearized reading' (duration: 1.999813339s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:05:20.63351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:05:18.633333Z","time spent":"2.000160053s","remote":"127.0.0.1:48260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/28 06:05:20 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-28T06:05:21.490855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.911930393s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-28T06:05:21.491105Z","caller":"traceutil/trace.go:171","msg":"trace[687629019] transaction","detail":"{read_only:false; response_revision:78; number_of_response:1; }","duration":"3.786113055s","start":"2026-04-28T06:05:17.704929Z","end":"2026-04-28T06:05:21.491042Z","steps":["trace[687629019] 'process raft request' (duration: 872.623982ms)","trace[687629019] 'compare' (duration: 2.911353396s)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:05:21.491166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:05:18.946085Z","time spent":"2.545076269s","remote":"127.0.0.1:48322","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-28T06:05:21.491282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:05:17.704914Z","time spent":"3.786283488s","remote":"127.0.0.1:48622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:05:21.491393Z","caller":"traceutil/trace.go:171","msg":"trace[386214882] linearizableReadLoop","detail":"{readStateIndex:82; appliedIndex:81; }","duration":"3.758868492s","start":"2026-04-28T06:05:17.732499Z","end":"2026-04-28T06:05:21.491367Z","steps":["trace[386214882] 'read index received' (duration: 844.998623ms)","trace[386214882] 'applied index is now lower than readState.Index' (duration: 2.913867479s)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:05:21.491528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.759016234s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-28T06:05:21.491564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.550713833s","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-04-28T06:05:21.491593Z","caller":"traceutil/trace.go:171","msg":"trace[20320377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:78; }","duration":"2.550779973s","start":"2026-04-28T06:05:18.940805Z","end":"2026-04-28T06:05:21.491585Z","steps":["trace[20320377] 'agreement among raft nodes before linearized reading' (duration: 2.550700652s)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:05:21.491587Z","caller":"traceutil/trace.go:171","msg":"trace[52690129] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:78; }","duration":"3.759132145s","start":"2026-04-28T06:05:17.73244Z","end":"2026-04-28T06:05:21.491572Z","steps":["trace[52690129] 'agreement among raft nodes before linearized reading' (duration: 3.759024434s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:05:21.491624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.64604355s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-28T06:05:21.491643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:05:17.732419Z","time spent":"3.759214436s","remote":"127.0.0.1:48418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"warn","ts":"2026-04-28T06:05:21.491633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:05:18.940784Z","time spent":"2.550841354s","remote":"127.0.0.1:48536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-28T06:05:21.491672Z","caller":"traceutil/trace.go:171","msg":"trace[1382040433] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:78; }","duration":"1.646130121s","start":"2026-04-28T06:05:19.845529Z","end":"2026-04-28T06:05:21.491659Z","steps":["trace[1382040433] 'agreement among raft nodes before linearized reading' (duration: 1.64604508s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:05:21.491708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:05:19.845508Z","time spent":"1.646193252s","remote":"127.0.0.1:48260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T06:05:21.491707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.55051457s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-28T06:05:21.49182Z","caller":"traceutil/trace.go:171","msg":"trace[575387764] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:78; }","duration":"2.550674292s","start":"2026-04-28T06:05:18.941122Z","end":"2026-04-28T06:05:21.491796Z","steps":["trace[575387764] 'agreement among raft nodes before linearized reading' (duration: 2.550482009s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:05:21.491907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:05:18.941114Z","time spent":"2.550781203s","remote":"127.0.0.1:48536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T06:05:21.492395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"858.142748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T06:05:21.492484Z","caller":"traceutil/trace.go:171","msg":"trace[464184178] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:78; }","duration":"858.913437ms","start":"2026-04-28T06:05:20.633549Z","end":"2026-04-28T06:05:21.492462Z","steps":["trace[464184178] 'agreement among raft nodes before linearized reading' (duration: 857.991476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:05:21.49256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:05:20.633519Z","time spent":"859.027539ms","remote":"127.0.0.1:48276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-28T06:05:21.991668Z","caller":"traceutil/trace.go:171","msg":"trace[382067932] linearizableReadLoop","detail":"{readStateIndex:84; appliedIndex:83; }","duration":"496.393818ms","start":"2026-04-28T06:05:21.495247Z","end":"2026-04-28T06:05:21.991641Z","steps":["trace[382067932] 'read index received' (duration: 475.113368ms)","trace[382067932] 'applied index is now lower than readState.Index' (duration: 21.279249ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:05:21.991692Z","caller":"traceutil/trace.go:171","msg":"trace[512091165] transaction","detail":"{read_only:false; response_revision:79; number_of_response:1; }","duration":"498.739517ms","start":"2026-04-28T06:05:21.492927Z","end":"2026-04-28T06:05:21.991667Z","steps":["trace[512091165] 'process raft request' (duration: 477.423587ms)","trace[512091165] 'compare' (duration: 21.060437ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:05:21.991906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:05:21.492909Z","time spent":"498.850118ms","remote":"127.0.0.1:48322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:05:21.992037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.728701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:discovery\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T06:05:21.992145Z","caller":"traceutil/trace.go:171","msg":"trace[23618653] range","detail":"{range_begin:/registry/clusterroles/system:discovery; range_end:; response_count:0; response_revision:79; }","duration":"496.918374ms","start":"2026-04-28T06:05:21.495207Z","end":"2026-04-28T06:05:21.992125Z","steps":["trace[23618653] 'agreement among raft nodes before linearized reading' (duration: 496.57946ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:05:21.992236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:05:21.495195Z","time spent":"497.030445ms","remote":"127.0.0.1:48622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/system:discovery\" "} {"level":"info","ts":"2026-04-28T06:05:22.001166Z","caller":"traceutil/trace.go:171","msg":"trace[1142148383] transaction","detail":"{read_only:false; response_revision:80; number_of_response:1; }","duration":"505.837567ms","start":"2026-04-28T06:05:21.4953Z","end":"2026-04-28T06:05:22.001138Z","steps":["trace[1142148383] 'process raft request' (duration: 505.674255ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:05:22.001201Z","caller":"traceutil/trace.go:171","msg":"trace[512766904] transaction","detail":"{read_only:false; response_revision:81; number_of_response:1; }","duration":"505.791317ms","start":"2026-04-28T06:05:21.495384Z","end":"2026-04-28T06:05:22.001176Z","steps":["trace[512766904] 'process raft request' (duration: 505.664585ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:05:22.001223Z","caller":"traceutil/trace.go:171","msg":"trace[46488771] transaction","detail":"{read_only:false; response_revision:82; number_of_response:1; }","duration":"505.742977ms","start":"2026-04-28T06:05:21.49546Z","end":"2026-04-28T06:05:22.001203Z","steps":["trace[46488771] 'process raft request' (duration: 505.623046ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:05:22.00131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:05:21.495375Z","time spent":"505.879189ms","remote":"127.0.0.1:48456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:05:22.00131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:05:21.495448Z","time spent":"505.820098ms","remote":"127.0.0.1:48456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3959,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:05:22.001321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:05:21.495283Z","time spent":"505.963779ms","remote":"127.0.0.1:48456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5877,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:05:22.001339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.590585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T06:05:22.001388Z","caller":"traceutil/trace.go:171","msg":"trace[577354617] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:85; }","duration":"468.675306ms","start":"2026-04-28T06:05:21.532699Z","end":"2026-04-28T06:05:22.001374Z","steps":["trace[577354617] 'agreement among raft nodes before linearized reading' (duration: 468.548124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:05:22.001423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:05:21.532685Z","time spent":"468.727316ms","remote":"127.0.0.1:48260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-28T06:05:22.001437Z","caller":"traceutil/trace.go:171","msg":"trace[606593103] transaction","detail":"{read_only:false; response_revision:83; number_of_response:1; }","duration":"505.953949ms","start":"2026-04-28T06:05:21.495472Z","end":"2026-04-28T06:05:22.001426Z","steps":["trace[606593103] 'process raft request' (duration: 505.656105ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:05:22.001441Z","caller":"traceutil/trace.go:171","msg":"trace[1349380999] transaction","detail":"{read_only:false; response_revision:84; number_of_response:1; }","duration":"505.044288ms","start":"2026-04-28T06:05:21.496386Z","end":"2026-04-28T06:05:22.00143Z","steps":["trace[1349380999] 'process raft request' (duration: 504.778155ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:05:22.001552Z","caller":"traceutil/trace.go:171","msg":"trace[1830631822] transaction","detail":"{read_only:false; response_revision:85; number_of_response:1; }","duration":"504.160226ms","start":"2026-04-28T06:05:21.497372Z","end":"2026-04-28T06:05:22.001533Z","steps":["trace[1830631822] 'process raft request' (duration: 503.833152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:05:22.001638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:05:21.495464Z","time spent":"505.99597ms","remote":"127.0.0.1:48456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3453,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:05:22.001666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:05:21.497357Z","time spent":"504.263467ms","remote":"127.0.0.1:48536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:05:22.001664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:05:21.496375Z","time spent":"505.087258ms","remote":"127.0.0.1:48536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:05:22.582102Z","caller":"traceutil/trace.go:171","msg":"trace[1577673449] transaction","detail":"{read_only:false; response_revision:112; number_of_response:1; }","duration":"197.214163ms","start":"2026-04-28T06:05:22.384858Z","end":"2026-04-28T06:05:22.582072Z","steps":["trace[1577673449] 'process raft request' (duration: 163.62855ms)","trace[1577673449] 'compare' (duration: 33.304479ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:05:22.582169Z","caller":"traceutil/trace.go:171","msg":"trace[364757338] transaction","detail":"{read_only:false; response_revision:113; number_of_response:1; }","duration":"194.914015ms","start":"2026-04-28T06:05:22.387222Z","end":"2026-04-28T06:05:22.582136Z","steps":["trace[364757338] 'process raft request' (duration: 194.668161ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:05:22.898906Z","caller":"traceutil/trace.go:171","msg":"trace[1132611326] transaction","detail":"{read_only:false; response_revision:131; number_of_response:1; }","duration":"100.761888ms","start":"2026-04-28T06:05:22.798109Z","end":"2026-04-28T06:05:22.898871Z","steps":["trace[1132611326] 'process raft request' (duration: 50.483375ms)","trace[1132611326] 'compare' (duration: 50.107121ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:05:37.853421Z","caller":"traceutil/trace.go:171","msg":"trace[122093121] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"151.647397ms","start":"2026-04-28T06:05:37.701745Z","end":"2026-04-28T06:05:37.853393Z","steps":["trace[122093121] 'process raft request' (duration: 151.475995ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:05:58.269658Z","caller":"traceutil/trace.go:171","msg":"trace[1939462730] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"133.09873ms","start":"2026-04-28T06:05:58.136535Z","end":"2026-04-28T06:05:58.269634Z","steps":["trace[1939462730] 'process raft request' (duration: 132.904538ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:05:58.285062Z","caller":"traceutil/trace.go:171","msg":"trace[471976815] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"141.465788ms","start":"2026-04-28T06:05:58.143577Z","end":"2026-04-28T06:05:58.285043Z","steps":["trace[471976815] 'process raft request' (duration: 140.946763ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:05:58.285116Z","caller":"traceutil/trace.go:171","msg":"trace[917923577] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"134.005239ms","start":"2026-04-28T06:05:58.151061Z","end":"2026-04-28T06:05:58.285066Z","steps":["trace[917923577] 'process raft request' (duration: 133.594255ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:05:58.285181Z","caller":"traceutil/trace.go:171","msg":"trace[1993043763] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"144.4948ms","start":"2026-04-28T06:05:58.140677Z","end":"2026-04-28T06:05:58.285172Z","steps":["trace[1993043763] 'process raft request' (duration: 143.673341ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:05:58.2852Z","caller":"traceutil/trace.go:171","msg":"trace[660321975] linearizableReadLoop","detail":"{readStateIndex:566; appliedIndex:558; }","duration":"110.629883ms","start":"2026-04-28T06:05:58.17454Z","end":"2026-04-28T06:05:58.28517Z","steps":["trace[660321975] 'read index received' (duration: 94.958887ms)","trace[660321975] 'applied index is now lower than readState.Index' (duration: 15.669506ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:05:58.285382Z","caller":"traceutil/trace.go:171","msg":"trace[1003591335] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"127.142797ms","start":"2026-04-28T06:05:58.158229Z","end":"2026-04-28T06:05:58.285372Z","steps":["trace[1003591335] 'process raft request' (duration: 126.50633ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:05:58.285477Z","caller":"traceutil/trace.go:171","msg":"trace[1912078599] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"134.416723ms","start":"2026-04-28T06:05:58.151004Z","end":"2026-04-28T06:05:58.285421Z","steps":["trace[1912078599] 'process raft request' (duration: 133.577134ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:05:58.285515Z","caller":"traceutil/trace.go:171","msg":"trace[1412317320] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"113.735835ms","start":"2026-04-28T06:05:58.17177Z","end":"2026-04-28T06:05:58.285506Z","steps":["trace[1412317320] 'process raft request' (duration: 113.080448ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:05:58.285663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.105758ms","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-04-28T06:05:58.285693Z","caller":"traceutil/trace.go:171","msg":"trace[731986542] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:547; }","duration":"111.148378ms","start":"2026-04-28T06:05:58.174537Z","end":"2026-04-28T06:05:58.285685Z","steps":["trace[731986542] 'agreement among raft nodes before linearized reading' (duration: 111.045647ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:05:58.285482Z","caller":"traceutil/trace.go:171","msg":"trace[2118866532] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"117.004531ms","start":"2026-04-28T06:05:58.168467Z","end":"2026-04-28T06:05:58.285472Z","steps":["trace[2118866532] 'process raft request' (duration: 116.321573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:05:59.041995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.867267ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:05:59.042101Z","caller":"traceutil/trace.go:171","msg":"trace[860626965] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"561.436962ms","start":"2026-04-28T06:05:58.480645Z","end":"2026-04-28T06:05:59.042082Z","steps":["trace[860626965] 'process raft request' (duration: 309.402073ms)","trace[860626965] 'compare' (duration: 251.670155ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:05:59.042188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:05:58.48062Z","time spent":"561.511203ms","remote":"127.0.0.1:48304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65190,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:05:59.043947Z","caller":"traceutil/trace.go:171","msg":"trace[165125529] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:601; }","duration":"512.953852ms","start":"2026-04-28T06:05:58.530978Z","end":"2026-04-28T06:05:59.043932Z","steps":["trace[165125529] 'read index received' (duration: 259.087454ms)","trace[165125529] 'applied index is now lower than readState.Index' (duration: 253.865358ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:05:59.043933Z","caller":"traceutil/trace.go:171","msg":"trace[1159551448] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"559.767464ms","start":"2026-04-28T06:05:58.484096Z","end":"2026-04-28T06:05:59.043863Z","steps":["trace[1159551448] 'process raft request' (duration: 559.557272ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:05:59.04404Z","caller":"traceutil/trace.go:171","msg":"trace[806579154] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"530.645878ms","start":"2026-04-28T06:05:58.513386Z","end":"2026-04-28T06:05:59.044032Z","steps":["trace[806579154] 'process raft request' (duration: 530.362684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:05:59.0441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:05:58.513369Z","time spent":"530.699899ms","remote":"127.0.0.1:48304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:05:59.044185Z","caller":"traceutil/trace.go:171","msg":"trace[34700821] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"515.191045ms","start":"2026-04-28T06:05:58.528986Z","end":"2026-04-28T06:05:59.044177Z","steps":["trace[34700821] 'process raft request' (duration: 514.8229ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:05:59.04423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:05:58.528947Z","time spent":"515.256596ms","remote":"127.0.0.1:48304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:05:59.044221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:05:58.484087Z","time spent":"559.966727ms","remote":"127.0.0.1:48304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:05:59.044355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.383117ms","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-04-28T06:05:59.044388Z","caller":"traceutil/trace.go:171","msg":"trace[704988762] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:578; }","duration":"513.461748ms","start":"2026-04-28T06:05:58.530918Z","end":"2026-04-28T06:05:59.04438Z","steps":["trace[704988762] 'agreement among raft nodes before linearized reading' (duration: 513.379867ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:05:59.044415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:05:58.530894Z","time spent":"513.514938ms","remote":"127.0.0.1:48536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T06:05:59.044569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.122717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:05:59.044602Z","caller":"traceutil/trace.go:171","msg":"trace[784395965] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:578; }","duration":"208.190597ms","start":"2026-04-28T06:05:58.836404Z","end":"2026-04-28T06:05:59.044594Z","steps":["trace[784395965] 'agreement among raft nodes before linearized reading' (duration: 208.142167ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:05.489983Z","caller":"traceutil/trace.go:171","msg":"trace[627606898] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"358.280216ms","start":"2026-04-28T06:06:05.131683Z","end":"2026-04-28T06:06:05.489963Z","steps":["trace[627606898] 'process raft request' (duration: 344.995301ms)","trace[627606898] 'compare' (duration: 12.540228ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:06:05.490108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:05.131662Z","time spent":"358.375857ms","remote":"127.0.0.1:48348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036297,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:06:06.430439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.73036ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:06:06.430757Z","caller":"traceutil/trace.go:171","msg":"trace[143930911] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"929.245851ms","start":"2026-04-28T06:06:05.501491Z","end":"2026-04-28T06:06:06.430737Z","steps":["trace[143930911] 'process raft request' (duration: 458.064126ms)","trace[143930911] 'compare' (duration: 465.658438ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:06:06.430818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:05.501471Z","time spent":"929.317471ms","remote":"127.0.0.1:48456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:06:06.431079Z","caller":"traceutil/trace.go:171","msg":"trace[387826856] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:676; }","duration":"405.851206ms","start":"2026-04-28T06:06:06.02517Z","end":"2026-04-28T06:06:06.431022Z","steps":["trace[387826856] 'read index received' (duration: 41.28902ms)","trace[387826856] 'applied index is now lower than readState.Index' (duration: 364.560666ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:06:06.431166Z","caller":"traceutil/trace.go:171","msg":"trace[990899276] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"627.932969ms","start":"2026-04-28T06:06:05.803212Z","end":"2026-04-28T06:06:06.431145Z","steps":["trace[990899276] 'process raft request' (duration: 627.713016ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:06.431231Z","caller":"traceutil/trace.go:171","msg":"trace[1062364318] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"875.040627ms","start":"2026-04-28T06:06:05.55617Z","end":"2026-04-28T06:06:06.43121Z","steps":["trace[1062364318] 'process raft request' (duration: 874.585322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:06.431284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:05.556147Z","time spent":"875.110837ms","remote":"127.0.0.1:48500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:06:06.431329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.1499ms","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-04-28T06:06:06.431387Z","caller":"traceutil/trace.go:171","msg":"trace[73568137] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:650; }","duration":"406.262812ms","start":"2026-04-28T06:06:06.025101Z","end":"2026-04-28T06:06:06.431364Z","steps":["trace[73568137] 'agreement among raft nodes before linearized reading' (duration: 406.11485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:06.431428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:06.025084Z","time spent":"406.333682ms","remote":"127.0.0.1:48536","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-04-28T06:06:06.433274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:05.803187Z","time spent":"628.02612ms","remote":"127.0.0.1:48536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:06:06.939853Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322102700978493092,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:06:07.160789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.19002ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:06:07.16108Z","caller":"traceutil/trace.go:171","msg":"trace[1600537777] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"725.035845ms","start":"2026-04-28T06:06:06.435943Z","end":"2026-04-28T06:06:07.160979Z","steps":["trace[1600537777] 'process raft request' (duration: 554.559412ms)","trace[1600537777] 'compare' (duration: 169.461042ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:06:07.161256Z","caller":"traceutil/trace.go:171","msg":"trace[1624850305] linearizableReadLoop","detail":"{readStateIndex:682; appliedIndex:679; }","duration":"722.073155ms","start":"2026-04-28T06:06:06.439163Z","end":"2026-04-28T06:06:07.161236Z","steps":["trace[1624850305] 'read index received' (duration: 551.357821ms)","trace[1624850305] 'applied index is now lower than readState.Index' (duration: 170.713564ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:06:07.161341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:06.435931Z","time spent":"725.272817ms","remote":"127.0.0.1:48536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:06:07.161364Z","caller":"traceutil/trace.go:171","msg":"trace[769813921] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"724.489329ms","start":"2026-04-28T06:06:06.43686Z","end":"2026-04-28T06:06:07.16135Z","steps":["trace[769813921] 'process raft request' (duration: 724.059965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:07.161429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:06.43684Z","time spent":"724.55692ms","remote":"127.0.0.1:48536","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-04-28T06:06:07.161505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.337928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-28T06:06:07.161543Z","caller":"traceutil/trace.go:171","msg":"trace[1997371307] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:653; }","duration":"722.402308ms","start":"2026-04-28T06:06:06.439129Z","end":"2026-04-28T06:06:07.161532Z","steps":["trace[1997371307] 'agreement among raft nodes before linearized reading' (duration: 722.305907ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:07.161557Z","caller":"traceutil/trace.go:171","msg":"trace[299092013] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"724.572649ms","start":"2026-04-28T06:06:06.436971Z","end":"2026-04-28T06:06:07.161544Z","steps":["trace[299092013] 'process raft request' (duration: 724.059154ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:07.161568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:06.439117Z","time spent":"722.447058ms","remote":"127.0.0.1:48476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-04-28T06:06:07.161615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:06.436963Z","time spent":"724.624291ms","remote":"127.0.0.1:48536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:06:07.161699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.089407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:06:07.161778Z","caller":"traceutil/trace.go:171","msg":"trace[1912177486] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:653; }","duration":"323.264348ms","start":"2026-04-28T06:06:06.838496Z","end":"2026-04-28T06:06:07.16176Z","steps":["trace[1912177486] 'agreement among raft nodes before linearized reading' (duration: 323.022626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:07.161851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:06.838465Z","time spent":"323.374321ms","remote":"127.0.0.1:48260","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-04-28T06:06:08.633834Z","caller":"traceutil/trace.go:171","msg":"trace[581682816] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"117.535649ms","start":"2026-04-28T06:06:08.516275Z","end":"2026-04-28T06:06:08.633811Z","steps":["trace[581682816] 'process raft request' (duration: 117.417648ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:09.347742Z","caller":"traceutil/trace.go:171","msg":"trace[434907282] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"130.416045ms","start":"2026-04-28T06:06:09.217298Z","end":"2026-04-28T06:06:09.347714Z","steps":["trace[434907282] 'process raft request' (duration: 129.891549ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:09.732813Z","caller":"traceutil/trace.go:171","msg":"trace[1664444165] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:704; }","duration":"127.433405ms","start":"2026-04-28T06:06:09.605362Z","end":"2026-04-28T06:06:09.732795Z","steps":["trace[1664444165] 'read index received' (duration: 127.104561ms)","trace[1664444165] 'applied index is now lower than readState.Index' (duration: 328.164µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:06:09.732883Z","caller":"traceutil/trace.go:171","msg":"trace[1663836058] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"213.660494ms","start":"2026-04-28T06:06:09.519188Z","end":"2026-04-28T06:06:09.732849Z","steps":["trace[1663836058] 'process raft request' (duration: 213.387382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:09.733058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.690687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-28T06:06:09.733127Z","caller":"traceutil/trace.go:171","msg":"trace[1287110658] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:675; }","duration":"127.829789ms","start":"2026-04-28T06:06:09.605279Z","end":"2026-04-28T06:06:09.733109Z","steps":["trace[1287110658] 'agreement among raft nodes before linearized reading' (duration: 127.603457ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:22.770401Z","caller":"traceutil/trace.go:171","msg":"trace[1615689095] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"163.743901ms","start":"2026-04-28T06:06:22.606622Z","end":"2026-04-28T06:06:22.770366Z","steps":["trace[1615689095] 'process raft request' (duration: 163.494929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:22.770851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.714721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T06:06:22.770925Z","caller":"traceutil/trace.go:171","msg":"trace[1151213903] linearizableReadLoop","detail":"{readStateIndex:847; appliedIndex:846; }","duration":"162.798352ms","start":"2026-04-28T06:06:22.60811Z","end":"2026-04-28T06:06:22.770909Z","steps":["trace[1151213903] 'read index received' (duration: 161.894253ms)","trace[1151213903] 'applied index is now lower than readState.Index' (duration: 902.449µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:06:22.771011Z","caller":"traceutil/trace.go:171","msg":"trace[1046502140] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:813; }","duration":"162.881953ms","start":"2026-04-28T06:06:22.608058Z","end":"2026-04-28T06:06:22.77094Z","steps":["trace[1046502140] 'agreement among raft nodes before linearized reading' (duration: 162.62198ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:25.417438Z","caller":"traceutil/trace.go:171","msg":"trace[923308492] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"116.076042ms","start":"2026-04-28T06:06:25.301337Z","end":"2026-04-28T06:06:25.417413Z","steps":["trace[923308492] 'process raft request' (duration: 115.87806ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:25.417696Z","caller":"traceutil/trace.go:171","msg":"trace[581334551] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"116.075001ms","start":"2026-04-28T06:06:25.301609Z","end":"2026-04-28T06:06:25.417684Z","steps":["trace[581334551] 'process raft request' (duration: 115.767378ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:55.767277Z","caller":"traceutil/trace.go:171","msg":"trace[498643951] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"160.070966ms","start":"2026-04-28T06:06:55.607184Z","end":"2026-04-28T06:06:55.767255Z","steps":["trace[498643951] 'process raft request' (duration: 159.540391ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:57.042156Z","caller":"traceutil/trace.go:171","msg":"trace[1769624116] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"100.764815ms","start":"2026-04-28T06:06:56.941362Z","end":"2026-04-28T06:06:57.042127Z","steps":["trace[1769624116] 'process raft request' (duration: 100.625434ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:08:12.187383Z","caller":"traceutil/trace.go:171","msg":"trace[562105553] linearizableReadLoop","detail":"{readStateIndex:1404; appliedIndex:1403; }","duration":"131.019969ms","start":"2026-04-28T06:08:12.056337Z","end":"2026-04-28T06:08:12.187357Z","steps":["trace[562105553] 'read index received' (duration: 130.867935ms)","trace[562105553] 'applied index is now lower than readState.Index' (duration: 150.604µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:08:12.187603Z","caller":"traceutil/trace.go:171","msg":"trace[409700816] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"175.14159ms","start":"2026-04-28T06:08:12.012385Z","end":"2026-04-28T06:08:12.187526Z","steps":["trace[409700816] 'process raft request' (duration: 174.800822ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:12.187674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.317187ms","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-04-28T06:08:12.188029Z","caller":"traceutil/trace.go:171","msg":"trace[2072980111] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1347; }","duration":"131.696215ms","start":"2026-04-28T06:08:12.056308Z","end":"2026-04-28T06:08:12.188004Z","steps":["trace[2072980111] 'agreement among raft nodes before linearized reading' (duration: 131.243434ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:08:12.1915Z","caller":"traceutil/trace.go:171","msg":"trace[1995152927] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"105.148769ms","start":"2026-04-28T06:08:12.086336Z","end":"2026-04-28T06:08:12.191484Z","steps":["trace[1995152927] 'process raft request' (duration: 104.874382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:12.1915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.169152ms","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-04-28T06:08:12.191573Z","caller":"traceutil/trace.go:171","msg":"trace[473162071] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1348; }","duration":"131.288655ms","start":"2026-04-28T06:08:12.060267Z","end":"2026-04-28T06:08:12.191556Z","steps":["trace[473162071] 'agreement among raft nodes before linearized reading' (duration: 131.0823ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:12.191729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.723562ms","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-04-28T06:08:12.19179Z","caller":"traceutil/trace.go:171","msg":"trace[1316101315] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1348; }","duration":"126.855435ms","start":"2026-04-28T06:08:12.064923Z","end":"2026-04-28T06:08:12.191779Z","steps":["trace[1316101315] 'agreement among raft nodes before linearized reading' (duration: 126.684101ms)"],"step_count":1}