{"level":"warn","ts":"2026-02-27T20:29:31.992319Z","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-27T20:29:31.992416Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.122:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.122:2380","--initial-cluster=instance=https://199.204.45.122:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.122:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.122: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-27T20:29:31.992488Z","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-27T20:29:31.992499Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.122:2380"]} {"level":"info","ts":"2026-02-27T20:29:31.99252Z","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-27T20:29:31.993138Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.122:2379"]} {"level":"info","ts":"2026-02-27T20:29:31.993215Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.122:2380"],"listen-peer-urls":["https://199.204.45.122:2380"],"advertise-client-urls":["https://199.204.45.122:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.122:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.122: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-27T20:29:31.997948Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.510336ms"} {"level":"info","ts":"2026-02-27T20:29:32.005452Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d2c3f297b56db0c4","cluster-id":"becafec23d27c587"} {"level":"info","ts":"2026-02-27T20:29:32.005511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 switched to configuration voters=()"} {"level":"info","ts":"2026-02-27T20:29:32.005646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became follower at term 0"} {"level":"info","ts":"2026-02-27T20:29:32.005663Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d2c3f297b56db0c4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-27T20:29:32.005679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became follower at term 1"} {"level":"info","ts":"2026-02-27T20:29:32.005717Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 switched to configuration voters=(15187249101821292740)"} {"level":"warn","ts":"2026-02-27T20:29:32.012322Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-27T20:29:32.014494Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-27T20:29:32.015788Z","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-27T20:29:32.018925Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d2c3f297b56db0c4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-27T20:29:32.019787Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d2c3f297b56db0c4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-27T20:29:32.02015Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 switched to configuration voters=(15187249101821292740)"} {"level":"info","ts":"2026-02-27T20:29:32.020259Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"becafec23d27c587","local-member-id":"d2c3f297b56db0c4","added-peer-id":"d2c3f297b56db0c4","added-peer-peer-urls":["https://199.204.45.122:2380"]} {"level":"info","ts":"2026-02-27T20:29:32.020726Z","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-27T20:29:32.021074Z","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-27T20:29:32.021105Z","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-27T20:29:32.024289Z","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-27T20:29:32.024406Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.122:2380"} {"level":"info","ts":"2026-02-27T20:29:32.024457Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.122:2380"} {"level":"info","ts":"2026-02-27T20:29:32.025803Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d2c3f297b56db0c4","initial-advertise-peer-urls":["https://199.204.45.122:2380"],"listen-peer-urls":["https://199.204.45.122:2380"],"advertise-client-urls":["https://199.204.45.122:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.122:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-27T20:29:32.025847Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-27T20:29:32.907236Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 is starting a new election at term 1"} {"level":"info","ts":"2026-02-27T20:29:32.907326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-27T20:29:32.907353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 received MsgPreVoteResp from d2c3f297b56db0c4 at term 1"} {"level":"info","ts":"2026-02-27T20:29:32.907374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became candidate at term 2"} {"level":"info","ts":"2026-02-27T20:29:32.907394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 received MsgVoteResp from d2c3f297b56db0c4 at term 2"} {"level":"info","ts":"2026-02-27T20:29:32.907417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became leader at term 2"} {"level":"info","ts":"2026-02-27T20:29:32.907431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d2c3f297b56db0c4 elected leader d2c3f297b56db0c4 at term 2"} {"level":"info","ts":"2026-02-27T20:29:32.908526Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T20:29:32.909277Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d2c3f297b56db0c4","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.122:2379]}","request-path":"/0/members/d2c3f297b56db0c4/attributes","cluster-id":"becafec23d27c587","publish-timeout":"7s"} {"level":"info","ts":"2026-02-27T20:29:32.909318Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-27T20:29:32.909279Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-27T20:29:32.909698Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"becafec23d27c587","local-member-id":"d2c3f297b56db0c4","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T20:29:32.909823Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T20:29:32.909875Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T20:29:32.910159Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-27T20:29:32.910269Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-27T20:29:32.913151Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-27T20:29:32.913565Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.122:2379"} {"level":"warn","ts":"2026-02-27T20:29:37.063176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.218185ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-27T20:29:37.063446Z","caller":"traceutil/trace.go:171","msg":"trace[1525155180] linearizableReadLoop","detail":"{readStateIndex:218; appliedIndex:216; }","duration":"407.179016ms","start":"2026-02-27T20:29:36.656245Z","end":"2026-02-27T20:29:37.063424Z","steps":["trace[1525155180] 'read index received' (duration: 115.557116ms)","trace[1525155180] 'applied index is now lower than readState.Index' (duration: 291.62061ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:29:37.063582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.336981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:29:37.06361Z","caller":"traceutil/trace.go:171","msg":"trace[194714884] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:212; }","duration":"407.429303ms","start":"2026-02-27T20:29:36.656171Z","end":"2026-02-27T20:29:37.0636Z","steps":["trace[194714884] 'agreement among raft nodes before linearized reading' (duration: 407.32463ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:37.063649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:36.656142Z","time spent":"407.498665ms","remote":"127.0.0.1:42182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-27T20:29:37.063736Z","caller":"traceutil/trace.go:171","msg":"trace[480550996] transaction","detail":"{read_only:false; response_revision:211; number_of_response:1; }","duration":"539.166766ms","start":"2026-02-27T20:29:36.524519Z","end":"2026-02-27T20:29:37.063686Z","steps":["trace[480550996] 'process raft request' (duration: 247.273019ms)","trace[480550996] 'compare' (duration: 289.944806ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:29:37.063674Z","caller":"traceutil/trace.go:171","msg":"trace[562485713] transaction","detail":"{read_only:false; response_revision:212; number_of_response:1; }","duration":"537.411671ms","start":"2026-02-27T20:29:36.526206Z","end":"2026-02-27T20:29:37.063618Z","steps":["trace[562485713] 'process raft request' (duration: 537.123443ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:37.064022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:36.524503Z","time spent":"539.393512ms","remote":"127.0.0.1:41998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11440,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T20:29:37.064094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:36.52619Z","time spent":"537.762269ms","remote":"127.0.0.1:42120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":205,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T20:29:37.157282Z","caller":"traceutil/trace.go:171","msg":"trace[1466524439] transaction","detail":"{read_only:false; response_revision:213; number_of_response:1; }","duration":"215.629323ms","start":"2026-02-27T20:29:36.941619Z","end":"2026-02-27T20:29:37.157248Z","steps":["trace[1466524439] 'process raft request' (duration: 215.485568ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:29:37.369566Z","caller":"traceutil/trace.go:171","msg":"trace[1816566954] linearizableReadLoop","detail":"{readStateIndex:220; appliedIndex:218; }","duration":"298.753115ms","start":"2026-02-27T20:29:37.070779Z","end":"2026-02-27T20:29:37.369532Z","steps":["trace[1816566954] 'read index received' (duration: 86.281062ms)","trace[1816566954] 'applied index is now lower than readState.Index' (duration: 212.470923ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:29:37.36959Z","caller":"traceutil/trace.go:171","msg":"trace[1911597803] transaction","detail":"{read_only:false; response_revision:214; number_of_response:1; }","duration":"301.784603ms","start":"2026-02-27T20:29:37.067766Z","end":"2026-02-27T20:29:37.369551Z","steps":["trace[1911597803] 'process raft request' (duration: 299.784311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:37.369733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.934629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T20:29:37.369759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:37.067738Z","time spent":"301.939567ms","remote":"127.0.0.1:42182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T20:29:37.369867Z","caller":"traceutil/trace.go:171","msg":"trace[2113096839] transaction","detail":"{read_only:false; response_revision:215; number_of_response:1; }","duration":"298.800396ms","start":"2026-02-27T20:29:37.07099Z","end":"2026-02-27T20:29:37.36979Z","steps":["trace[2113096839] 'process raft request' (duration: 298.487769ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:29:37.370405Z","caller":"traceutil/trace.go:171","msg":"trace[493550522] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:0; response_revision:215; }","duration":"299.097083ms","start":"2026-02-27T20:29:37.070678Z","end":"2026-02-27T20:29:37.369775Z","steps":["trace[493550522] 'agreement among raft nodes before linearized reading' (duration: 298.97091ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:30:07.758976Z","caller":"traceutil/trace.go:171","msg":"trace[1705738398] linearizableReadLoop","detail":"{readStateIndex:508; appliedIndex:507; }","duration":"225.29754ms","start":"2026-02-27T20:30:07.533652Z","end":"2026-02-27T20:30:07.75895Z","steps":["trace[1705738398] 'read index received' (duration: 225.025963ms)","trace[1705738398] 'applied index is now lower than readState.Index' (duration: 270.537µs)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:30:07.759247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.528516ms","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-02-27T20:30:07.759296Z","caller":"traceutil/trace.go:171","msg":"trace[85960395] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:491; }","duration":"225.6842ms","start":"2026-02-27T20:30:07.533602Z","end":"2026-02-27T20:30:07.759286Z","steps":["trace[85960395] 'agreement among raft nodes before linearized reading' (duration: 225.454225ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:30:07.759548Z","caller":"traceutil/trace.go:171","msg":"trace[1030488709] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"290.718547ms","start":"2026-02-27T20:30:07.468765Z","end":"2026-02-27T20:30:07.759484Z","steps":["trace[1030488709] 'process raft request' (duration: 290.015449ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:30:08.206214Z","caller":"traceutil/trace.go:171","msg":"trace[1820997300] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"157.289277ms","start":"2026-02-27T20:30:08.048899Z","end":"2026-02-27T20:30:08.206188Z","steps":["trace[1820997300] 'process raft request' (duration: 157.099392ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:30:57.619993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.946447ms","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-02-27T20:30:57.620095Z","caller":"traceutil/trace.go:171","msg":"trace[230201395] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:769; }","duration":"628.112311ms","start":"2026-02-27T20:30:56.991962Z","end":"2026-02-27T20:30:57.620074Z","steps":["trace[230201395] 'range keys from in-memory index tree' (duration: 627.736223ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:30:57.620141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:30:56.99194Z","time spent":"628.191924ms","remote":"127.0.0.1:42182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-27T20:30:57.620144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.841942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T20:30:57.620163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.681634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:30:57.620233Z","caller":"traceutil/trace.go:171","msg":"trace[637459113] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:769; }","duration":"398.010546ms","start":"2026-02-27T20:30:57.222204Z","end":"2026-02-27T20:30:57.620215Z","steps":["trace[637459113] 'count revisions from in-memory index tree' (duration: 397.710198ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:30:57.62026Z","caller":"traceutil/trace.go:171","msg":"trace[450571904] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:769; }","duration":"618.809666ms","start":"2026-02-27T20:30:57.001427Z","end":"2026-02-27T20:30:57.620237Z","steps":["trace[450571904] 'range keys from in-memory index tree' (duration: 618.599111ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:30:57.620303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:30:57.001413Z","time spent":"618.881478ms","remote":"127.0.0.1:41900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T20:30:57.620303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:30:57.22217Z","time spent":"398.121628ms","remote":"127.0.0.1:42270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-02-27T20:31:02.662532Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737477860521929926,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T20:31:03.163651Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737477860521929926,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T20:31:03.66477Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737477860521929926,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T20:31:04.165587Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737477860521929926,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T20:31:04.666591Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737477860521929926,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T20:31:05.000842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000240881s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-27T20:31:05.000941Z","caller":"traceutil/trace.go:171","msg":"trace[833412232] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000394535s","start":"2026-02-27T20:31:03.000527Z","end":"2026-02-27T20:31:05.000921Z","steps":["trace[833412232] 'agreement among raft nodes before linearized reading' (duration: 2.000279092s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:05.000994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:03.000506Z","time spent":"2.000478307s","remote":"127.0.0.1:41900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/27 20:31:05 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-27T20:31:05.16707Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737477860521929926,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T20:31:05.667253Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737477860521929926,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T20:31:06.167881Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737477860521929926,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T20:31:06.44475Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.283592718s","expected-duration":"1s"} {"level":"info","ts":"2026-02-27T20:31:06.445215Z","caller":"traceutil/trace.go:171","msg":"trace[1806382958] linearizableReadLoop","detail":"{readStateIndex:811; appliedIndex:810; }","duration":"4.283242069s","start":"2026-02-27T20:31:02.161941Z","end":"2026-02-27T20:31:06.445183Z","steps":["trace[1806382958] 'read index received' (duration: 4.282964891s)","trace[1806382958] 'applied index is now lower than readState.Index' (duration: 276.318µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:31:06.445367Z","caller":"traceutil/trace.go:171","msg":"trace[1986573441] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"4.284211253s","start":"2026-02-27T20:31:02.161066Z","end":"2026-02-27T20:31:06.445277Z","steps":["trace[1986573441] 'process raft request' (duration: 4.283895165s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:06.445403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.042822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-27T20:31:06.445458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.799418324s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-27T20:31:06.445464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.283513925s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-27T20:31:06.445516Z","caller":"traceutil/trace.go:171","msg":"trace[684198828] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:782; }","duration":"2.799554527s","start":"2026-02-27T20:31:03.645947Z","end":"2026-02-27T20:31:06.445502Z","steps":["trace[684198828] 'agreement among raft nodes before linearized reading' (duration: 2.799390643s)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:31:06.44553Z","caller":"traceutil/trace.go:171","msg":"trace[1445311239] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:782; }","duration":"4.28365975s","start":"2026-02-27T20:31:02.161855Z","end":"2026-02-27T20:31:06.445515Z","steps":["trace[1445311239] 'agreement among raft nodes before linearized reading' (duration: 4.283442245s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:06.445564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:03.645914Z","time spent":"2.799635829s","remote":"127.0.0.1:42182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-27T20:31:06.445583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:02.161819Z","time spent":"4.283753641s","remote":"127.0.0.1:42182","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":"warn","ts":"2026-02-27T20:31:06.445643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.229046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.122\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-27T20:31:06.44548Z","caller":"traceutil/trace.go:171","msg":"trace[100335499] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:782; }","duration":"794.179646ms","start":"2026-02-27T20:31:05.651282Z","end":"2026-02-27T20:31:06.445462Z","steps":["trace[100335499] 'agreement among raft nodes before linearized reading' (duration: 794.032802ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:31:06.445691Z","caller":"traceutil/trace.go:171","msg":"trace[1458243127] range","detail":"{range_begin:/registry/masterleases/199.204.45.122; range_end:; response_count:1; response_revision:782; }","duration":"491.317048ms","start":"2026-02-27T20:31:05.954363Z","end":"2026-02-27T20:31:06.44568Z","steps":["trace[1458243127] 'agreement among raft nodes before linearized reading' (duration: 491.158274ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:06.44571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:05.651261Z","time spent":"794.434451ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":13,"response size":31,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-02-27T20:31:06.445734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:05.954339Z","time spent":"491.386529ms","remote":"127.0.0.1:41942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.122\" "} {"level":"warn","ts":"2026-02-27T20:31:06.445802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:02.161049Z","time spent":"4.284407009s","remote":"127.0.0.1:42182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:31:06.445837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.444690576s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:31:06.44588Z","caller":"traceutil/trace.go:171","msg":"trace[1216404297] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:782; }","duration":"1.444762557s","start":"2026-02-27T20:31:05.001097Z","end":"2026-02-27T20:31:06.445859Z","steps":["trace[1216404297] 'agreement among raft nodes before linearized reading' (duration: 1.444714496s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:06.445906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:05.001076Z","time spent":"1.444823539s","remote":"127.0.0.1:41914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T20:31:06.446125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.640141788s","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-27T20:31:06.446201Z","caller":"traceutil/trace.go:171","msg":"trace[568906277] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:782; }","duration":"3.640239781s","start":"2026-02-27T20:31:02.805946Z","end":"2026-02-27T20:31:06.446185Z","steps":["trace[568906277] 'agreement among raft nodes before linearized reading' (duration: 3.640084567s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:06.446246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:02.805933Z","time spent":"3.640300152s","remote":"127.0.0.1:42182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-27T20:31:06.446626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.949759507s","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-02-27T20:31:06.446678Z","caller":"traceutil/trace.go:171","msg":"trace[23457168] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:782; }","duration":"3.949866969s","start":"2026-02-27T20:31:02.49679Z","end":"2026-02-27T20:31:06.446657Z","steps":["trace[23457168] 'agreement among raft nodes before linearized reading' (duration: 3.948588097s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:06.446751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:02.496771Z","time spent":"3.949935721s","remote":"127.0.0.1:42182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-27T20:31:10.753459Z","caller":"traceutil/trace.go:171","msg":"trace[1400982887] linearizableReadLoop","detail":"{readStateIndex:879; appliedIndex:878; }","duration":"280.814199ms","start":"2026-02-27T20:31:10.472623Z","end":"2026-02-27T20:31:10.753437Z","steps":["trace[1400982887] 'read index received' (duration: 280.587654ms)","trace[1400982887] 'applied index is now lower than readState.Index' (duration: 225.215µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:31:10.75359Z","caller":"traceutil/trace.go:171","msg":"trace[234641606] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"290.223338ms","start":"2026-02-27T20:31:10.463337Z","end":"2026-02-27T20:31:10.753561Z","steps":["trace[234641606] 'process raft request' (duration: 289.959641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:10.753709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.349137ms","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-02-27T20:31:10.753747Z","caller":"traceutil/trace.go:171","msg":"trace[1464647541] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:847; }","duration":"278.43963ms","start":"2026-02-27T20:31:10.475298Z","end":"2026-02-27T20:31:10.753738Z","steps":["trace[1464647541] 'agreement among raft nodes before linearized reading' (duration: 278.302606ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:10.753767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.512617ms","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-02-27T20:31:10.753845Z","caller":"traceutil/trace.go:171","msg":"trace[50155227] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:847; }","duration":"279.64011ms","start":"2026-02-27T20:31:10.474188Z","end":"2026-02-27T20:31:10.753828Z","steps":["trace[50155227] 'agreement among raft nodes before linearized reading' (duration: 279.414644ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:10.753766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.106307ms","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-27T20:31:10.753925Z","caller":"traceutil/trace.go:171","msg":"trace[1926735310] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:847; }","duration":"279.306581ms","start":"2026-02-27T20:31:10.474611Z","end":"2026-02-27T20:31:10.753918Z","steps":["trace[1926735310] 'agreement among raft nodes before linearized reading' (duration: 279.007414ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:10.753887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.262291ms","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-02-27T20:31:10.754272Z","caller":"traceutil/trace.go:171","msg":"trace[437424662] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:847; }","duration":"281.667291ms","start":"2026-02-27T20:31:10.472597Z","end":"2026-02-27T20:31:10.754264Z","steps":["trace[437424662] 'agreement among raft nodes before linearized reading' (duration: 280.960093ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:31:10.957207Z","caller":"traceutil/trace.go:171","msg":"trace[1178148691] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"200.073925ms","start":"2026-02-27T20:31:10.757112Z","end":"2026-02-27T20:31:10.957186Z","steps":["trace[1178148691] 'process raft request' (duration: 198.513495ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:31:10.957373Z","caller":"traceutil/trace.go:171","msg":"trace[1759049556] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"199.46803ms","start":"2026-02-27T20:31:10.7579Z","end":"2026-02-27T20:31:10.957368Z","steps":["trace[1759049556] 'process raft request' (duration: 199.225093ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:31:10.957421Z","caller":"traceutil/trace.go:171","msg":"trace[1055185449] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"199.577593ms","start":"2026-02-27T20:31:10.757839Z","end":"2026-02-27T20:31:10.957417Z","steps":["trace[1055185449] 'process raft request' (duration: 199.242724ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:31:10.95746Z","caller":"traceutil/trace.go:171","msg":"trace[545517068] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"199.544202ms","start":"2026-02-27T20:31:10.757912Z","end":"2026-02-27T20:31:10.957457Z","steps":["trace[545517068] 'process raft request' (duration: 199.246424ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:31:26.64734Z","caller":"traceutil/trace.go:171","msg":"trace[691937293] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"107.428569ms","start":"2026-02-27T20:31:26.539886Z","end":"2026-02-27T20:31:26.647315Z","steps":["trace[691937293] 'process raft request' (duration: 107.265555ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:31:29.043593Z","caller":"traceutil/trace.go:171","msg":"trace[1091816007] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"159.293073ms","start":"2026-02-27T20:31:28.884283Z","end":"2026-02-27T20:31:29.043576Z","steps":["trace[1091816007] 'process raft request' (duration: 148.007697ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:29.396222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.608485ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T20:31:29.39636Z","caller":"traceutil/trace.go:171","msg":"trace[535554898] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"348.256948ms","start":"2026-02-27T20:31:29.048079Z","end":"2026-02-27T20:31:29.396335Z","steps":["trace[535554898] 'process raft request' (duration: 140.454957ms)","trace[535554898] 'compare' (duration: 207.206955ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:31:29.396662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:29.048068Z","time spent":"348.343979ms","remote":"127.0.0.1:42182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:31:29.421297Z","caller":"traceutil/trace.go:171","msg":"trace[951428841] linearizableReadLoop","detail":"{readStateIndex:964; appliedIndex:962; }","duration":"366.781155ms","start":"2026-02-27T20:31:29.054493Z","end":"2026-02-27T20:31:29.421274Z","steps":["trace[951428841] 'read index received' (duration: 134.014853ms)","trace[951428841] 'applied index is now lower than readState.Index' (duration: 232.765052ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:31:29.421362Z","caller":"traceutil/trace.go:171","msg":"trace[512684120] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"365.362379ms","start":"2026-02-27T20:31:29.055967Z","end":"2026-02-27T20:31:29.42133Z","steps":["trace[512684120] 'process raft request' (duration: 365.254667ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:31:29.421402Z","caller":"traceutil/trace.go:171","msg":"trace[1092108332] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"372.91725ms","start":"2026-02-27T20:31:29.048463Z","end":"2026-02-27T20:31:29.42138Z","steps":["trace[1092108332] 'process raft request' (duration: 372.618123ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:29.42148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:29.055956Z","time spent":"365.464952ms","remote":"127.0.0.1:41988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":779,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T20:31:29.421527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:29.048423Z","time spent":"373.033682ms","remote":"127.0.0.1:42182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T20:31:29.421535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.038583ms","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-27T20:31:29.421575Z","caller":"traceutil/trace.go:171","msg":"trace[341027800] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:929; }","duration":"367.114804ms","start":"2026-02-27T20:31:29.05445Z","end":"2026-02-27T20:31:29.421565Z","steps":["trace[341027800] 'agreement among raft nodes before linearized reading' (duration: 366.955409ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:29.421589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.085413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-27T20:31:29.421617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:29.054436Z","time spent":"367.173106ms","remote":"127.0.0.1:42182","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-02-27T20:31:29.42169Z","caller":"traceutil/trace.go:171","msg":"trace[678210574] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:929; }","duration":"367.155205ms","start":"2026-02-27T20:31:29.054467Z","end":"2026-02-27T20:31:29.421622Z","steps":["trace[678210574] 'agreement among raft nodes before linearized reading' (duration: 366.910809ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:29.42173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:29.054423Z","time spent":"367.296869ms","remote":"127.0.0.1:42182","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-02-27T20:31:29.421782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.451956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-27T20:31:29.421811Z","caller":"traceutil/trace.go:171","msg":"trace[542676138] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:929; }","duration":"366.495169ms","start":"2026-02-27T20:31:29.055307Z","end":"2026-02-27T20:31:29.421802Z","steps":["trace[542676138] 'agreement among raft nodes before linearized reading' (duration: 366.389666ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:29.421836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:29.0553Z","time spent":"366.530249ms","remote":"127.0.0.1:42182","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-02-27T20:31:40.950092Z","caller":"traceutil/trace.go:171","msg":"trace[1490899841] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"145.576925ms","start":"2026-02-27T20:31:40.804493Z","end":"2026-02-27T20:31:40.95007Z","steps":["trace[1490899841] 'process raft request' (duration: 145.4142ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:31:40.974691Z","caller":"traceutil/trace.go:171","msg":"trace[772225464] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"112.588901ms","start":"2026-02-27T20:31:40.862074Z","end":"2026-02-27T20:31:40.974662Z","steps":["trace[772225464] 'process raft request' (duration: 112.284212ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:31:41.194998Z","caller":"traceutil/trace.go:171","msg":"trace[429622902] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"215.485366ms","start":"2026-02-27T20:31:40.97948Z","end":"2026-02-27T20:31:41.194966Z","steps":["trace[429622902] 'process raft request' (duration: 198.338711ms)","trace[429622902] 'compare' (duration: 16.98152ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:31:41.195132Z","caller":"traceutil/trace.go:171","msg":"trace[2119294819] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"215.558518ms","start":"2026-02-27T20:31:40.979551Z","end":"2026-02-27T20:31:41.195109Z","steps":["trace[2119294819] 'process raft request' (duration: 215.343342ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:41.35448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.531278ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"}