{"level":"warn","ts":"2026-03-26T09:45:24.554135Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-26T09:45:24.554236Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.36: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://162.253.55.36:2380","--initial-cluster=instance=https://162.253.55.36:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.36:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.36:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-26T09:45:24.554315Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-26T09:45:24.55433Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.36:2380"]} {"level":"info","ts":"2026-03-26T09:45:24.554349Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-26T09:45:24.555271Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.36:2379"]} {"level":"info","ts":"2026-03-26T09:45:24.555354Z","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://162.253.55.36:2380"],"listen-peer-urls":["https://162.253.55.36:2380"],"advertise-client-urls":["https://162.253.55.36:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.36:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.36:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-26T09:45:24.559744Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.14765ms"} {"level":"info","ts":"2026-03-26T09:45:24.56655Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cb89ec34a9687f0b","cluster-id":"d8e0d5e222e856c9"} {"level":"info","ts":"2026-03-26T09:45:24.566637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b switched to configuration voters=()"} {"level":"info","ts":"2026-03-26T09:45:24.56666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became follower at term 0"} {"level":"info","ts":"2026-03-26T09:45:24.566677Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cb89ec34a9687f0b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-26T09:45:24.56669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became follower at term 1"} {"level":"info","ts":"2026-03-26T09:45:24.56673Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b switched to configuration voters=(14666513372433383179)"} {"level":"warn","ts":"2026-03-26T09:45:24.574083Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-26T09:45:24.576567Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-26T09:45:24.577547Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-03-26T09:45:24.580375Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cb89ec34a9687f0b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-26T09:45:24.584228Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cb89ec34a9687f0b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-26T09:45:24.584489Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-26T09:45:24.584583Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-26T09:45:24.58473Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-26T09:45:24.585261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b switched to configuration voters=(14666513372433383179)"} {"level":"info","ts":"2026-03-26T09:45:24.585858Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d8e0d5e222e856c9","local-member-id":"cb89ec34a9687f0b","added-peer-id":"cb89ec34a9687f0b","added-peer-peer-urls":["https://162.253.55.36:2380"]} {"level":"info","ts":"2026-03-26T09:45:24.587564Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-26T09:45:24.587915Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cb89ec34a9687f0b","initial-advertise-peer-urls":["https://162.253.55.36:2380"],"listen-peer-urls":["https://162.253.55.36:2380"],"advertise-client-urls":["https://162.253.55.36:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.36:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-26T09:45:24.587969Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-26T09:45:24.587732Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.36:2380"} {"level":"info","ts":"2026-03-26T09:45:24.590105Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.36:2380"} {"level":"info","ts":"2026-03-26T09:45:25.267434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b is starting a new election at term 1"} {"level":"info","ts":"2026-03-26T09:45:25.267521Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became pre-candidate at term 1"} {"level":"info","ts":"2026-03-26T09:45:25.267552Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b received MsgPreVoteResp from cb89ec34a9687f0b at term 1"} {"level":"info","ts":"2026-03-26T09:45:25.267573Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became candidate at term 2"} {"level":"info","ts":"2026-03-26T09:45:25.267585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b received MsgVoteResp from cb89ec34a9687f0b at term 2"} {"level":"info","ts":"2026-03-26T09:45:25.267602Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became leader at term 2"} {"level":"info","ts":"2026-03-26T09:45:25.267616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cb89ec34a9687f0b elected leader cb89ec34a9687f0b at term 2"} {"level":"info","ts":"2026-03-26T09:45:25.268785Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cb89ec34a9687f0b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.36:2379]}","request-path":"/0/members/cb89ec34a9687f0b/attributes","cluster-id":"d8e0d5e222e856c9","publish-timeout":"7s"} {"level":"info","ts":"2026-03-26T09:45:25.268849Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-26T09:45:25.268807Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-26T09:45:25.268806Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-26T09:45:25.269156Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-26T09:45:25.269187Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-26T09:45:25.27Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d8e0d5e222e856c9","local-member-id":"cb89ec34a9687f0b","cluster-version":"3.5"} {"level":"info","ts":"2026-03-26T09:45:25.270062Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-26T09:45:25.270083Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-26T09:45:25.270602Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.36:2379"} {"level":"info","ts":"2026-03-26T09:45:25.272327Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-26T09:45:26.770889Z","caller":"traceutil/trace.go:171","msg":"trace[1071102639] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"163.660716ms","start":"2026-03-26T09:45:26.607199Z","end":"2026-03-26T09:45:26.77086Z","steps":["trace[1071102639] 'process raft request' (duration: 163.449666ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:26.770916Z","caller":"traceutil/trace.go:171","msg":"trace[2034723109] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"164.367653ms","start":"2026-03-26T09:45:26.606515Z","end":"2026-03-26T09:45:26.770883Z","steps":["trace[2034723109] 'process raft request' (duration: 158.130907ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:26.770982Z","caller":"traceutil/trace.go:171","msg":"trace[429925023] linearizableReadLoop","detail":"{readStateIndex:19; appliedIndex:15; }","duration":"163.156333ms","start":"2026-03-26T09:45:26.607797Z","end":"2026-03-26T09:45:26.770953Z","steps":["trace[429925023] 'read index received' (duration: 13.430673ms)","trace[429925023] 'applied index is now lower than readState.Index' (duration: 149.72456ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:45:26.77115Z","caller":"traceutil/trace.go:171","msg":"trace[138991128] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"163.069178ms","start":"2026-03-26T09:45:26.608057Z","end":"2026-03-26T09:45:26.771126Z","steps":["trace[138991128] 'process raft request' (duration: 162.794453ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:26.771133Z","caller":"traceutil/trace.go:171","msg":"trace[741138962] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"163.099909ms","start":"2026-03-26T09:45:26.607996Z","end":"2026-03-26T09:45:26.771095Z","steps":["trace[741138962] 'process raft request' (duration: 162.823454ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:26.771352Z","caller":"traceutil/trace.go:171","msg":"trace[1917332259] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"163.405784ms","start":"2026-03-26T09:45:26.607915Z","end":"2026-03-26T09:45:26.771321Z","steps":["trace[1917332259] 'process raft request' (duration: 162.846826ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:26.771404Z","caller":"traceutil/trace.go:171","msg":"trace[1149290429] transaction","detail":"{read_only:false; number_of_response:0; response_revision:15; }","duration":"164.10425ms","start":"2026-03-26T09:45:26.607289Z","end":"2026-03-26T09:45:26.771393Z","steps":["trace[1149290429] 'process raft request' (duration: 163.443186ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:26.77142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.551702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-p9vj2\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-03-26T09:45:26.771516Z","caller":"traceutil/trace.go:171","msg":"trace[967622907] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-p9vj2; range_end:; response_count:1; response_revision:20; }","duration":"163.723831ms","start":"2026-03-26T09:45:26.607775Z","end":"2026-03-26T09:45:26.771499Z","steps":["trace[967622907] 'agreement among raft nodes before linearized reading' (duration: 163.483139ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:26.771633Z","caller":"traceutil/trace.go:171","msg":"trace[1010911518] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"163.444936ms","start":"2026-03-26T09:45:26.608178Z","end":"2026-03-26T09:45:26.771623Z","steps":["trace[1010911518] 'process raft request' (duration: 162.73149ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:26.77162Z","caller":"traceutil/trace.go:171","msg":"trace[860788446] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"163.436156ms","start":"2026-03-26T09:45:26.608142Z","end":"2026-03-26T09:45:26.771579Z","steps":["trace[860788446] 'process raft request' (duration: 162.738661ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:26.771761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.871548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-03-26T09:45:26.772019Z","caller":"traceutil/trace.go:171","msg":"trace[1812142386] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"124.172443ms","start":"2026-03-26T09:45:26.647834Z","end":"2026-03-26T09:45:26.772006Z","steps":["trace[1812142386] 'agreement among raft nodes before linearized reading' (duration: 123.859398ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:26.771769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.977553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-03-26T09:45:26.772126Z","caller":"traceutil/trace.go:171","msg":"trace[1572113659] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"124.275608ms","start":"2026-03-26T09:45:26.647771Z","end":"2026-03-26T09:45:26.772046Z","steps":["trace[1572113659] 'agreement among raft nodes before linearized reading' (duration: 123.940151ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:26.771818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.888586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-03-26T09:45:26.772323Z","caller":"traceutil/trace.go:171","msg":"trace[570393413] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"146.39685ms","start":"2026-03-26T09:45:26.625902Z","end":"2026-03-26T09:45:26.772298Z","steps":["trace[570393413] 'agreement among raft nodes before linearized reading' (duration: 145.896836ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:26.775968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.192482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-26T09:45:26.776033Z","caller":"traceutil/trace.go:171","msg":"trace[1316959678] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:20; }","duration":"165.86879ms","start":"2026-03-26T09:45:26.610147Z","end":"2026-03-26T09:45:26.776015Z","steps":["trace[1316959678] 'agreement among raft nodes before linearized reading' (duration: 161.174881ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:27.850271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.189553ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-26T09:45:27.850378Z","caller":"traceutil/trace.go:171","msg":"trace[1933924431] linearizableReadLoop","detail":"{readStateIndex:122; appliedIndex:121; }","duration":"297.392788ms","start":"2026-03-26T09:45:27.552971Z","end":"2026-03-26T09:45:27.850364Z","steps":["trace[1933924431] 'read index received' (duration: 136.731302ms)","trace[1933924431] 'applied index is now lower than readState.Index' (duration: 160.660416ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:45:27.850518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.859439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-26T09:45:27.850555Z","caller":"traceutil/trace.go:171","msg":"trace[1777685757] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:117; }","duration":"242.928453ms","start":"2026-03-26T09:45:27.607616Z","end":"2026-03-26T09:45:27.850544Z","steps":["trace[1777685757] 'agreement among raft nodes before linearized reading' (duration: 242.833988ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:27.85047Z","caller":"traceutil/trace.go:171","msg":"trace[1151039391] transaction","detail":"{read_only:false; response_revision:117; number_of_response:1; }","duration":"298.121845ms","start":"2026-03-26T09:45:27.552299Z","end":"2026-03-26T09:45:27.850421Z","steps":["trace[1151039391] 'process raft request' (duration: 137.332302ms)","trace[1151039391] 'compare' (duration: 160.045875ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:45:27.850553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.563609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-26T09:45:27.850632Z","caller":"traceutil/trace.go:171","msg":"trace[495302820] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:0; response_revision:117; }","duration":"297.659444ms","start":"2026-03-26T09:45:27.552952Z","end":"2026-03-26T09:45:27.850612Z","steps":["trace[495302820] 'agreement among raft nodes before linearized reading' (duration: 297.480954ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:30.409125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.853178463s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-26T09:45:30.409267Z","caller":"traceutil/trace.go:171","msg":"trace[1232303780] linearizableReadLoop","detail":"{readStateIndex:123; appliedIndex:122; }","duration":"1.832595597s","start":"2026-03-26T09:45:28.576647Z","end":"2026-03-26T09:45:30.409243Z","steps":["trace[1232303780] 'read index received' (duration: 45.732µs)","trace[1232303780] 'applied index is now lower than readState.Index' (duration: 1.832548285s)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:45:30.40953Z","caller":"traceutil/trace.go:171","msg":"trace[317773604] transaction","detail":"{read_only:false; response_revision:118; number_of_response:1; }","duration":"2.543223717s","start":"2026-03-26T09:45:27.866287Z","end":"2026-03-26T09:45:30.409511Z","steps":["trace[317773604] 'process raft request' (duration: 689.58853ms)","trace[317773604] 'compare' (duration: 1.853037486s)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:45:30.409618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:27.866274Z","time spent":"2.543308681s","remote":"127.0.0.1:41930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-26T09:45:30.409721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.833050591s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-26T09:45:30.409785Z","caller":"traceutil/trace.go:171","msg":"trace[168063947] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:118; }","duration":"1.833170906s","start":"2026-03-26T09:45:28.576599Z","end":"2026-03-26T09:45:30.40977Z","steps":["trace[168063947] 'agreement among raft nodes before linearized reading' (duration: 1.833009508s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:30.40983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:28.576577Z","time spent":"1.833234259s","remote":"127.0.0.1:41542","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-03-26T09:45:30.71937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000119874s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-26T09:45:30.719504Z","caller":"traceutil/trace.go:171","msg":"trace[2080623878] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000309974s","start":"2026-03-26T09:45:28.719165Z","end":"2026-03-26T09:45:30.719475Z","steps":["trace[2080623878] 'agreement among raft nodes before linearized reading' (duration: 2.000153646s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:30.719581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:28.719146Z","time spent":"2.000419069s","remote":"127.0.0.1:41558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/26 09:45:30 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-26T09:45:30.90998Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154583469275043625,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-26T09:45:31.410344Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154583469275043625,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-26T09:45:31.596951Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.451471495s","expected-duration":"1s"} {"level":"info","ts":"2026-03-26T09:45:31.597589Z","caller":"traceutil/trace.go:171","msg":"trace[146220050] transaction","detail":"{read_only:false; response_revision:119; number_of_response:1; }","duration":"1.452226404s","start":"2026-03-26T09:45:30.145341Z","end":"2026-03-26T09:45:31.597568Z","steps":["trace[146220050] 'process raft request' (duration: 1.452145669s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:31.597699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:30.14531Z","time spent":"1.45233616s","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3959,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T09:45:31.606559Z","caller":"traceutil/trace.go:171","msg":"trace[482043463] transaction","detail":"{read_only:false; response_revision:120; number_of_response:1; }","duration":"1.381429185s","start":"2026-03-26T09:45:30.225106Z","end":"2026-03-26T09:45:31.606535Z","steps":["trace[482043463] 'process raft request' (duration: 1.381191383s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:31.606649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:30.225076Z","time spent":"1.38153623s","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T09:45:31.606631Z","caller":"traceutil/trace.go:171","msg":"trace[482725088] linearizableReadLoop","detail":"{readStateIndex:126; appliedIndex:124; }","duration":"1.197279698s","start":"2026-03-26T09:45:30.409302Z","end":"2026-03-26T09:45:31.606582Z","steps":["trace[482725088] 'read index received' (duration: 1.188294392s)","trace[482725088] 'applied index is now lower than readState.Index' (duration: 8.983796ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:45:31.606741Z","caller":"traceutil/trace.go:171","msg":"trace[451271407] transaction","detail":"{read_only:false; response_revision:121; number_of_response:1; }","duration":"363.57219ms","start":"2026-03-26T09:45:31.243156Z","end":"2026-03-26T09:45:31.606729Z","steps":["trace[451271407] 'process raft request' (duration: 363.291915ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:31.606838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:31.243134Z","time spent":"363.645974ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5877,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-26T09:45:31.607023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.789685087s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-26T09:45:31.607069Z","caller":"traceutil/trace.go:171","msg":"trace[302433182] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:121; }","duration":"2.789767881s","start":"2026-03-26T09:45:28.817289Z","end":"2026-03-26T09:45:31.607057Z","steps":["trace[302433182] 'agreement among raft nodes before linearized reading' (duration: 2.789661356s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:31.607099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:28.817263Z","time spent":"2.789828164s","remote":"127.0.0.1:41830","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":"warn","ts":"2026-03-26T09:45:31.607158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.484964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-26T09:45:31.607201Z","caller":"traceutil/trace.go:171","msg":"trace[1392892345] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:121; }","duration":"799.564048ms","start":"2026-03-26T09:45:30.807625Z","end":"2026-03-26T09:45:31.607189Z","steps":["trace[1392892345] 'agreement among raft nodes before linearized reading' (duration: 799.495095ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:31.607232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:30.807599Z","time spent":"799.626811ms","remote":"127.0.0.1:41558","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-03-26T09:45:31.607315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.030284792s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-26T09:45:31.607158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.194731868s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:discovery\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-26T09:45:31.607375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.79031967s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-03-26T09:45:31.607388Z","caller":"traceutil/trace.go:171","msg":"trace[242639372] range","detail":"{range_begin:/registry/clusterroles/system:discovery; range_end:; response_count:0; response_revision:121; }","duration":"1.195036954s","start":"2026-03-26T09:45:30.412339Z","end":"2026-03-26T09:45:31.607376Z","steps":["trace[242639372] 'agreement among raft nodes before linearized reading' (duration: 1.194693126s)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:31.607409Z","caller":"traceutil/trace.go:171","msg":"trace[618850697] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:121; }","duration":"2.790394123s","start":"2026-03-26T09:45:28.817006Z","end":"2026-03-26T09:45:31.607401Z","steps":["trace[618850697] 'agreement among raft nodes before linearized reading' (duration: 2.79033166s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:31.607428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:30.412316Z","time spent":"1.195101237s","remote":"127.0.0.1:41930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/system:discovery\" "} {"level":"info","ts":"2026-03-26T09:45:31.607474Z","caller":"traceutil/trace.go:171","msg":"trace[1009186112] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:121; }","duration":"1.030380718s","start":"2026-03-26T09:45:30.576982Z","end":"2026-03-26T09:45:31.607363Z","steps":["trace[1009186112] 'agreement among raft nodes before linearized reading' (duration: 1.030235219s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:31.607439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:28.816967Z","time spent":"2.790465097s","remote":"127.0.0.1:41830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-26T09:45:31.607529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:30.576964Z","time spent":"1.030556125s","remote":"127.0.0.1:41542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-26T09:45:32.340292Z","caller":"traceutil/trace.go:171","msg":"trace[1716135014] transaction","detail":"{read_only:false; response_revision:216; number_of_response:1; }","duration":"107.889346ms","start":"2026-03-26T09:45:32.232372Z","end":"2026-03-26T09:45:32.340261Z","steps":["trace[1716135014] 'process raft request' (duration: 22.405489ms)","trace[1716135014] 'compare' (duration: 85.3446ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:45:32.564365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.606155ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-26T09:45:32.564502Z","caller":"traceutil/trace.go:171","msg":"trace[1376287730] transaction","detail":"{read_only:false; response_revision:222; number_of_response:1; }","duration":"151.010988ms","start":"2026-03-26T09:45:32.413467Z","end":"2026-03-26T09:45:32.564478Z","steps":["trace[1376287730] 'process raft request' (duration: 50.197453ms)","trace[1376287730] 'compare' (duration: 100.482128ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:45:32.736034Z","caller":"traceutil/trace.go:171","msg":"trace[250511020] transaction","detail":"{read_only:false; response_revision:225; number_of_response:1; }","duration":"106.023192ms","start":"2026-03-26T09:45:32.629963Z","end":"2026-03-26T09:45:32.735986Z","steps":["trace[250511020] 'process raft request' (duration: 49.334379ms)","trace[250511020] 'compare' (duration: 56.546315ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:45:33.024842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.77455ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-26T09:45:33.024982Z","caller":"traceutil/trace.go:171","msg":"trace[1464522852] transaction","detail":"{read_only:false; response_revision:236; number_of_response:1; }","duration":"200.366538ms","start":"2026-03-26T09:45:32.824578Z","end":"2026-03-26T09:45:33.024945Z","steps":["trace[1464522852] 'process raft request' (duration: 44.395018ms)","trace[1464522852] 'compare' (duration: 155.636233ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:45:35.049844Z","caller":"traceutil/trace.go:171","msg":"trace[1464342138] linearizableReadLoop","detail":"{readStateIndex:270; appliedIndex:269; }","duration":"391.728519ms","start":"2026-03-26T09:45:34.658074Z","end":"2026-03-26T09:45:35.049802Z","steps":["trace[1464342138] 'read index received' (duration: 391.590311ms)","trace[1464342138] 'applied index is now lower than readState.Index' (duration: 137.068µs)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:45:35.050086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.983212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3708"} {"level":"info","ts":"2026-03-26T09:45:35.050157Z","caller":"traceutil/trace.go:171","msg":"trace[1970497198] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:264; }","duration":"392.097077ms","start":"2026-03-26T09:45:34.658042Z","end":"2026-03-26T09:45:35.050139Z","steps":["trace[1970497198] 'agreement among raft nodes before linearized reading' (duration: 391.931249ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:35.050154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.397532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-26T09:45:35.05021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:34.658027Z","time spent":"392.168671ms","remote":"127.0.0.1:41750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3732,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-03-26T09:45:35.050249Z","caller":"traceutil/trace.go:171","msg":"trace[447694407] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:264; }","duration":"231.54826ms","start":"2026-03-26T09:45:34.818678Z","end":"2026-03-26T09:45:35.050226Z","steps":["trace[447694407] 'agreement among raft nodes before linearized reading' (duration: 231.406843ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:35.050687Z","caller":"traceutil/trace.go:171","msg":"trace[1165560104] transaction","detail":"{read_only:false; response_revision:264; number_of_response:1; }","duration":"592.990627ms","start":"2026-03-26T09:45:34.457663Z","end":"2026-03-26T09:45:35.050654Z","steps":["trace[1165560104] 'process raft request' (duration: 591.981115ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:35.050817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:34.457636Z","time spent":"593.124774ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T09:45:35.650285Z","caller":"traceutil/trace.go:171","msg":"trace[769454934] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"109.937054ms","start":"2026-03-26T09:45:35.54032Z","end":"2026-03-26T09:45:35.650257Z","steps":["trace[769454934] 'process raft request' (duration: 68.18365ms)","trace[769454934] 'compare' (duration: 41.531973ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:45:35.919496Z","caller":"traceutil/trace.go:171","msg":"trace[827088964] transaction","detail":"{read_only:false; number_of_response:0; response_revision:293; }","duration":"265.569314ms","start":"2026-03-26T09:45:35.653868Z","end":"2026-03-26T09:45:35.919437Z","steps":["trace[827088964] 'process raft request' (duration: 232.240728ms)","trace[827088964] 'compare' (duration: 32.846921ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:45:35.922117Z","caller":"traceutil/trace.go:171","msg":"trace[662494246] linearizableReadLoop","detail":"{readStateIndex:301; appliedIndex:298; }","duration":"267.976516ms","start":"2026-03-26T09:45:35.654084Z","end":"2026-03-26T09:45:35.92206Z","steps":["trace[662494246] 'read index received' (duration: 61.413535ms)","trace[662494246] 'applied index is now lower than readState.Index' (duration: 206.561791ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:45:35.922569Z","caller":"traceutil/trace.go:171","msg":"trace[1261305248] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"268.508722ms","start":"2026-03-26T09:45:35.65403Z","end":"2026-03-26T09:45:35.922539Z","steps":["trace[1261305248] 'process raft request' (duration: 265.037506ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:35.922592Z","caller":"traceutil/trace.go:171","msg":"trace[1905289795] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"267.777156ms","start":"2026-03-26T09:45:35.654785Z","end":"2026-03-26T09:45:35.922563Z","steps":["trace[1905289795] 'process raft request' (duration: 266.421737ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:35.922613Z","caller":"traceutil/trace.go:171","msg":"trace[943864601] transaction","detail":"{read_only:false; number_of_response:0; response_revision:297; }","duration":"246.176466ms","start":"2026-03-26T09:45:35.676394Z","end":"2026-03-26T09:45:35.922571Z","steps":["trace[943864601] 'process raft request' (duration: 245.077711ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:35.922633Z","caller":"traceutil/trace.go:171","msg":"trace[1706233219] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"267.66699ms","start":"2026-03-26T09:45:35.654916Z","end":"2026-03-26T09:45:35.922583Z","steps":["trace[1706233219] 'process raft request' (duration: 266.397505ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:35.922727Z","caller":"traceutil/trace.go:171","msg":"trace[155600323] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"261.411641ms","start":"2026-03-26T09:45:35.661262Z","end":"2026-03-26T09:45:35.922674Z","steps":["trace[155600323] 'process raft request' (duration: 260.112505ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:35.923793Z","caller":"traceutil/trace.go:171","msg":"trace[807431976] transaction","detail":"{read_only:false; number_of_response:0; response_revision:297; }","duration":"247.574868ms","start":"2026-03-26T09:45:35.676185Z","end":"2026-03-26T09:45:35.92376Z","steps":["trace[807431976] 'process raft request' (duration: 245.24561ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:35.923844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.739836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4016"} {"level":"info","ts":"2026-03-26T09:45:35.923862Z","caller":"traceutil/trace.go:171","msg":"trace[135160680] transaction","detail":"{read_only:false; number_of_response:0; response_revision:297; }","duration":"246.22531ms","start":"2026-03-26T09:45:35.677611Z","end":"2026-03-26T09:45:35.923836Z","steps":["trace[135160680] 'process raft request' (duration: 244.360014ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:35.923975Z","caller":"traceutil/trace.go:171","msg":"trace[927016937] transaction","detail":"{read_only:false; number_of_response:0; response_revision:297; }","duration":"246.540615ms","start":"2026-03-26T09:45:35.677419Z","end":"2026-03-26T09:45:35.923959Z","steps":["trace[927016937] 'process raft request' (duration: 244.119422ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:35.923899Z","caller":"traceutil/trace.go:171","msg":"trace[1840150169] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:297; }","duration":"269.906034ms","start":"2026-03-26T09:45:35.653979Z","end":"2026-03-26T09:45:35.923885Z","steps":["trace[1840150169] 'agreement among raft nodes before linearized reading' (duration: 268.909154ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:35.923934Z","caller":"traceutil/trace.go:171","msg":"trace[1705809134] transaction","detail":"{read_only:false; number_of_response:0; response_revision:297; }","duration":"247.010199ms","start":"2026-03-26T09:45:35.676913Z","end":"2026-03-26T09:45:35.923923Z","steps":["trace[1705809134] 'process raft request' (duration: 244.587296ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:35.92416Z","caller":"traceutil/trace.go:171","msg":"trace[2017224731] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"204.866394ms","start":"2026-03-26T09:45:35.719284Z","end":"2026-03-26T09:45:35.924151Z","steps":["trace[2017224731] 'process raft request' (duration: 204.79171ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:36.165499Z","caller":"traceutil/trace.go:171","msg":"trace[1501885989] linearizableReadLoop","detail":"{readStateIndex:313; appliedIndex:311; }","duration":"172.056128ms","start":"2026-03-26T09:45:35.99342Z","end":"2026-03-26T09:45:36.165476Z","steps":["trace[1501885989] 'read index received' (duration: 134.919648ms)","trace[1501885989] 'applied index is now lower than readState.Index' (duration: 37.13553ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:45:36.165544Z","caller":"traceutil/trace.go:171","msg":"trace[1245329419] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"231.223958ms","start":"2026-03-26T09:45:35.934289Z","end":"2026-03-26T09:45:36.165513Z","steps":["trace[1245329419] 'process raft request' (duration: 231.120803ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:36.16558Z","caller":"traceutil/trace.go:171","msg":"trace[2025154480] transaction","detail":"{read_only:false; response_revision:300; number_of_response:1; }","duration":"236.16932ms","start":"2026-03-26T09:45:35.929384Z","end":"2026-03-26T09:45:36.165554Z","steps":["trace[2025154480] 'process raft request' (duration: 199.027989ms)","trace[2025154480] 'compare' (duration: 36.707549ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:45:36.16579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.124558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:45:36.165842Z","caller":"traceutil/trace.go:171","msg":"trace[1911285790] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:0; response_revision:301; }","duration":"236.347019ms","start":"2026-03-26T09:45:35.929479Z","end":"2026-03-26T09:45:36.165826Z","steps":["trace[1911285790] 'agreement among raft nodes before linearized reading' (duration: 236.092376ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:36.165847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.261665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4016"} {"level":"info","ts":"2026-03-26T09:45:36.16588Z","caller":"traceutil/trace.go:171","msg":"trace[2095405851] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:301; }","duration":"236.311757ms","start":"2026-03-26T09:45:35.929556Z","end":"2026-03-26T09:45:36.165868Z","steps":["trace[2095405851] 'agreement among raft nodes before linearized reading' (duration: 236.235824ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:36.481972Z","caller":"traceutil/trace.go:171","msg":"trace[1511311836] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"309.80116ms","start":"2026-03-26T09:45:36.172137Z","end":"2026-03-26T09:45:36.481939Z","steps":["trace[1511311836] 'process raft request' (duration: 213.925959ms)","trace[1511311836] 'compare' (duration: 95.505832ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:45:36.482227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:36.172117Z","time spent":"310.035701ms","remote":"127.0.0.1:41772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":201,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T09:45:36.48284Z","caller":"traceutil/trace.go:171","msg":"trace[483761126] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"214.734759ms","start":"2026-03-26T09:45:36.268088Z","end":"2026-03-26T09:45:36.482823Z","steps":["trace[483761126] 'process raft request' (duration: 214.617864ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:36.483112Z","caller":"traceutil/trace.go:171","msg":"trace[629783052] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"308.460091ms","start":"2026-03-26T09:45:36.174609Z","end":"2026-03-26T09:45:36.483069Z","steps":["trace[629783052] 'process raft request' (duration: 307.115833ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:36.483516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.775229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:115"} {"level":"info","ts":"2026-03-26T09:45:36.483564Z","caller":"traceutil/trace.go:171","msg":"trace[1419033406] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:305; }","duration":"310.872534ms","start":"2026-03-26T09:45:36.172681Z","end":"2026-03-26T09:45:36.483554Z","steps":["trace[1419033406] 'agreement among raft nodes before linearized reading' (duration: 310.709206ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:36.483128Z","caller":"traceutil/trace.go:171","msg":"trace[1757665733] linearizableReadLoop","detail":"{readStateIndex:315; appliedIndex:313; }","duration":"310.38089ms","start":"2026-03-26T09:45:36.172719Z","end":"2026-03-26T09:45:36.4831Z","steps":["trace[1757665733] 'read index received' (duration: 92.429555ms)","trace[1757665733] 'applied index is now lower than readState.Index' (duration: 217.948465ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:45:36.483598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:36.172665Z","time spent":"310.924576ms","remote":"127.0.0.1:41628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":139,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-03-26T09:45:36.484085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:36.174596Z","time spent":"308.955676ms","remote":"127.0.0.1:41750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4182,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:45:36.747657Z","caller":"traceutil/trace.go:171","msg":"trace[1241911881] linearizableReadLoop","detail":"{readStateIndex:320; appliedIndex:319; }","duration":"196.45519ms","start":"2026-03-26T09:45:36.551183Z","end":"2026-03-26T09:45:36.747639Z","steps":["trace[1241911881] 'read index received' (duration: 164.660561ms)","trace[1241911881] 'applied index is now lower than readState.Index' (duration: 31.793859ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:45:36.747771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.627769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:45:36.747793Z","caller":"traceutil/trace.go:171","msg":"trace[589182849] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:308; }","duration":"196.6616ms","start":"2026-03-26T09:45:36.551125Z","end":"2026-03-26T09:45:36.747787Z","steps":["trace[589182849] 'agreement among raft nodes before linearized reading' (duration: 196.595387ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:36.748259Z","caller":"traceutil/trace.go:171","msg":"trace[150718050] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"196.894232ms","start":"2026-03-26T09:45:36.550792Z","end":"2026-03-26T09:45:36.747686Z","steps":["trace[150718050] 'process raft request' (duration: 165.041671ms)","trace[150718050] 'compare' (duration: 31.624929ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:45:36.80374Z","caller":"traceutil/trace.go:171","msg":"trace[421759118] transaction","detail":"{read_only:false; number_of_response:0; response_revision:308; }","duration":"215.366332ms","start":"2026-03-26T09:45:36.588348Z","end":"2026-03-26T09:45:36.803714Z","steps":["trace[421759118] 'process raft request' (duration: 215.285658ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:36.803766Z","caller":"traceutil/trace.go:171","msg":"trace[1577812020] transaction","detail":"{read_only:false; number_of_response:0; response_revision:308; }","duration":"215.32444ms","start":"2026-03-26T09:45:36.588415Z","end":"2026-03-26T09:45:36.803739Z","steps":["trace[1577812020] 'process raft request' (duration: 215.237726ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:36.803773Z","caller":"traceutil/trace.go:171","msg":"trace[1793054492] transaction","detail":"{read_only:false; number_of_response:0; response_revision:308; }","duration":"215.761802ms","start":"2026-03-26T09:45:36.587974Z","end":"2026-03-26T09:45:36.803736Z","steps":["trace[1793054492] 'process raft request' (duration: 215.581083ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:36.803916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.817826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7293"} {"level":"info","ts":"2026-03-26T09:45:36.804006Z","caller":"traceutil/trace.go:171","msg":"trace[1844931391] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:308; }","duration":"216.923622ms","start":"2026-03-26T09:45:36.587065Z","end":"2026-03-26T09:45:36.803988Z","steps":["trace[1844931391] 'agreement among raft nodes before linearized reading' (duration: 216.716531ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:36.804031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.238879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-03-26T09:45:36.804065Z","caller":"traceutil/trace.go:171","msg":"trace[1906194325] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:308; }","duration":"252.309152ms","start":"2026-03-26T09:45:36.551745Z","end":"2026-03-26T09:45:36.804054Z","steps":["trace[1906194325] 'agreement among raft nodes before linearized reading' (duration: 252.230018ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:36.804163Z","caller":"traceutil/trace.go:171","msg":"trace[1097783100] transaction","detail":"{read_only:false; number_of_response:0; response_revision:308; }","duration":"215.456156ms","start":"2026-03-26T09:45:36.588693Z","end":"2026-03-26T09:45:36.804149Z","steps":["trace[1097783100] 'process raft request' (duration: 214.975372ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:36.804195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.521963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-26T09:45:36.80428Z","caller":"traceutil/trace.go:171","msg":"trace[2104825163] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:308; }","duration":"252.609568ms","start":"2026-03-26T09:45:36.551655Z","end":"2026-03-26T09:45:36.804265Z","steps":["trace[2104825163] 'agreement among raft nodes before linearized reading' (duration: 252.517553ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:36.804194Z","caller":"traceutil/trace.go:171","msg":"trace[1699840738] transaction","detail":"{read_only:false; number_of_response:0; response_revision:308; }","duration":"214.74481ms","start":"2026-03-26T09:45:36.589409Z","end":"2026-03-26T09:45:36.804154Z","steps":["trace[1699840738] 'process raft request' (duration: 214.276857ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:37.303797Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154583469275044032,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-26T09:45:37.361009Z","caller":"traceutil/trace.go:171","msg":"trace[112783281] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"560.472755ms","start":"2026-03-26T09:45:36.800506Z","end":"2026-03-26T09:45:37.360979Z","steps":["trace[112783281] 'process raft request' (duration: 560.389661ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:37.36102Z","caller":"traceutil/trace.go:171","msg":"trace[48569886] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"606.635143ms","start":"2026-03-26T09:45:36.754361Z","end":"2026-03-26T09:45:37.360996Z","steps":["trace[48569886] 'process raft request' (duration: 606.282775ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:37.361184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:36.800481Z","time spent":"560.612331ms","remote":"127.0.0.1:41830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:45:37.361417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:36.754345Z","time spent":"606.841334ms","remote":"127.0.0.1:42070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2651,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-26T09:45:37.645261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.302014ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-26T09:45:37.645505Z","caller":"traceutil/trace.go:171","msg":"trace[667156738] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"836.584512ms","start":"2026-03-26T09:45:36.808898Z","end":"2026-03-26T09:45:37.645482Z","steps":["trace[667156738] 'process raft request' (duration: 718.011405ms)","trace[667156738] 'compare' (duration: 118.130924ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:45:37.645534Z","caller":"traceutil/trace.go:171","msg":"trace[450372892] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"279.203246ms","start":"2026-03-26T09:45:37.36632Z","end":"2026-03-26T09:45:37.645523Z","steps":["trace[450372892] 'process raft request' (duration: 279.164974ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:37.645644Z","caller":"traceutil/trace.go:171","msg":"trace[1241301802] linearizableReadLoop","detail":"{readStateIndex:329; appliedIndex:325; }","duration":"841.923213ms","start":"2026-03-26T09:45:36.803711Z","end":"2026-03-26T09:45:37.645634Z","steps":["trace[1241301802] 'read index received' (duration: 557.019187ms)","trace[1241301802] 'applied index is now lower than readState.Index' (duration: 284.902956ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:45:37.645611Z","caller":"traceutil/trace.go:171","msg":"trace[1746610834] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"830.984137ms","start":"2026-03-26T09:45:36.814583Z","end":"2026-03-26T09:45:37.645567Z","steps":["trace[1746610834] 'process raft request' (duration: 830.769456ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:37.64568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:36.808886Z","time spent":"836.663006ms","remote":"127.0.0.1:41772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":192,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-26T09:45:37.645777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.179486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:45:37.645803Z","caller":"traceutil/trace.go:171","msg":"trace[414798568] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:313; }","duration":"897.233709ms","start":"2026-03-26T09:45:36.748563Z","end":"2026-03-26T09:45:37.645796Z","steps":["trace[414798568] 'agreement among raft nodes before linearized reading' (duration: 897.150834ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:37.645816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:36.814565Z","time spent":"831.144525ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7279,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:45:37.645876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"840.590077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"warn","ts":"2026-03-26T09:45:37.645925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.735323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7293"} {"level":"warn","ts":"2026-03-26T09:45:37.645946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.556233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-26T09:45:37.645946Z","caller":"traceutil/trace.go:171","msg":"trace[1475202259] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:313; }","duration":"840.703592ms","start":"2026-03-26T09:45:36.805224Z","end":"2026-03-26T09:45:37.645928Z","steps":["trace[1475202259] 'agreement among raft nodes before linearized reading' (duration: 840.546303ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:37.645956Z","caller":"traceutil/trace.go:171","msg":"trace[246254970] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:313; }","duration":"839.776175ms","start":"2026-03-26T09:45:36.806171Z","end":"2026-03-26T09:45:37.645947Z","steps":["trace[246254970] 'agreement among raft nodes before linearized reading' (duration: 839.703361ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:37.645929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.67601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5325"} {"level":"info","ts":"2026-03-26T09:45:37.645978Z","caller":"traceutil/trace.go:171","msg":"trace[1759733970] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:313; }","duration":"563.618657ms","start":"2026-03-26T09:45:37.082351Z","end":"2026-03-26T09:45:37.64597Z","steps":["trace[1759733970] 'agreement among raft nodes before linearized reading' (duration: 563.562954ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:37.645991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:36.806167Z","time spent":"839.816767ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7317,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-03-26T09:45:37.645994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:36.805209Z","time spent":"840.776466ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":2944,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-03-26T09:45:37.645997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"840.685851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4659"} {"level":"info","ts":"2026-03-26T09:45:37.646021Z","caller":"traceutil/trace.go:171","msg":"trace[442613511] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:313; }","duration":"839.791956ms","start":"2026-03-26T09:45:36.806205Z","end":"2026-03-26T09:45:37.645997Z","steps":["trace[442613511] 'agreement among raft nodes before linearized reading' (duration: 839.632148ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:37.646042Z","caller":"traceutil/trace.go:171","msg":"trace[493030241] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:313; }","duration":"840.756845ms","start":"2026-03-26T09:45:36.805272Z","end":"2026-03-26T09:45:37.646029Z","steps":["trace[493030241] 'agreement among raft nodes before linearized reading' (duration: 840.673211ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:37.646007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:37.082333Z","time spent":"563.666459ms","remote":"127.0.0.1:41830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-26T09:45:37.646069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:36.806181Z","time spent":"839.87454ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5349,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-03-26T09:45:37.64607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:36.805268Z","time spent":"840.795626ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":4683,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-03-26T09:45:37.646101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.132108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:45:37.646134Z","caller":"traceutil/trace.go:171","msg":"trace[1576088308] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:313; }","duration":"724.197541ms","start":"2026-03-26T09:45:36.921927Z","end":"2026-03-26T09:45:37.646125Z","steps":["trace[1576088308] 'agreement among raft nodes before linearized reading' (duration: 724.148999ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:37.646173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:36.92191Z","time spent":"724.256284ms","remote":"127.0.0.1:41830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-26T09:45:37.645885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.718682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6960"} {"level":"warn","ts":"2026-03-26T09:45:37.645828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:36.748553Z","time spent":"897.268911ms","remote":"127.0.0.1:41558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-26T09:45:37.646363Z","caller":"traceutil/trace.go:171","msg":"trace[1176689292] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:313; }","duration":"840.220487ms","start":"2026-03-26T09:45:36.806132Z","end":"2026-03-26T09:45:37.646353Z","steps":["trace[1176689292] 'agreement among raft nodes before linearized reading' (duration: 839.709121ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:37.646493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:36.806121Z","time spent":"840.348354ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6984,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"info","ts":"2026-03-26T09:45:38.065769Z","caller":"traceutil/trace.go:171","msg":"trace[316359403] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"406.36427ms","start":"2026-03-26T09:45:37.65938Z","end":"2026-03-26T09:45:38.065745Z","steps":["trace[316359403] 'process raft request' (duration: 328.487735ms)","trace[316359403] 'compare' (duration: 77.622473ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:45:38.065897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:37.659369Z","time spent":"406.466936ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4645,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:45:38.065993Z","caller":"traceutil/trace.go:171","msg":"trace[1188221688] linearizableReadLoop","detail":"{readStateIndex:334; appliedIndex:333; }","duration":"363.736261ms","start":"2026-03-26T09:45:37.70225Z","end":"2026-03-26T09:45:38.065987Z","steps":["trace[1188221688] 'read index received' (duration: 285.633304ms)","trace[1188221688] 'applied index is now lower than readState.Index' (duration: 78.101867ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:45:38.066123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.855807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/token-cleaner\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:45:38.066165Z","caller":"traceutil/trace.go:171","msg":"trace[738209301] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/token-cleaner; range_end:; response_count:0; response_revision:318; }","duration":"363.938762ms","start":"2026-03-26T09:45:37.702218Z","end":"2026-03-26T09:45:38.066157Z","steps":["trace[738209301] 'agreement among raft nodes before linearized reading' (duration: 363.834006ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:38.066187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:37.702203Z","time spent":"363.977854ms","remote":"127.0.0.1:41772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/kube-system/token-cleaner\" "} {"level":"info","ts":"2026-03-26T09:45:38.066259Z","caller":"traceutil/trace.go:171","msg":"trace[494243900] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"363.621034ms","start":"2026-03-26T09:45:37.70261Z","end":"2026-03-26T09:45:38.066231Z","steps":["trace[494243900] 'process raft request' (duration: 363.087077ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:38.066392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:37.702586Z","time spent":"363.741471ms","remote":"127.0.0.1:41916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":302,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-26T09:45:38.066503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.802155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-26T09:45:38.066583Z","caller":"traceutil/trace.go:171","msg":"trace[1939769994] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:319; }","duration":"137.942775ms","start":"2026-03-26T09:45:37.928623Z","end":"2026-03-26T09:45:38.066565Z","steps":["trace[1939769994] 'agreement among raft nodes before linearized reading' (duration: 137.796226ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:38.066604Z","caller":"traceutil/trace.go:171","msg":"trace[391845267] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"363.183093ms","start":"2026-03-26T09:45:37.703399Z","end":"2026-03-26T09:45:38.066582Z","steps":["trace[391845267] 'process raft request' (duration: 362.705958ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:38.066779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:37.703386Z","time spent":"363.346521ms","remote":"127.0.0.1:41650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":705,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-26T09:45:38.066668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.30131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-26T09:45:38.066974Z","caller":"traceutil/trace.go:171","msg":"trace[981875849] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:319; }","duration":"364.627796ms","start":"2026-03-26T09:45:37.702332Z","end":"2026-03-26T09:45:38.066959Z","steps":["trace[981875849] 'agreement among raft nodes before linearized reading' (duration: 364.045016ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:38.067573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:37.702311Z","time spent":"365.241158ms","remote":"127.0.0.1:41830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-26T09:45:38.066905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.945034ms","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-03-26T09:45:38.067906Z","caller":"traceutil/trace.go:171","msg":"trace[1910657109] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:319; }","duration":"139.969797ms","start":"2026-03-26T09:45:37.92791Z","end":"2026-03-26T09:45:38.06788Z","steps":["trace[1910657109] 'agreement among raft nodes before linearized reading' (duration: 138.944655ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:38.300806Z","caller":"traceutil/trace.go:171","msg":"trace[485742185] linearizableReadLoop","detail":"{readStateIndex:342; appliedIndex:341; }","duration":"163.655406ms","start":"2026-03-26T09:45:38.137129Z","end":"2026-03-26T09:45:38.300785Z","steps":["trace[485742185] 'read index received' (duration: 69.143922ms)","trace[485742185] 'applied index is now lower than readState.Index' (duration: 94.510564ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:45:38.300947Z","caller":"traceutil/trace.go:171","msg":"trace[1325135350] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"172.042591ms","start":"2026-03-26T09:45:38.128876Z","end":"2026-03-26T09:45:38.300919Z","steps":["trace[1325135350] 'process raft request' (duration: 77.464435ms)","trace[1325135350] 'compare' (duration: 94.351045ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:45:38.300961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.803403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:45:38.301029Z","caller":"traceutil/trace.go:171","msg":"trace[352254129] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-controller; range_end:; response_count:0; response_revision:325; }","duration":"163.914408ms","start":"2026-03-26T09:45:38.137098Z","end":"2026-03-26T09:45:38.301012Z","steps":["trace[352254129] 'agreement among raft nodes before linearized reading' (duration: 163.802302ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:38.700452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.221161ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T09:45:38.700531Z","caller":"traceutil/trace.go:171","msg":"trace[538866516] linearizableReadLoop","detail":"{readStateIndex:346; appliedIndex:345; }","duration":"181.279192ms","start":"2026-03-26T09:45:38.519237Z","end":"2026-03-26T09:45:38.700516Z","steps":["trace[538866516] 'read index received' (duration: 51.845781ms)","trace[538866516] 'applied index is now lower than readState.Index' (duration: 129.432131ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:45:38.700609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.368127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replication-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:45:38.700632Z","caller":"traceutil/trace.go:171","msg":"trace[970176517] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replication-controller; range_end:; response_count:0; response_revision:329; }","duration":"181.418189ms","start":"2026-03-26T09:45:38.519206Z","end":"2026-03-26T09:45:38.700624Z","steps":["trace[970176517] 'agreement among raft nodes before linearized reading' (duration: 181.372487ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:38.700773Z","caller":"traceutil/trace.go:171","msg":"trace[1350484243] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"247.524335ms","start":"2026-03-26T09:45:38.453224Z","end":"2026-03-26T09:45:38.700748Z","steps":["trace[1350484243] 'process raft request' (duration: 117.944856ms)","trace[1350484243] 'compare' (duration: 129.112796ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:45:39.026236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.013193ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T09:45:39.026338Z","caller":"traceutil/trace.go:171","msg":"trace[782551537] linearizableReadLoop","detail":"{readStateIndex:348; appliedIndex:347; }","duration":"298.316683ms","start":"2026-03-26T09:45:38.728006Z","end":"2026-03-26T09:45:39.026323Z","steps":["trace[782551537] 'read index received' (duration: 113.071159ms)","trace[782551537] 'applied index is now lower than readState.Index' (duration: 185.244464ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:45:39.026394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.383796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pod-garbage-collector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:45:39.026389Z","caller":"traceutil/trace.go:171","msg":"trace[318987003] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"317.001404ms","start":"2026-03-26T09:45:38.709354Z","end":"2026-03-26T09:45:39.026356Z","steps":["trace[318987003] 'process raft request' (duration: 131.791272ms)","trace[318987003] 'compare' (duration: 184.840303ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:45:39.026418Z","caller":"traceutil/trace.go:171","msg":"trace[466916342] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pod-garbage-collector; range_end:; response_count:0; response_revision:331; }","duration":"298.428889ms","start":"2026-03-26T09:45:38.727981Z","end":"2026-03-26T09:45:39.02641Z","steps":["trace[466916342] 'agreement among raft nodes before linearized reading' (duration: 298.383206ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:39.026531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:38.709335Z","time spent":"317.121011ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5311,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:45:40.44619Z","caller":"traceutil/trace.go:171","msg":"trace[1644922627] linearizableReadLoop","detail":"{readStateIndex:378; appliedIndex:377; }","duration":"151.51972ms","start":"2026-03-26T09:45:40.29465Z","end":"2026-03-26T09:45:40.44617Z","steps":["trace[1644922627] 'read index received' (duration: 151.416225ms)","trace[1644922627] 'applied index is now lower than readState.Index' (duration: 102.645µs)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:45:40.446313Z","caller":"traceutil/trace.go:171","msg":"trace[61091926] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"156.116556ms","start":"2026-03-26T09:45:40.290166Z","end":"2026-03-26T09:45:40.446283Z","steps":["trace[61091926] 'process raft request' (duration: 155.858972ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:40.446465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.669926ms","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-03-26T09:45:40.446521Z","caller":"traceutil/trace.go:171","msg":"trace[1468731861] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:361; }","duration":"116.75367ms","start":"2026-03-26T09:45:40.32975Z","end":"2026-03-26T09:45:40.446504Z","steps":["trace[1468731861] 'agreement among raft nodes before linearized reading' (duration: 116.671506ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:40.44646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.738313ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:45:40.446629Z","caller":"traceutil/trace.go:171","msg":"trace[1089586198] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:361; }","duration":"151.971914ms","start":"2026-03-26T09:45:40.294643Z","end":"2026-03-26T09:45:40.446615Z","steps":["trace[1089586198] 'agreement among raft nodes before linearized reading' (duration: 151.724292ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:40.446888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.213088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6960"} {"level":"info","ts":"2026-03-26T09:45:40.446953Z","caller":"traceutil/trace.go:171","msg":"trace[155317741] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:361; }","duration":"113.298074ms","start":"2026-03-26T09:45:40.333647Z","end":"2026-03-26T09:45:40.446945Z","steps":["trace[155317741] 'agreement among raft nodes before linearized reading' (duration: 113.207398ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:40.605141Z","caller":"traceutil/trace.go:171","msg":"trace[858497936] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"150.457566ms","start":"2026-03-26T09:45:40.45463Z","end":"2026-03-26T09:45:40.605087Z","steps":["trace[858497936] 'process raft request' (duration: 113.073461ms)","trace[858497936] 'compare' (duration: 37.178565ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:45:40.684886Z","caller":"traceutil/trace.go:171","msg":"trace[414696397] linearizableReadLoop","detail":"{readStateIndex:380; appliedIndex:378; }","duration":"205.069649ms","start":"2026-03-26T09:45:40.47979Z","end":"2026-03-26T09:45:40.68486Z","steps":["trace[414696397] 'read index received' (duration: 87.925331ms)","trace[414696397] 'applied index is now lower than readState.Index' (duration: 117.143168ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:45:40.684964Z","caller":"traceutil/trace.go:171","msg":"trace[2027189461] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"229.678033ms","start":"2026-03-26T09:45:40.455255Z","end":"2026-03-26T09:45:40.684933Z","steps":["trace[2027189461] 'process raft request' (duration: 229.444671ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:40.685213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.356386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7293"} {"level":"info","ts":"2026-03-26T09:45:40.685278Z","caller":"traceutil/trace.go:171","msg":"trace[1470142234] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:363; }","duration":"134.423779ms","start":"2026-03-26T09:45:40.550837Z","end":"2026-03-26T09:45:40.685261Z","steps":["trace[1470142234] 'agreement among raft nodes before linearized reading' (duration: 134.330745ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:40.685283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.554656ms","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-03-26T09:45:40.685103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.291201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-03-26T09:45:40.685383Z","caller":"traceutil/trace.go:171","msg":"trace[443277342] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:363; }","duration":"155.699453ms","start":"2026-03-26T09:45:40.529664Z","end":"2026-03-26T09:45:40.685363Z","steps":["trace[443277342] 'agreement among raft nodes before linearized reading' (duration: 155.503514ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:40.685452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.968058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5325"} {"level":"info","ts":"2026-03-26T09:45:40.6855Z","caller":"traceutil/trace.go:171","msg":"trace[1292408269] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:363; }","duration":"135.045062ms","start":"2026-03-26T09:45:40.550447Z","end":"2026-03-26T09:45:40.685492Z","steps":["trace[1292408269] 'agreement among raft nodes before linearized reading' (duration: 134.914845ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:40.685488Z","caller":"traceutil/trace.go:171","msg":"trace[1798217386] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:363; }","duration":"205.68058ms","start":"2026-03-26T09:45:40.479764Z","end":"2026-03-26T09:45:40.685445Z","steps":["trace[1798217386] 'agreement among raft nodes before linearized reading' (duration: 205.220698ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:40.685522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.330126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6960"} {"level":"warn","ts":"2026-03-26T09:45:40.685538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.411892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-03-26T09:45:40.685577Z","caller":"traceutil/trace.go:171","msg":"trace[786184085] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:363; }","duration":"135.413719ms","start":"2026-03-26T09:45:40.550147Z","end":"2026-03-26T09:45:40.685561Z","steps":["trace[786184085] 'agreement among raft nodes before linearized reading' (duration: 135.316804ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:40.685588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.541796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4659"} {"level":"warn","ts":"2026-03-26T09:45:40.685643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.075102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-03-26T09:45:40.685651Z","caller":"traceutil/trace.go:171","msg":"trace[916162426] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:363; }","duration":"135.62584ms","start":"2026-03-26T09:45:40.550016Z","end":"2026-03-26T09:45:40.685641Z","steps":["trace[916162426] 'agreement among raft nodes before linearized reading' (duration: 135.533826ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:40.685671Z","caller":"traceutil/trace.go:171","msg":"trace[339626940] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:363; }","duration":"135.113354ms","start":"2026-03-26T09:45:40.550551Z","end":"2026-03-26T09:45:40.685664Z","steps":["trace[339626940] 'agreement among raft nodes before linearized reading' (duration: 135.05024ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:40.685588Z","caller":"traceutil/trace.go:171","msg":"trace[418946782] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:363; }","duration":"106.492256ms","start":"2026-03-26T09:45:40.579082Z","end":"2026-03-26T09:45:40.685574Z","steps":["trace[418946782] 'agreement among raft nodes before linearized reading' (duration: 106.406452ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:40.685673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.136196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"info","ts":"2026-03-26T09:45:40.685706Z","caller":"traceutil/trace.go:171","msg":"trace[1350841707] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:363; }","duration":"135.178639ms","start":"2026-03-26T09:45:40.550517Z","end":"2026-03-26T09:45:40.685695Z","steps":["trace[1350841707] 'agreement among raft nodes before linearized reading' (duration: 135.108794ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:40.962964Z","caller":"traceutil/trace.go:171","msg":"trace[1741266131] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"269.732256ms","start":"2026-03-26T09:45:40.693204Z","end":"2026-03-26T09:45:40.962936Z","steps":["trace[1741266131] 'process raft request' (duration: 177.570369ms)","trace[1741266131] 'compare' (duration: 91.978827ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:45:40.987538Z","caller":"traceutil/trace.go:171","msg":"trace[1320558292] linearizableReadLoop","detail":"{readStateIndex:382; appliedIndex:380; }","duration":"257.05912ms","start":"2026-03-26T09:45:40.730457Z","end":"2026-03-26T09:45:40.987516Z","steps":["trace[1320558292] 'read index received' (duration: 140.447018ms)","trace[1320558292] 'applied index is now lower than readState.Index' (duration: 116.611102ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:45:40.988181Z","caller":"traceutil/trace.go:171","msg":"trace[390108883] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"293.787281ms","start":"2026-03-26T09:45:40.693853Z","end":"2026-03-26T09:45:40.98764Z","steps":["trace[390108883] 'process raft request' (duration: 293.552698ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:40.988566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.091102ms","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-03-26T09:45:40.988636Z","caller":"traceutil/trace.go:171","msg":"trace[406013871] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:365; }","duration":"258.202108ms","start":"2026-03-26T09:45:40.730419Z","end":"2026-03-26T09:45:40.988621Z","steps":["trace[406013871] 'agreement among raft nodes before linearized reading' (duration: 257.65986ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:41.016125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.787188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-03-26T09:45:41.01621Z","caller":"traceutil/trace.go:171","msg":"trace[657694023] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:366; }","duration":"135.880763ms","start":"2026-03-26T09:45:40.880313Z","end":"2026-03-26T09:45:41.016193Z","steps":["trace[657694023] 'agreement among raft nodes before linearized reading' (duration: 135.769467ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:41.016374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.880443ms","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-03-26T09:45:41.016428Z","caller":"traceutil/trace.go:171","msg":"trace[220079287] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:366; }","duration":"186.002798ms","start":"2026-03-26T09:45:40.830412Z","end":"2026-03-26T09:45:41.016415Z","steps":["trace[220079287] 'agreement among raft nodes before linearized reading' (duration: 185.857461ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:41.016692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.901341ms","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-03-26T09:45:41.016861Z","caller":"traceutil/trace.go:171","msg":"trace[1315366273] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:366; }","duration":"237.038358ms","start":"2026-03-26T09:45:40.779724Z","end":"2026-03-26T09:45:41.016763Z","steps":["trace[1315366273] 'agreement among raft nodes before linearized reading' (duration: 236.796156ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:41.407728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.948376ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T09:45:41.407972Z","caller":"traceutil/trace.go:171","msg":"trace[1007526522] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"382.532505ms","start":"2026-03-26T09:45:41.02542Z","end":"2026-03-26T09:45:41.407953Z","steps":["trace[1007526522] 'process raft request' (duration: 218.285875ms)","trace[1007526522] 'compare' (duration: 163.760775ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:45:41.408154Z","caller":"traceutil/trace.go:171","msg":"trace[497581517] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"382.427041ms","start":"2026-03-26T09:45:41.025715Z","end":"2026-03-26T09:45:41.408142Z","steps":["trace[497581517] 'process raft request' (duration: 382.258092ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:41.408201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:41.0257Z","time spent":"382.478575ms","remote":"127.0.0.1:41680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T09:45:41.408172Z","caller":"traceutil/trace.go:171","msg":"trace[1651053623] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"382.470973ms","start":"2026-03-26T09:45:41.025675Z","end":"2026-03-26T09:45:41.408146Z","steps":["trace[1651053623] 'process raft request' (duration: 382.266263ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:41.408215Z","caller":"traceutil/trace.go:171","msg":"trace[1776616974] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"362.299446ms","start":"2026-03-26T09:45:41.045906Z","end":"2026-03-26T09:45:41.408206Z","steps":["trace[1776616974] 'process raft request' (duration: 362.239512ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:41.40824Z","caller":"traceutil/trace.go:171","msg":"trace[1954452706] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"382.099355ms","start":"2026-03-26T09:45:41.026105Z","end":"2026-03-26T09:45:41.408205Z","steps":["trace[1954452706] 'process raft request' (duration: 381.952987ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:41.40832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:41.025669Z","time spent":"382.574239ms","remote":"127.0.0.1:41750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4354,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:45:41.408355Z","caller":"traceutil/trace.go:171","msg":"trace[1054791570] linearizableReadLoop","detail":"{readStateIndex:391; appliedIndex:384; }","duration":"379.704253ms","start":"2026-03-26T09:45:41.028642Z","end":"2026-03-26T09:45:41.408347Z","steps":["trace[1054791570] 'read index received' (duration: 14.441057ms)","trace[1054791570] 'applied index is now lower than readState.Index' (duration: 365.261846ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:45:41.408416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:41.0261Z","time spent":"382.227721ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5139,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:45:41.408471Z","caller":"traceutil/trace.go:171","msg":"trace[1436618094] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"382.839992ms","start":"2026-03-26T09:45:41.025606Z","end":"2026-03-26T09:45:41.408446Z","steps":["trace[1436618094] 'process raft request' (duration: 382.279783ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:41.408534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.866771ms","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-03-26T09:45:41.40854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.795458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"warn","ts":"2026-03-26T09:45:41.408563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:41.0256Z","time spent":"382.925057ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T09:45:41.408578Z","caller":"traceutil/trace.go:171","msg":"trace[1116193213] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:375; }","duration":"328.868251ms","start":"2026-03-26T09:45:41.079701Z","end":"2026-03-26T09:45:41.408569Z","steps":["trace[1116193213] 'agreement among raft nodes before linearized reading' (duration: 328.808908ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:41.408611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:41.079682Z","time spent":"328.923504ms","remote":"127.0.0.1:41772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":219,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"info","ts":"2026-03-26T09:45:41.408592Z","caller":"traceutil/trace.go:171","msg":"trace[46254427] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:375; }","duration":"379.942315ms","start":"2026-03-26T09:45:41.02862Z","end":"2026-03-26T09:45:41.408562Z","steps":["trace[46254427] 'agreement among raft nodes before linearized reading' (duration: 379.824049ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:41.408665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:41.028613Z","time spent":"380.044529ms","remote":"127.0.0.1:41772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":238,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"info","ts":"2026-03-26T09:45:41.408677Z","caller":"traceutil/trace.go:171","msg":"trace[1913284645] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"382.727765ms","start":"2026-03-26T09:45:41.025927Z","end":"2026-03-26T09:45:41.408655Z","steps":["trace[1913284645] 'process raft request' (duration: 382.066021ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:41.408767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:41.02592Z","time spent":"382.80727ms","remote":"127.0.0.1:42078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2150,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-26T09:45:41.408292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:41.045894Z","time spent":"362.377249ms","remote":"127.0.0.1:41772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":164,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-26T09:45:41.408878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:41.025404Z","time spent":"383.445922ms","remote":"127.0.0.1:41680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2162,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:45:41.622584Z","caller":"traceutil/trace.go:171","msg":"trace[2080335701] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"207.672775ms","start":"2026-03-26T09:45:41.414887Z","end":"2026-03-26T09:45:41.62256Z","steps":["trace[2080335701] 'process raft request' (duration: 207.436482ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:41.622629Z","caller":"traceutil/trace.go:171","msg":"trace[1885975424] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"200.433876ms","start":"2026-03-26T09:45:41.422188Z","end":"2026-03-26T09:45:41.622621Z","steps":["trace[1885975424] 'process raft request' (duration: 200.281779ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:41.622677Z","caller":"traceutil/trace.go:171","msg":"trace[1200525939] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"205.958897ms","start":"2026-03-26T09:45:41.416686Z","end":"2026-03-26T09:45:41.622645Z","steps":["trace[1200525939] 'process raft request' (duration: 205.737786ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:41.622836Z","caller":"traceutil/trace.go:171","msg":"trace[1712823607] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"208.18293ms","start":"2026-03-26T09:45:41.414643Z","end":"2026-03-26T09:45:41.622826Z","steps":["trace[1712823607] 'process raft request' (duration: 207.607001ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:41.622857Z","caller":"traceutil/trace.go:171","msg":"trace[794489327] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"207.844964ms","start":"2026-03-26T09:45:41.414996Z","end":"2026-03-26T09:45:41.622841Z","steps":["trace[794489327] 'process raft request' (duration: 207.38028ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:41.622873Z","caller":"traceutil/trace.go:171","msg":"trace[293534890] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"207.96632ms","start":"2026-03-26T09:45:41.414877Z","end":"2026-03-26T09:45:41.622843Z","steps":["trace[293534890] 'process raft request' (duration: 207.411832ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:41.622982Z","caller":"traceutil/trace.go:171","msg":"trace[1087289680] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"207.929328ms","start":"2026-03-26T09:45:41.415041Z","end":"2026-03-26T09:45:41.62297Z","steps":["trace[1087289680] 'process raft request' (duration: 207.357189ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:41.622575Z","caller":"traceutil/trace.go:171","msg":"trace[1836393805] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"193.970647ms","start":"2026-03-26T09:45:41.428581Z","end":"2026-03-26T09:45:41.622552Z","steps":["trace[1836393805] 'process raft request' (duration: 193.921774ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:41.623149Z","caller":"traceutil/trace.go:171","msg":"trace[950092131] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"209.098628ms","start":"2026-03-26T09:45:41.414037Z","end":"2026-03-26T09:45:41.623136Z","steps":["trace[950092131] 'process raft request' (duration: 130.969775ms)","trace[950092131] 'compare' (duration: 77.053807ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:45:42.322034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.485527ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-26T09:45:42.322131Z","caller":"traceutil/trace.go:171","msg":"trace[1159378213] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"694.200144ms","start":"2026-03-26T09:45:41.627915Z","end":"2026-03-26T09:45:42.322115Z","steps":["trace[1159378213] 'process raft request' (duration: 393.58301ms)","trace[1159378213] 'compare' (duration: 300.087187ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:45:42.32218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:41.627894Z","time spent":"694.265157ms","remote":"127.0.0.1:41650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T09:45:42.381624Z","caller":"traceutil/trace.go:171","msg":"trace[1711254770] linearizableReadLoop","detail":"{readStateIndex:410; appliedIndex:402; }","duration":"714.085359ms","start":"2026-03-26T09:45:41.667518Z","end":"2026-03-26T09:45:42.381603Z","steps":["trace[1711254770] 'read index received' (duration: 353.981131ms)","trace[1711254770] 'applied index is now lower than readState.Index' (duration: 360.101777ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:45:42.381655Z","caller":"traceutil/trace.go:171","msg":"trace[328504688] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"752.837213ms","start":"2026-03-26T09:45:41.628786Z","end":"2026-03-26T09:45:42.381623Z","steps":["trace[328504688] 'process raft request' (duration: 752.665934ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:42.381816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:41.628752Z","time spent":"752.9767ms","remote":"127.0.0.1:42072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3759,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:45:42.381933Z","caller":"traceutil/trace.go:171","msg":"trace[1421836378] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"750.158646ms","start":"2026-03-26T09:45:41.631741Z","end":"2026-03-26T09:45:42.3819Z","steps":["trace[1421836378] 'process raft request' (duration: 749.82382ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:42.382017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.319951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-26T09:45:42.382056Z","caller":"traceutil/trace.go:171","msg":"trace[272302500] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:393; }","duration":"209.394065ms","start":"2026-03-26T09:45:42.172651Z","end":"2026-03-26T09:45:42.382045Z","steps":["trace[272302500] 'agreement among raft nodes before linearized reading' (duration: 209.341542ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:42.382146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:41.631732Z","time spent":"750.266742ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:45:42.382151Z","caller":"traceutil/trace.go:171","msg":"trace[1784249090] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"753.954851ms","start":"2026-03-26T09:45:41.628134Z","end":"2026-03-26T09:45:42.382089Z","steps":["trace[1784249090] 'process raft request' (duration: 753.120717ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:42.382244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:41.628124Z","time spent":"754.070256ms","remote":"127.0.0.1:41680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1731,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T09:45:42.382309Z","caller":"traceutil/trace.go:171","msg":"trace[740322975] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"752.257114ms","start":"2026-03-26T09:45:41.63003Z","end":"2026-03-26T09:45:42.382287Z","steps":["trace[740322975] 'process raft request' (duration: 751.505216ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:42.382374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.332471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:45:42.382426Z","caller":"traceutil/trace.go:171","msg":"trace[760810929] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:393; }","duration":"137.437067ms","start":"2026-03-26T09:45:42.244979Z","end":"2026-03-26T09:45:42.382416Z","steps":["trace[760810929] 'agreement among raft nodes before linearized reading' (duration: 137.345722ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:42.382425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:41.63002Z","time spent":"752.341188ms","remote":"127.0.0.1:41650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-26T09:45:42.381963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.427895ms","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-03-26T09:45:42.382459Z","caller":"traceutil/trace.go:171","msg":"trace[1610714539] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"754.063866ms","start":"2026-03-26T09:45:41.628377Z","end":"2026-03-26T09:45:42.382441Z","steps":["trace[1610714539] 'process raft request' (duration: 753.001562ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:42.382511Z","caller":"traceutil/trace.go:171","msg":"trace[822571953] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:393; }","duration":"715.016335ms","start":"2026-03-26T09:45:41.667485Z","end":"2026-03-26T09:45:42.382502Z","steps":["trace[822571953] 'agreement among raft nodes before linearized reading' (duration: 714.429475ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:42.382536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:41.667472Z","time spent":"715.056467ms","remote":"127.0.0.1:41830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-26T09:45:42.382564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:41.628366Z","time spent":"754.16047ms","remote":"127.0.0.1:41650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":669,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-26T09:45:42.382619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.017272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-26T09:45:42.382641Z","caller":"traceutil/trace.go:171","msg":"trace[572839728] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"753.012012ms","start":"2026-03-26T09:45:41.629612Z","end":"2026-03-26T09:45:42.382624Z","steps":["trace[572839728] 'process raft request' (duration: 751.889916ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:42.382653Z","caller":"traceutil/trace.go:171","msg":"trace[1548814595] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:393; }","duration":"207.084246ms","start":"2026-03-26T09:45:42.17556Z","end":"2026-03-26T09:45:42.382644Z","steps":["trace[1548814595] 'agreement among raft nodes before linearized reading' (duration: 207.038324ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:42.382739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:41.6296Z","time spent":"753.091195ms","remote":"127.0.0.1:42070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2886,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:45:42.382762Z","caller":"traceutil/trace.go:171","msg":"trace[1761843539] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"754.298048ms","start":"2026-03-26T09:45:41.628449Z","end":"2026-03-26T09:45:42.382747Z","steps":["trace[1761843539] 'process raft request' (duration: 752.97103ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:42.38284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:41.628438Z","time spent":"754.367232ms","remote":"127.0.0.1:41650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":654,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-26T09:45:42.38258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.646435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-26T09:45:42.382996Z","caller":"traceutil/trace.go:171","msg":"trace[1393138556] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:393; }","duration":"207.085617ms","start":"2026-03-26T09:45:42.175904Z","end":"2026-03-26T09:45:42.382989Z","steps":["trace[1393138556] 'agreement among raft nodes before linearized reading' (duration: 206.646154ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:42.658463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.407737ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T09:45:42.658583Z","caller":"traceutil/trace.go:171","msg":"trace[516615393] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"269.997014ms","start":"2026-03-26T09:45:42.388566Z","end":"2026-03-26T09:45:42.658563Z","steps":["trace[516615393] 'process raft request' (duration: 78.409128ms)","trace[516615393] 'compare' (duration: 191.289571ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:45:42.692686Z","caller":"traceutil/trace.go:171","msg":"trace[283221179] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"303.680261ms","start":"2026-03-26T09:45:42.388981Z","end":"2026-03-26T09:45:42.692661Z","steps":["trace[283221179] 'process raft request' (duration: 303.44632ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:42.692884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:42.388957Z","time spent":"303.799287ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3424,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:45:42.692903Z","caller":"traceutil/trace.go:171","msg":"trace[175553009] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"303.599906ms","start":"2026-03-26T09:45:42.389283Z","end":"2026-03-26T09:45:42.692883Z","steps":["trace[175553009] 'process raft request' (duration: 303.328123ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:42.693008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:42.389241Z","time spent":"303.709643ms","remote":"127.0.0.1:41830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:45:42.693033Z","caller":"traceutil/trace.go:171","msg":"trace[83516016] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"303.220978ms","start":"2026-03-26T09:45:42.389808Z","end":"2026-03-26T09:45:42.693029Z","steps":["trace[83516016] 'process raft request' (duration: 303.095441ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:42.693022Z","caller":"traceutil/trace.go:171","msg":"trace[1819017209] linearizableReadLoop","detail":"{readStateIndex:417; appliedIndex:411; }","duration":"143.243053ms","start":"2026-03-26T09:45:42.549765Z","end":"2026-03-26T09:45:42.693008Z","steps":["trace[1819017209] 'read index received' (duration: 142.60467ms)","trace[1819017209] 'applied index is now lower than readState.Index' (duration: 637.552µs)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:45:42.693067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:42.389798Z","time spent":"303.248699ms","remote":"127.0.0.1:41830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:45:42.693099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.331497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:45:42.69313Z","caller":"traceutil/trace.go:171","msg":"trace[1374766687] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"303.443229ms","start":"2026-03-26T09:45:42.389682Z","end":"2026-03-26T09:45:42.693125Z","steps":["trace[1374766687] 'process raft request' (duration: 303.185436ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:42.692923Z","caller":"traceutil/trace.go:171","msg":"trace[1966302440] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"303.355735ms","start":"2026-03-26T09:45:42.389559Z","end":"2026-03-26T09:45:42.692915Z","steps":["trace[1966302440] 'process raft request' (duration: 303.214617ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:42.69316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:42.389673Z","time spent":"303.470481ms","remote":"127.0.0.1:41830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:45:42.693185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:42.389548Z","time spent":"303.609308ms","remote":"127.0.0.1:42072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:45:42.693269Z","caller":"traceutil/trace.go:171","msg":"trace[1707803938] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"299.74287ms","start":"2026-03-26T09:45:42.393517Z","end":"2026-03-26T09:45:42.69326Z","steps":["trace[1707803938] 'process raft request' (duration: 299.451506ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:42.693126Z","caller":"traceutil/trace.go:171","msg":"trace[1552436757] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:400; }","duration":"143.363619ms","start":"2026-03-26T09:45:42.549754Z","end":"2026-03-26T09:45:42.693117Z","steps":["trace[1552436757] 'agreement among raft nodes before linearized reading' (duration: 143.303206ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:43.033246Z","caller":"traceutil/trace.go:171","msg":"trace[901861848] linearizableReadLoop","detail":"{readStateIndex:420; appliedIndex:418; }","duration":"278.825616ms","start":"2026-03-26T09:45:42.754392Z","end":"2026-03-26T09:45:43.033218Z","steps":["trace[901861848] 'read index received' (duration: 211.184196ms)","trace[901861848] 'applied index is now lower than readState.Index' (duration: 67.63975ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:45:43.033382Z","caller":"traceutil/trace.go:171","msg":"trace[2033827607] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"329.695528ms","start":"2026-03-26T09:45:42.703676Z","end":"2026-03-26T09:45:43.033372Z","steps":["trace[2033827607] 'process raft request' (duration: 329.468377ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:45:43.033381Z","caller":"traceutil/trace.go:171","msg":"trace[1296100421] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"331.781625ms","start":"2026-03-26T09:45:42.701573Z","end":"2026-03-26T09:45:43.033355Z","steps":["trace[1296100421] 'process raft request' (duration: 263.968146ms)","trace[1296100421] 'compare' (duration: 67.46395ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:45:43.03351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.121961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/coredns-7c96b6546b-zsnn8.18a05b079b3f59ae\" ","response":"range_response_count:1 size:845"} {"level":"warn","ts":"2026-03-26T09:45:43.033523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:42.701561Z","time spent":"331.87352ms","remote":"127.0.0.1:42054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4041,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:45:43.033604Z","caller":"traceutil/trace.go:171","msg":"trace[1746007355] range","detail":"{range_begin:/registry/events/kube-system/coredns-7c96b6546b-zsnn8.18a05b079b3f59ae; range_end:; response_count:1; response_revision:403; }","duration":"279.220235ms","start":"2026-03-26T09:45:42.754324Z","end":"2026-03-26T09:45:43.033545Z","steps":["trace[1746007355] 'agreement among raft nodes before linearized reading' (duration: 279.132701ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:43.033485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:45:42.703665Z","time spent":"329.746431ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7087,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:45:44.928797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.765854ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T09:45:44.928948Z","caller":"traceutil/trace.go:171","msg":"trace[863885357] linearizableReadLoop","detail":"{readStateIndex:432; appliedIndex:431; }","duration":"231.421133ms","start":"2026-03-26T09:45:44.697508Z","end":"2026-03-26T09:45:44.928929Z","steps":["trace[863885357] 'read index received' (duration: 66.443089ms)","trace[863885357] 'applied index is now lower than readState.Index' (duration: 164.976454ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:45:44.929056Z","caller":"traceutil/trace.go:171","msg":"trace[136953566] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"262.742021ms","start":"2026-03-26T09:45:44.66627Z","end":"2026-03-26T09:45:44.929012Z","steps":["trace[136953566] 'process raft request' (duration: 97.669722ms)","trace[136953566] 'compare' (duration: 164.633438ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:45:44.929075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.556451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-26T09:45:44.929123Z","caller":"traceutil/trace.go:171","msg":"trace[1201739705] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:413; }","duration":"231.626094ms","start":"2026-03-26T09:45:44.697484Z","end":"2026-03-26T09:45:44.92911Z","steps":["trace[1201739705] 'agreement among raft nodes before linearized reading' (duration: 231.541709ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:44.929272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.688508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-26T09:45:44.929318Z","caller":"traceutil/trace.go:171","msg":"trace[962321662] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:413; }","duration":"231.74048ms","start":"2026-03-26T09:45:44.697567Z","end":"2026-03-26T09:45:44.929307Z","steps":["trace[962321662] 'agreement among raft nodes before linearized reading' (duration: 231.669556ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:45:44.929477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.809083ms","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-03-26T09:45:44.929529Z","caller":"traceutil/trace.go:171","msg":"trace[2110155935] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:413; }","duration":"230.860415ms","start":"2026-03-26T09:45:44.698657Z","end":"2026-03-26T09:45:44.929517Z","steps":["trace[2110155935] 'agreement among raft nodes before linearized reading' (duration: 230.784612ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:46:03.593974Z","caller":"traceutil/trace.go:171","msg":"trace[817857407] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"291.182888ms","start":"2026-03-26T09:46:03.302765Z","end":"2026-03-26T09:46:03.593948Z","steps":["trace[817857407] 'process raft request' (duration: 290.81938ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:46:03.786355Z","caller":"traceutil/trace.go:171","msg":"trace[1090900357] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"169.21082ms","start":"2026-03-26T09:46:03.617119Z","end":"2026-03-26T09:46:03.78633Z","steps":["trace[1090900357] 'process raft request' (duration: 169.166338ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:46:03.786591Z","caller":"traceutil/trace.go:171","msg":"trace[693403305] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"169.984449ms","start":"2026-03-26T09:46:03.616573Z","end":"2026-03-26T09:46:03.786557Z","steps":["trace[693403305] 'process raft request' (duration: 126.185155ms)","trace[693403305] 'compare' (duration: 43.32586ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:46:06.768482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.103794835s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T09:46:06.768916Z","caller":"traceutil/trace.go:171","msg":"trace[1518497766] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"1.253826035s","start":"2026-03-26T09:46:05.515058Z","end":"2026-03-26T09:46:06.768884Z","steps":["trace[1518497766] 'process raft request' (duration: 1.253674147s)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:46:06.76892Z","caller":"traceutil/trace.go:171","msg":"trace[1010744674] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"1.254011394s","start":"2026-03-26T09:46:05.514858Z","end":"2026-03-26T09:46:06.768869Z","steps":["trace[1010744674] 'process raft request' (duration: 149.742115ms)","trace[1010744674] 'compare' (duration: 1.103484069s)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:46:06.768992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:05.515051Z","time spent":"1.253901148s","remote":"127.0.0.1:41612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:46:06.769116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:05.514841Z","time spent":"1.254190353s","remote":"127.0.0.1:41612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7693,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:46:07.397106Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.610944861s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-26T09:46:07.548459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000563865s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-26T09:46:07.548552Z","caller":"traceutil/trace.go:171","msg":"trace[865998897] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000701012s","start":"2026-03-26T09:46:05.547831Z","end":"2026-03-26T09:46:07.548532Z","steps":["trace[865998897] 'agreement among raft nodes before linearized reading' (duration: 2.000590626s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:07.548601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:05.547815Z","time spent":"2.000776466s","remote":"127.0.0.1:41558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/26 09:46:07 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-26T09:46:08.731577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.33413481s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T09:46:08.732138Z","caller":"traceutil/trace.go:171","msg":"trace[2133712128] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"1.178028025s","start":"2026-03-26T09:46:07.554094Z","end":"2026-03-26T09:46:08.732122Z","steps":["trace[2133712128] 'process raft request' (duration: 1.177989053s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:08.732201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:07.554074Z","time spent":"1.178098379s","remote":"127.0.0.1:41650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T09:46:08.7323Z","caller":"traceutil/trace.go:171","msg":"trace[1973886661] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"2.422927874s","start":"2026-03-26T09:46:06.309344Z","end":"2026-03-26T09:46:08.732272Z","steps":["trace[1973886661] 'process raft request' (duration: 2.422695492s)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:46:08.732273Z","caller":"traceutil/trace.go:171","msg":"trace[161063284] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:579; }","duration":"3.184351676s","start":"2026-03-26T09:46:05.547878Z","end":"2026-03-26T09:46:08.73223Z","steps":["trace[161063284] 'read index received' (duration: 116.769296ms)","trace[161063284] 'applied index is now lower than readState.Index' (duration: 3.06757656s)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:46:08.732419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:06.309324Z","time spent":"2.42303424s","remote":"127.0.0.1:41830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:46:08.732374Z","caller":"traceutil/trace.go:171","msg":"trace[1045576095] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"3.209198499s","start":"2026-03-26T09:46:05.523133Z","end":"2026-03-26T09:46:08.732331Z","steps":["trace[1045576095] 'process raft request' (duration: 1.874229406s)","trace[1045576095] 'compare' (duration: 1.334015744s)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:46:08.732452Z","caller":"traceutil/trace.go:171","msg":"trace[1457210767] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"3.12628634s","start":"2026-03-26T09:46:05.60614Z","end":"2026-03-26T09:46:08.732426Z","steps":["trace[1457210767] 'process raft request' (duration: 3.125834256s)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:46:08.732494Z","caller":"traceutil/trace.go:171","msg":"trace[1700655256] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"3.209200259s","start":"2026-03-26T09:46:05.523273Z","end":"2026-03-26T09:46:08.732473Z","steps":["trace[1700655256] 'process raft request' (duration: 3.20842642s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:08.732574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:05.606111Z","time spent":"3.126382524s","remote":"127.0.0.1:41612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-26T09:46:08.732595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:05.523263Z","time spent":"3.209273222s","remote":"127.0.0.1:41612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:46:08.732651Z","caller":"traceutil/trace.go:171","msg":"trace[2015907507] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"3.20064078s","start":"2026-03-26T09:46:05.532001Z","end":"2026-03-26T09:46:08.732642Z","steps":["trace[2015907507] 'process raft request' (duration: 3.199817978s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:08.732633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:05.523116Z","time spent":"3.209370577s","remote":"127.0.0.1:41612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:46:08.732701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:05.531985Z","time spent":"3.200690912s","remote":"127.0.0.1:41612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:46:08.732921Z","caller":"traceutil/trace.go:171","msg":"trace[963481477] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"3.173875999s","start":"2026-03-26T09:46:05.558365Z","end":"2026-03-26T09:46:08.732241Z","steps":["trace[963481477] 'process raft request' (duration: 3.17352146s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:08.733622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:05.558347Z","time spent":"3.174811427s","remote":"127.0.0.1:41612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:46:08.943351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.565395ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-26T09:46:08.944009Z","caller":"traceutil/trace.go:171","msg":"trace[1454427812] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"154.528376ms","start":"2026-03-26T09:46:08.789468Z","end":"2026-03-26T09:46:08.943996Z","steps":["trace[1454427812] 'process raft request' (duration: 154.489304ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:46:08.94413Z","caller":"traceutil/trace.go:171","msg":"trace[642586399] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"195.184762ms","start":"2026-03-26T09:46:08.748923Z","end":"2026-03-26T09:46:08.944107Z","steps":["trace[642586399] 'process raft request' (duration: 194.923359ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:08.944257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.725243768s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-26T09:46:08.944304Z","caller":"traceutil/trace.go:171","msg":"trace[1116531373] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:564; }","duration":"1.725335044s","start":"2026-03-26T09:46:07.218957Z","end":"2026-03-26T09:46:08.944292Z","steps":["trace[1116531373] 'agreement among raft nodes before linearized reading' (duration: 1.725223197s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:08.944328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.776904567s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-03-26T09:46:08.94434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:07.218938Z","time spent":"1.725392977s","remote":"127.0.0.1:41830","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-03-26T09:46:08.944324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.719593358s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-26T09:46:08.944427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.389520573s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:46:08.944443Z","caller":"traceutil/trace.go:171","msg":"trace[1962984894] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:564; }","duration":"1.719766857s","start":"2026-03-26T09:46:07.224652Z","end":"2026-03-26T09:46:08.944419Z","steps":["trace[1962984894] 'agreement among raft nodes before linearized reading' (duration: 1.719536905s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:08.944517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:07.224632Z","time spent":"1.719870662s","remote":"127.0.0.1:41830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-26T09:46:08.944131Z","caller":"traceutil/trace.go:171","msg":"trace[492309770] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"202.150348ms","start":"2026-03-26T09:46:08.741946Z","end":"2026-03-26T09:46:08.944096Z","steps":["trace[492309770] 'process raft request' (duration: 201.847933ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:08.944605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.80523708s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-26T09:46:08.944368Z","caller":"traceutil/trace.go:171","msg":"trace[229909636] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:564; }","duration":"1.776977612s","start":"2026-03-26T09:46:07.167382Z","end":"2026-03-26T09:46:08.944359Z","steps":["trace[229909636] 'agreement among raft nodes before linearized reading' (duration: 1.776905799s)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:46:08.944668Z","caller":"traceutil/trace.go:171","msg":"trace[1984815808] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:564; }","duration":"1.805326125s","start":"2026-03-26T09:46:07.139333Z","end":"2026-03-26T09:46:08.944659Z","steps":["trace[1984815808] 'agreement among raft nodes before linearized reading' (duration: 1.805238341s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:08.944691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:07.167362Z","time spent":"1.777316178s","remote":"127.0.0.1:41830","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-03-26T09:46:08.944702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:07.139287Z","time spent":"1.805405629s","remote":"127.0.0.1:41830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-26T09:46:08.944458Z","caller":"traceutil/trace.go:171","msg":"trace[783187780] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:564; }","duration":"1.389567355s","start":"2026-03-26T09:46:07.554883Z","end":"2026-03-26T09:46:08.94445Z","steps":["trace[783187780] 'agreement among raft nodes before linearized reading' (duration: 1.389526133s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:08.944839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:07.554876Z","time spent":"1.389952795s","remote":"127.0.0.1:41542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-26T09:46:08.9452Z","caller":"traceutil/trace.go:171","msg":"trace[545906921] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:588; }","duration":"211.661886ms","start":"2026-03-26T09:46:08.732371Z","end":"2026-03-26T09:46:08.944033Z","steps":["trace[545906921] 'read index received' (duration: 80.542901ms)","trace[545906921] 'applied index is now lower than readState.Index' (duration: 131.113455ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:46:08.943886Z","caller":"traceutil/trace.go:171","msg":"trace[1596241341] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"712.661092ms","start":"2026-03-26T09:46:08.231197Z","end":"2026-03-26T09:46:08.943858Z","steps":["trace[1596241341] 'process raft request' (duration: 712.268292ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:08.945624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:08.231176Z","time spent":"714.353719ms","remote":"127.0.0.1:41830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:46:08.967803Z","caller":"traceutil/trace.go:171","msg":"trace[569114078] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"144.150173ms","start":"2026-03-26T09:46:08.823635Z","end":"2026-03-26T09:46:08.967786Z","steps":["trace[569114078] 'process raft request' (duration: 143.854159ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:46:09.15017Z","caller":"traceutil/trace.go:171","msg":"trace[1333299867] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"129.370947ms","start":"2026-03-26T09:46:09.020781Z","end":"2026-03-26T09:46:09.150152Z","steps":["trace[1333299867] 'process raft request' (duration: 73.828817ms)","trace[1333299867] 'compare' (duration: 55.404312ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:46:09.15186Z","caller":"traceutil/trace.go:171","msg":"trace[1216294689] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"105.084701ms","start":"2026-03-26T09:46:09.046743Z","end":"2026-03-26T09:46:09.151828Z","steps":["trace[1216294689] 'process raft request' (duration: 104.990927ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:46:09.151873Z","caller":"traceutil/trace.go:171","msg":"trace[669293216] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"111.736233ms","start":"2026-03-26T09:46:09.040118Z","end":"2026-03-26T09:46:09.151854Z","steps":["trace[669293216] 'process raft request' (duration: 111.447368ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:09.707241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.684675ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T09:46:09.707613Z","caller":"traceutil/trace.go:171","msg":"trace[1462784623] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"484.899956ms","start":"2026-03-26T09:46:09.22269Z","end":"2026-03-26T09:46:09.70759Z","steps":["trace[1462784623] 'process raft request' (duration: 191.775538ms)","trace[1462784623] 'compare' (duration: 292.543238ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:46:09.707924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:09.222668Z","time spent":"485.207771ms","remote":"127.0.0.1:41612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31121,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:46:09.756183Z","caller":"traceutil/trace.go:171","msg":"trace[2008871665] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"502.972262ms","start":"2026-03-26T09:46:09.253197Z","end":"2026-03-26T09:46:09.756169Z","steps":["trace[2008871665] 'process raft request' (duration: 502.647596ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:46:09.756122Z","caller":"traceutil/trace.go:171","msg":"trace[1525873238] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"459.46873ms","start":"2026-03-26T09:46:09.296611Z","end":"2026-03-26T09:46:09.75608Z","steps":["trace[1525873238] 'process raft request' (duration: 459.401027ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:09.756299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:09.253175Z","time spent":"503.063207ms","remote":"127.0.0.1:41612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31111,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:46:09.756314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:09.296584Z","time spent":"459.701032ms","remote":"127.0.0.1:41612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":73770,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T09:46:09.988125Z","caller":"traceutil/trace.go:171","msg":"trace[85632675] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"220.595457ms","start":"2026-03-26T09:46:09.767499Z","end":"2026-03-26T09:46:09.988094Z","steps":["trace[85632675] 'process raft request' (duration: 199.515816ms)","trace[85632675] 'compare' (duration: 20.790306ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:46:09.988354Z","caller":"traceutil/trace.go:171","msg":"trace[1508693864] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"204.652289ms","start":"2026-03-26T09:46:09.783671Z","end":"2026-03-26T09:46:09.988323Z","steps":["trace[1508693864] 'process raft request' (duration: 204.358544ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:46:10.033022Z","caller":"traceutil/trace.go:171","msg":"trace[474294725] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"214.855362ms","start":"2026-03-26T09:46:09.818135Z","end":"2026-03-26T09:46:10.03299Z","steps":["trace[474294725] 'process raft request' (duration: 214.64338ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:46:10.182236Z","caller":"traceutil/trace.go:171","msg":"trace[1358148735] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"128.968507ms","start":"2026-03-26T09:46:10.053244Z","end":"2026-03-26T09:46:10.182213Z","steps":["trace[1358148735] 'process raft request' (duration: 81.301032ms)","trace[1358148735] 'compare' (duration: 47.509347ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:46:10.184065Z","caller":"traceutil/trace.go:171","msg":"trace[1286140379] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"105.404667ms","start":"2026-03-26T09:46:10.078629Z","end":"2026-03-26T09:46:10.184033Z","steps":["trace[1286140379] 'process raft request' (duration: 105.25342ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:46:10.696981Z","caller":"traceutil/trace.go:171","msg":"trace[1913225444] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"408.589465ms","start":"2026-03-26T09:46:10.288372Z","end":"2026-03-26T09:46:10.696961Z","steps":["trace[1913225444] 'process raft request' (duration: 408.165493ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:10.697139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:10.287702Z","time spent":"409.337343ms","remote":"127.0.0.1:41612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T09:46:10.753451Z","caller":"traceutil/trace.go:171","msg":"trace[1823275985] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"463.226878ms","start":"2026-03-26T09:46:10.290208Z","end":"2026-03-26T09:46:10.753434Z","steps":["trace[1823275985] 'process raft request' (duration: 463.020797ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:10.75355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:10.290197Z","time spent":"463.303992ms","remote":"127.0.0.1:41612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151212,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:46:10.753625Z","caller":"traceutil/trace.go:171","msg":"trace[1037174319] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:618; }","duration":"439.264189ms","start":"2026-03-26T09:46:10.314355Z","end":"2026-03-26T09:46:10.753619Z","steps":["trace[1037174319] 'read index received' (duration: 381.749719ms)","trace[1037174319] 'applied index is now lower than readState.Index' (duration: 57.51394ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:46:10.753662Z","caller":"traceutil/trace.go:171","msg":"trace[606204738] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"405.942829ms","start":"2026-03-26T09:46:10.347715Z","end":"2026-03-26T09:46:10.753658Z","steps":["trace[606204738] 'process raft request' (duration: 405.684246ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:10.75369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:10.347702Z","time spent":"405.97248ms","remote":"127.0.0.1:41612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10855,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:46:10.753738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.08708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:46:10.75376Z","caller":"traceutil/trace.go:171","msg":"trace[1265508412] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:592; }","duration":"134.145673ms","start":"2026-03-26T09:46:10.619608Z","end":"2026-03-26T09:46:10.753754Z","steps":["trace[1265508412] 'agreement among raft nodes before linearized reading' (duration: 134.115892ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:10.753767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.414396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-26T09:46:10.753766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.545793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:46:10.753817Z","caller":"traceutil/trace.go:171","msg":"trace[931427491] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:592; }","duration":"439.48298ms","start":"2026-03-26T09:46:10.314327Z","end":"2026-03-26T09:46:10.75381Z","steps":["trace[931427491] 'agreement among raft nodes before linearized reading' (duration: 439.424397ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:10.753814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.289573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:46:10.753833Z","caller":"traceutil/trace.go:171","msg":"trace[658399249] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:592; }","duration":"134.667089ms","start":"2026-03-26T09:46:10.619152Z","end":"2026-03-26T09:46:10.753819Z","steps":["trace[658399249] 'agreement among raft nodes before linearized reading' (duration: 134.544833ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:46:10.753854Z","caller":"traceutil/trace.go:171","msg":"trace[371079296] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"205.354136ms","start":"2026-03-26T09:46:10.548485Z","end":"2026-03-26T09:46:10.753839Z","steps":["trace[371079296] 'agreement among raft nodes before linearized reading' (duration: 205.278863ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:10.753854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:10.314318Z","time spent":"439.515572ms","remote":"127.0.0.1:55726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 "} {"level":"warn","ts":"2026-03-26T09:46:10.753901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.381847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:46:10.753919Z","caller":"traceutil/trace.go:171","msg":"trace[1995146135] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:592; }","duration":"113.43087ms","start":"2026-03-26T09:46:10.640484Z","end":"2026-03-26T09:46:10.753915Z","steps":["trace[1995146135] 'agreement among raft nodes before linearized reading' (duration: 113.403989ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:10.753897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.437988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:46:10.75396Z","caller":"traceutil/trace.go:171","msg":"trace[1476013740] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:592; }","duration":"134.573485ms","start":"2026-03-26T09:46:10.619374Z","end":"2026-03-26T09:46:10.753947Z","steps":["trace[1476013740] 'agreement among raft nodes before linearized reading' (duration: 134.405656ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:10.754031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.808149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-26T09:46:10.75405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.516205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:46:10.754055Z","caller":"traceutil/trace.go:171","msg":"trace[412382833] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:592; }","duration":"112.83544ms","start":"2026-03-26T09:46:10.641214Z","end":"2026-03-26T09:46:10.754049Z","steps":["trace[412382833] 'agreement among raft nodes before linearized reading' (duration: 112.798728ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:10.754053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.196733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:46:10.754065Z","caller":"traceutil/trace.go:171","msg":"trace[81185639] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:592; }","duration":"411.556176ms","start":"2026-03-26T09:46:10.342505Z","end":"2026-03-26T09:46:10.754061Z","steps":["trace[81185639] 'agreement among raft nodes before linearized reading' (duration: 411.532635ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:46:10.75408Z","caller":"traceutil/trace.go:171","msg":"trace[593797155] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:592; }","duration":"170.234075ms","start":"2026-03-26T09:46:10.583836Z","end":"2026-03-26T09:46:10.75407Z","steps":["trace[593797155] 'agreement among raft nodes before linearized reading' (duration: 170.192843ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:10.754103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.172148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:46:10.754118Z","caller":"traceutil/trace.go:171","msg":"trace[1044075832] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:592; }","duration":"411.20452ms","start":"2026-03-26T09:46:10.34291Z","end":"2026-03-26T09:46:10.754115Z","steps":["trace[1044075832] 'agreement among raft nodes before linearized reading' (duration: 411.183519ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:10.75416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:10.3429Z","time spent":"411.255022ms","remote":"127.0.0.1:55742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 "} {"level":"warn","ts":"2026-03-26T09:46:10.753736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.161527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-26T09:46:10.754188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.640055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:46:10.754195Z","caller":"traceutil/trace.go:171","msg":"trace[1676766816] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:592; }","duration":"113.653923ms","start":"2026-03-26T09:46:10.640533Z","end":"2026-03-26T09:46:10.754187Z","steps":["trace[1676766816] 'agreement among raft nodes before linearized reading' (duration: 113.158767ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:46:10.754216Z","caller":"traceutil/trace.go:171","msg":"trace[1176296929] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:592; }","duration":"170.691218ms","start":"2026-03-26T09:46:10.583518Z","end":"2026-03-26T09:46:10.754209Z","steps":["trace[1176296929] 'agreement among raft nodes before linearized reading' (duration: 170.649486ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:10.754086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:10.342488Z","time spent":"411.593599ms","remote":"127.0.0.1:55742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 "} {"level":"warn","ts":"2026-03-26T09:46:10.754291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.858253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:46:10.754423Z","caller":"traceutil/trace.go:171","msg":"trace[1963770212] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:592; }","duration":"412.021241ms","start":"2026-03-26T09:46:10.342395Z","end":"2026-03-26T09:46:10.754417Z","steps":["trace[1963770212] 'agreement among raft nodes before linearized reading' (duration: 411.859593ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:10.754464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:10.342388Z","time spent":"412.067424ms","remote":"127.0.0.1:55742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-03-26T09:46:10.754581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.28664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:46:10.754646Z","caller":"traceutil/trace.go:171","msg":"trace[1638845836] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:592; }","duration":"440.169334ms","start":"2026-03-26T09:46:10.314433Z","end":"2026-03-26T09:46:10.754603Z","steps":["trace[1638845836] 'agreement among raft nodes before linearized reading' (duration: 439.261748ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:10.754676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:10.314417Z","time spent":"440.251489ms","remote":"127.0.0.1:55726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-03-26T09:46:11.028607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.80415ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T09:46:11.028752Z","caller":"traceutil/trace.go:171","msg":"trace[1984820538] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"257.410008ms","start":"2026-03-26T09:46:10.771317Z","end":"2026-03-26T09:46:11.028727Z","steps":["trace[1984820538] 'process raft request' (duration: 112.396968ms)","trace[1984820538] 'compare' (duration: 144.645461ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:46:11.390046Z","caller":"traceutil/trace.go:171","msg":"trace[1290189875] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"614.530796ms","start":"2026-03-26T09:46:10.775494Z","end":"2026-03-26T09:46:11.390025Z","steps":["trace[1290189875] 'process raft request' (duration: 533.215023ms)","trace[1290189875] 'compare' (duration: 80.935323ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:46:11.3903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:10.775267Z","time spent":"614.970888ms","remote":"127.0.0.1:41612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80688,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:46:11.391047Z","caller":"traceutil/trace.go:171","msg":"trace[258207776] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"590.795428ms","start":"2026-03-26T09:46:10.800236Z","end":"2026-03-26T09:46:11.391031Z","steps":["trace[258207776] 'process raft request' (duration: 590.568757ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:46:11.391062Z","caller":"traceutil/trace.go:171","msg":"trace[1984895746] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"337.639487ms","start":"2026-03-26T09:46:11.053401Z","end":"2026-03-26T09:46:11.39104Z","steps":["trace[1984895746] 'process raft request' (duration: 337.535382ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:11.391099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:10.80021Z","time spent":"590.862321ms","remote":"127.0.0.1:41612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14661,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T09:46:11.39107Z","caller":"traceutil/trace.go:171","msg":"trace[1678474199] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:622; }","duration":"358.73044ms","start":"2026-03-26T09:46:11.032306Z","end":"2026-03-26T09:46:11.391037Z","steps":["trace[1678474199] 'read index received' (duration: 276.413366ms)","trace[1678474199] 'applied index is now lower than readState.Index' (duration: 82.314584ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:46:11.39117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:11.053376Z","time spent":"337.734712ms","remote":"127.0.0.1:41612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":98689,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:46:11.3912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.890909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-26T09:46:11.391228Z","caller":"traceutil/trace.go:171","msg":"trace[19219240] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:596; }","duration":"358.960992ms","start":"2026-03-26T09:46:11.03226Z","end":"2026-03-26T09:46:11.391221Z","steps":["trace[19219240] 'agreement among raft nodes before linearized reading' (duration: 358.856486ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:11.391246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:11.032242Z","time spent":"358.999874ms","remote":"127.0.0.1:41830","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-03-26T09:46:11.39133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.582882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-26T09:46:11.391356Z","caller":"traceutil/trace.go:171","msg":"trace[419399132] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:596; }","duration":"358.646657ms","start":"2026-03-26T09:46:11.032702Z","end":"2026-03-26T09:46:11.391349Z","steps":["trace[419399132] 'agreement among raft nodes before linearized reading' (duration: 358.568633ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:11.391376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:11.032686Z","time spent":"358.685299ms","remote":"127.0.0.1:41830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-26T09:46:11.391423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.017735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-03-26T09:46:11.391418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.101498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-26T09:46:11.391474Z","caller":"traceutil/trace.go:171","msg":"trace[1154151417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:596; }","duration":"359.102819ms","start":"2026-03-26T09:46:11.03236Z","end":"2026-03-26T09:46:11.391462Z","steps":["trace[1154151417] 'agreement among raft nodes before linearized reading' (duration: 358.92118ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:46:11.391492Z","caller":"traceutil/trace.go:171","msg":"trace[340112030] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:596; }","duration":"358.210874ms","start":"2026-03-26T09:46:11.033263Z","end":"2026-03-26T09:46:11.391474Z","steps":["trace[340112030] 'agreement among raft nodes before linearized reading' (duration: 357.979392ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:11.391511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:11.032349Z","time spent":"359.153512ms","remote":"127.0.0.1:41830","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-03-26T09:46:11.391536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:11.033253Z","time spent":"358.273917ms","remote":"127.0.0.1:41830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-26T09:46:11.541523Z","caller":"traceutil/trace.go:171","msg":"trace[166535209] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"144.507086ms","start":"2026-03-26T09:46:11.396985Z","end":"2026-03-26T09:46:11.541492Z","steps":["trace[166535209] 'process raft request' (duration: 97.885213ms)","trace[166535209] 'compare' (duration: 46.264935ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:46:11.543068Z","caller":"traceutil/trace.go:171","msg":"trace[1012017922] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"145.971171ms","start":"2026-03-26T09:46:11.397036Z","end":"2026-03-26T09:46:11.543007Z","steps":["trace[1012017922] 'process raft request' (duration: 144.218862ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:46:11.543516Z","caller":"traceutil/trace.go:171","msg":"trace[1673915528] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"146.414594ms","start":"2026-03-26T09:46:11.397053Z","end":"2026-03-26T09:46:11.543467Z","steps":["trace[1673915528] 'process raft request' (duration: 144.251873ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:46:11.547598Z","caller":"traceutil/trace.go:171","msg":"trace[1523788284] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"132.175474ms","start":"2026-03-26T09:46:11.415254Z","end":"2026-03-26T09:46:11.547429Z","steps":["trace[1523788284] 'process raft request' (duration: 127.752136ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:13.654608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.142285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:46:13.654681Z","caller":"traceutil/trace.go:171","msg":"trace[2065735063] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"103.281543ms","start":"2026-03-26T09:46:13.551385Z","end":"2026-03-26T09:46:13.654666Z","steps":["trace[2065735063] 'range keys from in-memory index tree' (duration: 103.011479ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:13.654857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.122775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-26T09:46:13.654963Z","caller":"traceutil/trace.go:171","msg":"trace[1686257822] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:618; }","duration":"103.260702ms","start":"2026-03-26T09:46:13.551673Z","end":"2026-03-26T09:46:13.654934Z","steps":["trace[1686257822] 'range keys from in-memory index tree' (duration: 102.829768ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:13.654887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.638671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-26T09:46:13.655167Z","caller":"traceutil/trace.go:171","msg":"trace[869299308] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:618; }","duration":"103.966758ms","start":"2026-03-26T09:46:13.551184Z","end":"2026-03-26T09:46:13.655151Z","steps":["trace[869299308] 'range keys from in-memory index tree' (duration: 103.455162ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:46:15.584775Z","caller":"traceutil/trace.go:171","msg":"trace[1448906011] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"114.30888ms","start":"2026-03-26T09:46:15.470435Z","end":"2026-03-26T09:46:15.584744Z","steps":["trace[1448906011] 'process raft request' (duration: 114.108751ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:15.816375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.465211ms","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-03-26T09:46:15.816461Z","caller":"traceutil/trace.go:171","msg":"trace[813248652] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:622; }","duration":"109.573767ms","start":"2026-03-26T09:46:15.706865Z","end":"2026-03-26T09:46:15.816439Z","steps":["trace[813248652] 'range keys from in-memory index tree' (duration: 109.340796ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:15.816491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.437614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-26T09:46:15.816492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.639911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-26T09:46:15.816548Z","caller":"traceutil/trace.go:171","msg":"trace[462398064] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:622; }","duration":"112.498737ms","start":"2026-03-26T09:46:15.704034Z","end":"2026-03-26T09:46:15.816533Z","steps":["trace[462398064] 'range keys from in-memory index tree' (duration: 112.34722ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:46:15.816554Z","caller":"traceutil/trace.go:171","msg":"trace[1024946219] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:622; }","duration":"109.719554ms","start":"2026-03-26T09:46:15.70682Z","end":"2026-03-26T09:46:15.816539Z","steps":["trace[1024946219] 'range keys from in-memory index tree' (duration: 109.476333ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:46:15.994331Z","caller":"traceutil/trace.go:171","msg":"trace[1651930377] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"174.128303ms","start":"2026-03-26T09:46:15.820175Z","end":"2026-03-26T09:46:15.994303Z","steps":["trace[1651930377] 'process raft request' (duration: 173.943854ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:46:16.116346Z","caller":"traceutil/trace.go:171","msg":"trace[1755052563] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:653; }","duration":"294.722149ms","start":"2026-03-26T09:46:15.821578Z","end":"2026-03-26T09:46:16.1163Z","steps":["trace[1755052563] 'read index received' (duration: 172.714311ms)","trace[1755052563] 'applied index is now lower than readState.Index' (duration: 122.006678ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:46:16.116445Z","caller":"traceutil/trace.go:171","msg":"trace[1722693800] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"294.820454ms","start":"2026-03-26T09:46:15.821594Z","end":"2026-03-26T09:46:16.116415Z","steps":["trace[1722693800] 'process raft request' (duration: 294.661206ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:16.116505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.907248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/envoy-gateway-system/envoy-gateway-config\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:46:16.116694Z","caller":"traceutil/trace.go:171","msg":"trace[284424755] range","detail":"{range_begin:/registry/configmaps/envoy-gateway-system/envoy-gateway-config; range_end:; response_count:0; response_revision:625; }","duration":"295.123098ms","start":"2026-03-26T09:46:15.821548Z","end":"2026-03-26T09:46:16.116671Z","steps":["trace[284424755] 'agreement among raft nodes before linearized reading' (duration: 294.874026ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:46:16.116812Z","caller":"traceutil/trace.go:171","msg":"trace[598916263] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"296.136372ms","start":"2026-03-26T09:46:15.820238Z","end":"2026-03-26T09:46:16.116374Z","steps":["trace[598916263] 'process raft request' (duration: 239.130052ms)","trace[598916263] 'compare' (duration: 56.741035ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:46:16.321381Z","caller":"traceutil/trace.go:171","msg":"trace[210269381] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"156.758752ms","start":"2026-03-26T09:46:16.164595Z","end":"2026-03-26T09:46:16.321354Z","steps":["trace[210269381] 'process raft request' (duration: 116.82323ms)","trace[210269381] 'compare' (duration: 39.816706ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:46:16.415763Z","caller":"traceutil/trace.go:171","msg":"trace[1310929058] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"251.11495ms","start":"2026-03-26T09:46:16.164624Z","end":"2026-03-26T09:46:16.415739Z","steps":["trace[1310929058] 'process raft request' (duration: 250.987823ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:46:16.66842Z","caller":"traceutil/trace.go:171","msg":"trace[240294618] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:658; }","duration":"112.30778ms","start":"2026-03-26T09:46:16.556094Z","end":"2026-03-26T09:46:16.668402Z","steps":["trace[240294618] 'read index received' (duration: 111.834076ms)","trace[240294618] 'applied index is now lower than readState.Index' (duration: 472.834µs)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:46:16.668469Z","caller":"traceutil/trace.go:171","msg":"trace[515039903] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"122.908174ms","start":"2026-03-26T09:46:16.545522Z","end":"2026-03-26T09:46:16.66843Z","steps":["trace[515039903] 'process raft request' (duration: 122.6331ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:16.669147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.033117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:46:16.669261Z","caller":"traceutil/trace.go:171","msg":"trace[576298406] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:629; }","duration":"113.167913ms","start":"2026-03-26T09:46:16.556064Z","end":"2026-03-26T09:46:16.669232Z","steps":["trace[576298406] 'agreement among raft nodes before linearized reading' (duration: 112.416365ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:46:19.846066Z","caller":"traceutil/trace.go:171","msg":"trace[1868409782] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"737.430368ms","start":"2026-03-26T09:46:19.108612Z","end":"2026-03-26T09:46:19.846043Z","steps":["trace[1868409782] 'process raft request' (duration: 735.759831ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:19.846184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:19.108575Z","time spent":"737.550128ms","remote":"127.0.0.1:41674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:46:19.853702Z","caller":"traceutil/trace.go:171","msg":"trace[158016257] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"684.396917ms","start":"2026-03-26T09:46:19.169266Z","end":"2026-03-26T09:46:19.853662Z","steps":["trace[158016257] 'process raft request' (duration: 684.215119ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:46:19.853715Z","caller":"traceutil/trace.go:171","msg":"trace[1358893309] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:676; }","duration":"257.665123ms","start":"2026-03-26T09:46:19.595975Z","end":"2026-03-26T09:46:19.85364Z","steps":["trace[1358893309] 'read index received' (duration: 243.970426ms)","trace[1358893309] 'applied index is now lower than readState.Index' (duration: 13.693267ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:46:19.853883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.884951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-26T09:46:19.8539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:19.169241Z","time spent":"684.541966ms","remote":"127.0.0.1:41830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:46:19.853949Z","caller":"traceutil/trace.go:171","msg":"trace[971130940] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:647; }","duration":"257.999861ms","start":"2026-03-26T09:46:19.59593Z","end":"2026-03-26T09:46:19.85393Z","steps":["trace[971130940] 'agreement among raft nodes before linearized reading' (duration: 257.799113ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:19.853945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.093646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-kc6gn\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-03-26T09:46:19.853989Z","caller":"traceutil/trace.go:171","msg":"trace[667447976] range","detail":"{range_begin:/registry/pods/kube-system/cilium-kc6gn; range_end:; response_count:1; response_revision:647; }","duration":"160.196135ms","start":"2026-03-26T09:46:19.693779Z","end":"2026-03-26T09:46:19.853975Z","steps":["trace[667447976] 'agreement among raft nodes before linearized reading' (duration: 160.054287ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:21.050183Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154583469275044719,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-26T09:46:21.527425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.518481345s","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-03-26T09:46:21.527504Z","caller":"traceutil/trace.go:171","msg":"trace[19059787] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:650; }","duration":"1.518569835s","start":"2026-03-26T09:46:20.008916Z","end":"2026-03-26T09:46:21.527486Z","steps":["trace[19059787] 'range keys from in-memory index tree' (duration: 1.518331026s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:21.527544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:20.008875Z","time spent":"1.518659154s","remote":"127.0.0.1:41830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-26T09:46:21.527684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.37962021s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-26T09:46:21.527772Z","caller":"traceutil/trace.go:171","msg":"trace[1027317332] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:650; }","duration":"1.37975687s","start":"2026-03-26T09:46:20.147985Z","end":"2026-03-26T09:46:21.527742Z","steps":["trace[1027317332] 'range keys from in-memory index tree' (duration: 1.379411821s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:21.527814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:20.14797Z","time spent":"1.379832479s","remote":"127.0.0.1:41830","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-03-26T09:46:21.528125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.380022487s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-26T09:46:21.528268Z","caller":"traceutil/trace.go:171","msg":"trace[1194974039] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:650; }","duration":"1.380237746s","start":"2026-03-26T09:46:20.148011Z","end":"2026-03-26T09:46:21.528249Z","steps":["trace[1194974039] 'range keys from in-memory index tree' (duration: 1.379827649s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:21.528444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:20.147991Z","time spent":"1.380434795s","remote":"127.0.0.1:41830","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-03-26T09:46:21.551175Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154583469275044719,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-26T09:46:22.051874Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154583469275044719,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-26T09:46:22.108257Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.78663027s","expected-duration":"1s"} {"level":"info","ts":"2026-03-26T09:46:22.109228Z","caller":"traceutil/trace.go:171","msg":"trace[1887387141] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"1.788811084s","start":"2026-03-26T09:46:20.320398Z","end":"2026-03-26T09:46:22.109209Z","steps":["trace[1887387141] 'process raft request' (duration: 1.788279038s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:22.10941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:20.320368Z","time spent":"1.788917313s","remote":"127.0.0.1:41674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:46:22.109609Z","caller":"traceutil/trace.go:171","msg":"trace[1435343419] linearizableReadLoop","detail":"{readStateIndex:685; appliedIndex:681; }","duration":"1.559888516s","start":"2026-03-26T09:46:20.54969Z","end":"2026-03-26T09:46:22.109579Z","steps":["trace[1435343419] 'read index received' (duration: 1.558758215s)","trace[1435343419] 'applied index is now lower than readState.Index' (duration: 1.129501ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:46:22.109749Z","caller":"traceutil/trace.go:171","msg":"trace[30575087] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"578.06116ms","start":"2026-03-26T09:46:21.531673Z","end":"2026-03-26T09:46:22.109734Z","steps":["trace[30575087] 'process raft request' (duration: 577.493674ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:46:22.109754Z","caller":"traceutil/trace.go:171","msg":"trace[486743034] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"577.183536ms","start":"2026-03-26T09:46:21.53255Z","end":"2026-03-26T09:46:22.109733Z","steps":["trace[486743034] 'process raft request' (duration: 576.888397ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:22.109778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.560068356s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-26T09:46:22.109873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:21.53165Z","time spent":"578.129999ms","remote":"127.0.0.1:41830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:46:22.109923Z","caller":"traceutil/trace.go:171","msg":"trace[524294397] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:654; }","duration":"1.560192045s","start":"2026-03-26T09:46:20.549631Z","end":"2026-03-26T09:46:22.109823Z","steps":["trace[524294397] 'agreement among raft nodes before linearized reading' (duration: 1.560077716s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:22.109965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:20.549584Z","time spent":"1.560373143s","remote":"127.0.0.1:41542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-26T09:46:22.109994Z","caller":"traceutil/trace.go:171","msg":"trace[1046527022] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"577.017477ms","start":"2026-03-26T09:46:21.532951Z","end":"2026-03-26T09:46:22.109969Z","steps":["trace[1046527022] 'process raft request' (duration: 576.582839ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:22.110228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:21.532939Z","time spent":"577.217925ms","remote":"127.0.0.1:41830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:46:22.110218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:21.532513Z","time spent":"577.308735ms","remote":"127.0.0.1:41830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:46:22.111116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.223176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-26T09:46:22.111176Z","caller":"traceutil/trace.go:171","msg":"trace[2117579219] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:654; }","duration":"229.332075ms","start":"2026-03-26T09:46:21.88183Z","end":"2026-03-26T09:46:22.111162Z","steps":["trace[2117579219] 'agreement among raft nodes before linearized reading' (duration: 228.413861ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:46:36.623475Z","caller":"traceutil/trace.go:171","msg":"trace[1856445449] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"410.755417ms","start":"2026-03-26T09:46:36.212673Z","end":"2026-03-26T09:46:36.623429Z","steps":["trace[1856445449] 'process raft request' (duration: 410.576288ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:46:36.623418Z","caller":"traceutil/trace.go:171","msg":"trace[183541891] linearizableReadLoop","detail":"{readStateIndex:839; appliedIndex:838; }","duration":"219.000468ms","start":"2026-03-26T09:46:36.404395Z","end":"2026-03-26T09:46:36.623395Z","steps":["trace[183541891] 'read index received' (duration: 218.841729ms)","trace[183541891] 'applied index is now lower than readState.Index' (duration: 157.769µs)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:46:36.62369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:36.212644Z","time spent":"410.931916ms","remote":"127.0.0.1:41830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:46:36.623908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.214315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-26T09:46:36.623936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.522326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-26T09:46:36.623985Z","caller":"traceutil/trace.go:171","msg":"trace[1562873970] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:803; }","duration":"217.313685ms","start":"2026-03-26T09:46:36.40665Z","end":"2026-03-26T09:46:36.623964Z","steps":["trace[1562873970] 'agreement among raft nodes before linearized reading' (duration: 217.095956ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:46:36.624052Z","caller":"traceutil/trace.go:171","msg":"trace[1742963714] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:803; }","duration":"219.689816ms","start":"2026-03-26T09:46:36.404334Z","end":"2026-03-26T09:46:36.624023Z","steps":["trace[1742963714] 'agreement among raft nodes before linearized reading' (duration: 219.192977ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:36.624319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.314106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-26T09:46:36.624414Z","caller":"traceutil/trace.go:171","msg":"trace[210431547] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:803; }","duration":"217.455615ms","start":"2026-03-26T09:46:36.406941Z","end":"2026-03-26T09:46:36.624397Z","steps":["trace[210431547] 'agreement among raft nodes before linearized reading' (duration: 216.993516ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:40.05066Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154583469275045048,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-26T09:46:40.438704Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.303910964s","expected-duration":"1s"} {"level":"info","ts":"2026-03-26T09:46:40.440037Z","caller":"traceutil/trace.go:171","msg":"trace[1330782621] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"1.30533049s","start":"2026-03-26T09:46:39.134678Z","end":"2026-03-26T09:46:40.440009Z","steps":["trace[1330782621] 'process raft request' (duration: 1.30520176s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:40.440203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:39.13466Z","time spent":"1.305438919s","remote":"127.0.0.1:41830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:46:40.44491Z","caller":"traceutil/trace.go:171","msg":"trace[161800262] linearizableReadLoop","detail":"{readStateIndex:860; appliedIndex:858; }","duration":"894.453285ms","start":"2026-03-26T09:46:39.550443Z","end":"2026-03-26T09:46:40.444896Z","steps":["trace[161800262] 'read index received' (duration: 888.823153ms)","trace[161800262] 'applied index is now lower than readState.Index' (duration: 5.629372ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:46:40.445008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"894.557635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:46:40.445045Z","caller":"traceutil/trace.go:171","msg":"trace[678592200] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:823; }","duration":"894.625323ms","start":"2026-03-26T09:46:39.550411Z","end":"2026-03-26T09:46:40.445036Z","steps":["trace[678592200] 'agreement among raft nodes before linearized reading' (duration: 894.566704ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:40.445092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.694819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-26T09:46:40.445135Z","caller":"traceutil/trace.go:171","msg":"trace[1697929149] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:823; }","duration":"809.790109ms","start":"2026-03-26T09:46:39.635333Z","end":"2026-03-26T09:46:40.445123Z","steps":["trace[1697929149] 'agreement among raft nodes before linearized reading' (duration: 809.66733ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:40.445135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:39.550395Z","time spent":"894.709583ms","remote":"127.0.0.1:41542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-26T09:46:40.445163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:39.635302Z","time spent":"809.853029ms","remote":"127.0.0.1:41830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-26T09:46:40.445023Z","caller":"traceutil/trace.go:171","msg":"trace[184374136] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"624.209778ms","start":"2026-03-26T09:46:39.82077Z","end":"2026-03-26T09:46:40.44498Z","steps":["trace[184374136] 'process raft request' (duration: 624.034068ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:46:40.44581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:46:39.820731Z","time spent":"625.037675ms","remote":"127.0.0.1:41802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:48:31.278479Z","caller":"traceutil/trace.go:171","msg":"trace[1065040057] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"108.744539ms","start":"2026-03-26T09:48:31.169711Z","end":"2026-03-26T09:48:31.278456Z","steps":["trace[1065040057] 'process raft request' (duration: 108.348134ms)"],"step_count":1}