{"level":"warn","ts":"2026-04-28T07:21:30.549943Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T07:21:30.550065Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.213:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.213:2380","--initial-cluster=instance=https://199.19.213.213:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.213:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.213:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-28T07:21:30.550142Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T07:21:30.550156Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.213:2380"]} {"level":"info","ts":"2026-04-28T07:21:30.550173Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-28T07:21:30.550821Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.213:2379"]} {"level":"info","ts":"2026-04-28T07:21:30.550895Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.213:2380"],"listen-peer-urls":["https://199.19.213.213:2380"],"advertise-client-urls":["https://199.19.213.213:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.213:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.213:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-28T07:21:30.557203Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.009788ms"} {"level":"info","ts":"2026-04-28T07:21:30.570226Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f3faf6a3dfbe0100","cluster-id":"4c2e50e35fb92a79"} {"level":"info","ts":"2026-04-28T07:21:30.57032Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 switched to configuration voters=()"} {"level":"info","ts":"2026-04-28T07:21:30.570355Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 became follower at term 0"} {"level":"info","ts":"2026-04-28T07:21:30.570379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f3faf6a3dfbe0100 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-28T07:21:30.570395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 became follower at term 1"} {"level":"info","ts":"2026-04-28T07:21:30.570449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 switched to configuration voters=(17580635279088025856)"} {"level":"warn","ts":"2026-04-28T07:21:30.579053Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-28T07:21:30.58054Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-28T07:21:30.583218Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-28T07:21:30.589325Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f3faf6a3dfbe0100","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-28T07:21:30.5895Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f3faf6a3dfbe0100","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-28T07:21:30.589673Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-28T07:21:30.589703Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-28T07:21:30.589714Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-28T07:21:30.590336Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 switched to configuration voters=(17580635279088025856)"} {"level":"info","ts":"2026-04-28T07:21:30.591154Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4c2e50e35fb92a79","local-member-id":"f3faf6a3dfbe0100","added-peer-id":"f3faf6a3dfbe0100","added-peer-peer-urls":["https://199.19.213.213:2380"]} {"level":"info","ts":"2026-04-28T07:21:30.593825Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-28T07:21:30.594047Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f3faf6a3dfbe0100","initial-advertise-peer-urls":["https://199.19.213.213:2380"],"listen-peer-urls":["https://199.19.213.213:2380"],"advertise-client-urls":["https://199.19.213.213:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.213:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-28T07:21:30.59408Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-28T07:21:30.594191Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.213:2380"} {"level":"info","ts":"2026-04-28T07:21:30.594203Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.213:2380"} {"level":"info","ts":"2026-04-28T07:21:30.970999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 is starting a new election at term 1"} {"level":"info","ts":"2026-04-28T07:21:30.97106Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-28T07:21:30.971504Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 received MsgPreVoteResp from f3faf6a3dfbe0100 at term 1"} {"level":"info","ts":"2026-04-28T07:21:30.971556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 became candidate at term 2"} {"level":"info","ts":"2026-04-28T07:21:30.971575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 received MsgVoteResp from f3faf6a3dfbe0100 at term 2"} {"level":"info","ts":"2026-04-28T07:21:30.97162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 became leader at term 2"} {"level":"info","ts":"2026-04-28T07:21:30.971669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f3faf6a3dfbe0100 elected leader f3faf6a3dfbe0100 at term 2"} {"level":"info","ts":"2026-04-28T07:21:30.972617Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T07:21:30.973426Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f3faf6a3dfbe0100","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.213:2379]}","request-path":"/0/members/f3faf6a3dfbe0100/attributes","cluster-id":"4c2e50e35fb92a79","publish-timeout":"7s"} {"level":"info","ts":"2026-04-28T07:21:30.97359Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T07:21:30.973627Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T07:21:30.974117Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4c2e50e35fb92a79","local-member-id":"f3faf6a3dfbe0100","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T07:21:30.974251Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T07:21:30.974291Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T07:21:30.977714Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.213:2379"} {"level":"info","ts":"2026-04-28T07:21:30.977885Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-28T07:21:30.977915Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-28T07:21:30.978006Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-28T07:21:34.480572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.258156ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T07:21:34.480702Z","caller":"traceutil/trace.go:171","msg":"trace[1752173819] transaction","detail":"{read_only:false; response_revision:233; number_of_response:1; }","duration":"444.915763ms","start":"2026-04-28T07:21:34.035768Z","end":"2026-04-28T07:21:34.480683Z","steps":["trace[1752173819] 'process raft request' (duration: 186.232535ms)","trace[1752173819] 'compare' (duration: 257.901964ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:21:34.480796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:21:34.035744Z","time spent":"445.000304ms","remote":"127.0.0.1:43780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":101,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:21:34.488547Z","caller":"traceutil/trace.go:171","msg":"trace[479775021] transaction","detail":"{read_only:false; response_revision:234; number_of_response:1; }","duration":"422.535553ms","start":"2026-04-28T07:21:34.065988Z","end":"2026-04-28T07:21:34.488523Z","steps":["trace[479775021] 'process raft request' (duration: 422.413692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:21:34.488816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:21:34.065972Z","time spent":"422.642763ms","remote":"127.0.0.1:43808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1069,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T07:21:34.972048Z","caller":"traceutil/trace.go:171","msg":"trace[67464266] transaction","detail":"{read_only:false; response_revision:251; number_of_response:1; }","duration":"102.046128ms","start":"2026-04-28T07:21:34.869969Z","end":"2026-04-28T07:21:34.972015Z","steps":["trace[67464266] 'process raft request' (duration: 77.526015ms)","trace[67464266] 'compare' (duration: 24.357823ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:21:35.180823Z","caller":"traceutil/trace.go:171","msg":"trace[1821030677] linearizableReadLoop","detail":"{readStateIndex:259; appliedIndex:258; }","duration":"164.921746ms","start":"2026-04-28T07:21:35.015883Z","end":"2026-04-28T07:21:35.180804Z","steps":["trace[1821030677] 'read index received' (duration: 123.524949ms)","trace[1821030677] 'applied index is now lower than readState.Index' (duration: 41.395857ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:21:35.18106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.154577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3172"} {"level":"info","ts":"2026-04-28T07:21:35.180959Z","caller":"traceutil/trace.go:171","msg":"trace[995295237] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"183.597042ms","start":"2026-04-28T07:21:34.997345Z","end":"2026-04-28T07:21:35.180942Z","steps":["trace[995295237] 'process raft request' (duration: 142.046754ms)","trace[995295237] 'compare' (duration: 41.311726ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:21:35.181766Z","caller":"traceutil/trace.go:171","msg":"trace[1306000355] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:253; }","duration":"165.864622ms","start":"2026-04-28T07:21:35.015838Z","end":"2026-04-28T07:21:35.181703Z","steps":["trace[1306000355] 'agreement among raft nodes before linearized reading' (duration: 165.136207ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:21:36.47078Z","caller":"traceutil/trace.go:171","msg":"trace[667841800] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"108.064838ms","start":"2026-04-28T07:21:36.362683Z","end":"2026-04-28T07:21:36.470748Z","steps":["trace[667841800] 'process raft request' (duration: 107.837987ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:21:36.787679Z","caller":"traceutil/trace.go:171","msg":"trace[1843640782] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"220.274802ms","start":"2026-04-28T07:21:36.567368Z","end":"2026-04-28T07:21:36.787643Z","steps":["trace[1843640782] 'process raft request' (duration: 199.367503ms)","trace[1843640782] 'compare' (duration: 20.749249ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:21:36.787771Z","caller":"traceutil/trace.go:171","msg":"trace[2074484275] linearizableReadLoop","detail":"{readStateIndex:305; appliedIndex:304; }","duration":"153.855971ms","start":"2026-04-28T07:21:36.633892Z","end":"2026-04-28T07:21:36.787748Z","steps":["trace[2074484275] 'read index received' (duration: 132.846481ms)","trace[2074484275] 'applied index is now lower than readState.Index' (duration: 21.00817ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:21:36.787932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.559164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:21:36.787989Z","caller":"traceutil/trace.go:171","msg":"trace[119393016] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"152.522513ms","start":"2026-04-28T07:21:36.635457Z","end":"2026-04-28T07:21:36.787979Z","steps":["trace[119393016] 'process raft request' (duration: 152.140231ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:21:36.787998Z","caller":"traceutil/trace.go:171","msg":"trace[148729865] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:299; }","duration":"199.659694ms","start":"2026-04-28T07:21:36.588322Z","end":"2026-04-28T07:21:36.787981Z","steps":["trace[148729865] 'agreement among raft nodes before linearized reading' (duration: 199.526194ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:21:36.787941Z","caller":"traceutil/trace.go:171","msg":"trace[928298152] transaction","detail":"{read_only:false; response_revision:299; number_of_response:1; }","duration":"150.332869ms","start":"2026-04-28T07:21:36.63753Z","end":"2026-04-28T07:21:36.787863Z","steps":["trace[928298152] 'process raft request' (duration: 150.265158ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:21:36.986048Z","caller":"traceutil/trace.go:171","msg":"trace[965625064] linearizableReadLoop","detail":"{readStateIndex:311; appliedIndex:310; }","duration":"103.974172ms","start":"2026-04-28T07:21:36.882058Z","end":"2026-04-28T07:21:36.986033Z","steps":["trace[965625064] 'read index received' (duration: 5.514254ms)","trace[965625064] 'applied index is now lower than readState.Index' (duration: 98.458618ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:21:36.986167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.828469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T07:21:36.986192Z","caller":"traceutil/trace.go:171","msg":"trace[531884535] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:304; }","duration":"138.894829ms","start":"2026-04-28T07:21:36.84729Z","end":"2026-04-28T07:21:36.986185Z","steps":["trace[531884535] 'agreement among raft nodes before linearized reading' (duration: 138.815469ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:21:36.986299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.90885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-28T07:21:36.986322Z","caller":"traceutil/trace.go:171","msg":"trace[906775988] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"138.035413ms","start":"2026-04-28T07:21:36.847964Z","end":"2026-04-28T07:21:36.985999Z","steps":["trace[906775988] 'process raft request' (duration: 39.547095ms)","trace[906775988] 'compare' (duration: 98.283147ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:21:36.986345Z","caller":"traceutil/trace.go:171","msg":"trace[1243543153] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:304; }","duration":"172.99858ms","start":"2026-04-28T07:21:36.813336Z","end":"2026-04-28T07:21:36.986335Z","steps":["trace[1243543153] 'agreement among raft nodes before linearized reading' (duration: 172.91985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:21:36.989069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.070284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-28T07:21:36.989361Z","caller":"traceutil/trace.go:171","msg":"trace[856947962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:305; }","duration":"104.316546ms","start":"2026-04-28T07:21:36.884937Z","end":"2026-04-28T07:21:36.989254Z","steps":["trace[856947962] 'agreement among raft nodes before linearized reading' (duration: 104.023484ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:21:37.263964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.684795ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T07:21:37.26425Z","caller":"traceutil/trace.go:171","msg":"trace[298872773] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"184.899546ms","start":"2026-04-28T07:21:37.079311Z","end":"2026-04-28T07:21:37.26421Z","steps":["trace[298872773] 'process raft request' (duration: 17.85531ms)","trace[298872773] 'compare' (duration: 166.455303ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:21:37.264349Z","caller":"traceutil/trace.go:171","msg":"trace[1869081005] linearizableReadLoop","detail":"{readStateIndex:322; appliedIndex:321; }","duration":"178.034294ms","start":"2026-04-28T07:21:37.086146Z","end":"2026-04-28T07:21:37.26418Z","steps":["trace[1869081005] 'read index received' (duration: 11.054348ms)","trace[1869081005] 'applied index is now lower than readState.Index' (duration: 166.977946ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:21:37.264728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.557417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-04-28T07:21:37.264802Z","caller":"traceutil/trace.go:171","msg":"trace[1751836172] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:314; }","duration":"178.630429ms","start":"2026-04-28T07:21:37.086138Z","end":"2026-04-28T07:21:37.264769Z","steps":["trace[1751836172] 'agreement among raft nodes before linearized reading' (duration: 178.478947ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:21:40.578685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.857451ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T07:21:40.578758Z","caller":"traceutil/trace.go:171","msg":"trace[489764220] linearizableReadLoop","detail":"{readStateIndex:355; appliedIndex:353; }","duration":"1.143974299s","start":"2026-04-28T07:21:39.434773Z","end":"2026-04-28T07:21:40.578747Z","steps":["trace[489764220] 'read index received' (duration: 52.073257ms)","trace[489764220] 'applied index is now lower than readState.Index' (duration: 1.091900512s)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:21:40.57883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.14405715s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-04-28T07:21:40.578844Z","caller":"traceutil/trace.go:171","msg":"trace[763800375] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:343; }","duration":"1.144093571s","start":"2026-04-28T07:21:39.434745Z","end":"2026-04-28T07:21:40.578838Z","steps":["trace[763800375] 'agreement among raft nodes before linearized reading' (duration: 1.144056081s)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:21:40.578824Z","caller":"traceutil/trace.go:171","msg":"trace[1895709319] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"1.1835642s","start":"2026-04-28T07:21:39.395226Z","end":"2026-04-28T07:21:40.57879Z","steps":["trace[1895709319] 'process raft request' (duration: 359.548108ms)","trace[1895709319] 'compare' (duration: 823.762621ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:21:40.578864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:21:39.434733Z","time spent":"1.144126022s","remote":"127.0.0.1:43898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":220,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"warn","ts":"2026-04-28T07:21:40.578928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.093935476s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"warn","ts":"2026-04-28T07:21:40.578965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:21:39.395196Z","time spent":"1.183696882s","remote":"127.0.0.1:43886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4781,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:21:40.578996Z","caller":"traceutil/trace.go:171","msg":"trace[609373206] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:343; }","duration":"1.094053806s","start":"2026-04-28T07:21:39.484924Z","end":"2026-04-28T07:21:40.578978Z","steps":["trace[609373206] 'agreement among raft nodes before linearized reading' (duration: 1.093931886s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:21:40.579048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:21:39.4849Z","time spent":"1.094136277s","remote":"127.0.0.1:43898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":226,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-04-28T07:21:40.579109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.763994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-04-28T07:21:40.579154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.116623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-28T07:21:40.579152Z","caller":"traceutil/trace.go:171","msg":"trace[1934733153] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:343; }","duration":"843.828384ms","start":"2026-04-28T07:21:39.735315Z","end":"2026-04-28T07:21:40.579143Z","steps":["trace[1934733153] 'agreement among raft nodes before linearized reading' (duration: 843.754734ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:21:40.579169Z","caller":"traceutil/trace.go:171","msg":"trace[25468117] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:343; }","duration":"745.151703ms","start":"2026-04-28T07:21:39.834013Z","end":"2026-04-28T07:21:40.579165Z","steps":["trace[25468117] 'agreement among raft nodes before linearized reading' (duration: 745.126163ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:21:40.579187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:21:39.834003Z","time spent":"745.180053ms","remote":"127.0.0.1:43898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-04-28T07:21:40.579181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:21:39.7353Z","time spent":"843.875604ms","remote":"127.0.0.1:43898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-04-28T07:21:40.579126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.448542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-28T07:21:40.579237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.87249ms","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-04-28T07:21:40.579253Z","caller":"traceutil/trace.go:171","msg":"trace[770493590] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:343; }","duration":"793.90983ms","start":"2026-04-28T07:21:39.785338Z","end":"2026-04-28T07:21:40.579248Z","steps":["trace[770493590] 'agreement among raft nodes before linearized reading' (duration: 793.88483ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:21:40.579256Z","caller":"traceutil/trace.go:171","msg":"trace[856864043] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:343; }","duration":"779.592374ms","start":"2026-04-28T07:21:39.799655Z","end":"2026-04-28T07:21:40.579247Z","steps":["trace[856864043] 'agreement among raft nodes before linearized reading' (duration: 779.449343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:21:40.579272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:21:39.785327Z","time spent":"793.94192ms","remote":"127.0.0.1:43898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-04-28T07:21:40.579281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:21:39.799645Z","time spent":"779.629504ms","remote":"127.0.0.1:43952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T07:21:40.579336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.04534102s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"warn","ts":"2026-04-28T07:21:40.579383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"894.21586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-04-28T07:21:40.579398Z","caller":"traceutil/trace.go:171","msg":"trace[600681743] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:343; }","duration":"894.25487ms","start":"2026-04-28T07:21:39.685138Z","end":"2026-04-28T07:21:40.579393Z","steps":["trace[600681743] 'agreement among raft nodes before linearized reading' (duration: 894.23104ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:21:40.579392Z","caller":"traceutil/trace.go:171","msg":"trace[1301886535] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:343; }","duration":"1.04542472s","start":"2026-04-28T07:21:39.533951Z","end":"2026-04-28T07:21:40.579376Z","steps":["trace[1301886535] 'agreement among raft nodes before linearized reading' (duration: 1.04532745s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:21:40.579411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:21:39.685123Z","time spent":"894.28478ms","remote":"127.0.0.1:43898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-04-28T07:21:40.57944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:21:39.533936Z","time spent":"1.045489371s","remote":"127.0.0.1:43898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":241,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"warn","ts":"2026-04-28T07:21:40.579459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"994.55756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-04-28T07:21:40.579477Z","caller":"traceutil/trace.go:171","msg":"trace[1171770636] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:343; }","duration":"994.62041ms","start":"2026-04-28T07:21:39.584853Z","end":"2026-04-28T07:21:40.579473Z","steps":["trace[1171770636] 'agreement among raft nodes before linearized reading' (duration: 994.59274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:21:40.57949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:21:39.584825Z","time spent":"994.66209ms","remote":"127.0.0.1:43898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":257,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-04-28T07:21:40.579361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"943.995312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-28T07:21:40.579535Z","caller":"traceutil/trace.go:171","msg":"trace[1018548185] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:343; }","duration":"944.214434ms","start":"2026-04-28T07:21:39.635316Z","end":"2026-04-28T07:21:40.579531Z","steps":["trace[1018548185] 'agreement among raft nodes before linearized reading' (duration: 944.027963ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:21:40.579548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:21:39.635289Z","time spent":"944.255474ms","remote":"127.0.0.1:43898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-04-28T07:21:41.087168Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":72231123449203996,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T07:21:41.587893Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":72231123449203996,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T07:21:41.647167Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.061081982s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-28T07:21:41.957169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.672844ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T07:21:41.957818Z","caller":"traceutil/trace.go:171","msg":"trace[1570253056] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"1.371752221s","start":"2026-04-28T07:21:40.585995Z","end":"2026-04-28T07:21:41.957747Z","steps":["trace[1570253056] 'process raft request' (duration: 1.061437553s)","trace[1570253056] 'compare' (duration: 305.542739ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:21:41.958288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:21:40.585972Z","time spent":"1.371943134s","remote":"127.0.0.1:43798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":654,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T07:21:42.554355Z","caller":"traceutil/trace.go:171","msg":"trace[695779764] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"1.968219896s","start":"2026-04-28T07:21:40.586112Z","end":"2026-04-28T07:21:42.554331Z","steps":["trace[695779764] 'process raft request' (duration: 1.968054235s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:21:42.5545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:21:40.586098Z","time spent":"1.968329628s","remote":"127.0.0.1:43952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:21:42.55478Z","caller":"traceutil/trace.go:171","msg":"trace[682176243] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"1.966082883s","start":"2026-04-28T07:21:40.588635Z","end":"2026-04-28T07:21:42.554717Z","steps":["trace[682176243] 'process raft request' (duration: 1.9656507s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:21:42.554976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:21:40.588625Z","time spent":"1.966229234s","remote":"127.0.0.1:43876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4355,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:21:42.554974Z","caller":"traceutil/trace.go:171","msg":"trace[1114614948] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"1.966226754s","start":"2026-04-28T07:21:40.588727Z","end":"2026-04-28T07:21:42.554954Z","steps":["trace[1114614948] 'process raft request' (duration: 1.965749322s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:21:42.555073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:21:40.588715Z","time spent":"1.966323625s","remote":"127.0.0.1:43964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1050,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T07:21:42.55511Z","caller":"traceutil/trace.go:171","msg":"trace[237376749] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"1.96546783s","start":"2026-04-28T07:21:40.589624Z","end":"2026-04-28T07:21:42.555092Z","steps":["trace[237376749] 'process raft request' (duration: 1.965197099s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:21:42.555177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:21:40.589617Z","time spent":"1.9655281s","remote":"127.0.0.1:44156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3642,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T07:21:42.555394Z","caller":"traceutil/trace.go:171","msg":"trace[1934522474] linearizableReadLoop","detail":"{readStateIndex:362; appliedIndex:355; }","duration":"1.969183842s","start":"2026-04-28T07:21:40.586149Z","end":"2026-04-28T07:21:42.555333Z","steps":["trace[1934522474] 'read index received' (duration: 1.061298243s)","trace[1934522474] 'applied index is now lower than readState.Index' (duration: 907.884539ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:21:42.55542Z","caller":"traceutil/trace.go:171","msg":"trace[96288733] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"1.964393503s","start":"2026-04-28T07:21:40.591013Z","end":"2026-04-28T07:21:42.555406Z","steps":["trace[96288733] 'process raft request' (duration: 1.964263792s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:21:42.556051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:21:40.591005Z","time spent":"1.964979208s","remote":"127.0.0.1:43946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1247,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:21:42.556431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.970190028s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-04-28T07:21:42.556851Z","caller":"traceutil/trace.go:171","msg":"trace[1978546090] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:350; }","duration":"1.97058284s","start":"2026-04-28T07:21:40.5862Z","end":"2026-04-28T07:21:42.556783Z","steps":["trace[1978546090] 'agreement among raft nodes before linearized reading' (duration: 1.969749246s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:21:42.556946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:21:40.586194Z","time spent":"1.970701631s","remote":"127.0.0.1:43898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":220,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"warn","ts":"2026-04-28T07:21:42.555581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.969419484s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-04-28T07:21:42.557126Z","caller":"traceutil/trace.go:171","msg":"trace[1297519443] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:350; }","duration":"1.970966653s","start":"2026-04-28T07:21:40.586114Z","end":"2026-04-28T07:21:42.55708Z","steps":["trace[1297519443] 'agreement among raft nodes before linearized reading' (duration: 1.969365553s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:21:42.557193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:21:40.586094Z","time spent":"1.971081504s","remote":"127.0.0.1:43898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":257,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-04-28T07:21:42.556505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.544269532s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-28T07:21:42.556539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.20030177s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-04-28T07:21:42.557342Z","caller":"traceutil/trace.go:171","msg":"trace[1586255588] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:350; }","duration":"1.201130816s","start":"2026-04-28T07:21:41.3562Z","end":"2026-04-28T07:21:42.557331Z","steps":["trace[1586255588] 'agreement among raft nodes before linearized reading' (duration: 1.199781258s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:21:42.557395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:21:41.356186Z","time spent":"1.201186316s","remote":"127.0.0.1:43886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4349,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-04-28T07:21:42.557397Z","caller":"traceutil/trace.go:171","msg":"trace[2052512042] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:350; }","duration":"1.545109977s","start":"2026-04-28T07:21:41.01219Z","end":"2026-04-28T07:21:42.5573Z","steps":["trace[2052512042] 'agreement among raft nodes before linearized reading' (duration: 1.543777559s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:21:42.556518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.968857851s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-04-28T07:21:42.557586Z","caller":"traceutil/trace.go:171","msg":"trace[2039195081] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:350; }","duration":"1.969951997s","start":"2026-04-28T07:21:40.587623Z","end":"2026-04-28T07:21:42.557575Z","steps":["trace[2039195081] 'agreement among raft nodes before linearized reading' (duration: 1.96881449s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:21:42.557629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:21:40.587615Z","time spent":"1.970003447s","remote":"127.0.0.1:43898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":226,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-04-28T07:21:42.557549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:21:41.012169Z","time spent":"1.545348208s","remote":"127.0.0.1:43952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T07:21:42.55657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.532889023s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-28T07:21:42.55806Z","caller":"traceutil/trace.go:171","msg":"trace[1705999873] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:350; }","duration":"1.534390622s","start":"2026-04-28T07:21:41.023656Z","end":"2026-04-28T07:21:42.558047Z","steps":["trace[1705999873] 'agreement among raft nodes before linearized reading' (duration: 1.53233377s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:21:42.558172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:21:41.023646Z","time spent":"1.534511232s","remote":"127.0.0.1:43952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-28T07:21:42.555481Z","caller":"traceutil/trace.go:171","msg":"trace[1700888705] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"1.964995916s","start":"2026-04-28T07:21:40.590476Z","end":"2026-04-28T07:21:42.555472Z","steps":["trace[1700888705] 'process raft request' (duration: 1.964520174s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:21:42.558482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:21:40.59047Z","time spent":"1.967948374s","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2150,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T07:21:42.556621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.551722126s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-28T07:21:42.559618Z","caller":"traceutil/trace.go:171","msg":"trace[1246401666] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:350; }","duration":"1.554715334s","start":"2026-04-28T07:21:41.004892Z","end":"2026-04-28T07:21:42.559608Z","steps":["trace[1246401666] 'agreement among raft nodes before linearized reading' (duration: 1.551700076s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:21:42.559679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:21:41.004831Z","time spent":"1.554836594s","remote":"127.0.0.1:43952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T07:21:42.556146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.967253561s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-04-28T07:21:42.559871Z","caller":"traceutil/trace.go:171","msg":"trace[457458603] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:350; }","duration":"1.971016312s","start":"2026-04-28T07:21:40.588843Z","end":"2026-04-28T07:21:42.55986Z","steps":["trace[457458603] 'agreement among raft nodes before linearized reading' (duration: 1.96716521s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:21:42.55999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:21:40.588828Z","time spent":"1.971152034s","remote":"127.0.0.1:43898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":241,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"info","ts":"2026-04-28T07:21:43.520438Z","caller":"traceutil/trace.go:171","msg":"trace[1101291426] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"183.153552ms","start":"2026-04-28T07:21:43.337259Z","end":"2026-04-28T07:21:43.520412Z","steps":["trace[1101291426] 'process raft request' (duration: 182.91948ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:22:11.726983Z","caller":"traceutil/trace.go:171","msg":"trace[1187134311] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"636.768324ms","start":"2026-04-28T07:22:11.09018Z","end":"2026-04-28T07:22:11.726948Z","steps":["trace[1187134311] 'process raft request' (duration: 636.62217ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:22:11.72703Z","caller":"traceutil/trace.go:171","msg":"trace[822630751] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"636.822795ms","start":"2026-04-28T07:22:11.090178Z","end":"2026-04-28T07:22:11.727001Z","steps":["trace[822630751] 'process raft request' (duration: 633.435276ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:22:11.727182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:22:11.090162Z","time spent":"636.910368ms","remote":"127.0.0.1:43774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23066,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:22:11.727431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:22:11.09016Z","time spent":"636.943069ms","remote":"127.0.0.1:43774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31121,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:22:11.729933Z","caller":"traceutil/trace.go:171","msg":"trace[2046610146] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"590.253342ms","start":"2026-04-28T07:22:11.139655Z","end":"2026-04-28T07:22:11.729908Z","steps":["trace[2046610146] 'process raft request' (duration: 590.207231ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:22:11.730064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:22:11.139625Z","time spent":"590.377825ms","remote":"127.0.0.1:43774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T07:22:11.7308Z","caller":"traceutil/trace.go:171","msg":"trace[466966572] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"593.743475ms","start":"2026-04-28T07:22:11.137026Z","end":"2026-04-28T07:22:11.73077Z","steps":["trace[466966572] 'process raft request' (duration: 592.649556ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:22:11.730918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:22:11.137006Z","time spent":"593.832478ms","remote":"127.0.0.1:43774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:22:13.625596Z","caller":"traceutil/trace.go:171","msg":"trace[851796294] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"299.968277ms","start":"2026-04-28T07:22:13.325603Z","end":"2026-04-28T07:22:13.625571Z","steps":["trace[851796294] 'process raft request' (duration: 299.839104ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:22:13.625769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:22:13.325574Z","time spent":"300.102751ms","remote":"127.0.0.1:43952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:22:15.917886Z","caller":"traceutil/trace.go:171","msg":"trace[1085954424] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"115.022845ms","start":"2026-04-28T07:22:15.802824Z","end":"2026-04-28T07:22:15.917847Z","steps":["trace[1085954424] 'process raft request' (duration: 91.087468ms)","trace[1085954424] 'compare' (duration: 23.594768ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:22:18.777323Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":72231123449204548,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T07:22:19.278217Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":72231123449204548,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T07:22:19.523578Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.161157386s","expected-duration":"1s"} {"level":"info","ts":"2026-04-28T07:22:19.524733Z","caller":"traceutil/trace.go:171","msg":"trace[1718187254] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"1.280729119s","start":"2026-04-28T07:22:18.243976Z","end":"2026-04-28T07:22:19.524705Z","steps":["trace[1718187254] 'process raft request' (duration: 1.280213937s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:22:19.5249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:22:18.243949Z","time spent":"1.280858073s","remote":"127.0.0.1:43806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:22:19.733076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.553698ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-28T07:22:19.733345Z","caller":"traceutil/trace.go:171","msg":"trace[215333171] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"137.792396ms","start":"2026-04-28T07:22:19.595534Z","end":"2026-04-28T07:22:19.733326Z","steps":["trace[215333171] 'process raft request' (duration: 137.657863ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:22:19.733385Z","caller":"traceutil/trace.go:171","msg":"trace[321176958] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:663; }","duration":"1.456648364s","start":"2026-04-28T07:22:18.276713Z","end":"2026-04-28T07:22:19.733362Z","steps":["trace[321176958] 'read index received' (duration: 1.24731508s)","trace[321176958] 'applied index is now lower than readState.Index' (duration: 209.330444ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:22:19.733762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.900849ms","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-04-28T07:22:19.733765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.31994926s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-jmn9l\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-28T07:22:19.733806Z","caller":"traceutil/trace.go:171","msg":"trace[1158496722] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:643; }","duration":"780.977211ms","start":"2026-04-28T07:22:18.952817Z","end":"2026-04-28T07:22:19.733794Z","steps":["trace[1158496722] 'agreement among raft nodes before linearized reading' (duration: 780.869059ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:22:19.733811Z","caller":"traceutil/trace.go:171","msg":"trace[825090307] range","detail":"{range_begin:/registry/pods/kube-system/cilium-jmn9l; range_end:; response_count:1; response_revision:643; }","duration":"1.320046822s","start":"2026-04-28T07:22:18.413753Z","end":"2026-04-28T07:22:19.7338Z","steps":["trace[825090307] 'agreement among raft nodes before linearized reading' (duration: 1.319914508s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:22:19.733838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:22:18.952802Z","time spent":"781.027732ms","remote":"127.0.0.1:43952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T07:22:19.733842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:22:18.413734Z","time spent":"1.320100153s","remote":"127.0.0.1:43886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-jmn9l\" "} {"level":"warn","ts":"2026-04-28T07:22:19.733873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"868.727945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-28T07:22:19.733967Z","caller":"traceutil/trace.go:171","msg":"trace[1181324204] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:643; }","duration":"868.873229ms","start":"2026-04-28T07:22:18.865072Z","end":"2026-04-28T07:22:19.733946Z","steps":["trace[1181324204] 'agreement among raft nodes before linearized reading' (duration: 868.673184ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:22:19.734012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:22:18.865051Z","time spent":"868.953251ms","remote":"127.0.0.1:43952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T07:22:19.734218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.564088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-28T07:22:19.734256Z","caller":"traceutil/trace.go:171","msg":"trace[1794710651] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:643; }","duration":"863.64279ms","start":"2026-04-28T07:22:18.870604Z","end":"2026-04-28T07:22:19.734247Z","steps":["trace[1794710651] 'agreement among raft nodes before linearized reading' (duration: 863.562228ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:22:19.734284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:22:18.870582Z","time spent":"863.695812ms","remote":"127.0.0.1:43952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T07:22:19.734212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.4572361s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:22:19.734526Z","caller":"traceutil/trace.go:171","msg":"trace[806202970] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:643; }","duration":"1.457836844s","start":"2026-04-28T07:22:18.276656Z","end":"2026-04-28T07:22:19.734493Z","steps":["trace[806202970] 'agreement among raft nodes before linearized reading' (duration: 1.456966423s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:22:19.734749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:22:18.276628Z","time spent":"1.4581053s","remote":"127.0.0.1:43738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T07:22:19.736842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.536758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-28T07:22:19.736946Z","caller":"traceutil/trace.go:171","msg":"trace[200553646] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:643; }","duration":"696.753482ms","start":"2026-04-28T07:22:19.040166Z","end":"2026-04-28T07:22:19.736919Z","steps":["trace[200553646] 'agreement among raft nodes before linearized reading' (duration: 693.665477ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:22:19.737156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:22:19.040153Z","time spent":"696.988588ms","remote":"127.0.0.1:43952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T07:22:21.710904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.735839198s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T07:22:21.711207Z","caller":"traceutil/trace.go:171","msg":"trace[1288420388] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:666; }","duration":"1.967811351s","start":"2026-04-28T07:22:19.743379Z","end":"2026-04-28T07:22:21.71119Z","steps":["trace[1288420388] 'read index received' (duration: 231.622395ms)","trace[1288420388] 'applied index is now lower than readState.Index' (duration: 1.736188026s)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:22:21.711321Z","caller":"traceutil/trace.go:171","msg":"trace[1779175039] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"1.96732181s","start":"2026-04-28T07:22:19.743977Z","end":"2026-04-28T07:22:21.711299Z","steps":["trace[1779175039] 'process raft request' (duration: 1.967138316s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:22:21.711414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:22:19.743967Z","time spent":"1.967404482s","remote":"127.0.0.1:43952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:22:21.711444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.968052267s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-28T07:22:21.711482Z","caller":"traceutil/trace.go:171","msg":"trace[1258247801] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"1.970128338s","start":"2026-04-28T07:22:19.741343Z","end":"2026-04-28T07:22:21.711471Z","steps":["trace[1258247801] 'process raft request' (duration: 233.619024ms)","trace[1258247801] 'compare' (duration: 1.735700445s)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:22:21.711509Z","caller":"traceutil/trace.go:171","msg":"trace[1148302362] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:648; }","duration":"1.968135088s","start":"2026-04-28T07:22:19.743355Z","end":"2026-04-28T07:22:21.71149Z","steps":["trace[1148302362] 'agreement among raft nodes before linearized reading' (duration: 1.967949314s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:22:21.711547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:22:19.743348Z","time spent":"1.968191831s","remote":"127.0.0.1:43898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-04-28T07:22:21.711567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:22:19.741326Z","time spent":"1.970183139s","remote":"127.0.0.1:43952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:22:21.711304Z","caller":"traceutil/trace.go:171","msg":"trace[1548381866] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"1.96895446s","start":"2026-04-28T07:22:19.742329Z","end":"2026-04-28T07:22:21.711284Z","steps":["trace[1548381866] 'process raft request' (duration: 1.968750375s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:22:21.711611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.966203792s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:22:21.712086Z","caller":"traceutil/trace.go:171","msg":"trace[1187947608] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:648; }","duration":"1.966715635s","start":"2026-04-28T07:22:19.745357Z","end":"2026-04-28T07:22:21.712072Z","steps":["trace[1187947608] 'agreement among raft nodes before linearized reading' (duration: 1.966181231s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:22:21.712131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:22:19.745346Z","time spent":"1.966773046s","remote":"127.0.0.1:43738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T07:22:21.712178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:22:19.742302Z","time spent":"1.969664406s","remote":"127.0.0.1:43952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:22:21.711657Z","caller":"traceutil/trace.go:171","msg":"trace[369587078] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"1.96981746s","start":"2026-04-28T07:22:19.741833Z","end":"2026-04-28T07:22:21.71165Z","steps":["trace[369587078] 'process raft request' (duration: 1.969169435s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:22:21.712505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:22:19.741808Z","time spent":"1.970649751s","remote":"127.0.0.1:43952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:22:21.711743Z","caller":"traceutil/trace.go:171","msg":"trace[499973015] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"1.961071247s","start":"2026-04-28T07:22:19.750636Z","end":"2026-04-28T07:22:21.711708Z","steps":["trace[499973015] 'process raft request' (duration: 1.960513175s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:22:21.712801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:22:19.750626Z","time spent":"1.962064191s","remote":"127.0.0.1:43886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:22:24.221176Z","caller":"traceutil/trace.go:171","msg":"trace[115738501] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"499.961325ms","start":"2026-04-28T07:22:23.721187Z","end":"2026-04-28T07:22:24.221149Z","steps":["trace[115738501] 'process raft request' (duration: 499.615396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:22:24.221352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:22:23.721165Z","time spent":"500.083798ms","remote":"127.0.0.1:43952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:22:24.231527Z","caller":"traceutil/trace.go:171","msg":"trace[20798959] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"509.368872ms","start":"2026-04-28T07:22:23.722125Z","end":"2026-04-28T07:22:24.231494Z","steps":["trace[20798959] 'process raft request' (duration: 509.079706ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:22:24.231536Z","caller":"traceutil/trace.go:171","msg":"trace[1992935744] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"508.992354ms","start":"2026-04-28T07:22:23.722502Z","end":"2026-04-28T07:22:24.231494Z","steps":["trace[1992935744] 'process raft request' (duration: 508.848081ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:22:24.231534Z","caller":"traceutil/trace.go:171","msg":"trace[369441472] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"487.106643ms","start":"2026-04-28T07:22:23.744395Z","end":"2026-04-28T07:22:24.231502Z","steps":["trace[369441472] 'process raft request' (duration: 487.028722ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:22:24.231698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:22:23.722077Z","time spent":"509.523446ms","remote":"127.0.0.1:43952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:22:24.231711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:22:23.744353Z","time spent":"487.274627ms","remote":"127.0.0.1:43774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T07:22:24.231712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:22:23.722471Z","time spent":"509.144677ms","remote":"127.0.0.1:43952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:22:24.231701Z","caller":"traceutil/trace.go:171","msg":"trace[191312156] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"507.453327ms","start":"2026-04-28T07:22:23.72417Z","end":"2026-04-28T07:22:24.231624Z","steps":["trace[191312156] 'process raft request' (duration: 507.216972ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:22:24.231874Z","caller":"traceutil/trace.go:171","msg":"trace[605633500] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"288.598619ms","start":"2026-04-28T07:22:23.943258Z","end":"2026-04-28T07:22:24.231856Z","steps":["trace[605633500] 'process raft request' (duration: 288.214361ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:22:24.232114Z","caller":"traceutil/trace.go:171","msg":"trace[2111364110] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"210.594726ms","start":"2026-04-28T07:22:24.021508Z","end":"2026-04-28T07:22:24.232103Z","steps":["trace[2111364110] 'process raft request' (duration: 210.272829ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:22:24.232263Z","caller":"traceutil/trace.go:171","msg":"trace[1383351947] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:698; }","duration":"196.828639ms","start":"2026-04-28T07:22:24.035412Z","end":"2026-04-28T07:22:24.23224Z","steps":["trace[1383351947] 'read index received' (duration: 185.459166ms)","trace[1383351947] 'applied index is now lower than readState.Index' (duration: 11.367963ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:22:24.232416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.005733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.213\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-28T07:22:24.232293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:22:23.724153Z","time spent":"507.874486ms","remote":"127.0.0.1:43952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:22:24.232483Z","caller":"traceutil/trace.go:171","msg":"trace[1558741217] range","detail":"{range_begin:/registry/masterleases/199.19.213.213; range_end:; response_count:1; response_revision:683; }","duration":"197.127656ms","start":"2026-04-28T07:22:24.035336Z","end":"2026-04-28T07:22:24.232464Z","steps":["trace[1558741217] 'agreement among raft nodes before linearized reading' (duration: 196.998833ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:22:27.515058Z","caller":"traceutil/trace.go:171","msg":"trace[823936205] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"112.321792ms","start":"2026-04-28T07:22:27.402712Z","end":"2026-04-28T07:22:27.515034Z","steps":["trace[823936205] 'process raft request' (duration: 111.911223ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:22:32.031987Z","caller":"traceutil/trace.go:171","msg":"trace[675356536] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"140.389621ms","start":"2026-04-28T07:22:31.89156Z","end":"2026-04-28T07:22:32.031949Z","steps":["trace[675356536] 'process raft request' (duration: 139.924432ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:22:38.125429Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":72231123449204875,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T07:22:38.146061Z","caller":"traceutil/trace.go:171","msg":"trace[1450229483] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"617.743669ms","start":"2026-04-28T07:22:37.528273Z","end":"2026-04-28T07:22:38.146017Z","steps":["trace[1450229483] 'process raft request' (duration: 617.570796ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:22:38.146221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:22:37.528247Z","time spent":"617.895823ms","remote":"127.0.0.1:43886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:22:38.159546Z","caller":"traceutil/trace.go:171","msg":"trace[872920244] linearizableReadLoop","detail":"{readStateIndex:838; appliedIndex:835; }","duration":"535.067922ms","start":"2026-04-28T07:22:37.62446Z","end":"2026-04-28T07:22:38.159528Z","steps":["trace[872920244] 'read index received' (duration: 521.379407ms)","trace[872920244] 'applied index is now lower than readState.Index' (duration: 13.687595ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:22:38.159762Z","caller":"traceutil/trace.go:171","msg":"trace[506949109] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"302.488674ms","start":"2026-04-28T07:22:37.857241Z","end":"2026-04-28T07:22:38.15973Z","steps":["trace[506949109] 'process raft request' (duration: 302.256849ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:22:38.159931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:22:37.857216Z","time spent":"302.633067ms","remote":"127.0.0.1:43952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:22:38.159942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.446979ms","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-04-28T07:22:38.159903Z","caller":"traceutil/trace.go:171","msg":"trace[786650752] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"513.304879ms","start":"2026-04-28T07:22:37.646489Z","end":"2026-04-28T07:22:38.159794Z","steps":["trace[786650752] 'process raft request' (duration: 512.917631ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:22:38.16001Z","caller":"traceutil/trace.go:171","msg":"trace[675314782] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:810; }","duration":"535.575231ms","start":"2026-04-28T07:22:37.624425Z","end":"2026-04-28T07:22:38.16Z","steps":["trace[675314782] 'agreement among raft nodes before linearized reading' (duration: 535.340596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:22:38.16001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.842421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-28T07:22:38.160084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:22:37.624409Z","time spent":"535.667543ms","remote":"127.0.0.1:43952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-28T07:22:38.160089Z","caller":"traceutil/trace.go:171","msg":"trace[1007987517] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:810; }","duration":"336.983374ms","start":"2026-04-28T07:22:37.823087Z","end":"2026-04-28T07:22:38.16007Z","steps":["trace[1007987517] 'agreement among raft nodes before linearized reading' (duration: 336.796051ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:22:38.160178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:22:37.823066Z","time spent":"337.102016ms","remote":"127.0.0.1:43774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-04-28T07:22:38.160185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:22:37.646473Z","time spent":"513.574015ms","remote":"127.0.0.1:43876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4905,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:22:40.532909Z","caller":"traceutil/trace.go:171","msg":"trace[1327117254] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"106.191074ms","start":"2026-04-28T07:22:40.426179Z","end":"2026-04-28T07:22:40.53237Z","steps":["trace[1327117254] 'process raft request' (duration: 72.119732ms)","trace[1327117254] 'compare' (duration: 33.815997ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:22:40.538195Z","caller":"traceutil/trace.go:171","msg":"trace[1269764058] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"111.309354ms","start":"2026-04-28T07:22:40.426861Z","end":"2026-04-28T07:22:40.53817Z","steps":["trace[1269764058] 'process raft request' (duration: 111.261823ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:22:40.538217Z","caller":"traceutil/trace.go:171","msg":"trace[213926391] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"111.480287ms","start":"2026-04-28T07:22:40.426703Z","end":"2026-04-28T07:22:40.538183Z","steps":["trace[213926391] 'process raft request' (duration: 111.287393ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:22:40.696417Z","caller":"traceutil/trace.go:171","msg":"trace[1923337299] linearizableReadLoop","detail":"{readStateIndex:852; appliedIndex:851; }","duration":"121.409243ms","start":"2026-04-28T07:22:40.574977Z","end":"2026-04-28T07:22:40.696387Z","steps":["trace[1923337299] 'read index received' (duration: 98.42405ms)","trace[1923337299] 'applied index is now lower than readState.Index' (duration: 22.984103ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:22:40.696475Z","caller":"traceutil/trace.go:171","msg":"trace[1098401411] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"151.61848ms","start":"2026-04-28T07:22:40.544829Z","end":"2026-04-28T07:22:40.696447Z","steps":["trace[1098401411] 'process raft request' (duration: 128.571525ms)","trace[1098401411] 'compare' (duration: 22.866261ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:22:40.696707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.658798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:22:40.696823Z","caller":"traceutil/trace.go:171","msg":"trace[1998901265] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:823; }","duration":"121.858183ms","start":"2026-04-28T07:22:40.574942Z","end":"2026-04-28T07:22:40.6968Z","steps":["trace[1998901265] 'agreement among raft nodes before linearized reading' (duration: 121.591448ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:22:46.678365Z","caller":"traceutil/trace.go:171","msg":"trace[1252823207] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"114.795002ms","start":"2026-04-28T07:22:46.563542Z","end":"2026-04-28T07:22:46.678337Z","steps":["trace[1252823207] 'process raft request' (duration: 112.698752ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:22:46.678647Z","caller":"traceutil/trace.go:171","msg":"trace[1526575761] linearizableReadLoop","detail":"{readStateIndex:928; appliedIndex:926; }","duration":"113.690262ms","start":"2026-04-28T07:22:46.564906Z","end":"2026-04-28T07:22:46.678597Z","steps":["trace[1526575761] 'read index received' (duration: 13.893959ms)","trace[1526575761] 'applied index is now lower than readState.Index' (duration: 99.793753ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:22:46.678697Z","caller":"traceutil/trace.go:171","msg":"trace[1969722324] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"113.284384ms","start":"2026-04-28T07:22:46.565394Z","end":"2026-04-28T07:22:46.678678Z","steps":["trace[1969722324] 'process raft request' (duration: 112.893537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:22:46.678909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.972077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-rj6wc\" ","response":"range_response_count:1 size:4777"} {"level":"info","ts":"2026-04-28T07:22:46.678972Z","caller":"traceutil/trace.go:171","msg":"trace[1961064307] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-rj6wc; range_end:; response_count:1; response_revision:899; }","duration":"114.063208ms","start":"2026-04-28T07:22:46.56489Z","end":"2026-04-28T07:22:46.678953Z","steps":["trace[1961064307] 'agreement among raft nodes before linearized reading' (duration: 113.862454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:22:47.032927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.3763ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T07:22:47.033124Z","caller":"traceutil/trace.go:171","msg":"trace[1774395426] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"163.346123ms","start":"2026-04-28T07:22:46.869743Z","end":"2026-04-28T07:22:47.033089Z","steps":["trace[1774395426] 'process raft request' (duration: 26.642547ms)","trace[1774395426] 'compare' (duration: 136.156677ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:22:47.078595Z","caller":"traceutil/trace.go:171","msg":"trace[1739187562] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"208.403107ms","start":"2026-04-28T07:22:46.870164Z","end":"2026-04-28T07:22:47.078567Z","steps":["trace[1739187562] 'process raft request' (duration: 208.027859ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:22:47.078843Z","caller":"traceutil/trace.go:171","msg":"trace[71125703] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"208.091202ms","start":"2026-04-28T07:22:46.870733Z","end":"2026-04-28T07:22:47.078824Z","steps":["trace[71125703] 'process raft request' (duration: 207.799747ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:22:47.078612Z","caller":"traceutil/trace.go:171","msg":"trace[785637990] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"208.303825ms","start":"2026-04-28T07:22:46.870282Z","end":"2026-04-28T07:22:47.078586Z","steps":["trace[785637990] 'process raft request' (duration: 208.161663ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:22:47.247658Z","caller":"traceutil/trace.go:171","msg":"trace[1058470625] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"209.225249ms","start":"2026-04-28T07:22:47.038387Z","end":"2026-04-28T07:22:47.247613Z","steps":["trace[1058470625] 'process raft request' (duration: 179.49765ms)","trace[1058470625] 'compare' (duration: 29.429634ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:22:47.247697Z","caller":"traceutil/trace.go:171","msg":"trace[1395521424] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"205.266637ms","start":"2026-04-28T07:22:47.042392Z","end":"2026-04-28T07:22:47.247658Z","steps":["trace[1395521424] 'process raft request' (duration: 205.169334ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:22:47.294598Z","caller":"traceutil/trace.go:171","msg":"trace[1301827136] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"213.626442ms","start":"2026-04-28T07:22:47.080946Z","end":"2026-04-28T07:22:47.294573Z","steps":["trace[1301827136] 'process raft request' (duration: 213.474779ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:22:49.531993Z","caller":"traceutil/trace.go:171","msg":"trace[874504591] linearizableReadLoop","detail":"{readStateIndex:977; appliedIndex:974; }","duration":"102.94335ms","start":"2026-04-28T07:22:49.429024Z","end":"2026-04-28T07:22:49.531968Z","steps":["trace[874504591] 'read index received' (duration: 30.508274ms)","trace[874504591] 'applied index is now lower than readState.Index' (duration: 72.433746ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:22:49.532098Z","caller":"traceutil/trace.go:171","msg":"trace[624512763] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"157.437301ms","start":"2026-04-28T07:22:49.374602Z","end":"2026-04-28T07:22:49.53204Z","steps":["trace[624512763] 'process raft request' (duration: 157.312659ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:22:49.53213Z","caller":"traceutil/trace.go:171","msg":"trace[2066636654] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"159.460688ms","start":"2026-04-28T07:22:49.372628Z","end":"2026-04-28T07:22:49.532089Z","steps":["trace[2066636654] 'process raft request' (duration: 159.158902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:22:49.532254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.219256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12755"} {"level":"info","ts":"2026-04-28T07:22:49.532297Z","caller":"traceutil/trace.go:171","msg":"trace[1620215678] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:946; }","duration":"103.336418ms","start":"2026-04-28T07:22:49.428946Z","end":"2026-04-28T07:22:49.532283Z","steps":["trace[1620215678] 'agreement among raft nodes before linearized reading' (duration: 103.121094ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:23:11.240099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.156199ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T07:23:11.240215Z","caller":"traceutil/trace.go:171","msg":"trace[1461462866] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"279.698167ms","start":"2026-04-28T07:23:10.960499Z","end":"2026-04-28T07:23:11.240197Z","steps":["trace[1461462866] 'process raft request' (duration: 126.359116ms)","trace[1461462866] 'compare' (duration: 152.948576ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:23:11.271173Z","caller":"traceutil/trace.go:171","msg":"trace[786532404] linearizableReadLoop","detail":"{readStateIndex:1081; appliedIndex:1079; }","duration":"300.468054ms","start":"2026-04-28T07:23:10.970666Z","end":"2026-04-28T07:23:11.271134Z","steps":["trace[786532404] 'read index received' (duration: 116.221392ms)","trace[786532404] 'applied index is now lower than readState.Index' (duration: 184.245622ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:23:11.271201Z","caller":"traceutil/trace.go:171","msg":"trace[610832697] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"300.612585ms","start":"2026-04-28T07:23:10.97056Z","end":"2026-04-28T07:23:11.271173Z","steps":["trace[610832697] 'process raft request' (duration: 300.433032ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:23:11.271322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:23:10.970543Z","time spent":"300.719427ms","remote":"127.0.0.1:43952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:23:11.271386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.229125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:1 size:224"} {"level":"warn","ts":"2026-04-28T07:23:11.271427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.737608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-28T07:23:11.271441Z","caller":"traceutil/trace.go:171","msg":"trace[602706536] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:1; response_revision:1045; }","duration":"269.324306ms","start":"2026-04-28T07:23:11.002102Z","end":"2026-04-28T07:23:11.271427Z","steps":["trace[602706536] 'agreement among raft nodes before linearized reading' (duration: 269.200724ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:23:11.271469Z","caller":"traceutil/trace.go:171","msg":"trace[630507352] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1045; }","duration":"300.809928ms","start":"2026-04-28T07:23:10.970648Z","end":"2026-04-28T07:23:11.271458Z","steps":["trace[630507352] 'agreement among raft nodes before linearized reading' (duration: 300.654276ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:23:11.271511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:23:10.970643Z","time spent":"300.859129ms","remote":"127.0.0.1:43952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-28T07:23:11.411976Z","caller":"traceutil/trace.go:171","msg":"trace[398602723] linearizableReadLoop","detail":"{readStateIndex:1082; appliedIndex:1081; }","duration":"126.869899ms","start":"2026-04-28T07:23:11.285073Z","end":"2026-04-28T07:23:11.411943Z","steps":["trace[398602723] 'read index received' (duration: 70.531892ms)","trace[398602723] 'applied index is now lower than readState.Index' (duration: 56.336357ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:23:11.412127Z","caller":"traceutil/trace.go:171","msg":"trace[320470871] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"129.59482ms","start":"2026-04-28T07:23:11.282499Z","end":"2026-04-28T07:23:11.412094Z","steps":["trace[320470871] 'process raft request' (duration: 73.074221ms)","trace[320470871] 'compare' (duration: 56.153843ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:23:11.412292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.188433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:1 size:2897"} {"level":"info","ts":"2026-04-28T07:23:11.41235Z","caller":"traceutil/trace.go:171","msg":"trace[89590583] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:1; response_revision:1046; }","duration":"127.289075ms","start":"2026-04-28T07:23:11.285045Z","end":"2026-04-28T07:23:11.412334Z","steps":["trace[89590583] 'agreement among raft nodes before linearized reading' (duration: 127.017101ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:23:35.674681Z","caller":"traceutil/trace.go:171","msg":"trace[918322020] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"190.728001ms","start":"2026-04-28T07:23:35.483913Z","end":"2026-04-28T07:23:35.674641Z","steps":["trace[918322020] 'process raft request' (duration: 153.978175ms)","trace[918322020] 'compare' (duration: 36.555243ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:23:37.639639Z","caller":"traceutil/trace.go:171","msg":"trace[1358372917] transaction","detail":"{read_only:false; response_revision:1135; number_of_response:1; }","duration":"156.363211ms","start":"2026-04-28T07:23:37.483251Z","end":"2026-04-28T07:23:37.639614Z","steps":["trace[1358372917] 'process raft request' (duration: 156.24912ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:24:06.069013Z","caller":"traceutil/trace.go:171","msg":"trace[987178961] linearizableReadLoop","detail":"{readStateIndex:1275; appliedIndex:1273; }","duration":"103.985911ms","start":"2026-04-28T07:24:05.964994Z","end":"2026-04-28T07:24:06.06898Z","steps":["trace[987178961] 'read index received' (duration: 103.648237ms)","trace[987178961] 'applied index is now lower than readState.Index' (duration: 336.444µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:24:06.069256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.234524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:24:06.06935Z","caller":"traceutil/trace.go:171","msg":"trace[1835896729] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"184.591117ms","start":"2026-04-28T07:24:05.884726Z","end":"2026-04-28T07:24:06.069317Z","steps":["trace[1835896729] 'process raft request' (duration: 184.00284ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:24:06.069407Z","caller":"traceutil/trace.go:171","msg":"trace[702822828] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1228; }","duration":"104.459107ms","start":"2026-04-28T07:24:05.964927Z","end":"2026-04-28T07:24:06.069386Z","steps":["trace[702822828] 'agreement among raft nodes before linearized reading' (duration: 104.256075ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:24:06.069484Z","caller":"traceutil/trace.go:171","msg":"trace[1503422661] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"184.730649ms","start":"2026-04-28T07:24:05.884721Z","end":"2026-04-28T07:24:06.069451Z","steps":["trace[1503422661] 'process raft request' (duration: 184.186812ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:24:07.337319Z","caller":"traceutil/trace.go:171","msg":"trace[1634372353] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"173.718142ms","start":"2026-04-28T07:24:07.163539Z","end":"2026-04-28T07:24:07.337257Z","steps":["trace[1634372353] 'process raft request' (duration: 173.342598ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:24:32.355994Z","caller":"traceutil/trace.go:171","msg":"trace[1242038617] linearizableReadLoop","detail":"{readStateIndex:1405; appliedIndex:1403; }","duration":"100.924874ms","start":"2026-04-28T07:24:32.255044Z","end":"2026-04-28T07:24:32.355969Z","steps":["trace[1242038617] 'read index received' (duration: 2.390824ms)","trace[1242038617] 'applied index is now lower than readState.Index' (duration: 98.53282ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:24:32.356043Z","caller":"traceutil/trace.go:171","msg":"trace[628707417] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"105.142896ms","start":"2026-04-28T07:24:32.250875Z","end":"2026-04-28T07:24:32.356018Z","steps":["trace[628707417] 'process raft request' (duration: 100.097336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:24:32.356171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.103576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-04-28T07:24:32.356245Z","caller":"traceutil/trace.go:171","msg":"trace[1798602123] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1352; }","duration":"101.182076ms","start":"2026-04-28T07:24:32.255017Z","end":"2026-04-28T07:24:32.356199Z","steps":["trace[1798602123] 'agreement among raft nodes before linearized reading' (duration: 101.091445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:24:32.356383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.305968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:107878"} {"level":"info","ts":"2026-04-28T07:24:32.356426Z","caller":"traceutil/trace.go:171","msg":"trace[161107653] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1352; }","duration":"101.369018ms","start":"2026-04-28T07:24:32.255046Z","end":"2026-04-28T07:24:32.356415Z","steps":["trace[161107653] 'agreement among raft nodes before linearized reading' (duration: 101.182666ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:24:35.691179Z","caller":"traceutil/trace.go:171","msg":"trace[345482287] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"114.668732ms","start":"2026-04-28T07:24:35.576461Z","end":"2026-04-28T07:24:35.69113Z","steps":["trace[345482287] 'process raft request' (duration: 114.438859ms)"],"step_count":1}