{"level":"warn","ts":"2026-02-18T05:11:54.246777Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-18T05:11:54.246989Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.43:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.43:2380","--initial-cluster=instance=https://162.253.55.43:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.43:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.43: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-02-18T05:11:54.2471Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-18T05:11:54.247156Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.43:2380"]} {"level":"info","ts":"2026-02-18T05:11:54.24719Z","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-02-18T05:11:54.24795Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.43:2379"]} {"level":"info","ts":"2026-02-18T05:11:54.248142Z","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":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.43:2380"],"listen-peer-urls":["https://162.253.55.43:2380"],"advertise-client-urls":["https://162.253.55.43:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.43:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.43: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-02-18T05:11:54.254014Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.60022ms"} {"level":"info","ts":"2026-02-18T05:11:54.287949Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"14f8f2dfdaccebdb","cluster-id":"5f02ccb15672f35d"} {"level":"info","ts":"2026-02-18T05:11:54.288014Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb switched to configuration voters=()"} {"level":"info","ts":"2026-02-18T05:11:54.288126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became follower at term 0"} {"level":"info","ts":"2026-02-18T05:11:54.288357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 14f8f2dfdaccebdb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-18T05:11:54.288372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became follower at term 1"} {"level":"info","ts":"2026-02-18T05:11:54.288448Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb switched to configuration voters=(1511224718245293019)"} {"level":"warn","ts":"2026-02-18T05:11:54.301954Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-18T05:11:54.307769Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-18T05:11:54.317364Z","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-02-18T05:11:54.327601Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"14f8f2dfdaccebdb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-18T05:11:54.332539Z","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-02-18T05:11:54.332604Z","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-02-18T05:11:54.332611Z","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-02-18T05:11:54.337878Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"14f8f2dfdaccebdb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-18T05:11:54.338144Z","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-02-18T05:11:54.342935Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb switched to configuration voters=(1511224718245293019)"} {"level":"info","ts":"2026-02-18T05:11:54.343021Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5f02ccb15672f35d","local-member-id":"14f8f2dfdaccebdb","added-peer-id":"14f8f2dfdaccebdb","added-peer-peer-urls":["https://162.253.55.43:2380"]} {"level":"info","ts":"2026-02-18T05:11:54.343147Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.43:2380"} {"level":"info","ts":"2026-02-18T05:11:54.343173Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.43:2380"} {"level":"info","ts":"2026-02-18T05:11:54.343334Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"14f8f2dfdaccebdb","initial-advertise-peer-urls":["https://162.253.55.43:2380"],"listen-peer-urls":["https://162.253.55.43:2380"],"advertise-client-urls":["https://162.253.55.43:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.43:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-18T05:11:54.343361Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-18T05:11:55.189703Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb is starting a new election at term 1"} {"level":"info","ts":"2026-02-18T05:11:55.189746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became pre-candidate at term 1"} {"level":"info","ts":"2026-02-18T05:11:55.189773Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb received MsgPreVoteResp from 14f8f2dfdaccebdb at term 1"} {"level":"info","ts":"2026-02-18T05:11:55.189835Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became candidate at term 2"} {"level":"info","ts":"2026-02-18T05:11:55.189867Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb received MsgVoteResp from 14f8f2dfdaccebdb at term 2"} {"level":"info","ts":"2026-02-18T05:11:55.189876Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became leader at term 2"} {"level":"info","ts":"2026-02-18T05:11:55.18991Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 14f8f2dfdaccebdb elected leader 14f8f2dfdaccebdb at term 2"} {"level":"info","ts":"2026-02-18T05:11:55.194541Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"14f8f2dfdaccebdb","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.43:2379]}","request-path":"/0/members/14f8f2dfdaccebdb/attributes","cluster-id":"5f02ccb15672f35d","publish-timeout":"7s"} {"level":"info","ts":"2026-02-18T05:11:55.194714Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T05:11:55.194771Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T05:11:55.19568Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T05:11:55.195817Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-18T05:11:55.195856Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-18T05:11:55.197212Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.43:2379"} {"level":"info","ts":"2026-02-18T05:11:55.198423Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-18T05:11:55.198753Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5f02ccb15672f35d","local-member-id":"14f8f2dfdaccebdb","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T05:11:55.198839Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T05:11:55.198885Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-18T05:11:58.191875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.745447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system:controller:cloud-provider\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:11:58.191984Z","caller":"traceutil/trace.go:171","msg":"trace[1956863777] range","detail":"{range_begin:/registry/rolebindings/kube-system/system:controller:cloud-provider; range_end:; response_count:0; response_revision:192; }","duration":"124.920762ms","start":"2026-02-18T05:11:58.067041Z","end":"2026-02-18T05:11:58.191962Z","steps":["trace[1956863777] 'range keys from in-memory index tree' (duration: 124.66564ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:03.753546Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995349620063646414,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T05:12:03.927323Z","caller":"traceutil/trace.go:171","msg":"trace[661037324] linearizableReadLoop","detail":"{readStateIndex:311; appliedIndex:310; }","duration":"674.255596ms","start":"2026-02-18T05:12:03.253043Z","end":"2026-02-18T05:12:03.927299Z","steps":["trace[661037324] 'read index received' (duration: 674.065199ms)","trace[661037324] 'applied index is now lower than readState.Index' (duration: 189.376µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:12:03.927682Z","caller":"traceutil/trace.go:171","msg":"trace[1065441682] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"716.325185ms","start":"2026-02-18T05:12:03.211341Z","end":"2026-02-18T05:12:03.927667Z","steps":["trace[1065441682] 'process raft request' (duration: 715.831331ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:03.928262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:03.211329Z","time spent":"716.39ms","remote":"127.0.0.1:54080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1251,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:12:03.928479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.436519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-02-18T05:12:03.928518Z","caller":"traceutil/trace.go:171","msg":"trace[1646677031] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:303; }","duration":"675.497574ms","start":"2026-02-18T05:12:03.253006Z","end":"2026-02-18T05:12:03.928504Z","steps":["trace[1646677031] 'agreement among raft nodes before linearized reading' (duration: 675.433879ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:03.92855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:03.252994Z","time spent":"675.549289ms","remote":"127.0.0.1:54018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":215,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-02-18T05:12:03.928747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.524002ms","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-02-18T05:12:03.928784Z","caller":"traceutil/trace.go:171","msg":"trace[525234669] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:303; }","duration":"625.584257ms","start":"2026-02-18T05:12:03.30319Z","end":"2026-02-18T05:12:03.928774Z","steps":["trace[525234669] 'agreement among raft nodes before linearized reading' (duration: 625.524482ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:03.928812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:03.303177Z","time spent":"625.628341ms","remote":"127.0.0.1:54018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-02-18T05:12:03.929007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.849862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-18T05:12:03.929045Z","caller":"traceutil/trace.go:171","msg":"trace[1275570901] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:303; }","duration":"217.909788ms","start":"2026-02-18T05:12:03.711123Z","end":"2026-02-18T05:12:03.929032Z","steps":["trace[1275570901] 'agreement among raft nodes before linearized reading' (duration: 217.854333ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:03.929186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.895409ms","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-02-18T05:12:03.929216Z","caller":"traceutil/trace.go:171","msg":"trace[1966682690] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:303; }","duration":"272.955455ms","start":"2026-02-18T05:12:03.656252Z","end":"2026-02-18T05:12:03.929207Z","steps":["trace[1966682690] 'agreement among raft nodes before linearized reading' (duration: 272.90252ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:03.929378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.734033ms","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-02-18T05:12:03.929406Z","caller":"traceutil/trace.go:171","msg":"trace[1017832812] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:303; }","duration":"325.788328ms","start":"2026-02-18T05:12:03.603609Z","end":"2026-02-18T05:12:03.929398Z","steps":["trace[1017832812] 'agreement among raft nodes before linearized reading' (duration: 325.740333ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:03.929433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:03.603575Z","time spent":"325.851313ms","remote":"127.0.0.1:54018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":240,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"warn","ts":"2026-02-18T05:12:03.929557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.465107ms","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-02-18T05:12:03.929584Z","caller":"traceutil/trace.go:171","msg":"trace[595337416] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:303; }","duration":"374.572626ms","start":"2026-02-18T05:12:03.555004Z","end":"2026-02-18T05:12:03.929576Z","steps":["trace[595337416] 'agreement among raft nodes before linearized reading' (duration: 374.526002ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:03.92961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:03.554983Z","time spent":"374.621001ms","remote":"127.0.0.1:54018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":236,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-02-18T05:12:03.929745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.521479ms","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-02-18T05:12:03.929787Z","caller":"traceutil/trace.go:171","msg":"trace[464804492] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:303; }","duration":"427.575814ms","start":"2026-02-18T05:12:03.5022Z","end":"2026-02-18T05:12:03.929775Z","steps":["trace[464804492] 'agreement among raft nodes before linearized reading' (duration: 427.512318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:03.929826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:03.502191Z","time spent":"427.624438ms","remote":"127.0.0.1:54018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-02-18T05:12:03.929963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.354242ms","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-02-18T05:12:03.92999Z","caller":"traceutil/trace.go:171","msg":"trace[1062982811] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:303; }","duration":"476.405306ms","start":"2026-02-18T05:12:03.453576Z","end":"2026-02-18T05:12:03.929982Z","steps":["trace[1062982811] 'agreement among raft nodes before linearized reading' (duration: 476.359492ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:03.930016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:03.453562Z","time spent":"476.447539ms","remote":"127.0.0.1:54018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":256,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-02-18T05:12:03.930135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.556381ms","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-02-18T05:12:03.930161Z","caller":"traceutil/trace.go:171","msg":"trace[1008878190] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:303; }","duration":"527.602965ms","start":"2026-02-18T05:12:03.402551Z","end":"2026-02-18T05:12:03.930154Z","steps":["trace[1008878190] 'agreement among raft nodes before linearized reading' (duration: 527.559931ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:03.930187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:03.40254Z","time spent":"527.640748ms","remote":"127.0.0.1:54018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":225,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-02-18T05:12:03.930305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.0312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-02-18T05:12:03.930334Z","caller":"traceutil/trace.go:171","msg":"trace[463765902] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:303; }","duration":"577.084115ms","start":"2026-02-18T05:12:03.35324Z","end":"2026-02-18T05:12:03.930324Z","steps":["trace[463765902] 'agreement among raft nodes before linearized reading' (duration: 577.040901ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:03.930362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:03.353226Z","time spent":"577.130019ms","remote":"127.0.0.1:54018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"info","ts":"2026-02-18T05:12:04.375101Z","caller":"traceutil/trace.go:171","msg":"trace[1808235307] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"431.290927ms","start":"2026-02-18T05:12:03.943771Z","end":"2026-02-18T05:12:04.375062Z","steps":["trace[1808235307] 'process raft request' (duration: 427.560558ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:04.375637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:03.943763Z","time spent":"431.741527ms","remote":"127.0.0.1:54080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2263,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:12:04.377429Z","caller":"traceutil/trace.go:171","msg":"trace[131057950] linearizableReadLoop","detail":"{readStateIndex:317; appliedIndex:311; }","duration":"424.863475ms","start":"2026-02-18T05:12:03.952545Z","end":"2026-02-18T05:12:04.377409Z","steps":["trace[131057950] 'read index received' (duration: 418.875187ms)","trace[131057950] 'applied index is now lower than readState.Index' (duration: 5.987448ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:12:04.377459Z","caller":"traceutil/trace.go:171","msg":"trace[2106648282] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"431.308461ms","start":"2026-02-18T05:12:03.946136Z","end":"2026-02-18T05:12:04.377445Z","steps":["trace[2106648282] 'process raft request' (duration: 430.979802ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:04.378032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:03.946128Z","time spent":"431.870109ms","remote":"127.0.0.1:54018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":156,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T05:12:04.379355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.902292ms","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-02-18T05:12:04.379402Z","caller":"traceutil/trace.go:171","msg":"trace[120884647] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:309; }","duration":"375.9983ms","start":"2026-02-18T05:12:04.003391Z","end":"2026-02-18T05:12:04.379389Z","steps":["trace[120884647] 'agreement among raft nodes before linearized reading' (duration: 375.902782ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:04.379435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:04.003363Z","time spent":"376.064796ms","remote":"127.0.0.1:54018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"info","ts":"2026-02-18T05:12:04.377538Z","caller":"traceutil/trace.go:171","msg":"trace[100386728] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"429.864553ms","start":"2026-02-18T05:12:03.947656Z","end":"2026-02-18T05:12:04.377521Z","steps":["trace[100386728] 'process raft request' (duration: 429.634103ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:04.380077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:03.94765Z","time spent":"432.289898ms","remote":"127.0.0.1:54002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3998,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:12:04.381714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.248847ms","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-02-18T05:12:04.382063Z","caller":"traceutil/trace.go:171","msg":"trace[2072893130] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:309; }","duration":"177.839609ms","start":"2026-02-18T05:12:04.203995Z","end":"2026-02-18T05:12:04.381834Z","steps":["trace[2072893130] 'agreement among raft nodes before linearized reading' (duration: 177.019136ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:04.382421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.734675ms","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-02-18T05:12:04.382475Z","caller":"traceutil/trace.go:171","msg":"trace[888682595] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:309; }","duration":"429.938693ms","start":"2026-02-18T05:12:03.952522Z","end":"2026-02-18T05:12:04.38246Z","steps":["trace[888682595] 'agreement among raft nodes before linearized reading' (duration: 429.279345ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:04.382674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:03.952515Z","time spent":"430.14345ms","remote":"127.0.0.1:54018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":236,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-02-18T05:12:04.38301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.736127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-18T05:12:04.383177Z","caller":"traceutil/trace.go:171","msg":"trace[1404064325] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:309; }","duration":"229.942425ms","start":"2026-02-18T05:12:04.153224Z","end":"2026-02-18T05:12:04.383166Z","steps":["trace[1404064325] 'agreement among raft nodes before linearized reading' (duration: 229.712795ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:04.38338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.984399ms","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-02-18T05:12:04.383463Z","caller":"traceutil/trace.go:171","msg":"trace[1960203009] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:309; }","duration":"280.067048ms","start":"2026-02-18T05:12:04.103388Z","end":"2026-02-18T05:12:04.383455Z","steps":["trace[1960203009] 'agreement among raft nodes before linearized reading' (duration: 279.967259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:04.3836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.665983ms","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-02-18T05:12:04.383661Z","caller":"traceutil/trace.go:171","msg":"trace[1703284834] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:309; }","duration":"329.765352ms","start":"2026-02-18T05:12:04.053885Z","end":"2026-02-18T05:12:04.383651Z","steps":["trace[1703284834] 'agreement among raft nodes before linearized reading' (duration: 329.693426ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:04.383698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:04.053862Z","time spent":"329.827777ms","remote":"127.0.0.1:54018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":256,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"info","ts":"2026-02-18T05:12:04.377627Z","caller":"traceutil/trace.go:171","msg":"trace[848948660] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"431.474435ms","start":"2026-02-18T05:12:03.946129Z","end":"2026-02-18T05:12:04.377604Z","steps":["trace[848948660] 'process raft request' (duration: 431.092641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:04.38396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:03.946112Z","time spent":"437.790782ms","remote":"127.0.0.1:53936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2162,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:12:04.38477Z","caller":"traceutil/trace.go:171","msg":"trace[874326259] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"437.009554ms","start":"2026-02-18T05:12:03.947752Z","end":"2026-02-18T05:12:04.384762Z","steps":["trace[874326259] 'process raft request' (duration: 429.576468ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:12:04.385179Z","caller":"traceutil/trace.go:171","msg":"trace[935088714] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"436.311663ms","start":"2026-02-18T05:12:03.948857Z","end":"2026-02-18T05:12:04.385168Z","steps":["trace[935088714] 'process raft request' (duration: 428.514265ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:04.398894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:03.94885Z","time spent":"449.971598ms","remote":"127.0.0.1:54004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4711,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:12:04.397447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:03.947748Z","time spent":"449.659619ms","remote":"127.0.0.1:53912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":649,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T05:12:22.473231Z","caller":"traceutil/trace.go:171","msg":"trace[1562763917] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"130.049689ms","start":"2026-02-18T05:12:22.34316Z","end":"2026-02-18T05:12:22.47321Z","steps":["trace[1562763917] 'process raft request' (duration: 57.306984ms)","trace[1562763917] 'compare' (duration: 72.592651ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:12:22.476627Z","caller":"traceutil/trace.go:171","msg":"trace[1894135920] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"118.839229ms","start":"2026-02-18T05:12:22.357775Z","end":"2026-02-18T05:12:22.476614Z","steps":["trace[1894135920] 'process raft request' (duration: 118.780844ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:12:22.614301Z","caller":"traceutil/trace.go:171","msg":"trace[409271962] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"126.423602ms","start":"2026-02-18T05:12:22.487859Z","end":"2026-02-18T05:12:22.614282Z","steps":["trace[409271962] 'process raft request' (duration: 119.141728ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:12:22.63663Z","caller":"traceutil/trace.go:171","msg":"trace[1130734212] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"125.901793ms","start":"2026-02-18T05:12:22.510708Z","end":"2026-02-18T05:12:22.63661Z","steps":["trace[1130734212] 'process raft request' (duration: 125.407366ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:12:22.637082Z","caller":"traceutil/trace.go:171","msg":"trace[1687517668] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"126.266597ms","start":"2026-02-18T05:12:22.510767Z","end":"2026-02-18T05:12:22.637033Z","steps":["trace[1687517668] 'process raft request' (duration: 125.393324ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:12:22.637221Z","caller":"traceutil/trace.go:171","msg":"trace[189427512] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"126.60266ms","start":"2026-02-18T05:12:22.510604Z","end":"2026-02-18T05:12:22.637206Z","steps":["trace[189427512] 'process raft request' (duration: 125.392034ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:22.823304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.396449ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:12:22.823487Z","caller":"traceutil/trace.go:171","msg":"trace[1185104026] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"133.490006ms","start":"2026-02-18T05:12:22.689987Z","end":"2026-02-18T05:12:22.823477Z","steps":["trace[1185104026] 'process raft request' (duration: 133.473224ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:12:22.823605Z","caller":"traceutil/trace.go:171","msg":"trace[2086825220] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"160.438055ms","start":"2026-02-18T05:12:22.663162Z","end":"2026-02-18T05:12:22.8236Z","steps":["trace[2086825220] 'process raft request' (duration: 52.614095ms)","trace[2086825220] 'compare' (duration: 107.282668ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:12:22.823717Z","caller":"traceutil/trace.go:171","msg":"trace[835796775] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"160.427744ms","start":"2026-02-18T05:12:22.663285Z","end":"2026-02-18T05:12:22.823712Z","steps":["trace[835796775] 'process raft request' (duration: 160.080161ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:12:22.823812Z","caller":"traceutil/trace.go:171","msg":"trace[1634461992] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"160.455406ms","start":"2026-02-18T05:12:22.663351Z","end":"2026-02-18T05:12:22.823807Z","steps":["trace[1634461992] 'process raft request' (duration: 160.059809ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:12:22.823869Z","caller":"traceutil/trace.go:171","msg":"trace[107269148] linearizableReadLoop","detail":"{readStateIndex:472; appliedIndex:469; }","duration":"140.03555ms","start":"2026-02-18T05:12:22.683829Z","end":"2026-02-18T05:12:22.823865Z","steps":["trace[107269148] 'read index received' (duration: 31.216946ms)","trace[107269148] 'applied index is now lower than readState.Index' (duration: 108.818044ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:12:22.823955Z","caller":"traceutil/trace.go:171","msg":"trace[308957847] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"136.78704ms","start":"2026-02-18T05:12:22.687164Z","end":"2026-02-18T05:12:22.823951Z","steps":["trace[308957847] 'process raft request' (duration: 136.275301ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:22.824145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.310496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-l9rrj\" ","response":"range_response_count:1 size:14921"} {"level":"info","ts":"2026-02-18T05:12:22.824166Z","caller":"traceutil/trace.go:171","msg":"trace[1696854665] range","detail":"{range_begin:/registry/pods/kube-system/cilium-l9rrj; range_end:; response_count:1; response_revision:461; }","duration":"140.356861ms","start":"2026-02-18T05:12:22.683804Z","end":"2026-02-18T05:12:22.824161Z","steps":["trace[1696854665] 'agreement among raft nodes before linearized reading' (duration: 140.276634ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:12:25.15321Z","caller":"traceutil/trace.go:171","msg":"trace[1684465278] linearizableReadLoop","detail":"{readStateIndex:488; appliedIndex:487; }","duration":"201.755989ms","start":"2026-02-18T05:12:24.95144Z","end":"2026-02-18T05:12:25.153196Z","steps":["trace[1684465278] 'read index received' (duration: 201.634497ms)","trace[1684465278] 'applied index is now lower than readState.Index' (duration: 120.912µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:12:25.153389Z","caller":"traceutil/trace.go:171","msg":"trace[839430932] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"287.950385ms","start":"2026-02-18T05:12:24.865433Z","end":"2026-02-18T05:12:25.153384Z","steps":["trace[839430932] 'process raft request' (duration: 287.68348ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:25.153513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.060368ms","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-02-18T05:12:25.153536Z","caller":"traceutil/trace.go:171","msg":"trace[1484393774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:474; }","duration":"202.113443ms","start":"2026-02-18T05:12:24.951415Z","end":"2026-02-18T05:12:25.153529Z","steps":["trace[1484393774] 'agreement among raft nodes before linearized reading' (duration: 202.068999ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:25.153599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.445054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-18T05:12:25.153623Z","caller":"traceutil/trace.go:171","msg":"trace[325670825] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:474; }","duration":"195.479627ms","start":"2026-02-18T05:12:24.95814Z","end":"2026-02-18T05:12:25.15362Z","steps":["trace[325670825] 'agreement among raft nodes before linearized reading' (duration: 195.446844ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:12:31.323223Z","caller":"traceutil/trace.go:171","msg":"trace[829220342] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:528; }","duration":"114.420951ms","start":"2026-02-18T05:12:31.208788Z","end":"2026-02-18T05:12:31.323209Z","steps":["trace[829220342] 'read index received' (duration: 82.96997ms)","trace[829220342] 'applied index is now lower than readState.Index' (duration: 31.45025ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:12:31.323399Z","caller":"traceutil/trace.go:171","msg":"trace[904120023] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"122.09788ms","start":"2026-02-18T05:12:31.201295Z","end":"2026-02-18T05:12:31.323392Z","steps":["trace[904120023] 'process raft request' (duration: 121.815163ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:31.323575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.773036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T05:12:31.323603Z","caller":"traceutil/trace.go:171","msg":"trace[51759574] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:515; }","duration":"114.841182ms","start":"2026-02-18T05:12:31.208755Z","end":"2026-02-18T05:12:31.323596Z","steps":["trace[51759574] 'agreement among raft nodes before linearized reading' (duration: 114.746763ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:31.323715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.338322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-18T05:12:31.323736Z","caller":"traceutil/trace.go:171","msg":"trace[1774604160] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:515; }","duration":"114.372057ms","start":"2026-02-18T05:12:31.209359Z","end":"2026-02-18T05:12:31.323731Z","steps":["trace[1774604160] 'agreement among raft nodes before linearized reading' (duration: 114.323502ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:32.492591Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.078458936s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T05:12:32.493394Z","caller":"traceutil/trace.go:171","msg":"trace[1383376482] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:532; }","duration":"239.173191ms","start":"2026-02-18T05:12:32.254202Z","end":"2026-02-18T05:12:32.493375Z","steps":["trace[1383376482] 'read index received' (duration: 238.498225ms)","trace[1383376482] 'applied index is now lower than readState.Index' (duration: 674.046µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:12:32.493496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.276842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:12:32.49353Z","caller":"traceutil/trace.go:171","msg":"trace[1742153274] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:518; }","duration":"239.349869ms","start":"2026-02-18T05:12:32.254171Z","end":"2026-02-18T05:12:32.493521Z","steps":["trace[1742153274] 'agreement among raft nodes before linearized reading' (duration: 239.266771ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:12:32.493808Z","caller":"traceutil/trace.go:171","msg":"trace[1865899235] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"1.079763464s","start":"2026-02-18T05:12:31.414033Z","end":"2026-02-18T05:12:32.493797Z","steps":["trace[1865899235] 'process raft request' (duration: 1.078741154s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:32.493887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:31.41402Z","time spent":"1.079819419s","remote":"127.0.0.1:54004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:12:41.003281Z","caller":"traceutil/trace.go:171","msg":"trace[22032087] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"109.428582ms","start":"2026-02-18T05:12:40.893828Z","end":"2026-02-18T05:12:41.003257Z","steps":["trace[22032087] 'process raft request' (duration: 109.214671ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:12:52.819868Z","caller":"traceutil/trace.go:171","msg":"trace[1121102877] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"159.967896ms","start":"2026-02-18T05:12:52.659878Z","end":"2026-02-18T05:12:52.819845Z","steps":["trace[1121102877] 'process raft request' (duration: 159.862574ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:13:01.461921Z","caller":"traceutil/trace.go:171","msg":"trace[190185278] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"129.030838ms","start":"2026-02-18T05:13:01.332391Z","end":"2026-02-18T05:13:01.461422Z","steps":["trace[190185278] 'process raft request' (duration: 128.59724ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:13:01.625383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.486506ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:13:01.62545Z","caller":"traceutil/trace.go:171","msg":"trace[158696360] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"156.958144ms","start":"2026-02-18T05:13:01.46848Z","end":"2026-02-18T05:13:01.625438Z","steps":["trace[158696360] 'process raft request' (duration: 47.357754ms)","trace[158696360] 'compare' (duration: 109.414459ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:13:17.984859Z","caller":"traceutil/trace.go:171","msg":"trace[982712145] linearizableReadLoop","detail":"{readStateIndex:818; appliedIndex:817; }","duration":"108.473729ms","start":"2026-02-18T05:13:17.876366Z","end":"2026-02-18T05:13:17.98484Z","steps":["trace[982712145] 'read index received' (duration: 77.365301ms)","trace[982712145] 'applied index is now lower than readState.Index' (duration: 31.107658ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:13:17.985002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.618297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-18T05:13:17.984997Z","caller":"traceutil/trace.go:171","msg":"trace[894013043] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"111.627378ms","start":"2026-02-18T05:13:17.87335Z","end":"2026-02-18T05:13:17.984977Z","steps":["trace[894013043] 'process raft request' (duration: 80.448451ms)","trace[894013043] 'compare' (duration: 30.952761ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:13:17.985035Z","caller":"traceutil/trace.go:171","msg":"trace[1755833432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:792; }","duration":"108.664286ms","start":"2026-02-18T05:13:17.876362Z","end":"2026-02-18T05:13:17.985026Z","steps":["trace[1755833432] 'agreement among raft nodes before linearized reading' (duration: 108.555958ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:13:23.791621Z","caller":"traceutil/trace.go:171","msg":"trace[1562020345] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"102.43216ms","start":"2026-02-18T05:13:23.689173Z","end":"2026-02-18T05:13:23.791605Z","steps":["trace[1562020345] 'process raft request' (duration: 102.314942ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:13:24.166515Z","caller":"traceutil/trace.go:171","msg":"trace[1990177768] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"134.392172ms","start":"2026-02-18T05:13:24.032079Z","end":"2026-02-18T05:13:24.166471Z","steps":["trace[1990177768] 'process raft request' (duration: 96.753612ms)","trace[1990177768] 'compare' (duration: 37.486891ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:13:27.16121Z","caller":"traceutil/trace.go:171","msg":"trace[429270524] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"157.651097ms","start":"2026-02-18T05:13:27.003541Z","end":"2026-02-18T05:13:27.161192Z","steps":["trace[429270524] 'process raft request' (duration: 157.490128ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:13:51.462632Z","caller":"traceutil/trace.go:171","msg":"trace[1846678095] linearizableReadLoop","detail":"{readStateIndex:949; appliedIndex:948; }","duration":"215.345949ms","start":"2026-02-18T05:13:51.247249Z","end":"2026-02-18T05:13:51.462594Z","steps":["trace[1846678095] 'read index received' (duration: 118.2828ms)","trace[1846678095] 'applied index is now lower than readState.Index' (duration: 97.061869ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:13:51.462644Z","caller":"traceutil/trace.go:171","msg":"trace[165183441] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"215.696387ms","start":"2026-02-18T05:13:51.246915Z","end":"2026-02-18T05:13:51.462611Z","steps":["trace[165183441] 'process raft request' (duration: 118.678829ms)","trace[165183441] 'compare' (duration: 96.024565ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:13:51.463224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.968853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1536"} {"level":"info","ts":"2026-02-18T05:13:51.463307Z","caller":"traceutil/trace.go:171","msg":"trace[1267616918] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:914; }","duration":"216.084996ms","start":"2026-02-18T05:13:51.247209Z","end":"2026-02-18T05:13:51.463294Z","steps":["trace[1267616918] 'agreement among raft nodes before linearized reading' (duration: 215.522513ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:13:51.463608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.738499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-18T05:13:51.463735Z","caller":"traceutil/trace.go:171","msg":"trace[1503822937] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:914; }","duration":"207.852891ms","start":"2026-02-18T05:13:51.255833Z","end":"2026-02-18T05:13:51.463686Z","steps":["trace[1503822937] 'agreement among raft nodes before linearized reading' (duration: 207.636186ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:13:51.464098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.346556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-02-18T05:13:51.46419Z","caller":"traceutil/trace.go:171","msg":"trace[1116222068] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:914; }","duration":"203.445868ms","start":"2026-02-18T05:13:51.260728Z","end":"2026-02-18T05:13:51.464174Z","steps":["trace[1116222068] 'agreement among raft nodes before linearized reading' (duration: 203.301004ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:13:54.319088Z","caller":"traceutil/trace.go:171","msg":"trace[82815692] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"312.194112ms","start":"2026-02-18T05:13:54.006869Z","end":"2026-02-18T05:13:54.319063Z","steps":["trace[82815692] 'process raft request' (duration: 311.873874ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:13:54.319459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:13:54.006804Z","time spent":"312.554801ms","remote":"127.0.0.1:54086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:13:54.319815Z","caller":"traceutil/trace.go:171","msg":"trace[2015266036] linearizableReadLoop","detail":"{readStateIndex:963; appliedIndex:962; }","duration":"261.7511ms","start":"2026-02-18T05:13:54.05805Z","end":"2026-02-18T05:13:54.319801Z","steps":["trace[2015266036] 'read index received' (duration: 261.662397ms)","trace[2015266036] 'applied index is now lower than readState.Index' (duration: 87.613µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:13:54.319906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.852283ms","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":"info","ts":"2026-02-18T05:13:54.321759Z","caller":"traceutil/trace.go:171","msg":"trace[1099595383] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:927; }","duration":"263.742863ms","start":"2026-02-18T05:13:54.058007Z","end":"2026-02-18T05:13:54.32175Z","steps":["trace[1099595383] 'agreement among raft nodes before linearized reading' (duration: 261.836623ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:13:54.319998Z","caller":"traceutil/trace.go:171","msg":"trace[1680858772] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"310.309032ms","start":"2026-02-18T05:13:54.009679Z","end":"2026-02-18T05:13:54.319988Z","steps":["trace[1680858772] 'process raft request' (duration: 310.062735ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:13:54.321543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.606128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T05:13:54.322712Z","caller":"traceutil/trace.go:171","msg":"trace[1731587814] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:927; }","duration":"167.81409ms","start":"2026-02-18T05:13:54.154891Z","end":"2026-02-18T05:13:54.322705Z","steps":["trace[1731587814] 'agreement among raft nodes before linearized reading' (duration: 166.580958ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:13:54.322662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:13:54.009665Z","time spent":"312.968373ms","remote":"127.0.0.1:54086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:14:02.018556Z","caller":"traceutil/trace.go:171","msg":"trace[1262016760] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"414.613597ms","start":"2026-02-18T05:14:01.603915Z","end":"2026-02-18T05:14:02.018529Z","steps":["trace[1262016760] 'process raft request' (duration: 414.453101ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:02.019046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:01.603878Z","time spent":"415.063311ms","remote":"127.0.0.1:54086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:14:02.019748Z","caller":"traceutil/trace.go:171","msg":"trace[1049311958] linearizableReadLoop","detail":"{readStateIndex:990; appliedIndex:990; }","duration":"158.334876ms","start":"2026-02-18T05:14:01.861398Z","end":"2026-02-18T05:14:02.019733Z","steps":["trace[1049311958] 'read index received' (duration: 158.329085ms)","trace[1049311958] 'applied index is now lower than readState.Index' (duration: 4.701µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:14:02.021665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.520892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T05:14:02.022062Z","caller":"traceutil/trace.go:171","msg":"trace[1394633958] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:953; }","duration":"160.725155ms","start":"2026-02-18T05:14:01.86132Z","end":"2026-02-18T05:14:02.022045Z","steps":["trace[1394633958] 'agreement among raft nodes before linearized reading' (duration: 158.560353ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:02.892103Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995349620063647859,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:14:03.392244Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995349620063647859,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:14:03.777506Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.40294256s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T05:14:03.778723Z","caller":"traceutil/trace.go:171","msg":"trace[1956656300] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"1.404232145s","start":"2026-02-18T05:14:02.374456Z","end":"2026-02-18T05:14:03.778689Z","steps":["trace[1956656300] 'process raft request' (duration: 1.403949655s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:03.781203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:02.374437Z","time spent":"1.406649646s","remote":"127.0.0.1:54086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:14:03.800835Z","caller":"traceutil/trace.go:171","msg":"trace[1911930211] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"890.057552ms","start":"2026-02-18T05:14:02.91077Z","end":"2026-02-18T05:14:03.800827Z","steps":["trace[1911930211] 'process raft request' (duration: 889.752082ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:03.800975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:02.910732Z","time spent":"890.146105ms","remote":"127.0.0.1:54002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5217,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:14:03.800771Z","caller":"traceutil/trace.go:171","msg":"trace[1798736801] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"1.2915282s","start":"2026-02-18T05:14:02.509219Z","end":"2026-02-18T05:14:03.800747Z","steps":["trace[1798736801] 'process raft request' (duration: 1.291183128s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:03.802056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:02.5092Z","time spent":"1.292828923s","remote":"127.0.0.1:54086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:14:03.809587Z","caller":"traceutil/trace.go:171","msg":"trace[1563034005] linearizableReadLoop","detail":"{readStateIndex:993; appliedIndex:990; }","duration":"1.418488493s","start":"2026-02-18T05:14:02.391082Z","end":"2026-02-18T05:14:03.809571Z","steps":["trace[1563034005] 'read index received' (duration: 1.387548949s)","trace[1563034005] 'applied index is now lower than readState.Index' (duration: 30.938534ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:14:03.809905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.418823684s","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-02-18T05:14:03.809931Z","caller":"traceutil/trace.go:171","msg":"trace[359395375] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:956; }","duration":"1.418876527s","start":"2026-02-18T05:14:02.391048Z","end":"2026-02-18T05:14:03.809924Z","steps":["trace[359395375] 'agreement among raft nodes before linearized reading' (duration: 1.418781113s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:03.809948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:02.391031Z","time spent":"1.418913437s","remote":"127.0.0.1:54086","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-02-18T05:14:03.810055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.409379871s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-18T05:14:03.810067Z","caller":"traceutil/trace.go:171","msg":"trace[1472115342] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:956; }","duration":"1.409459983s","start":"2026-02-18T05:14:02.400603Z","end":"2026-02-18T05:14:03.810063Z","steps":["trace[1472115342] 'agreement among raft nodes before linearized reading' (duration: 1.409417102s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:03.810087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:02.400566Z","time spent":"1.409509725s","remote":"127.0.0.1:54086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T05:14:03.810191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.146642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:14:03.810203Z","caller":"traceutil/trace.go:171","msg":"trace[1510728316] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:956; }","duration":"551.199903ms","start":"2026-02-18T05:14:03.258999Z","end":"2026-02-18T05:14:03.810199Z","steps":["trace[1510728316] 'agreement among raft nodes before linearized reading' (duration: 551.180322ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:03.810215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:03.258978Z","time spent":"551.234694ms","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T05:14:03.810307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.407882701s","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-02-18T05:14:03.810319Z","caller":"traceutil/trace.go:171","msg":"trace[749485171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:956; }","duration":"1.407907602s","start":"2026-02-18T05:14:02.402407Z","end":"2026-02-18T05:14:03.810315Z","steps":["trace[749485171] 'agreement among raft nodes before linearized reading' (duration: 1.407868621s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:03.810333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:02.402399Z","time spent":"1.407931022s","remote":"127.0.0.1:54086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-18T05:14:07.984858Z","caller":"traceutil/trace.go:171","msg":"trace[1114037745] linearizableReadLoop","detail":"{readStateIndex:1009; appliedIndex:1008; }","duration":"138.209479ms","start":"2026-02-18T05:14:07.846628Z","end":"2026-02-18T05:14:07.984838Z","steps":["trace[1114037745] 'read index received' (duration: 138.065113ms)","trace[1114037745] 'applied index is now lower than readState.Index' (duration: 143.506µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:14:07.98647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.760336ms","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-02-18T05:14:07.987785Z","caller":"traceutil/trace.go:171","msg":"trace[936760425] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:970; }","duration":"141.172929ms","start":"2026-02-18T05:14:07.846575Z","end":"2026-02-18T05:14:07.987748Z","steps":["trace[936760425] 'agreement among raft nodes before linearized reading' (duration: 138.570552ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:07.988213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.711878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-18T05:14:07.988374Z","caller":"traceutil/trace.go:171","msg":"trace[786816625] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:970; }","duration":"138.894154ms","start":"2026-02-18T05:14:07.84947Z","end":"2026-02-18T05:14:07.988364Z","steps":["trace[786816625] 'agreement among raft nodes before linearized reading' (duration: 138.624514ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:07.986877Z","caller":"traceutil/trace.go:171","msg":"trace[172607361] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"181.789859ms","start":"2026-02-18T05:14:07.805062Z","end":"2026-02-18T05:14:07.986852Z","steps":["trace[172607361] 'process raft request' (duration: 179.685971ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:07.988895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.356124ms","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-02-18T05:14:07.988936Z","caller":"traceutil/trace.go:171","msg":"trace[1235569435] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:970; }","duration":"138.466908ms","start":"2026-02-18T05:14:07.850459Z","end":"2026-02-18T05:14:07.988926Z","steps":["trace[1235569435] 'agreement among raft nodes before linearized reading' (duration: 138.278241ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:08.210488Z","caller":"traceutil/trace.go:171","msg":"trace[811688888] linearizableReadLoop","detail":"{readStateIndex:1012; appliedIndex:1009; }","duration":"168.030652ms","start":"2026-02-18T05:14:08.042435Z","end":"2026-02-18T05:14:08.210465Z","steps":["trace[811688888] 'read index received' (duration: 96.494569ms)","trace[811688888] 'applied index is now lower than readState.Index' (duration: 71.534903ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:14:08.21069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.23858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-18T05:14:08.210728Z","caller":"traceutil/trace.go:171","msg":"trace[630025207] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:973; }","duration":"168.342054ms","start":"2026-02-18T05:14:08.042376Z","end":"2026-02-18T05:14:08.210718Z","steps":["trace[630025207] 'agreement among raft nodes before linearized reading' (duration: 168.175277ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:08.210954Z","caller":"traceutil/trace.go:171","msg":"trace[246177970] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"212.814857ms","start":"2026-02-18T05:14:07.998129Z","end":"2026-02-18T05:14:08.210944Z","steps":["trace[246177970] 'process raft request' (duration: 212.264856ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:08.211099Z","caller":"traceutil/trace.go:171","msg":"trace[519058788] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"213.050087ms","start":"2026-02-18T05:14:07.998037Z","end":"2026-02-18T05:14:08.211087Z","steps":["trace[519058788] 'process raft request' (duration: 212.395812ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:08.210948Z","caller":"traceutil/trace.go:171","msg":"trace[1755299616] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"212.654741ms","start":"2026-02-18T05:14:07.997862Z","end":"2026-02-18T05:14:08.210516Z","steps":["trace[1755299616] 'process raft request' (duration: 141.059966ms)","trace[1755299616] 'compare' (duration: 71.305724ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:14:10.401102Z","caller":"traceutil/trace.go:171","msg":"trace[1615106919] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"183.489234ms","start":"2026-02-18T05:14:10.217594Z","end":"2026-02-18T05:14:10.401083Z","steps":["trace[1615106919] 'process raft request' (duration: 183.366619ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:10.600811Z","caller":"traceutil/trace.go:171","msg":"trace[21138395] linearizableReadLoop","detail":"{readStateIndex:1019; appliedIndex:1018; }","duration":"380.910746ms","start":"2026-02-18T05:14:10.219876Z","end":"2026-02-18T05:14:10.600787Z","steps":["trace[21138395] 'read index received' (duration: 181.566498ms)","trace[21138395] 'applied index is now lower than readState.Index' (duration: 199.343058ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:14:10.601227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.717934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:14:10.601278Z","caller":"traceutil/trace.go:171","msg":"trace[1274607604] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:979; }","duration":"102.816288ms","start":"2026-02-18T05:14:10.498447Z","end":"2026-02-18T05:14:10.601264Z","steps":["trace[1274607604] 'agreement among raft nodes before linearized reading' (duration: 102.726914ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:10.601475Z","caller":"traceutil/trace.go:171","msg":"trace[1466917609] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"381.74636ms","start":"2026-02-18T05:14:10.219717Z","end":"2026-02-18T05:14:10.601463Z","steps":["trace[1466917609] 'process raft request' (duration: 335.700407ms)","trace[1466917609] 'compare' (duration: 45.271962ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:14:10.601563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:10.219705Z","time spent":"381.802002ms","remote":"127.0.0.1:54086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:14:10.60136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.30627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:14:10.60295Z","caller":"traceutil/trace.go:171","msg":"trace[633458670] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:979; }","duration":"353.167084ms","start":"2026-02-18T05:14:10.249769Z","end":"2026-02-18T05:14:10.602936Z","steps":["trace[633458670] 'agreement among raft nodes before linearized reading' (duration: 351.30328ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:10.602993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:10.249747Z","time spent":"353.236277ms","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T05:14:10.601791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.912325ms","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-02-18T05:14:10.60317Z","caller":"traceutil/trace.go:171","msg":"trace[357296161] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:979; }","duration":"383.307351ms","start":"2026-02-18T05:14:10.219853Z","end":"2026-02-18T05:14:10.60316Z","steps":["trace[357296161] 'agreement among raft nodes before linearized reading' (duration: 381.838623ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:10.603207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:10.219844Z","time spent":"383.353813ms","remote":"127.0.0.1:54086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T05:14:10.602163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.600941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:basic-user\" ","response":"range_response_count:1 size:678"} {"level":"info","ts":"2026-02-18T05:14:10.603362Z","caller":"traceutil/trace.go:171","msg":"trace[1935767153] range","detail":"{range_begin:/registry/clusterroles/system:basic-user; range_end:; response_count:1; response_revision:979; }","duration":"338.809669ms","start":"2026-02-18T05:14:10.264544Z","end":"2026-02-18T05:14:10.603354Z","steps":["trace[1935767153] 'agreement among raft nodes before linearized reading' (duration: 337.583001ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:10.603404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:10.264539Z","time spent":"338.854181ms","remote":"127.0.0.1:54148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":700,"request content":"key:\"/registry/clusterroles/system:basic-user\" "} {"level":"warn","ts":"2026-02-18T05:14:10.602249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.700059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-18T05:14:10.603991Z","caller":"traceutil/trace.go:171","msg":"trace[593475645] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:979; }","duration":"347.446269ms","start":"2026-02-18T05:14:10.256534Z","end":"2026-02-18T05:14:10.60398Z","steps":["trace[593475645] 'agreement among raft nodes before linearized reading' (duration: 345.662059ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:10.604039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:10.256529Z","time spent":"347.497751ms","remote":"127.0.0.1:54086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T05:14:10.602875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.109029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:nodeclient\" ","response":"range_response_count:1 size:706"} {"level":"info","ts":"2026-02-18T05:14:10.604183Z","caller":"traceutil/trace.go:171","msg":"trace[763808593] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:nodeclient; range_end:; response_count:1; response_revision:979; }","duration":"312.358418ms","start":"2026-02-18T05:14:10.291815Z","end":"2026-02-18T05:14:10.604173Z","steps":["trace[763808593] 'agreement among raft nodes before linearized reading' (duration: 310.092138ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:10.604223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:10.291802Z","time spent":"312.411309ms","remote":"127.0.0.1:54148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":1,"response size":728,"request content":"key:\"/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:nodeclient\" "}