{"level":"warn","ts":"2026-02-11T09:10:29.47071Z","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-11T09:10:29.47082Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.95: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.95:2380","--initial-cluster=instance=https://199.204.45.95:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.95:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.95: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-11T09:10:29.470913Z","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-11T09:10:29.470927Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-02-11T09:10:29.470946Z","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-11T09:10:29.471673Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"]} {"level":"info","ts":"2026-02-11T09:10:29.472363Z","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://199.204.45.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.95: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-11T09:10:29.478647Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.082793ms"} {"level":"info","ts":"2026-02-11T09:10:29.490357Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"be773dfc027af3eb","cluster-id":"9ba5fbd7288fd5af"} {"level":"info","ts":"2026-02-11T09:10:29.490475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=()"} {"level":"info","ts":"2026-02-11T09:10:29.490538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 0"} {"level":"info","ts":"2026-02-11T09:10:29.490556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft be773dfc027af3eb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-11T09:10:29.490563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 1"} {"level":"info","ts":"2026-02-11T09:10:29.490603Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"warn","ts":"2026-02-11T09:10:29.498386Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-11T09:10:29.515009Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-11T09:10:29.534508Z","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-11T09:10:29.537646Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"be773dfc027af3eb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-11T09:10:29.541405Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"be773dfc027af3eb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-11T09:10:29.541661Z","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-11T09:10:29.541695Z","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-11T09:10:29.541705Z","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-11T09:10:29.541936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"info","ts":"2026-02-11T09:10:29.542029Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","added-peer-id":"be773dfc027af3eb","added-peer-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-02-11T09:10:29.545765Z","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-11T09:10:29.546002Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-02-11T09:10:29.554454Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-02-11T09:10:29.554648Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"be773dfc027af3eb","initial-advertise-peer-urls":["https://199.204.45.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-11T09:10:29.554702Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-11T09:10:30.291493Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb is starting a new election at term 1"} {"level":"info","ts":"2026-02-11T09:10:30.291593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became pre-candidate at term 1"} {"level":"info","ts":"2026-02-11T09:10:30.291627Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgPreVoteResp from be773dfc027af3eb at term 1"} {"level":"info","ts":"2026-02-11T09:10:30.291652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became candidate at term 2"} {"level":"info","ts":"2026-02-11T09:10:30.291685Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgVoteResp from be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-02-11T09:10:30.29171Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became leader at term 2"} {"level":"info","ts":"2026-02-11T09:10:30.291731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: be773dfc027af3eb elected leader be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-02-11T09:10:30.293379Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"be773dfc027af3eb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.95:2379]}","request-path":"/0/members/be773dfc027af3eb/attributes","cluster-id":"9ba5fbd7288fd5af","publish-timeout":"7s"} {"level":"info","ts":"2026-02-11T09:10:30.293482Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-11T09:10:30.293526Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-11T09:10:30.293775Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-11T09:10:30.293798Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-11T09:10:30.293882Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T09:10:30.294646Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T09:10:30.294716Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T09:10:30.294735Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T09:10:30.295984Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.95:2379"} {"level":"info","ts":"2026-02-11T09:10:30.296806Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-11T09:10:44.359973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.923195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4256"} {"level":"info","ts":"2026-02-11T09:10:44.360075Z","caller":"traceutil/trace.go:171","msg":"trace[1212134544] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:370; }","duration":"114.098606ms","start":"2026-02-11T09:10:44.245959Z","end":"2026-02-11T09:10:44.360058Z","steps":["trace[1212134544] 'range keys from in-memory index tree' (duration: 113.839355ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:10:50.299284Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576313820830332304,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-11T09:10:50.585713Z","caller":"traceutil/trace.go:171","msg":"trace[909047534] linearizableReadLoop","detail":"{readStateIndex:425; appliedIndex:424; }","duration":"786.965824ms","start":"2026-02-11T09:10:49.798729Z","end":"2026-02-11T09:10:50.585695Z","steps":["trace[909047534] 'read index received' (duration: 786.764401ms)","trace[909047534] 'applied index is now lower than readState.Index' (duration: 183.902µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T09:10:50.585823Z","caller":"traceutil/trace.go:171","msg":"trace[2031665861] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"880.136612ms","start":"2026-02-11T09:10:49.705678Z","end":"2026-02-11T09:10:50.585815Z","steps":["trace[2031665861] 'process raft request' (duration: 879.828839ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:10:50.585952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.455205ms","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-11T09:10:50.585999Z","caller":"traceutil/trace.go:171","msg":"trace[967411417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:412; }","duration":"654.525376ms","start":"2026-02-11T09:10:49.93146Z","end":"2026-02-11T09:10:50.585986Z","steps":["trace[967411417] 'agreement among raft nodes before linearized reading' (duration: 654.452855ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:10:50.586027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:10:49.931448Z","time spent":"654.568686ms","remote":"127.0.0.1:53804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-11T09:10:50.586125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.400877ms","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-11T09:10:50.586137Z","caller":"traceutil/trace.go:171","msg":"trace[1679261620] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:412; }","duration":"787.450968ms","start":"2026-02-11T09:10:49.798682Z","end":"2026-02-11T09:10:50.586133Z","steps":["trace[1679261620] 'agreement among raft nodes before linearized reading' (duration: 787.428007ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:10:50.586149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:10:49.798668Z","time spent":"787.478648ms","remote":"127.0.0.1:53804","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-11T09:10:50.586208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.571725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T09:10:50.58622Z","caller":"traceutil/trace.go:171","msg":"trace[1104174319] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:412; }","duration":"177.622696ms","start":"2026-02-11T09:10:50.408593Z","end":"2026-02-11T09:10:50.586216Z","steps":["trace[1104174319] 'agreement among raft nodes before linearized reading' (duration: 177.602356ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:10:50.58625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:10:49.70567Z","time spent":"880.187563ms","remote":"127.0.0.1:53804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T09:10:50.586283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.053605ms","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-11T09:10:50.586297Z","caller":"traceutil/trace.go:171","msg":"trace[1372751997] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:412; }","duration":"646.077265ms","start":"2026-02-11T09:10:49.940216Z","end":"2026-02-11T09:10:50.586293Z","steps":["trace[1372751997] 'agreement among raft nodes before linearized reading' (duration: 646.054875ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:10:50.586308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:10:49.940192Z","time spent":"646.113245ms","remote":"127.0.0.1:53804","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-11T09:11:02.004848Z","caller":"traceutil/trace.go:171","msg":"trace[1088924493] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"410.073203ms","start":"2026-02-11T09:11:01.594735Z","end":"2026-02-11T09:11:02.004808Z","steps":["trace[1088924493] 'process raft request' (duration: 409.942232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:11:02.004951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:11:01.59472Z","time spent":"410.183354ms","remote":"127.0.0.1:53588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":24229,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T09:11:02.015455Z","caller":"traceutil/trace.go:171","msg":"trace[1524754459] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"105.312807ms","start":"2026-02-11T09:11:01.91013Z","end":"2026-02-11T09:11:02.015443Z","steps":["trace[1524754459] 'process raft request' (duration: 105.289307ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:11:02.015652Z","caller":"traceutil/trace.go:171","msg":"trace[746168831] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"305.610421ms","start":"2026-02-11T09:11:01.710035Z","end":"2026-02-11T09:11:02.015645Z","steps":["trace[746168831] 'process raft request' (duration: 305.312169ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:11:02.015696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:11:01.71002Z","time spent":"305.644551ms","remote":"127.0.0.1:53588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-11T09:11:02.857634Z","caller":"traceutil/trace.go:171","msg":"trace[1945106090] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"547.629808ms","start":"2026-02-11T09:11:02.309986Z","end":"2026-02-11T09:11:02.857616Z","steps":["trace[1945106090] 'process raft request' (duration: 547.539017ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:11:02.857735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:11:02.309971Z","time spent":"547.721528ms","remote":"127.0.0.1:53588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-11T09:11:02.858063Z","caller":"traceutil/trace.go:171","msg":"trace[1591655258] linearizableReadLoop","detail":"{readStateIndex:521; appliedIndex:521; }","duration":"449.581863ms","start":"2026-02-11T09:11:02.408474Z","end":"2026-02-11T09:11:02.858056Z","steps":["trace[1591655258] 'read index received' (duration: 449.578873ms)","trace[1591655258] 'applied index is now lower than readState.Index' (duration: 2.46µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T09:11:02.858138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.658363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T09:11:02.858156Z","caller":"traceutil/trace.go:171","msg":"trace[1103047506] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:506; }","duration":"449.703313ms","start":"2026-02-11T09:11:02.408448Z","end":"2026-02-11T09:11:02.858151Z","steps":["trace[1103047506] 'agreement among raft nodes before linearized reading' (duration: 449.650393ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:11:02.858173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:11:02.408435Z","time spent":"449.734803ms","remote":"127.0.0.1:53512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-11T09:11:02.864515Z","caller":"traceutil/trace.go:171","msg":"trace[1700680693] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"350.23017ms","start":"2026-02-11T09:11:02.51427Z","end":"2026-02-11T09:11:02.8645Z","steps":["trace[1700680693] 'process raft request' (duration: 350.194959ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:11:02.864551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:11:02.514255Z","time spent":"350.27936ms","remote":"127.0.0.1:53588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-11T09:11:02.864766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.648746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T09:11:02.864784Z","caller":"traceutil/trace.go:171","msg":"trace[290843501] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:507; }","duration":"156.674586ms","start":"2026-02-11T09:11:02.708103Z","end":"2026-02-11T09:11:02.864778Z","steps":["trace[290843501] 'agreement among raft nodes before linearized reading' (duration: 156.624276ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:11:02.864875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.389625ms","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-11T09:11:02.864888Z","caller":"traceutil/trace.go:171","msg":"trace[1934043903] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:507; }","duration":"162.417005ms","start":"2026-02-11T09:11:02.702467Z","end":"2026-02-11T09:11:02.864884Z","steps":["trace[1934043903] 'agreement among raft nodes before linearized reading' (duration: 162.365255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:11:02.86497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.865599ms","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-11T09:11:02.864984Z","caller":"traceutil/trace.go:171","msg":"trace[1842789817] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:507; }","duration":"168.912039ms","start":"2026-02-11T09:11:02.696068Z","end":"2026-02-11T09:11:02.86498Z","steps":["trace[1842789817] 'agreement among raft nodes before linearized reading' (duration: 168.876018ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:11:02.865055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.641063ms","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-11T09:11:02.865068Z","caller":"traceutil/trace.go:171","msg":"trace[737620332] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:507; }","duration":"199.678134ms","start":"2026-02-11T09:11:02.665385Z","end":"2026-02-11T09:11:02.865064Z","steps":["trace[737620332] 'agreement among raft nodes before linearized reading' (duration: 199.643574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:11:02.865142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.057081ms","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-11T09:11:02.865155Z","caller":"traceutil/trace.go:171","msg":"trace[794026031] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:507; }","duration":"220.093081ms","start":"2026-02-11T09:11:02.645058Z","end":"2026-02-11T09:11:02.865151Z","steps":["trace[794026031] 'agreement among raft nodes before linearized reading' (duration: 220.069651ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:11:11.359727Z","caller":"traceutil/trace.go:171","msg":"trace[150065837] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:584; }","duration":"321.835496ms","start":"2026-02-11T09:11:11.037878Z","end":"2026-02-11T09:11:11.359713Z","steps":["trace[150065837] 'read index received' (duration: 321.469661ms)","trace[150065837] 'applied index is now lower than readState.Index' (duration: 365.035µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T09:11:11.359812Z","caller":"traceutil/trace.go:171","msg":"trace[1074292376] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"325.51304ms","start":"2026-02-11T09:11:11.034293Z","end":"2026-02-11T09:11:11.359806Z","steps":["trace[1074292376] 'process raft request' (duration: 325.073082ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:11:11.359877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:11:11.034273Z","time spent":"325.556302ms","remote":"127.0.0.1:53804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T09:11:11.359984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.543912ms","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-11T09:11:11.360042Z","caller":"traceutil/trace.go:171","msg":"trace[311217384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:568; }","duration":"315.622235ms","start":"2026-02-11T09:11:11.044408Z","end":"2026-02-11T09:11:11.36003Z","steps":["trace[311217384] 'agreement among raft nodes before linearized reading' (duration: 315.518421ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:11:11.360066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:11:11.044397Z","time spent":"315.665407ms","remote":"127.0.0.1:53804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-11T09:11:11.360157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.385898ms","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-11T09:11:11.360182Z","caller":"traceutil/trace.go:171","msg":"trace[1030589008] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:568; }","duration":"311.417759ms","start":"2026-02-11T09:11:11.048759Z","end":"2026-02-11T09:11:11.360177Z","steps":["trace[1030589008] 'agreement among raft nodes before linearized reading' (duration: 311.381028ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:11:11.360192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.315096ms","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-11T09:11:11.360201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:11:11.048753Z","time spent":"311.44035ms","remote":"127.0.0.1:53804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-11T09:11:11.360219Z","caller":"traceutil/trace.go:171","msg":"trace[1057019362] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:568; }","duration":"322.342067ms","start":"2026-02-11T09:11:11.037869Z","end":"2026-02-11T09:11:11.360211Z","steps":["trace[1057019362] 'agreement among raft nodes before linearized reading' (duration: 322.294835ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:11:11.360239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:11:11.037854Z","time spent":"322.381459ms","remote":"127.0.0.1:53804","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-11T09:11:12.909798Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576313820830332640,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T09:11:13.410454Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576313820830332640,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T09:11:13.910558Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576313820830332640,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T09:11:14.408897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000037686s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-11T09:11:14.409073Z","caller":"traceutil/trace.go:171","msg":"trace[2078888087] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000244214s","start":"2026-02-11T09:11:12.408808Z","end":"2026-02-11T09:11:14.409052Z","steps":["trace[2078888087] 'agreement among raft nodes before linearized reading' (duration: 2.000060107s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:11:14.409149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:11:12.408794Z","time spent":"2.000342458s","remote":"127.0.0.1:53512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/11 09:11:14 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-11T09:11:14.411289Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576313820830332640,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T09:11:14.632312Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.276925661s","expected-duration":"1s"} {"level":"info","ts":"2026-02-11T09:11:14.632649Z","caller":"traceutil/trace.go:171","msg":"trace[1588984354] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:589; }","duration":"2.223787764s","start":"2026-02-11T09:11:12.408837Z","end":"2026-02-11T09:11:14.632625Z","steps":["trace[1588984354] 'read index received' (duration: 2.223583715s)","trace[1588984354] 'applied index is now lower than readState.Index' (duration: 202.989µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T09:11:14.632937Z","caller":"traceutil/trace.go:171","msg":"trace[184221254] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"2.27761192s","start":"2026-02-11T09:11:12.355313Z","end":"2026-02-11T09:11:14.632925Z","steps":["trace[184221254] 'process raft request' (duration: 2.277172172s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:11:14.63304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:11:12.355297Z","time spent":"2.277678453s","remote":"127.0.0.1:53804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T09:11:14.633288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.321010096s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.95\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-11T09:11:14.633321Z","caller":"traceutil/trace.go:171","msg":"trace[1038061588] range","detail":"{range_begin:/registry/masterleases/199.204.45.95; range_end:; response_count:1; response_revision:573; }","duration":"1.321042537s","start":"2026-02-11T09:11:13.312267Z","end":"2026-02-11T09:11:14.63331Z","steps":["trace[1038061588] 'agreement among raft nodes before linearized reading' (duration: 1.320936633s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:11:14.633383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:11:13.312218Z","time spent":"1.321154522s","remote":"127.0.0.1:53562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.95\" "} {"level":"warn","ts":"2026-02-11T09:11:14.633605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.434325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T09:11:14.63365Z","caller":"traceutil/trace.go:171","msg":"trace[1277245543] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:573; }","duration":"224.518288ms","start":"2026-02-11T09:11:14.40912Z","end":"2026-02-11T09:11:14.633638Z","steps":["trace[1277245543] 'agreement among raft nodes before linearized reading' (duration: 224.443745ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:11:14.633844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.243509707s","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-11T09:11:14.63388Z","caller":"traceutil/trace.go:171","msg":"trace[208657114] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:573; }","duration":"1.243640533s","start":"2026-02-11T09:11:13.390231Z","end":"2026-02-11T09:11:14.633872Z","steps":["trace[208657114] 'agreement among raft nodes before linearized reading' (duration: 1.243541589s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:11:14.633908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:11:13.390221Z","time spent":"1.243680374s","remote":"127.0.0.1:53804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-11T09:11:14.634132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.244688355s","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-11T09:11:14.63417Z","caller":"traceutil/trace.go:171","msg":"trace[874850870] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:573; }","duration":"1.244752107s","start":"2026-02-11T09:11:13.389409Z","end":"2026-02-11T09:11:14.634161Z","steps":["trace[874850870] 'agreement among raft nodes before linearized reading' (duration: 1.244602011s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:11:14.634199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:11:13.389398Z","time spent":"1.244794409s","remote":"127.0.0.1:53804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-11T09:11:14.634366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.24602187s","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-11T09:11:14.634403Z","caller":"traceutil/trace.go:171","msg":"trace[119060915] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:573; }","duration":"1.246077232s","start":"2026-02-11T09:11:13.388317Z","end":"2026-02-11T09:11:14.634395Z","steps":["trace[119060915] 'agreement among raft nodes before linearized reading' (duration: 1.245980738s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:11:14.635165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:11:13.388307Z","time spent":"1.246845473s","remote":"127.0.0.1:53804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-11T09:11:14.635324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.252935163s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-11T09:11:14.635346Z","caller":"traceutil/trace.go:171","msg":"trace[1404922679] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:573; }","duration":"1.252977774s","start":"2026-02-11T09:11:13.382361Z","end":"2026-02-11T09:11:14.635339Z","steps":["trace[1404922679] 'agreement among raft nodes before linearized reading' (duration: 1.252906942s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:11:14.635386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:11:13.382351Z","time spent":"1.253025126s","remote":"127.0.0.1:53804","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-11T09:11:14.635522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.271865695s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-11T09:11:14.635543Z","caller":"traceutil/trace.go:171","msg":"trace[1763000375] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:573; }","duration":"1.271921297s","start":"2026-02-11T09:11:13.363615Z","end":"2026-02-11T09:11:14.635536Z","steps":["trace[1763000375] 'agreement among raft nodes before linearized reading' (duration: 1.271879246s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:11:14.635561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:11:13.3636Z","time spent":"1.271956419s","remote":"127.0.0.1:53804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-11T09:11:45.010015Z","caller":"traceutil/trace.go:171","msg":"trace[1550950078] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"186.510752ms","start":"2026-02-11T09:11:44.823482Z","end":"2026-02-11T09:11:45.009993Z","steps":["trace[1550950078] 'process raft request' (duration: 159.840595ms)","trace[1550950078] 'compare' (duration: 26.615525ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T09:11:45.014962Z","caller":"traceutil/trace.go:171","msg":"trace[443715301] linearizableReadLoop","detail":"{readStateIndex:798; appliedIndex:796; }","duration":"186.4358ms","start":"2026-02-11T09:11:44.828518Z","end":"2026-02-11T09:11:45.014954Z","steps":["trace[443715301] 'read index received' (duration: 154.77361ms)","trace[443715301] 'applied index is now lower than readState.Index' (duration: 31.66168ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T09:11:45.015001Z","caller":"traceutil/trace.go:171","msg":"trace[1763971685] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"191.369301ms","start":"2026-02-11T09:11:44.823628Z","end":"2026-02-11T09:11:45.014997Z","steps":["trace[1763971685] 'process raft request' (duration: 191.286119ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:11:45.01514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.608995ms","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-11T09:11:45.01516Z","caller":"traceutil/trace.go:171","msg":"trace[642403740] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:773; }","duration":"186.649346ms","start":"2026-02-11T09:11:44.828505Z","end":"2026-02-11T09:11:45.015155Z","steps":["trace[642403740] 'agreement among raft nodes before linearized reading' (duration: 186.571404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:11:45.015245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.156742ms","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-11T09:11:45.015261Z","caller":"traceutil/trace.go:171","msg":"trace[1496512707] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:773; }","duration":"158.182803ms","start":"2026-02-11T09:11:44.857074Z","end":"2026-02-11T09:11:45.015257Z","steps":["trace[1496512707] 'agreement among raft nodes before linearized reading' (duration: 158.143702ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:12:24.35124Z","caller":"traceutil/trace.go:171","msg":"trace[1997643636] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"162.661606ms","start":"2026-02-11T09:12:24.188534Z","end":"2026-02-11T09:12:24.351196Z","steps":["trace[1997643636] 'process raft request' (duration: 162.440622ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:12:24.657694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.108017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d6ff88cf-9d9a-4f00-8037-2fdb429a5c1f\" ","response":"range_response_count:1 size:3534"} {"level":"info","ts":"2026-02-11T09:12:24.657826Z","caller":"traceutil/trace.go:171","msg":"trace[1348807838] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d6ff88cf-9d9a-4f00-8037-2fdb429a5c1f; range_end:; response_count:1; response_revision:898; }","duration":"135.275149ms","start":"2026-02-11T09:12:24.522534Z","end":"2026-02-11T09:12:24.657809Z","steps":["trace[1348807838] 'range keys from in-memory index tree' (duration: 134.995824ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:12:27.367751Z","caller":"traceutil/trace.go:171","msg":"trace[459257614] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"118.788195ms","start":"2026-02-11T09:12:27.248938Z","end":"2026-02-11T09:12:27.367726Z","steps":["trace[459257614] 'process raft request' (duration: 118.246564ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:12:27.479154Z","caller":"traceutil/trace.go:171","msg":"trace[2055511156] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"102.501757ms","start":"2026-02-11T09:12:27.376631Z","end":"2026-02-11T09:12:27.479133Z","steps":["trace[2055511156] 'process raft request' (duration: 102.358395ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:12:27.479712Z","caller":"traceutil/trace.go:171","msg":"trace[1233918639] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"102.995977ms","start":"2026-02-11T09:12:27.376704Z","end":"2026-02-11T09:12:27.4797Z","steps":["trace[1233918639] 'process raft request' (duration: 102.388576ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:12:27.674922Z","caller":"traceutil/trace.go:171","msg":"trace[1225030535] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"102.251312ms","start":"2026-02-11T09:12:27.572653Z","end":"2026-02-11T09:12:27.674905Z","steps":["trace[1225030535] 'process raft request' (duration: 71.146916ms)","trace[1225030535] 'compare' (duration: 30.920603ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T09:12:27.78375Z","caller":"traceutil/trace.go:171","msg":"trace[27865077] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"101.577629ms","start":"2026-02-11T09:12:27.682153Z","end":"2026-02-11T09:12:27.783731Z","steps":["trace[27865077] 'process raft request' (duration: 101.208832ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:12:31.751385Z","caller":"traceutil/trace.go:171","msg":"trace[575587490] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:968; }","duration":"254.832186ms","start":"2026-02-11T09:12:31.496532Z","end":"2026-02-11T09:12:31.751364Z","steps":["trace[575587490] 'read index received' (duration: 254.49749ms)","trace[575587490] 'applied index is now lower than readState.Index' (duration: 333.746µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T09:12:31.751751Z","caller":"traceutil/trace.go:171","msg":"trace[293640886] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"366.027107ms","start":"2026-02-11T09:12:31.385711Z","end":"2026-02-11T09:12:31.751738Z","steps":["trace[293640886] 'process raft request' (duration: 365.404036ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:12:31.752734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.750015ms","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-11T09:12:31.752901Z","caller":"traceutil/trace.go:171","msg":"trace[1057122072] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:934; }","duration":"254.936468ms","start":"2026-02-11T09:12:31.497945Z","end":"2026-02-11T09:12:31.752882Z","steps":["trace[1057122072] 'agreement among raft nodes before linearized reading' (duration: 254.573202ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:12:31.753193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:12:31.385703Z","time spent":"366.078488ms","remote":"127.0.0.1:53804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T09:12:31.753546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.012388ms","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-11T09:12:31.753713Z","caller":"traceutil/trace.go:171","msg":"trace[2065711589] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:934; }","duration":"257.13685ms","start":"2026-02-11T09:12:31.496497Z","end":"2026-02-11T09:12:31.753634Z","steps":["trace[2065711589] 'agreement among raft nodes before linearized reading' (duration: 256.928057ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:12:31.754278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.648193ms","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-11T09:12:31.756912Z","caller":"traceutil/trace.go:171","msg":"trace[1326539994] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:934; }","duration":"257.284323ms","start":"2026-02-11T09:12:31.49961Z","end":"2026-02-11T09:12:31.756894Z","steps":["trace[1326539994] 'agreement among raft nodes before linearized reading' (duration: 254.592102ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:12:36.11836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.675561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T09:12:36.118606Z","caller":"traceutil/trace.go:171","msg":"trace[1422605818] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:952; }","duration":"251.956236ms","start":"2026-02-11T09:12:35.866611Z","end":"2026-02-11T09:12:36.118568Z","steps":["trace[1422605818] 'count revisions from in-memory index tree' (duration: 251.60585ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:12:38.026636Z","caller":"traceutil/trace.go:171","msg":"trace[1109753322] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"199.749198ms","start":"2026-02-11T09:12:37.826873Z","end":"2026-02-11T09:12:38.026622Z","steps":["trace[1109753322] 'process raft request' (duration: 193.100359ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:12:38.026705Z","caller":"traceutil/trace.go:171","msg":"trace[578247290] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"199.117847ms","start":"2026-02-11T09:12:37.827582Z","end":"2026-02-11T09:12:38.0267Z","steps":["trace[578247290] 'process raft request' (duration: 198.870113ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:12:38.795815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.811961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T09:12:38.795882Z","caller":"traceutil/trace.go:171","msg":"trace[1697781857] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:962; }","duration":"387.923193ms","start":"2026-02-11T09:12:38.407945Z","end":"2026-02-11T09:12:38.795868Z","steps":["trace[1697781857] 'range keys from in-memory index tree' (duration: 387.716359ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:12:38.79591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:12:38.407926Z","time spent":"387.978244ms","remote":"127.0.0.1:53512","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-11T09:12:38.796082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.860101ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T09:12:38.796127Z","caller":"traceutil/trace.go:171","msg":"trace[1485391300] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:962; }","duration":"246.907541ms","start":"2026-02-11T09:12:38.549214Z","end":"2026-02-11T09:12:38.796121Z","steps":["trace[1485391300] 'range keys from in-memory index tree' (duration: 246.853621ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:12:39.028988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.934918ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-11T09:12:39.02908Z","caller":"traceutil/trace.go:171","msg":"trace[1304116723] linearizableReadLoop","detail":"{readStateIndex:999; appliedIndex:998; }","duration":"231.988872ms","start":"2026-02-11T09:12:38.797076Z","end":"2026-02-11T09:12:39.029065Z","steps":["trace[1304116723] 'read index received' (duration: 62.85771ms)","trace[1304116723] 'applied index is now lower than readState.Index' (duration: 169.129882ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T09:12:39.029145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.064973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T09:12:39.029172Z","caller":"traceutil/trace.go:171","msg":"trace[642306994] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:962; }","duration":"232.120363ms","start":"2026-02-11T09:12:38.797041Z","end":"2026-02-11T09:12:39.029161Z","steps":["trace[642306994] 'agreement among raft nodes before linearized reading' (duration: 232.066562ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:12:39.266644Z","caller":"traceutil/trace.go:171","msg":"trace[432389478] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"121.093538ms","start":"2026-02-11T09:12:39.14553Z","end":"2026-02-11T09:12:39.266624Z","steps":["trace[432389478] 'process raft request' (duration: 120.939135ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:12:39.896124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.030159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T09:12:39.896177Z","caller":"traceutil/trace.go:171","msg":"trace[1935276226] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:963; }","duration":"483.119891ms","start":"2026-02-11T09:12:39.413044Z","end":"2026-02-11T09:12:39.896164Z","steps":["trace[1935276226] 'range keys from in-memory index tree' (duration: 482.934768ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:12:39.8962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:12:39.413027Z","time spent":"483.167732ms","remote":"127.0.0.1:53512","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-11T09:12:39.896495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.828363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T09:12:39.896524Z","caller":"traceutil/trace.go:171","msg":"trace[1283617326] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:963; }","duration":"474.868444ms","start":"2026-02-11T09:12:39.421649Z","end":"2026-02-11T09:12:39.896517Z","steps":["trace[1283617326] 'range keys from in-memory index tree' (duration: 474.770252ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:12:39.896543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:12:39.421643Z","time spent":"474.894865ms","remote":"127.0.0.1:53690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-02-11T09:12:40.377655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.383104ms","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":"warn","ts":"2026-02-11T09:12:40.377767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.444465ms","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-11T09:12:40.377825Z","caller":"traceutil/trace.go:171","msg":"trace[1002425923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:965; }","duration":"345.544816ms","start":"2026-02-11T09:12:40.032269Z","end":"2026-02-11T09:12:40.377813Z","steps":["trace[1002425923] 'range keys from in-memory index tree' (duration: 345.321082ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:12:40.37786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:12:40.032254Z","time spent":"345.591906ms","remote":"127.0.0.1:53804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-11T09:12:40.37779Z","caller":"traceutil/trace.go:171","msg":"trace[549364743] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:965; }","duration":"288.555757ms","start":"2026-02-11T09:12:40.089217Z","end":"2026-02-11T09:12:40.377773Z","steps":["trace[549364743] 'count revisions from in-memory index tree' (duration: 288.331483ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:12:40.37798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.081677ms","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-11T09:12:40.377998Z","caller":"traceutil/trace.go:171","msg":"trace[150676019] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:965; }","duration":"345.100177ms","start":"2026-02-11T09:12:40.032894Z","end":"2026-02-11T09:12:40.377994Z","steps":["trace[150676019] 'range keys from in-memory index tree' (duration: 345.001865ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:12:40.378011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:12:40.032874Z","time spent":"345.134548ms","remote":"127.0.0.1:53804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-11T09:12:56.189264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.252498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:service-account-issuer-discovery\" ","response":"range_response_count:1 size:699"} {"level":"info","ts":"2026-02-11T09:12:56.189361Z","caller":"traceutil/trace.go:171","msg":"trace[1112415219] range","detail":"{range_begin:/registry/clusterroles/system:service-account-issuer-discovery; range_end:; response_count:1; response_revision:1013; }","duration":"164.37676ms","start":"2026-02-11T09:12:56.024965Z","end":"2026-02-11T09:12:56.189342Z","steps":["trace[1112415219] 'range keys from in-memory index tree' (duration: 164.162516ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:12:56.189641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.554878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:volume-scheduler\" ","response":"range_response_count:1 size:723"} {"level":"info","ts":"2026-02-11T09:12:56.189725Z","caller":"traceutil/trace.go:171","msg":"trace[889859384] range","detail":"{range_begin:/registry/clusterroles/system:volume-scheduler; range_end:; response_count:1; response_revision:1013; }","duration":"143.681789ms","start":"2026-02-11T09:12:56.046023Z","end":"2026-02-11T09:12:56.189705Z","steps":["trace[889859384] 'range keys from in-memory index tree' (duration: 143.462036ms)"],"step_count":1}