{"level":"warn","ts":"2026-04-08T01:15:23.960399Z","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-08T01:15:23.960531Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.153: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.153:2380","--initial-cluster=instance=https://199.204.45.153:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.153:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.153: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-08T01:15:23.960612Z","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-08T01:15:23.960642Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.153:2380"]} {"level":"info","ts":"2026-04-08T01:15:23.960681Z","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-08T01:15:23.961294Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"]} {"level":"info","ts":"2026-04-08T01:15:23.961407Z","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.153:2380"],"listen-peer-urls":["https://199.204.45.153:2380"],"advertise-client-urls":["https://199.204.45.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.153: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-08T01:15:24.417773Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"456.136066ms"} {"level":"info","ts":"2026-04-08T01:15:24.64844Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"355195d5aae85944","cluster-id":"e424441517bc0b32"} {"level":"info","ts":"2026-04-08T01:15:24.648678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=()"} {"level":"info","ts":"2026-04-08T01:15:24.648773Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became follower at term 0"} {"level":"info","ts":"2026-04-08T01:15:24.648809Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 355195d5aae85944 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-08T01:15:24.648869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became follower at term 1"} {"level":"info","ts":"2026-04-08T01:15:24.649014Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=(3842016702051670340)"} {"level":"warn","ts":"2026-04-08T01:15:24.814235Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-08T01:15:24.890643Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-08T01:15:24.898365Z","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-08T01:15:24.90611Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"355195d5aae85944","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-08T01:15:24.906253Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"355195d5aae85944","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-08T01:15:24.906515Z","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-08T01:15:24.906627Z","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-08T01:15:24.906646Z","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-08T01:15:24.909986Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=(3842016702051670340)"} {"level":"info","ts":"2026-04-08T01:15:24.910136Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e424441517bc0b32","local-member-id":"355195d5aae85944","added-peer-id":"355195d5aae85944","added-peer-peer-urls":["https://199.204.45.153:2380"]} {"level":"info","ts":"2026-04-08T01:15:24.911419Z","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-08T01:15:24.911501Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.153:2380"} {"level":"info","ts":"2026-04-08T01:15:24.91155Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.153:2380"} {"level":"info","ts":"2026-04-08T01:15:24.911871Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"355195d5aae85944","initial-advertise-peer-urls":["https://199.204.45.153:2380"],"listen-peer-urls":["https://199.204.45.153:2380"],"advertise-client-urls":["https://199.204.45.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-08T01:15:24.911962Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-08T01:15:25.053282Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 is starting a new election at term 1"} {"level":"info","ts":"2026-04-08T01:15:25.053331Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-08T01:15:25.053466Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 received MsgPreVoteResp from 355195d5aae85944 at term 1"} {"level":"info","ts":"2026-04-08T01:15:25.053504Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became candidate at term 2"} {"level":"info","ts":"2026-04-08T01:15:25.053517Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 received MsgVoteResp from 355195d5aae85944 at term 2"} {"level":"info","ts":"2026-04-08T01:15:25.05354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became leader at term 2"} {"level":"info","ts":"2026-04-08T01:15:25.053555Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 355195d5aae85944 elected leader 355195d5aae85944 at term 2"} {"level":"info","ts":"2026-04-08T01:15:25.082248Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"355195d5aae85944","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.153:2379]}","request-path":"/0/members/355195d5aae85944/attributes","cluster-id":"e424441517bc0b32","publish-timeout":"7s"} {"level":"info","ts":"2026-04-08T01:15:25.082244Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T01:15:25.082284Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T01:15:25.082265Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T01:15:25.082688Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-08T01:15:25.082722Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-08T01:15:25.084433Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-08T01:15:25.086095Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.153:2379"} {"level":"info","ts":"2026-04-08T01:15:25.119743Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e424441517bc0b32","local-member-id":"355195d5aae85944","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T01:15:25.119934Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T01:15:25.120005Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-04-08T01:15:35.46418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.097813ms","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-08T01:15:35.464422Z","caller":"traceutil/trace.go:171","msg":"trace[819719574] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:287; }","duration":"156.394936ms","start":"2026-04-08T01:15:35.308002Z","end":"2026-04-08T01:15:35.464397Z","steps":["trace[819719574] 'range keys from in-memory index tree' (duration: 155.902222ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:15:36.796636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.903436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-04-08T01:15:36.796728Z","caller":"traceutil/trace.go:171","msg":"trace[1194923700] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:300; }","duration":"139.058087ms","start":"2026-04-08T01:15:36.657647Z","end":"2026-04-08T01:15:36.796705Z","steps":["trace[1194923700] 'agreement among raft nodes before linearized reading' (duration: 52.375934ms)","trace[1194923700] 'range keys from in-memory index tree' (duration: 86.496512ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:15:37.511628Z","caller":"traceutil/trace.go:171","msg":"trace[1039597827] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"274.00932ms","start":"2026-04-08T01:15:37.23759Z","end":"2026-04-08T01:15:37.511599Z","steps":["trace[1039597827] 'process raft request' (duration: 271.567789ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:15:37.511698Z","caller":"traceutil/trace.go:171","msg":"trace[1015327315] linearizableReadLoop","detail":"{readStateIndex:318; appliedIndex:316; }","duration":"252.419603ms","start":"2026-04-08T01:15:37.259253Z","end":"2026-04-08T01:15:37.511672Z","steps":["trace[1015327315] 'read index received' (duration: 14.360624ms)","trace[1015327315] 'applied index is now lower than readState.Index' (duration: 238.056919ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:15:37.511868Z","caller":"traceutil/trace.go:171","msg":"trace[1161036143] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"273.93872ms","start":"2026-04-08T01:15:37.237894Z","end":"2026-04-08T01:15:37.511833Z","steps":["trace[1161036143] 'process raft request' (duration: 273.645097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:15:37.512021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.831304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"warn","ts":"2026-04-08T01:15:37.512052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.749946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-08T01:15:37.512178Z","caller":"traceutil/trace.go:171","msg":"trace[640050239] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:309; }","duration":"252.950877ms","start":"2026-04-08T01:15:37.2592Z","end":"2026-04-08T01:15:37.512151Z","steps":["trace[640050239] 'agreement among raft nodes before linearized reading' (duration: 252.622425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:15:37.5122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.060382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-04-08T01:15:37.512201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.293134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-04-08T01:15:37.512299Z","caller":"traceutil/trace.go:171","msg":"trace[182693104] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:309; }","duration":"103.120332ms","start":"2026-04-08T01:15:37.409105Z","end":"2026-04-08T01:15:37.512225Z","steps":["trace[182693104] 'agreement among raft nodes before linearized reading' (duration: 103.032391ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:15:37.512107Z","caller":"traceutil/trace.go:171","msg":"trace[1837014702] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:309; }","duration":"203.003697ms","start":"2026-04-08T01:15:37.309061Z","end":"2026-04-08T01:15:37.512065Z","steps":["trace[1837014702] 'agreement among raft nodes before linearized reading' (duration: 202.764735ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:15:37.512452Z","caller":"traceutil/trace.go:171","msg":"trace[684530906] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:309; }","duration":"154.396825ms","start":"2026-04-08T01:15:37.357839Z","end":"2026-04-08T01:15:37.512236Z","steps":["trace[684530906] 'agreement among raft nodes before linearized reading' (duration: 154.308914ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:15:37.748623Z","caller":"traceutil/trace.go:171","msg":"trace[367785446] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"226.320598ms","start":"2026-04-08T01:15:37.522063Z","end":"2026-04-08T01:15:37.748384Z","steps":["trace[367785446] 'process raft request' (duration: 140.328944ms)","trace[367785446] 'compare' (duration: 85.847413ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:15:37.752633Z","caller":"traceutil/trace.go:171","msg":"trace[2136935160] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"230.022249ms","start":"2026-04-08T01:15:37.522589Z","end":"2026-04-08T01:15:37.752611Z","steps":["trace[2136935160] 'process raft request' (duration: 229.800227ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:15:37.752966Z","caller":"traceutil/trace.go:171","msg":"trace[2066603060] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"226.841482ms","start":"2026-04-08T01:15:37.526113Z","end":"2026-04-08T01:15:37.752954Z","steps":["trace[2066603060] 'process raft request' (duration: 226.400668ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:15:37.753061Z","caller":"traceutil/trace.go:171","msg":"trace[1909385391] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"230.605195ms","start":"2026-04-08T01:15:37.522372Z","end":"2026-04-08T01:15:37.752977Z","steps":["trace[1909385391] 'process raft request' (duration: 229.936189ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:15:37.75315Z","caller":"traceutil/trace.go:171","msg":"trace[1375273223] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"230.517483ms","start":"2026-04-08T01:15:37.52262Z","end":"2026-04-08T01:15:37.753137Z","steps":["trace[1375273223] 'process raft request' (duration: 229.814877ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:15:37.753314Z","caller":"traceutil/trace.go:171","msg":"trace[1192349544] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"228.540087ms","start":"2026-04-08T01:15:37.524767Z","end":"2026-04-08T01:15:37.753307Z","steps":["trace[1192349544] 'process raft request' (duration: 227.71483ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:15:37.752682Z","caller":"traceutil/trace.go:171","msg":"trace[576393243] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"230.293832ms","start":"2026-04-08T01:15:37.522356Z","end":"2026-04-08T01:15:37.752649Z","steps":["trace[576393243] 'process raft request' (duration: 229.843077ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:15:37.923946Z","caller":"traceutil/trace.go:171","msg":"trace[511050110] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"162.073402ms","start":"2026-04-08T01:15:37.761851Z","end":"2026-04-08T01:15:37.923925Z","steps":["trace[511050110] 'process raft request' (duration: 126.069741ms)","trace[511050110] 'compare' (duration: 35.88801ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:15:37.92629Z","caller":"traceutil/trace.go:171","msg":"trace[1596970393] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"164.214611ms","start":"2026-04-08T01:15:37.762041Z","end":"2026-04-08T01:15:37.926256Z","steps":["trace[1596970393] 'process raft request' (duration: 163.955438ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:15:37.926403Z","caller":"traceutil/trace.go:171","msg":"trace[920236720] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"164.089989ms","start":"2026-04-08T01:15:37.762265Z","end":"2026-04-08T01:15:37.926355Z","steps":["trace[920236720] 'process raft request' (duration: 163.885328ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:15:37.926402Z","caller":"traceutil/trace.go:171","msg":"trace[1821857382] linearizableReadLoop","detail":"{readStateIndex:329; appliedIndex:327; }","duration":"164.254861ms","start":"2026-04-08T01:15:37.762126Z","end":"2026-04-08T01:15:37.92638Z","steps":["trace[1821857382] 'read index received' (duration: 125.784258ms)","trace[1821857382] 'applied index is now lower than readState.Index' (duration: 38.469723ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T01:15:37.928007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.844844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-08T01:15:37.928221Z","caller":"traceutil/trace.go:171","msg":"trace[2008404322] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:324; }","duration":"166.090276ms","start":"2026-04-08T01:15:37.762112Z","end":"2026-04-08T01:15:37.928202Z","steps":["trace[2008404322] 'agreement among raft nodes before linearized reading' (duration: 164.416462ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:15:37.928602Z","caller":"traceutil/trace.go:171","msg":"trace[1661672683] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"163.00484ms","start":"2026-04-08T01:15:37.765565Z","end":"2026-04-08T01:15:37.92857Z","steps":["trace[1661672683] 'process raft request' (duration: 160.779041ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:15:37.928746Z","caller":"traceutil/trace.go:171","msg":"trace[526408403] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"163.189511ms","start":"2026-04-08T01:15:37.765189Z","end":"2026-04-08T01:15:37.928378Z","steps":["trace[526408403] 'process raft request' (duration: 161.091724ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:15:37.928834Z","caller":"traceutil/trace.go:171","msg":"trace[417084650] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"166.384109ms","start":"2026-04-08T01:15:37.762289Z","end":"2026-04-08T01:15:37.928673Z","steps":["trace[417084650] 'process raft request' (duration: 163.951838ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:15:40.076196Z","caller":"traceutil/trace.go:171","msg":"trace[1493992961] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"137.592407ms","start":"2026-04-08T01:15:39.938569Z","end":"2026-04-08T01:15:40.076161Z","steps":["trace[1493992961] 'process raft request' (duration: 137.472176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:15:40.642153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.597108ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T01:15:40.642589Z","caller":"traceutil/trace.go:171","msg":"trace[867523366] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"703.444254ms","start":"2026-04-08T01:15:39.939104Z","end":"2026-04-08T01:15:40.642549Z","steps":["trace[867523366] 'process raft request' (duration: 444.379234ms)","trace[867523366] 'compare' (duration: 257.474917ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:15:40.642651Z","caller":"traceutil/trace.go:171","msg":"trace[1693848101] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"615.960755ms","start":"2026-04-08T01:15:40.026654Z","end":"2026-04-08T01:15:40.642615Z","steps":["trace[1693848101] 'process raft request' (duration: 615.634013ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:15:40.642763Z","caller":"traceutil/trace.go:171","msg":"trace[327164820] linearizableReadLoop","detail":"{readStateIndex:363; appliedIndex:361; }","duration":"571.184733ms","start":"2026-04-08T01:15:40.071536Z","end":"2026-04-08T01:15:40.642721Z","steps":["trace[327164820] 'read index received' (duration: 4.789671ms)","trace[327164820] 'applied index is now lower than readState.Index' (duration: 566.389752ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T01:15:40.642843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:15:40.026637Z","time spent":"616.097206ms","remote":"127.0.0.1:53666","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-04-08T01:15:40.642786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:15:39.939086Z","time spent":"703.603115ms","remote":"127.0.0.1:53666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T01:15:40.642931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.391205ms","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-08T01:15:40.642974Z","caller":"traceutil/trace.go:171","msg":"trace[319302327] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:353; }","duration":"571.467326ms","start":"2026-04-08T01:15:40.071492Z","end":"2026-04-08T01:15:40.642959Z","steps":["trace[319302327] 'agreement among raft nodes before linearized reading' (duration: 571.399596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:15:40.643014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:15:40.071471Z","time spent":"571.534807ms","remote":"127.0.0.1:53666","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-08T01:15:40.643027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.09269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T01:15:40.643087Z","caller":"traceutil/trace.go:171","msg":"trace[2024012818] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:353; }","duration":"341.20194ms","start":"2026-04-08T01:15:40.301867Z","end":"2026-04-08T01:15:40.643069Z","steps":["trace[2024012818] 'agreement among raft nodes before linearized reading' (duration: 341.058949ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:15:40.643108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.476693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-08T01:15:40.643143Z","caller":"traceutil/trace.go:171","msg":"trace[413010726] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:353; }","duration":"170.550105ms","start":"2026-04-08T01:15:40.472582Z","end":"2026-04-08T01:15:40.643133Z","steps":["trace[413010726] 'agreement among raft nodes before linearized reading' (duration: 170.470684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:15:40.64314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:15:40.301848Z","time spent":"341.280771ms","remote":"127.0.0.1:42912","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-08T01:15:40.832522Z","caller":"traceutil/trace.go:171","msg":"trace[1684454971] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"175.630748ms","start":"2026-04-08T01:15:40.656856Z","end":"2026-04-08T01:15:40.832487Z","steps":["trace[1684454971] 'process raft request' (duration: 164.898617ms)","trace[1684454971] 'compare' (duration: 10.628191ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T01:15:41.152152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.222127ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T01:15:41.152575Z","caller":"traceutil/trace.go:171","msg":"trace[767494862] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"264.149149ms","start":"2026-04-08T01:15:40.888275Z","end":"2026-04-08T01:15:41.152424Z","steps":["trace[767494862] 'process raft request' (duration: 147.575539ms)","trace[767494862] 'compare' (duration: 116.104016ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:15:42.243712Z","caller":"traceutil/trace.go:171","msg":"trace[626077980] linearizableReadLoop","detail":"{readStateIndex:372; appliedIndex:371; }","duration":"163.670775ms","start":"2026-04-08T01:15:42.080026Z","end":"2026-04-08T01:15:42.243697Z","steps":["trace[626077980] 'read index received' (duration: 163.575554ms)","trace[626077980] 'applied index is now lower than readState.Index' (duration: 94.221µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:15:42.243772Z","caller":"traceutil/trace.go:171","msg":"trace[1329968215] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"360.976548ms","start":"2026-04-08T01:15:41.882768Z","end":"2026-04-08T01:15:42.243745Z","steps":["trace[1329968215] 'process raft request' (duration: 360.833887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:15:42.243909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:15:41.882744Z","time spent":"361.098159ms","remote":"127.0.0.1:53582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6740,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T01:15:42.244515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.913758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-08T01:15:42.244916Z","caller":"traceutil/trace.go:171","msg":"trace[1729076701] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:361; }","duration":"164.631793ms","start":"2026-04-08T01:15:42.079976Z","end":"2026-04-08T01:15:42.244608Z","steps":["trace[1729076701] 'agreement among raft nodes before linearized reading' (duration: 163.876277ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:15:42.373275Z","caller":"traceutil/trace.go:171","msg":"trace[2111849377] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"123.599986ms","start":"2026-04-08T01:15:42.24965Z","end":"2026-04-08T01:15:42.37325Z","steps":["trace[2111849377] 'process raft request' (duration: 123.408634ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:15:42.455674Z","caller":"traceutil/trace.go:171","msg":"trace[186443679] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"201.525704ms","start":"2026-04-08T01:15:42.254128Z","end":"2026-04-08T01:15:42.455654Z","steps":["trace[186443679] 'process raft request' (duration: 201.414093ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:15:42.919814Z","caller":"traceutil/trace.go:171","msg":"trace[703474677] linearizableReadLoop","detail":"{readStateIndex:377; appliedIndex:375; }","duration":"192.971402ms","start":"2026-04-08T01:15:42.726817Z","end":"2026-04-08T01:15:42.919789Z","steps":["trace[703474677] 'read index received' (duration: 187.720637ms)","trace[703474677] 'applied index is now lower than readState.Index' (duration: 5.249415ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:15:42.919886Z","caller":"traceutil/trace.go:171","msg":"trace[2077374552] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"250.005714ms","start":"2026-04-08T01:15:42.669847Z","end":"2026-04-08T01:15:42.919853Z","steps":["trace[2077374552] 'process raft request' (duration: 244.70921ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:15:42.919902Z","caller":"traceutil/trace.go:171","msg":"trace[2060057218] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"244.152605ms","start":"2026-04-08T01:15:42.675727Z","end":"2026-04-08T01:15:42.91988Z","steps":["trace[2060057218] 'process raft request' (duration: 244.002213ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:15:42.919963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.136873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-08T01:15:42.920009Z","caller":"traceutil/trace.go:171","msg":"trace[1491044105] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:366; }","duration":"193.193984ms","start":"2026-04-08T01:15:42.726802Z","end":"2026-04-08T01:15:42.919995Z","steps":["trace[1491044105] 'agreement among raft nodes before linearized reading' (duration: 193.101043ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:15:43.170502Z","caller":"traceutil/trace.go:171","msg":"trace[1167343373] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"307.589047ms","start":"2026-04-08T01:15:42.862885Z","end":"2026-04-08T01:15:43.170474Z","steps":["trace[1167343373] 'process raft request' (duration: 295.834117ms)","trace[1167343373] 'compare' (duration: 11.633519ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T01:15:43.170851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:15:42.862846Z","time spent":"307.890369ms","remote":"127.0.0.1:53666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T01:15:43.192745Z","caller":"traceutil/trace.go:171","msg":"trace[684346226] linearizableReadLoop","detail":"{readStateIndex:379; appliedIndex:377; }","duration":"267.925201ms","start":"2026-04-08T01:15:42.924784Z","end":"2026-04-08T01:15:43.192709Z","steps":["trace[684346226] 'read index received' (duration: 233.954944ms)","trace[684346226] 'applied index is now lower than readState.Index' (duration: 33.968637ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T01:15:43.192971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.155333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5336"} {"level":"info","ts":"2026-04-08T01:15:43.193023Z","caller":"traceutil/trace.go:171","msg":"trace[89553940] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:368; }","duration":"268.256884ms","start":"2026-04-08T01:15:42.92475Z","end":"2026-04-08T01:15:43.193007Z","steps":["trace[89553940] 'agreement among raft nodes before linearized reading' (duration: 268.094962ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:15:43.193027Z","caller":"traceutil/trace.go:171","msg":"trace[1468918819] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"268.198783ms","start":"2026-04-08T01:15:42.924751Z","end":"2026-04-08T01:15:43.19295Z","steps":["trace[1468918819] 'process raft request' (duration: 267.78506ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:15:48.445812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.359835ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-08T01:15:48.446183Z","caller":"traceutil/trace.go:171","msg":"trace[1872134467] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"326.853883ms","start":"2026-04-08T01:15:48.119284Z","end":"2026-04-08T01:15:48.446138Z","steps":["trace[1872134467] 'process raft request' (duration: 76.097582ms)","trace[1872134467] 'compare' (duration: 250.199149ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T01:15:48.446324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:15:48.11926Z","time spent":"327.005449ms","remote":"127.0.0.1:53866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3678,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T01:15:48.649663Z","caller":"traceutil/trace.go:171","msg":"trace[1700507922] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"112.225874ms","start":"2026-04-08T01:15:48.537407Z","end":"2026-04-08T01:15:48.649633Z","steps":["trace[1700507922] 'process raft request' (duration: 69.363132ms)","trace[1700507922] 'compare' (duration: 42.508627ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:15:48.650826Z","caller":"traceutil/trace.go:171","msg":"trace[234466352] linearizableReadLoop","detail":"{readStateIndex:402; appliedIndex:401; }","duration":"105.676431ms","start":"2026-04-08T01:15:48.545093Z","end":"2026-04-08T01:15:48.650769Z","steps":["trace[234466352] 'read index received' (duration: 61.687056ms)","trace[234466352] 'applied index is now lower than readState.Index' (duration: 43.986945ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T01:15:48.651156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.033665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-28c2d\" ","response":"range_response_count:1 size:4016"} {"level":"info","ts":"2026-04-08T01:15:48.651204Z","caller":"traceutil/trace.go:171","msg":"trace[1207835110] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-28c2d; range_end:; response_count:1; response_revision:391; }","duration":"106.130428ms","start":"2026-04-08T01:15:48.545062Z","end":"2026-04-08T01:15:48.651193Z","steps":["trace[1207835110] 'agreement among raft nodes before linearized reading' (duration: 105.875819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:15:48.651333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.801634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-04-08T01:15:48.651399Z","caller":"traceutil/trace.go:171","msg":"trace[259548303] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:391; }","duration":"100.913618ms","start":"2026-04-08T01:15:48.550468Z","end":"2026-04-08T01:15:48.651382Z","steps":["trace[259548303] 'agreement among raft nodes before linearized reading' (duration: 100.789103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:15:48.897477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.400966ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T01:15:48.897677Z","caller":"traceutil/trace.go:171","msg":"trace[697709296] linearizableReadLoop","detail":"{readStateIndex:407; appliedIndex:404; }","duration":"179.060139ms","start":"2026-04-08T01:15:48.718605Z","end":"2026-04-08T01:15:48.897665Z","steps":["trace[697709296] 'read index received' (duration: 63.421704ms)","trace[697709296] 'applied index is now lower than readState.Index' (duration: 115.637535ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T01:15:48.897757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.154602ms","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-08T01:15:48.897742Z","caller":"traceutil/trace.go:171","msg":"trace[1318561778] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"221.380938ms","start":"2026-04-08T01:15:48.676323Z","end":"2026-04-08T01:15:48.897704Z","steps":["trace[1318561778] 'process raft request' (duration: 221.299945ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:15:48.897782Z","caller":"traceutil/trace.go:171","msg":"trace[513892342] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:394; }","duration":"179.199884ms","start":"2026-04-08T01:15:48.718575Z","end":"2026-04-08T01:15:48.897774Z","steps":["trace[513892342] 'agreement among raft nodes before linearized reading' (duration: 179.135532ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:15:48.897807Z","caller":"traceutil/trace.go:171","msg":"trace[1128693476] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"237.403197ms","start":"2026-04-08T01:15:48.660334Z","end":"2026-04-08T01:15:48.897737Z","steps":["trace[1128693476] 'process raft request' (duration: 121.678148ms)","trace[1128693476] 'compare' (duration: 115.284652ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:15:48.897877Z","caller":"traceutil/trace.go:171","msg":"trace[602258945] transaction","detail":"{read_only:false; number_of_response:1; response_revision:393; }","duration":"224.763599ms","start":"2026-04-08T01:15:48.673087Z","end":"2026-04-08T01:15:48.89785Z","steps":["trace[602258945] 'process raft request' (duration: 224.481888ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:15:49.31967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.978675ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-08T01:15:49.319985Z","caller":"traceutil/trace.go:171","msg":"trace[1387969122] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"416.716607ms","start":"2026-04-08T01:15:48.903251Z","end":"2026-04-08T01:15:49.319968Z","steps":["trace[1387969122] 'process raft request' (duration: 416.672316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:15:49.320082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:15:48.903234Z","time spent":"416.818302ms","remote":"127.0.0.1:53582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3563,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T01:15:49.320303Z","caller":"traceutil/trace.go:171","msg":"trace[64110447] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"418.111891ms","start":"2026-04-08T01:15:48.902155Z","end":"2026-04-08T01:15:49.320266Z","steps":["trace[64110447] 'process raft request' (duration: 189.46477ms)","trace[64110447] 'compare' (duration: 227.727206ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:15:49.320396Z","caller":"traceutil/trace.go:171","msg":"trace[519294276] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"418.053618ms","start":"2026-04-08T01:15:48.90231Z","end":"2026-04-08T01:15:49.320364Z","steps":["trace[519294276] 'process raft request' (duration: 417.525728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:15:49.320465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:15:48.90213Z","time spent":"418.271667ms","remote":"127.0.0.1:53468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":687,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T01:15:49.320562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:15:48.902285Z","time spent":"418.184503ms","remote":"127.0.0.1:53666","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-04-08T01:15:49.354085Z","caller":"traceutil/trace.go:171","msg":"trace[1676196311] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"447.336402ms","start":"2026-04-08T01:15:48.906729Z","end":"2026-04-08T01:15:49.354066Z","steps":["trace[1676196311] 'process raft request' (duration: 447.115654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:15:49.354182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:15:48.906711Z","time spent":"447.432536ms","remote":"127.0.0.1:53468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T01:15:49.354322Z","caller":"traceutil/trace.go:171","msg":"trace[1293018041] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"446.831611ms","start":"2026-04-08T01:15:48.907428Z","end":"2026-04-08T01:15:49.354259Z","steps":["trace[1293018041] 'process raft request' (duration: 446.54218ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:15:49.354399Z","caller":"traceutil/trace.go:171","msg":"trace[1752463452] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"444.447521ms","start":"2026-04-08T01:15:48.90993Z","end":"2026-04-08T01:15:49.354378Z","steps":["trace[1752463452] 'process raft request' (duration: 444.203362ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:15:49.354414Z","caller":"traceutil/trace.go:171","msg":"trace[1163626972] linearizableReadLoop","detail":"{readStateIndex:414; appliedIndex:408; }","duration":"373.07471ms","start":"2026-04-08T01:15:48.981316Z","end":"2026-04-08T01:15:49.354391Z","steps":["trace[1163626972] 'read index received' (duration: 110.329861ms)","trace[1163626972] 'applied index is now lower than readState.Index' (duration: 262.743249ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T01:15:49.354533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:15:48.90992Z","time spent":"444.559955ms","remote":"127.0.0.1:53866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T01:15:49.354541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:15:48.907409Z","time spent":"447.027219ms","remote":"127.0.0.1:53832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T01:15:49.354615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.287218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-08T01:15:49.35467Z","caller":"traceutil/trace.go:171","msg":"trace[800528102] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:401; }","duration":"373.382682ms","start":"2026-04-08T01:15:48.981275Z","end":"2026-04-08T01:15:49.354657Z","steps":["trace[800528102] 'agreement among raft nodes before linearized reading' (duration: 373.294879ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:15:49.354693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:15:48.981258Z","time spent":"373.430604ms","remote":"127.0.0.1:53666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-08T01:15:49.632736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.888576ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T01:15:49.632986Z","caller":"traceutil/trace.go:171","msg":"trace[2051722008] linearizableReadLoop","detail":"{readStateIndex:417; appliedIndex:414; }","duration":"278.411856ms","start":"2026-04-08T01:15:49.354556Z","end":"2026-04-08T01:15:49.632967Z","steps":["trace[2051722008] 'read index received' (duration: 99.526312ms)","trace[2051722008] 'applied index is now lower than readState.Index' (duration: 178.884414ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:15:49.633027Z","caller":"traceutil/trace.go:171","msg":"trace[318511208] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"305.597703ms","start":"2026-04-08T01:15:49.327408Z","end":"2026-04-08T01:15:49.633006Z","steps":["trace[318511208] 'process raft request' (duration: 305.477468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:15:49.633056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.947555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T01:15:49.633084Z","caller":"traceutil/trace.go:171","msg":"trace[535517575] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:404; }","duration":"437.002607ms","start":"2026-04-08T01:15:49.196072Z","end":"2026-04-08T01:15:49.633075Z","steps":["trace[535517575] 'agreement among raft nodes before linearized reading' (duration: 436.953626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:15:49.633129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:15:49.327394Z","time spent":"305.681005ms","remote":"127.0.0.1:53866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3840,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T01:15:49.633175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.76613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-08T01:15:49.633207Z","caller":"traceutil/trace.go:171","msg":"trace[1520866501] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:404; }","duration":"299.822842ms","start":"2026-04-08T01:15:49.333377Z","end":"2026-04-08T01:15:49.633199Z","steps":["trace[1520866501] 'agreement among raft nodes before linearized reading' (duration: 299.76879ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:15:49.633123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:15:49.196056Z","time spent":"437.05985ms","remote":"127.0.0.1:42912","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-08T01:15:49.633239Z","caller":"traceutil/trace.go:171","msg":"trace[1112238353] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"307.469263ms","start":"2026-04-08T01:15:49.325733Z","end":"2026-04-08T01:15:49.633202Z","steps":["trace[1112238353] 'process raft request' (duration: 307.090879ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:15:49.633414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:15:49.325713Z","time spent":"307.624839ms","remote":"127.0.0.1:53468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T01:15:49.633531Z","caller":"traceutil/trace.go:171","msg":"trace[1017915646] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"307.488454ms","start":"2026-04-08T01:15:49.325442Z","end":"2026-04-08T01:15:49.63293Z","steps":["trace[1017915646] 'process raft request' (duration: 128.601249ms)","trace[1017915646] 'compare' (duration: 177.71691ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T01:15:49.633734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:15:49.325421Z","time spent":"308.236372ms","remote":"127.0.0.1:53582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4037,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T01:15:49.646531Z","caller":"traceutil/trace.go:171","msg":"trace[838976624] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"288.540001ms","start":"2026-04-08T01:15:49.357974Z","end":"2026-04-08T01:15:49.646514Z","steps":["trace[838976624] 'process raft request' (duration: 288.400956ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:15:49.646558Z","caller":"traceutil/trace.go:171","msg":"trace[1434551509] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"286.14764ms","start":"2026-04-08T01:15:49.360395Z","end":"2026-04-08T01:15:49.646543Z","steps":["trace[1434551509] 'process raft request' (duration: 286.054697ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:15:49.646678Z","caller":"traceutil/trace.go:171","msg":"trace[1345302915] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"284.847321ms","start":"2026-04-08T01:15:49.361812Z","end":"2026-04-08T01:15:49.64666Z","steps":["trace[1345302915] 'process raft request' (duration: 284.674474ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:15:49.646694Z","caller":"traceutil/trace.go:171","msg":"trace[15993824] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"284.83583ms","start":"2026-04-08T01:15:49.361842Z","end":"2026-04-08T01:15:49.646678Z","steps":["trace[15993824] 'process raft request' (duration: 284.679265ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:15:49.933069Z","caller":"traceutil/trace.go:171","msg":"trace[783883028] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"268.645144ms","start":"2026-04-08T01:15:49.664402Z","end":"2026-04-08T01:15:49.933047Z","steps":["trace[783883028] 'process raft request' (duration: 194.970147ms)","trace[783883028] 'compare' (duration: 73.575534ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:15:49.951973Z","caller":"traceutil/trace.go:171","msg":"trace[571133420] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"285.321419ms","start":"2026-04-08T01:15:49.66663Z","end":"2026-04-08T01:15:49.951951Z","steps":["trace[571133420] 'process raft request' (duration: 285.198864ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:15:50.131723Z","caller":"traceutil/trace.go:171","msg":"trace[8899006] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"170.959778ms","start":"2026-04-08T01:15:49.960729Z","end":"2026-04-08T01:15:50.131689Z","steps":["trace[8899006] 'process raft request' (duration: 88.804452ms)","trace[8899006] 'compare' (duration: 82.0155ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:15:50.643573Z","caller":"traceutil/trace.go:171","msg":"trace[723228679] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"141.656605ms","start":"2026-04-08T01:15:50.501899Z","end":"2026-04-08T01:15:50.643556Z","steps":["trace[723228679] 'process raft request' (duration: 141.52201ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:15:50.93162Z","caller":"traceutil/trace.go:171","msg":"trace[598718968] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"148.39299ms","start":"2026-04-08T01:15:50.783195Z","end":"2026-04-08T01:15:50.931588Z","steps":["trace[598718968] 'process raft request' (duration: 128.839484ms)","trace[598718968] 'compare' (duration: 19.423101ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T01:15:51.213062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.774982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.153\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-08T01:15:51.213129Z","caller":"traceutil/trace.go:171","msg":"trace[558772130] range","detail":"{range_begin:/registry/masterleases/199.204.45.153; range_end:; response_count:1; response_revision:414; }","duration":"119.900117ms","start":"2026-04-08T01:15:51.093215Z","end":"2026-04-08T01:15:51.213115Z","steps":["trace[558772130] 'range keys from in-memory index tree' (duration: 119.620577ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:15:57.564564Z","caller":"traceutil/trace.go:171","msg":"trace[1666667748] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"147.313679ms","start":"2026-04-08T01:15:57.417229Z","end":"2026-04-08T01:15:57.564542Z","steps":["trace[1666667748] 'process raft request' (duration: 147.180054ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:15:59.757272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.000253ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-08T01:15:59.757584Z","caller":"traceutil/trace.go:171","msg":"trace[996647639] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"356.015862ms","start":"2026-04-08T01:15:59.401548Z","end":"2026-04-08T01:15:59.757564Z","steps":["trace[996647639] 'process raft request' (duration: 202.663428ms)","trace[996647639] 'compare' (duration: 152.862607ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T01:15:59.757662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:15:59.401517Z","time spent":"356.115736ms","remote":"127.0.0.1:53832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3827,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T01:15:59.757998Z","caller":"traceutil/trace.go:171","msg":"trace[1468828146] linearizableReadLoop","detail":"{readStateIndex:460; appliedIndex:458; }","duration":"188.980292ms","start":"2026-04-08T01:15:59.569003Z","end":"2026-04-08T01:15:59.757984Z","steps":["trace[1468828146] 'read index received' (duration: 35.352047ms)","trace[1468828146] 'applied index is now lower than readState.Index' (duration: 153.626945ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:15:59.758078Z","caller":"traceutil/trace.go:171","msg":"trace[2070230713] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"355.940259ms","start":"2026-04-08T01:15:59.402098Z","end":"2026-04-08T01:15:59.758039Z","steps":["trace[2070230713] 'process raft request' (duration: 355.278637ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:15:59.759043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.032047ms","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-08T01:15:59.759213Z","caller":"traceutil/trace.go:171","msg":"trace[530770379] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:445; }","duration":"190.224484ms","start":"2026-04-08T01:15:59.568954Z","end":"2026-04-08T01:15:59.759178Z","steps":["trace[530770379] 'agreement among raft nodes before linearized reading' (duration: 189.166648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:15:59.759838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:15:59.402082Z","time spent":"356.063654ms","remote":"127.0.0.1:53878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T01:16:00.086642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.56991ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-08T01:16:00.08731Z","caller":"traceutil/trace.go:171","msg":"trace[1886874440] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"318.183711ms","start":"2026-04-08T01:15:59.769101Z","end":"2026-04-08T01:16:00.087284Z","steps":["trace[1886874440] 'process raft request' (duration: 166.835925ms)","trace[1886874440] 'compare' (duration: 150.424125ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T01:16:00.087667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:15:59.769081Z","time spent":"318.549442ms","remote":"127.0.0.1:53866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4177,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T01:16:00.14117Z","caller":"traceutil/trace.go:171","msg":"trace[2111079669] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"371.326366ms","start":"2026-04-08T01:15:59.769806Z","end":"2026-04-08T01:16:00.141132Z","steps":["trace[2111079669] 'process raft request' (duration: 370.986654ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:16:00.141188Z","caller":"traceutil/trace.go:171","msg":"trace[1498601867] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"371.223702ms","start":"2026-04-08T01:15:59.769918Z","end":"2026-04-08T01:16:00.141142Z","steps":["trace[1498601867] 'process raft request' (duration: 370.984004ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:00.142053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:15:59.76991Z","time spent":"372.090191ms","remote":"127.0.0.1:53666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T01:16:00.141321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:15:59.769797Z","time spent":"371.45558ms","remote":"127.0.0.1:53582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15015,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T01:16:00.141328Z","caller":"traceutil/trace.go:171","msg":"trace[331580428] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"371.323916ms","start":"2026-04-08T01:15:59.769978Z","end":"2026-04-08T01:16:00.141302Z","steps":["trace[331580428] 'process raft request' (duration: 371.012745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:00.142485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:15:59.769971Z","time spent":"372.477254ms","remote":"127.0.0.1:53666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T01:16:00.141336Z","caller":"traceutil/trace.go:171","msg":"trace[1027531884] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"371.241243ms","start":"2026-04-08T01:15:59.769978Z","end":"2026-04-08T01:16:00.141219Z","steps":["trace[1027531884] 'process raft request' (duration: 370.977374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:00.142778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:15:59.769972Z","time spent":"372.64527ms","remote":"127.0.0.1:53666","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-08T01:16:00.14136Z","caller":"traceutil/trace.go:171","msg":"trace[572652294] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"290.408974ms","start":"2026-04-08T01:15:59.850927Z","end":"2026-04-08T01:16:00.141336Z","steps":["trace[572652294] 'process raft request' (duration: 290.228308ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:00.544906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.370144ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-08T01:16:00.54544Z","caller":"traceutil/trace.go:171","msg":"trace[1031583281] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"453.878129ms","start":"2026-04-08T01:16:00.091461Z","end":"2026-04-08T01:16:00.545339Z","steps":["trace[1031583281] 'process raft request' (duration: 230.005998ms)","trace[1031583281] 'compare' (duration: 223.24106ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:16:00.545506Z","caller":"traceutil/trace.go:171","msg":"trace[893135422] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"453.903039ms","start":"2026-04-08T01:16:00.091569Z","end":"2026-04-08T01:16:00.545472Z","steps":["trace[893135422] 'process raft request' (duration: 453.61275ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:16:00.545549Z","caller":"traceutil/trace.go:171","msg":"trace[826434532] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"451.692274ms","start":"2026-04-08T01:16:00.093838Z","end":"2026-04-08T01:16:00.54553Z","steps":["trace[826434532] 'process raft request' (duration: 451.502218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:00.545639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:00.091562Z","time spent":"454.012343ms","remote":"127.0.0.1:53582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3576,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T01:16:00.545729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:00.091441Z","time spent":"454.117916ms","remote":"127.0.0.1:53468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":712,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T01:16:00.545643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:00.09383Z","time spent":"451.768787ms","remote":"127.0.0.1:53832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4320,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T01:16:00.554656Z","caller":"traceutil/trace.go:171","msg":"trace[1001343362] linearizableReadLoop","detail":"{readStateIndex:472; appliedIndex:466; }","duration":"360.966891ms","start":"2026-04-08T01:16:00.193658Z","end":"2026-04-08T01:16:00.554625Z","steps":["trace[1001343362] 'read index received' (duration: 127.862249ms)","trace[1001343362] 'applied index is now lower than readState.Index' (duration: 233.103382ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:16:00.55514Z","caller":"traceutil/trace.go:171","msg":"trace[554261187] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"407.651327ms","start":"2026-04-08T01:16:00.147475Z","end":"2026-04-08T01:16:00.555126Z","steps":["trace[554261187] 'process raft request' (duration: 406.683045ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:00.5553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:00.147457Z","time spent":"407.771841ms","remote":"127.0.0.1:53582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15061,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T01:16:00.555544Z","caller":"traceutil/trace.go:171","msg":"trace[615039648] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"407.974919ms","start":"2026-04-08T01:16:00.147547Z","end":"2026-04-08T01:16:00.555522Z","steps":["trace[615039648] 'process raft request' (duration: 406.878402ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:00.555646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:00.147538Z","time spent":"408.077362ms","remote":"127.0.0.1:53468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":653,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T01:16:00.555967Z","caller":"traceutil/trace.go:171","msg":"trace[856846568] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"404.192922ms","start":"2026-04-08T01:16:00.151761Z","end":"2026-04-08T01:16:00.555954Z","steps":["trace[856846568] 'process raft request' (duration: 402.768633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:00.556095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:00.15174Z","time spent":"404.315986ms","remote":"127.0.0.1:53860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14448,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T01:16:00.556249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.598006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T01:16:00.556301Z","caller":"traceutil/trace.go:171","msg":"trace[1723760637] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:457; }","duration":"362.6813ms","start":"2026-04-08T01:16:00.193613Z","end":"2026-04-08T01:16:00.556294Z","steps":["trace[1723760637] 'agreement among raft nodes before linearized reading' (duration: 362.616168ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:00.556337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:00.193595Z","time spent":"362.735561ms","remote":"127.0.0.1:42912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-08T01:16:00.902323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.347431ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T01:16:00.902496Z","caller":"traceutil/trace.go:171","msg":"trace[1307240671] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"349.9811ms","start":"2026-04-08T01:16:00.552496Z","end":"2026-04-08T01:16:00.902477Z","steps":["trace[1307240671] 'process raft request' (duration: 349.922778ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:16:00.902551Z","caller":"traceutil/trace.go:171","msg":"trace[842181942] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"352.289577ms","start":"2026-04-08T01:16:00.550191Z","end":"2026-04-08T01:16:00.902481Z","steps":["trace[842181942] 'process raft request' (duration: 203.697879ms)","trace[842181942] 'compare' (duration: 148.193435ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T01:16:00.902644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:00.552479Z","time spent":"350.130395ms","remote":"127.0.0.1:53468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":736,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T01:16:00.902759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:00.550161Z","time spent":"352.467423ms","remote":"127.0.0.1:53582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3622,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T01:16:00.90483Z","caller":"traceutil/trace.go:171","msg":"trace[108928334] linearizableReadLoop","detail":"{readStateIndex:475; appliedIndex:472; }","duration":"350.014251ms","start":"2026-04-08T01:16:00.554804Z","end":"2026-04-08T01:16:00.904818Z","steps":["trace[108928334] 'read index received' (duration: 199.099884ms)","trace[108928334] 'applied index is now lower than readState.Index' (duration: 150.913397ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:16:00.904917Z","caller":"traceutil/trace.go:171","msg":"trace[890676904] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"344.495825ms","start":"2026-04-08T01:16:00.560413Z","end":"2026-04-08T01:16:00.904909Z","steps":["trace[890676904] 'process raft request' (duration: 344.369071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:00.904966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:00.560381Z","time spent":"344.558217ms","remote":"127.0.0.1:53468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":671,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T01:16:00.905074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.376232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T01:16:00.905173Z","caller":"traceutil/trace.go:171","msg":"trace[424192566] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:461; }","duration":"347.497916ms","start":"2026-04-08T01:16:00.557654Z","end":"2026-04-08T01:16:00.905152Z","steps":["trace[424192566] 'agreement among raft nodes before linearized reading' (duration: 347.343231ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:00.905248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.476463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4335"} {"level":"info","ts":"2026-04-08T01:16:00.904879Z","caller":"traceutil/trace.go:171","msg":"trace[1130213332] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"350.838909ms","start":"2026-04-08T01:16:00.554Z","end":"2026-04-08T01:16:00.904839Z","steps":["trace[1130213332] 'process raft request' (duration: 350.701424ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:16:00.905297Z","caller":"traceutil/trace.go:171","msg":"trace[1028047447] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:461; }","duration":"352.562786ms","start":"2026-04-08T01:16:00.552727Z","end":"2026-04-08T01:16:00.90529Z","steps":["trace[1028047447] 'agreement among raft nodes before linearized reading' (duration: 352.490863ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:00.905325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:00.552712Z","time spent":"352.606978ms","remote":"127.0.0.1:53832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":4358,"request content":"key:\"/registry/deployments/kube-system/cilium-operator\" "} {"level":"warn","ts":"2026-04-08T01:16:00.905264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:00.557638Z","time spent":"347.61492ms","remote":"127.0.0.1:42906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-08T01:16:00.905632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:00.553982Z","time spent":"351.333095ms","remote":"127.0.0.1:53866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4294,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T01:16:00.905659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.101215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-r6kh5\" ","response":"range_response_count:1 size:15076"} {"level":"info","ts":"2026-04-08T01:16:00.905699Z","caller":"traceutil/trace.go:171","msg":"trace[1441166485] range","detail":"{range_begin:/registry/pods/kube-system/cilium-r6kh5; range_end:; response_count:1; response_revision:461; }","duration":"345.160858ms","start":"2026-04-08T01:16:00.560526Z","end":"2026-04-08T01:16:00.905687Z","steps":["trace[1441166485] 'agreement among raft nodes before linearized reading' (duration: 345.025553ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:00.905741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:00.560479Z","time spent":"345.254811ms","remote":"127.0.0.1:53582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":15099,"request content":"key:\"/registry/pods/kube-system/cilium-r6kh5\" "} {"level":"warn","ts":"2026-04-08T01:16:00.905747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.973963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-08T01:16:00.905775Z","caller":"traceutil/trace.go:171","msg":"trace[216544215] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:461; }","duration":"136.046715ms","start":"2026-04-08T01:16:00.769722Z","end":"2026-04-08T01:16:00.905769Z","steps":["trace[216544215] 'agreement among raft nodes before linearized reading' (duration: 135.992773ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:00.906346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.368421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329952"} {"level":"info","ts":"2026-04-08T01:16:00.906403Z","caller":"traceutil/trace.go:171","msg":"trace[1820589813] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:461; }","duration":"694.441644ms","start":"2026-04-08T01:16:00.211945Z","end":"2026-04-08T01:16:00.906387Z","steps":["trace[1820589813] 'agreement among raft nodes before linearized reading' (duration: 693.018945ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:00.906458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:00.211911Z","time spent":"694.529966ms","remote":"127.0.0.1:53474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":329975,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"info","ts":"2026-04-08T01:16:01.117065Z","caller":"traceutil/trace.go:171","msg":"trace[712116610] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"204.180295ms","start":"2026-04-08T01:16:00.912861Z","end":"2026-04-08T01:16:01.117041Z","steps":["trace[712116610] 'process raft request' (duration: 204.122503ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:16:01.117086Z","caller":"traceutil/trace.go:171","msg":"trace[1273730828] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"204.300189ms","start":"2026-04-08T01:16:00.912759Z","end":"2026-04-08T01:16:01.117059Z","steps":["trace[1273730828] 'process raft request' (duration: 156.962317ms)","trace[1273730828] 'compare' (duration: 47.138685ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:16:01.159065Z","caller":"traceutil/trace.go:171","msg":"trace[153698571] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"242.192324ms","start":"2026-04-08T01:16:00.91683Z","end":"2026-04-08T01:16:01.159023Z","steps":["trace[153698571] 'process raft request' (duration: 241.220382ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:16:01.16039Z","caller":"traceutil/trace.go:171","msg":"trace[1218233828] linearizableReadLoop","detail":"{readStateIndex:481; appliedIndex:477; }","duration":"158.902589ms","start":"2026-04-08T01:16:01.001427Z","end":"2026-04-08T01:16:01.16033Z","steps":["trace[1218233828] 'read index received' (duration: 68.458337ms)","trace[1218233828] 'applied index is now lower than readState.Index' (duration: 90.440272ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:16:01.160756Z","caller":"traceutil/trace.go:171","msg":"trace[1843070940] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"235.100723ms","start":"2026-04-08T01:16:00.925629Z","end":"2026-04-08T01:16:01.16073Z","steps":["trace[1843070940] 'process raft request' (duration: 233.131447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:01.164287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.837552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329952"} {"level":"info","ts":"2026-04-08T01:16:01.164353Z","caller":"traceutil/trace.go:171","msg":"trace[350892546] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:466; }","duration":"162.947735ms","start":"2026-04-08T01:16:01.001385Z","end":"2026-04-08T01:16:01.164333Z","steps":["trace[350892546] 'agreement among raft nodes before linearized reading' (duration: 159.153028ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:01.206323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.183029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.153\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-08T01:16:01.206389Z","caller":"traceutil/trace.go:171","msg":"trace[1834195826] range","detail":"{range_begin:/registry/masterleases/199.204.45.153; range_end:; response_count:1; response_revision:467; }","duration":"112.304842ms","start":"2026-04-08T01:16:01.09407Z","end":"2026-04-08T01:16:01.206375Z","steps":["trace[1834195826] 'agreement among raft nodes before linearized reading' (duration: 112.135507ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:01.206444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.913911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-04-08T01:16:01.206517Z","caller":"traceutil/trace.go:171","msg":"trace[269622606] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:467; }","duration":"168.056356ms","start":"2026-04-08T01:16:01.038442Z","end":"2026-04-08T01:16:01.206498Z","steps":["trace[269622606] 'agreement among raft nodes before linearized reading' (duration: 167.921102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:02.856675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.793718ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-08T01:16:02.857051Z","caller":"traceutil/trace.go:171","msg":"trace[618044941] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"360.169129ms","start":"2026-04-08T01:16:02.496838Z","end":"2026-04-08T01:16:02.857007Z","steps":["trace[618044941] 'process raft request' (duration: 116.983968ms)","trace[618044941] 'compare' (duration: 242.670155ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:16:02.857202Z","caller":"traceutil/trace.go:171","msg":"trace[1115493003] transaction","detail":"{read_only:false; number_of_response:1; response_revision:486; }","duration":"352.359741ms","start":"2026-04-08T01:16:02.504825Z","end":"2026-04-08T01:16:02.857184Z","steps":["trace[1115493003] 'process raft request' (duration: 352.31562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:02.857321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:02.504818Z","time spent":"352.448424ms","remote":"127.0.0.1:53444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7366,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T01:16:02.857471Z","caller":"traceutil/trace.go:171","msg":"trace[166889484] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"352.926119ms","start":"2026-04-08T01:16:02.504536Z","end":"2026-04-08T01:16:02.857462Z","steps":["trace[166889484] 'process raft request' (duration: 352.392312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:02.857514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:02.504523Z","time spent":"352.970481ms","remote":"127.0.0.1:53928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T01:16:02.857647Z","caller":"traceutil/trace.go:171","msg":"trace[576870596] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"356.002721ms","start":"2026-04-08T01:16:02.501584Z","end":"2026-04-08T01:16:02.857586Z","steps":["trace[576870596] 'process raft request' (duration: 355.204075ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:16:02.857713Z","caller":"traceutil/trace.go:171","msg":"trace[2118679744] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"352.996652ms","start":"2026-04-08T01:16:02.504706Z","end":"2026-04-08T01:16:02.857702Z","steps":["trace[2118679744] 'process raft request' (duration: 352.31336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:02.857792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:02.504698Z","time spent":"353.045223ms","remote":"127.0.0.1:53444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T01:16:02.857823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:02.501563Z","time spent":"356.173296ms","remote":"127.0.0.1:53444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T01:16:02.858001Z","caller":"traceutil/trace.go:171","msg":"trace[950173172] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"356.334421ms","start":"2026-04-08T01:16:02.501656Z","end":"2026-04-08T01:16:02.85799Z","steps":["trace[950173172] 'process raft request' (duration: 355.204435ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:02.858053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:02.501651Z","time spent":"356.373184ms","remote":"127.0.0.1:53444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T01:16:02.858048Z","caller":"traceutil/trace.go:171","msg":"trace[649822322] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"353.261981ms","start":"2026-04-08T01:16:02.50477Z","end":"2026-04-08T01:16:02.858032Z","steps":["trace[649822322] 'process raft request' (duration: 352.32724ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:02.858117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:02.504764Z","time spent":"353.323213ms","remote":"127.0.0.1:53444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T01:16:02.857212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:02.49682Z","time spent":"360.315805ms","remote":"127.0.0.1:53444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T01:16:03.622579Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439249211912239,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T01:16:04.123855Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439249211912239,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T01:16:04.624846Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439249211912239,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T01:16:04.797006Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.092286859s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-08T01:16:05.194532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000241895s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-08T01:16:05.194638Z","caller":"traceutil/trace.go:171","msg":"trace[52151255] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000406991s","start":"2026-04-08T01:16:03.194215Z","end":"2026-04-08T01:16:05.194622Z","steps":["trace[52151255] 'agreement among raft nodes before linearized reading' (duration: 2.000274126s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:05.194696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:03.194175Z","time spent":"2.000510974s","remote":"127.0.0.1:42912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/08 01:16:05 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-08T01:16:05.735097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.129749ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-08T01:16:05.735463Z","caller":"traceutil/trace.go:171","msg":"trace[720215] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"3.223470892s","start":"2026-04-08T01:16:02.511965Z","end":"2026-04-08T01:16:05.735436Z","steps":["trace[720215] 'process raft request' (duration: 2.28527875s)","trace[720215] 'compare' (duration: 932.710966ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T01:16:05.735537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:02.511946Z","time spent":"3.223549575s","remote":"127.0.0.1:53444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T01:16:05.735911Z","caller":"traceutil/trace.go:171","msg":"trace[2108075079] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"3.222466079s","start":"2026-04-08T01:16:02.513416Z","end":"2026-04-08T01:16:05.735882Z","steps":["trace[2108075079] 'process raft request' (duration: 3.222270033s)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:16:05.735977Z","caller":"traceutil/trace.go:171","msg":"trace[1337956978] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"3.216703618s","start":"2026-04-08T01:16:02.519237Z","end":"2026-04-08T01:16:05.735941Z","steps":["trace[1337956978] 'process raft request' (duration: 3.216511472s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:05.736008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:02.513406Z","time spent":"3.222554781s","remote":"127.0.0.1:53928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T01:16:05.736031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:02.519218Z","time spent":"3.216792821s","remote":"127.0.0.1:53928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T01:16:05.736175Z","caller":"traceutil/trace.go:171","msg":"trace[124838360] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"3.223020066s","start":"2026-04-08T01:16:02.513125Z","end":"2026-04-08T01:16:05.736145Z","steps":["trace[124838360] 'process raft request' (duration: 3.222055445s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:05.736264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:02.513115Z","time spent":"3.22309999s","remote":"127.0.0.1:53928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T01:16:05.737133Z","caller":"traceutil/trace.go:171","msg":"trace[501462394] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"3.198141232s","start":"2026-04-08T01:16:02.538971Z","end":"2026-04-08T01:16:05.737113Z","steps":["trace[501462394] 'process raft request' (duration: 3.197243134s)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:16:05.737203Z","caller":"traceutil/trace.go:171","msg":"trace[710373899] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"3.197657007s","start":"2026-04-08T01:16:02.538707Z","end":"2026-04-08T01:16:05.736364Z","steps":["trace[710373899] 'process raft request' (duration: 3.197207313s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:05.737263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:02.538964Z","time spent":"3.198216324s","remote":"127.0.0.1:53444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T01:16:05.737287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:02.538675Z","time spent":"3.198566586s","remote":"127.0.0.1:53444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T01:16:05.739498Z","caller":"traceutil/trace.go:171","msg":"trace[1966014067] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"3.207500771s","start":"2026-04-08T01:16:02.529845Z","end":"2026-04-08T01:16:05.737345Z","steps":["trace[1966014067] 'process raft request' (duration: 3.205954342s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:05.739589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:02.529827Z","time spent":"3.209711733s","remote":"127.0.0.1:53444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T01:16:05.739693Z","caller":"traceutil/trace.go:171","msg":"trace[1974772280] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"3.170609862s","start":"2026-04-08T01:16:02.566728Z","end":"2026-04-08T01:16:05.737338Z","steps":["trace[1974772280] 'process raft request' (duration: 3.17023537s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:05.739747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:02.566704Z","time spent":"3.17301756s","remote":"127.0.0.1:53444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T01:16:05.740162Z","caller":"traceutil/trace.go:171","msg":"trace[1284972087] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"2.8739613s","start":"2026-04-08T01:16:02.866189Z","end":"2026-04-08T01:16:05.74015Z","steps":["trace[1284972087] 'process raft request' (duration: 2.873793865s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:05.740235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:02.866164Z","time spent":"2.874036333s","remote":"127.0.0.1:53444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T01:16:05.740251Z","caller":"traceutil/trace.go:171","msg":"trace[1428667578] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"2.873810156s","start":"2026-04-08T01:16:02.866427Z","end":"2026-04-08T01:16:05.740237Z","steps":["trace[1428667578] 'process raft request' (duration: 2.87361403s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:05.740314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:02.866419Z","time spent":"2.873867317s","remote":"127.0.0.1:53928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T01:16:05.740354Z","caller":"traceutil/trace.go:171","msg":"trace[1472123132] linearizableReadLoop","detail":"{readStateIndex:518; appliedIndex:503; }","duration":"2.618490824s","start":"2026-04-08T01:16:03.121827Z","end":"2026-04-08T01:16:05.740318Z","steps":["trace[1472123132] 'read index received' (duration: 1.675430728s)","trace[1472123132] 'applied index is now lower than readState.Index' (duration: 943.059176ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:16:05.740322Z","caller":"traceutil/trace.go:171","msg":"trace[870422804] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"2.871753857s","start":"2026-04-08T01:16:02.868542Z","end":"2026-04-08T01:16:05.740296Z","steps":["trace[870422804] 'process raft request' (duration: 2.871571701s)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:16:05.740426Z","caller":"traceutil/trace.go:171","msg":"trace[1041930508] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"1.959346528s","start":"2026-04-08T01:16:03.781071Z","end":"2026-04-08T01:16:05.740418Z","steps":["trace[1041930508] 'process raft request' (duration: 1.959200953s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:05.740497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:02.868534Z","time spent":"2.871880491s","remote":"127.0.0.1:53444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7684,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T01:16:05.740558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.618736262s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-08T01:16:05.740575Z","caller":"traceutil/trace.go:171","msg":"trace[1660351939] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"2.868442247s","start":"2026-04-08T01:16:02.872125Z","end":"2026-04-08T01:16:05.740567Z","steps":["trace[1660351939] 'process raft request' (duration: 2.868079496s)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:16:05.740595Z","caller":"traceutil/trace.go:171","msg":"trace[129457164] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:500; }","duration":"2.618810974s","start":"2026-04-08T01:16:03.121775Z","end":"2026-04-08T01:16:05.740586Z","steps":["trace[129457164] 'agreement among raft nodes before linearized reading' (duration: 2.61869699s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:05.740621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:03.121753Z","time spent":"2.618863766s","remote":"127.0.0.1:53666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-08T01:16:05.740629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:02.872113Z","time spent":"2.868485419s","remote":"127.0.0.1:53444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T01:16:05.740385Z","caller":"traceutil/trace.go:171","msg":"trace[1082819845] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"3.063769455s","start":"2026-04-08T01:16:02.676605Z","end":"2026-04-08T01:16:05.740374Z","steps":["trace[1082819845] 'process raft request' (duration: 3.063253709s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:05.740742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:02.676586Z","time spent":"3.064134677s","remote":"127.0.0.1:53444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T01:16:05.740539Z","caller":"traceutil/trace.go:171","msg":"trace[1223626420] transaction","detail":"{read_only:false; number_of_response:1; response_revision:498; }","duration":"2.871943543s","start":"2026-04-08T01:16:02.868584Z","end":"2026-04-08T01:16:05.740528Z","steps":["trace[1223626420] 'process raft request' (duration: 2.871580412s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:05.740834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:02.868573Z","time spent":"2.872219721s","remote":"127.0.0.1:53444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7733,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T01:16:05.740544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:03.781047Z","time spent":"1.959437261s","remote":"127.0.0.1:53666","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-08T01:16:05.744611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.849959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T01:16:05.744653Z","caller":"traceutil/trace.go:171","msg":"trace[1594871169] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:500; }","duration":"549.915901ms","start":"2026-04-08T01:16:05.194725Z","end":"2026-04-08T01:16:05.744641Z","steps":["trace[1594871169] 'agreement among raft nodes before linearized reading' (duration: 549.852789ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:05.744676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:05.194713Z","time spent":"549.959232ms","remote":"127.0.0.1:42906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-08T01:16:05.744827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.541467361s","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-08T01:16:05.744851Z","caller":"traceutil/trace.go:171","msg":"trace[634702088] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:500; }","duration":"1.541529113s","start":"2026-04-08T01:16:04.203315Z","end":"2026-04-08T01:16:05.744844Z","steps":["trace[634702088] 'agreement among raft nodes before linearized reading' (duration: 1.54145997s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:05.744871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:04.203292Z","time spent":"1.541575144s","remote":"127.0.0.1:53666","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-08T01:16:05.744936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.58778373s","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-08T01:16:05.744959Z","caller":"traceutil/trace.go:171","msg":"trace[1083993878] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:500; }","duration":"1.587845403s","start":"2026-04-08T01:16:04.157107Z","end":"2026-04-08T01:16:05.744952Z","steps":["trace[1083993878] 'agreement among raft nodes before linearized reading' (duration: 1.587808832s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:05.744979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:04.157087Z","time spent":"1.587888214s","remote":"127.0.0.1:53666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-08T01:16:05.745696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.542600818s","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-04-08T01:16:05.746546Z","caller":"traceutil/trace.go:171","msg":"trace[997625302] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:500; }","duration":"1.543747115s","start":"2026-04-08T01:16:04.202776Z","end":"2026-04-08T01:16:05.746523Z","steps":["trace[997625302] 'agreement among raft nodes before linearized reading' (duration: 1.542142923s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:05.746599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:04.202758Z","time spent":"1.543828958s","remote":"127.0.0.1:53666","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-04-08T01:16:11.963629Z","caller":"traceutil/trace.go:171","msg":"trace[333671278] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:595; }","duration":"176.020765ms","start":"2026-04-08T01:16:11.787587Z","end":"2026-04-08T01:16:11.963608Z","steps":["trace[333671278] 'read index received' (duration: 175.299594ms)","trace[333671278] 'applied index is now lower than readState.Index' (duration: 720.341µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:16:11.963691Z","caller":"traceutil/trace.go:171","msg":"trace[1545476781] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"243.156048ms","start":"2026-04-08T01:16:11.720491Z","end":"2026-04-08T01:16:11.963647Z","steps":["trace[1545476781] 'process raft request' (duration: 242.617902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:11.963817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.222442ms","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-08T01:16:11.963856Z","caller":"traceutil/trace.go:171","msg":"trace[570998204] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:574; }","duration":"176.302193ms","start":"2026-04-08T01:16:11.787543Z","end":"2026-04-08T01:16:11.963845Z","steps":["trace[570998204] 'agreement among raft nodes before linearized reading' (duration: 176.173449ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:11.963938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.96753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-08T01:16:11.963979Z","caller":"traceutil/trace.go:171","msg":"trace[168151396] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:574; }","duration":"148.031092ms","start":"2026-04-08T01:16:11.815937Z","end":"2026-04-08T01:16:11.963968Z","steps":["trace[168151396] 'agreement among raft nodes before linearized reading' (duration: 147.946759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:11.964042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.615708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-08T01:16:11.964153Z","caller":"traceutil/trace.go:171","msg":"trace[2096421738] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:574; }","duration":"136.773243ms","start":"2026-04-08T01:16:11.827356Z","end":"2026-04-08T01:16:11.964129Z","steps":["trace[2096421738] 'agreement among raft nodes before linearized reading' (duration: 136.563867ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:11.964433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.270468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-08T01:16:11.964621Z","caller":"traceutil/trace.go:171","msg":"trace[1320364138] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:574; }","duration":"146.550397ms","start":"2026-04-08T01:16:11.818041Z","end":"2026-04-08T01:16:11.964592Z","steps":["trace[1320364138] 'agreement among raft nodes before linearized reading' (duration: 145.98024ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:16:12.113893Z","caller":"traceutil/trace.go:171","msg":"trace[1045062216] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"144.940094ms","start":"2026-04-08T01:16:11.968925Z","end":"2026-04-08T01:16:12.113865Z","steps":["trace[1045062216] 'process raft request' (duration: 144.748958ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:16:12.11427Z","caller":"traceutil/trace.go:171","msg":"trace[242249424] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"142.302256ms","start":"2026-04-08T01:16:11.971926Z","end":"2026-04-08T01:16:12.114228Z","steps":["trace[242249424] 'process raft request' (duration: 141.895473ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:16:12.114555Z","caller":"traceutil/trace.go:171","msg":"trace[1827685818] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"141.485962ms","start":"2026-04-08T01:16:11.973046Z","end":"2026-04-08T01:16:12.114532Z","steps":["trace[1827685818] 'process raft request' (duration: 141.065949ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:16:12.114878Z","caller":"traceutil/trace.go:171","msg":"trace[1664332175] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"137.324075ms","start":"2026-04-08T01:16:11.977533Z","end":"2026-04-08T01:16:12.114857Z","steps":["trace[1664332175] 'process raft request' (duration: 136.829921ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:13.251695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.147483ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-08T01:16:13.252012Z","caller":"traceutil/trace.go:171","msg":"trace[778047220] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"1.227939348s","start":"2026-04-08T01:16:12.024055Z","end":"2026-04-08T01:16:13.251994Z","steps":["trace[778047220] 'process raft request' (duration: 364.432855ms)","trace[778047220] 'compare' (duration: 863.021869ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T01:16:13.252061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:12.024029Z","time spent":"1.228011889s","remote":"127.0.0.1:53608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T01:16:14.491817Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439249211912496,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T01:16:14.992947Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439249211912496,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T01:16:15.493558Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439249211912496,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T01:16:15.994482Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439249211912496,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T01:16:16.167176Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.493336662s","expected-duration":"1s"} {"level":"info","ts":"2026-04-08T01:16:16.174273Z","caller":"traceutil/trace.go:171","msg":"trace[232911974] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"2.502216769s","start":"2026-04-08T01:16:13.672029Z","end":"2026-04-08T01:16:16.174246Z","steps":["trace[232911974] 'process raft request' (duration: 2.499499921s)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:16:16.174299Z","caller":"traceutil/trace.go:171","msg":"trace[1821602130] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:602; }","duration":"2.18274094s","start":"2026-04-08T01:16:13.99151Z","end":"2026-04-08T01:16:16.174251Z","steps":["trace[1821602130] 'read index received' (duration: 2.176124689s)","trace[1821602130] 'applied index is now lower than readState.Index' (duration: 6.615371ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T01:16:16.174414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:13.672009Z","time spent":"2.502334362s","remote":"127.0.0.1:53474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036161,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T01:16:16.174408Z","caller":"traceutil/trace.go:171","msg":"trace[364069200] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"233.65778ms","start":"2026-04-08T01:16:15.940715Z","end":"2026-04-08T01:16:16.174373Z","steps":["trace[364069200] 'process raft request' (duration: 233.450154ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:16.174496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.182976066s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-08T01:16:16.174531Z","caller":"traceutil/trace.go:171","msg":"trace[1923263190] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:582; }","duration":"2.183015677s","start":"2026-04-08T01:16:13.991505Z","end":"2026-04-08T01:16:16.174521Z","steps":["trace[1923263190] 'agreement among raft nodes before linearized reading' (duration: 2.182890864s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:16.174564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:13.991444Z","time spent":"2.18311228s","remote":"127.0.0.1:53666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-08T01:16:16.174696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.056574786s","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-08T01:16:16.174738Z","caller":"traceutil/trace.go:171","msg":"trace[784337392] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:582; }","duration":"2.056662308s","start":"2026-04-08T01:16:14.118062Z","end":"2026-04-08T01:16:16.174725Z","steps":["trace[784337392] 'agreement among raft nodes before linearized reading' (duration: 2.056512004s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:16.174757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.971341493s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T01:16:16.174765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:14.11803Z","time spent":"2.05672743s","remote":"127.0.0.1:53666","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-04-08T01:16:16.17479Z","caller":"traceutil/trace.go:171","msg":"trace[1765495202] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:582; }","duration":"1.971391834s","start":"2026-04-08T01:16:14.20339Z","end":"2026-04-08T01:16:16.174782Z","steps":["trace[1765495202] 'agreement among raft nodes before linearized reading' (duration: 1.971336742s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:16.174811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:14.203376Z","time spent":"1.971430436s","remote":"127.0.0.1:42912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-08T01:16:16.174866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.055588127s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-08T01:16:16.174931Z","caller":"traceutil/trace.go:171","msg":"trace[2057248770] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:582; }","duration":"2.0556862s","start":"2026-04-08T01:16:14.119235Z","end":"2026-04-08T01:16:16.174921Z","steps":["trace[2057248770] 'agreement among raft nodes before linearized reading' (duration: 2.055582747s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:16.174962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:14.119214Z","time spent":"2.055738212s","remote":"127.0.0.1:53666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-08T01:16:16.175189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.055467754s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-08T01:16:16.175245Z","caller":"traceutil/trace.go:171","msg":"trace[997695111] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:582; }","duration":"2.055567827s","start":"2026-04-08T01:16:14.119661Z","end":"2026-04-08T01:16:16.175228Z","steps":["trace[997695111] 'agreement among raft nodes before linearized reading' (duration: 2.05498949s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:16.175299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:14.11964Z","time spent":"2.055647329s","remote":"127.0.0.1:53666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-08T01:16:17.683018Z","caller":"traceutil/trace.go:171","msg":"trace[2009849076] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:616; }","duration":"488.190428ms","start":"2026-04-08T01:16:17.194801Z","end":"2026-04-08T01:16:17.682992Z","steps":["trace[2009849076] 'read index received' (duration: 487.065166ms)","trace[2009849076] 'applied index is now lower than readState.Index' (duration: 1.124412ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:16:17.683183Z","caller":"traceutil/trace.go:171","msg":"trace[789545501] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"514.053372ms","start":"2026-04-08T01:16:17.169115Z","end":"2026-04-08T01:16:17.683168Z","steps":["trace[789545501] 'process raft request' (duration: 513.349632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:17.683178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.336152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T01:16:17.683241Z","caller":"traceutil/trace.go:171","msg":"trace[661440786] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:594; }","duration":"488.492126ms","start":"2026-04-08T01:16:17.19473Z","end":"2026-04-08T01:16:17.683222Z","steps":["trace[661440786] 'agreement among raft nodes before linearized reading' (duration: 488.356312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:17.683288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:17.194698Z","time spent":"488.57645ms","remote":"127.0.0.1:42912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-08T01:16:17.683295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:17.169085Z","time spent":"514.145355ms","remote":"127.0.0.1:53474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036229,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T01:16:19.462238Z","caller":"traceutil/trace.go:171","msg":"trace[1852832263] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"110.172631ms","start":"2026-04-08T01:16:19.352007Z","end":"2026-04-08T01:16:19.462179Z","steps":["trace[1852832263] 'process raft request' (duration: 40.16899ms)","trace[1852832263] 'compare' (duration: 69.861766ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:16:21.548777Z","caller":"traceutil/trace.go:171","msg":"trace[1114799446] linearizableReadLoop","detail":"{readStateIndex:696; appliedIndex:695; }","duration":"207.590225ms","start":"2026-04-08T01:16:21.341166Z","end":"2026-04-08T01:16:21.548756Z","steps":["trace[1114799446] 'read index received' (duration: 207.301867ms)","trace[1114799446] 'applied index is now lower than readState.Index' (duration: 287.558µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:16:21.548863Z","caller":"traceutil/trace.go:171","msg":"trace[827036522] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"270.991148ms","start":"2026-04-08T01:16:21.277837Z","end":"2026-04-08T01:16:21.548828Z","steps":["trace[827036522] 'process raft request' (duration: 270.68412ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:21.549342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.157791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-04-08T01:16:21.549408Z","caller":"traceutil/trace.go:171","msg":"trace[68040428] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:672; }","duration":"208.253724ms","start":"2026-04-08T01:16:21.341126Z","end":"2026-04-08T01:16:21.54938Z","steps":["trace[68040428] 'agreement among raft nodes before linearized reading' (duration: 207.718119ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:16:21.553381Z","caller":"traceutil/trace.go:171","msg":"trace[1111077277] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"155.829641ms","start":"2026-04-08T01:16:21.397513Z","end":"2026-04-08T01:16:21.553343Z","steps":["trace[1111077277] 'process raft request' (duration: 155.621615ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:35.694818Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439249211912850,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T01:16:36.195947Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439249211912850,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T01:16:36.334228Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.24831139s","expected-duration":"1s"} {"level":"info","ts":"2026-04-08T01:16:36.334678Z","caller":"traceutil/trace.go:171","msg":"trace[1057151500] linearizableReadLoop","detail":"{readStateIndex:796; appliedIndex:795; }","duration":"1.140073084s","start":"2026-04-08T01:16:35.19458Z","end":"2026-04-08T01:16:36.334653Z","steps":["trace[1057151500] 'read index received' (duration: 1.139753926s)","trace[1057151500] 'applied index is now lower than readState.Index' (duration: 317.528µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:16:36.334796Z","caller":"traceutil/trace.go:171","msg":"trace[1630416567] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"1.249074739s","start":"2026-04-08T01:16:35.085639Z","end":"2026-04-08T01:16:36.334714Z","steps":["trace[1630416567] 'process raft request' (duration: 1.248857994s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:36.334793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.140193376s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T01:16:36.33498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:35.085616Z","time spent":"1.249281984s","remote":"127.0.0.1:53582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4022,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T01:16:36.335059Z","caller":"traceutil/trace.go:171","msg":"trace[1649190010] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:767; }","duration":"1.140476703s","start":"2026-04-08T01:16:35.194549Z","end":"2026-04-08T01:16:36.335026Z","steps":["trace[1649190010] 'agreement among raft nodes before linearized reading' (duration: 1.140198876s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:36.335385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:16:35.194535Z","time spent":"1.140832022s","remote":"127.0.0.1:42912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-08T01:16:36.33519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.865412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-08T01:16:36.33553Z","caller":"traceutil/trace.go:171","msg":"trace[919844235] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:767; }","duration":"227.271012ms","start":"2026-04-08T01:16:36.108242Z","end":"2026-04-08T01:16:36.335513Z","steps":["trace[919844235] 'agreement among raft nodes before linearized reading' (duration: 226.581455ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:38.525638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.689235ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T01:16:38.525771Z","caller":"traceutil/trace.go:171","msg":"trace[2013650516] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"135.157974ms","start":"2026-04-08T01:16:38.390594Z","end":"2026-04-08T01:16:38.525751Z","steps":["trace[2013650516] 'process raft request' (duration: 31.271735ms)","trace[2013650516] 'compare' (duration: 103.5496ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:16:38.555116Z","caller":"traceutil/trace.go:171","msg":"trace[616123737] linearizableReadLoop","detail":"{readStateIndex:807; appliedIndex:805; }","duration":"161.273918ms","start":"2026-04-08T01:16:38.393821Z","end":"2026-04-08T01:16:38.555095Z","steps":["trace[616123737] 'read index received' (duration: 28.007808ms)","trace[616123737] 'applied index is now lower than readState.Index' (duration: 133.26508ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:16:38.555179Z","caller":"traceutil/trace.go:171","msg":"trace[93637240] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"164.511783ms","start":"2026-04-08T01:16:38.390645Z","end":"2026-04-08T01:16:38.555157Z","steps":["trace[93637240] 'process raft request' (duration: 164.344709ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:38.555331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.496263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-66vjk\" ","response":"range_response_count:1 size:2874"} {"level":"info","ts":"2026-04-08T01:16:38.555373Z","caller":"traceutil/trace.go:171","msg":"trace[1098749141] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-66vjk; range_end:; response_count:1; response_revision:777; }","duration":"161.574615ms","start":"2026-04-08T01:16:38.393788Z","end":"2026-04-08T01:16:38.555363Z","steps":["trace[1098749141] 'agreement among raft nodes before linearized reading' (duration: 161.415441ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:38.555418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.806961ms","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-08T01:16:38.555469Z","caller":"traceutil/trace.go:171","msg":"trace[348133914] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:777; }","duration":"147.882473ms","start":"2026-04-08T01:16:38.407576Z","end":"2026-04-08T01:16:38.555458Z","steps":["trace[348133914] 'agreement among raft nodes before linearized reading' (duration: 147.719029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:16:38.555448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.724219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-08T01:16:38.55552Z","caller":"traceutil/trace.go:171","msg":"trace[231905840] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:777; }","duration":"127.832981ms","start":"2026-04-08T01:16:38.427668Z","end":"2026-04-08T01:16:38.555501Z","steps":["trace[231905840] 'agreement among raft nodes before linearized reading' (duration: 127.637887ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:18:01.22419Z","caller":"traceutil/trace.go:171","msg":"trace[1250199668] linearizableReadLoop","detail":"{readStateIndex:1225; appliedIndex:1224; }","duration":"113.427914ms","start":"2026-04-08T01:18:01.11074Z","end":"2026-04-08T01:18:01.224168Z","steps":["trace[1250199668] 'read index received' (duration: 112.482561ms)","trace[1250199668] 'applied index is now lower than readState.Index' (duration: 943.903µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T01:18:01.224385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.626527ms","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-08T01:18:01.224457Z","caller":"traceutil/trace.go:171","msg":"trace[1884810418] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1176; }","duration":"113.71729ms","start":"2026-04-08T01:18:01.110721Z","end":"2026-04-08T01:18:01.224438Z","steps":["trace[1884810418] 'agreement among raft nodes before linearized reading' (duration: 113.525406ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:18:03.415117Z","caller":"traceutil/trace.go:171","msg":"trace[607438230] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"115.648785ms","start":"2026-04-08T01:18:03.299445Z","end":"2026-04-08T01:18:03.415094Z","steps":["trace[607438230] 'process raft request' (duration: 115.604693ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:18:03.415553Z","caller":"traceutil/trace.go:171","msg":"trace[950204887] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"117.614492ms","start":"2026-04-08T01:18:03.297925Z","end":"2026-04-08T01:18:03.41554Z","steps":["trace[950204887] 'process raft request' (duration: 63.153177ms)","trace[950204887] 'compare' (duration: 53.864246ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:18:28.009532Z","caller":"traceutil/trace.go:171","msg":"trace[193377802] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"565.020759ms","start":"2026-04-08T01:18:27.44448Z","end":"2026-04-08T01:18:28.0095Z","steps":["trace[193377802] 'process raft request' (duration: 564.854277ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:18:28.009715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:18:27.444461Z","time spent":"565.154371ms","remote":"127.0.0.1:53666","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-04-08T01:18:28.010072Z","caller":"traceutil/trace.go:171","msg":"trace[1719704740] linearizableReadLoop","detail":"{readStateIndex:1355; appliedIndex:1355; }","duration":"483.369242ms","start":"2026-04-08T01:18:27.526659Z","end":"2026-04-08T01:18:28.010029Z","steps":["trace[1719704740] 'read index received' (duration: 483.358172ms)","trace[1719704740] 'applied index is now lower than readState.Index' (duration: 8.61µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T01:18:28.010352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.689986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-08T01:18:28.010424Z","caller":"traceutil/trace.go:171","msg":"trace[535936723] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1300; }","duration":"483.798527ms","start":"2026-04-08T01:18:27.526609Z","end":"2026-04-08T01:18:28.010407Z","steps":["trace[535936723] 'agreement among raft nodes before linearized reading' (duration: 483.554914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:18:28.010524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:18:27.526589Z","time spent":"483.919119ms","remote":"127.0.0.1:53666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-08T01:18:28.036111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.807394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-08T01:18:28.036186Z","caller":"traceutil/trace.go:171","msg":"trace[532495321] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1301; }","duration":"504.917286ms","start":"2026-04-08T01:18:27.531252Z","end":"2026-04-08T01:18:28.036169Z","steps":["trace[532495321] 'agreement among raft nodes before linearized reading' (duration: 504.731673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:18:28.036279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:18:27.531237Z","time spent":"505.032268ms","remote":"127.0.0.1:53666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-08T01:18:28.036373Z","caller":"traceutil/trace.go:171","msg":"trace[785283592] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"323.862429ms","start":"2026-04-08T01:18:27.712491Z","end":"2026-04-08T01:18:28.036354Z","steps":["trace[785283592] 'process raft request' (duration: 323.351581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:18:28.036765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:18:27.712472Z","time spent":"323.95683ms","remote":"127.0.0.1:53666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}