{"level":"warn","ts":"2026-02-03T12:55:24.022916Z","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-03T12:55:24.023184Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.141: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.141:2380","--initial-cluster=instance=https://199.204.45.141:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.141:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.141: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-03T12:55:24.023315Z","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-03T12:55:24.023383Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.141:2380"]} {"level":"info","ts":"2026-02-03T12:55:24.023449Z","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-03T12:55:24.024226Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.141:2379"]} {"level":"info","ts":"2026-02-03T12:55:24.024373Z","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.141:2380"],"listen-peer-urls":["https://199.204.45.141:2380"],"advertise-client-urls":["https://199.204.45.141:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.141:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.141: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-03T12:55:24.0308Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.121475ms"} {"level":"info","ts":"2026-02-03T12:55:24.052352Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9ef3b4f68930dc2","cluster-id":"b19a5957e6e65747"} {"level":"info","ts":"2026-02-03T12:55:24.05254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 switched to configuration voters=()"} {"level":"info","ts":"2026-02-03T12:55:24.052616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became follower at term 0"} {"level":"info","ts":"2026-02-03T12:55:24.052672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9ef3b4f68930dc2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-03T12:55:24.052727Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became follower at term 1"} {"level":"info","ts":"2026-02-03T12:55:24.052808Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 switched to configuration voters=(715856078018121154)"} {"level":"warn","ts":"2026-02-03T12:55:24.063603Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-03T12:55:24.065122Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-03T12:55:24.066285Z","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-03T12:55:24.068094Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9ef3b4f68930dc2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-03T12:55:24.068918Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9ef3b4f68930dc2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-03T12:55:24.069074Z","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-03T12:55:24.069149Z","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-03T12:55:24.069195Z","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-03T12:55:24.069389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 switched to configuration voters=(715856078018121154)"} {"level":"info","ts":"2026-02-03T12:55:24.069524Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b19a5957e6e65747","local-member-id":"9ef3b4f68930dc2","added-peer-id":"9ef3b4f68930dc2","added-peer-peer-urls":["https://199.204.45.141:2380"]} {"level":"info","ts":"2026-02-03T12:55:24.078743Z","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-03T12:55:24.080059Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9ef3b4f68930dc2","initial-advertise-peer-urls":["https://199.204.45.141:2380"],"listen-peer-urls":["https://199.204.45.141:2380"],"advertise-client-urls":["https://199.204.45.141:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.141:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-03T12:55:24.079549Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.141:2380"} {"level":"info","ts":"2026-02-03T12:55:24.081033Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.141:2380"} {"level":"info","ts":"2026-02-03T12:55:24.080953Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-03T12:55:24.353048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 is starting a new election at term 1"} {"level":"info","ts":"2026-02-03T12:55:24.353088Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-03T12:55:24.353109Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 received MsgPreVoteResp from 9ef3b4f68930dc2 at term 1"} {"level":"info","ts":"2026-02-03T12:55:24.353189Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became candidate at term 2"} {"level":"info","ts":"2026-02-03T12:55:24.353204Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 received MsgVoteResp from 9ef3b4f68930dc2 at term 2"} {"level":"info","ts":"2026-02-03T12:55:24.353213Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became leader at term 2"} {"level":"info","ts":"2026-02-03T12:55:24.353269Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9ef3b4f68930dc2 elected leader 9ef3b4f68930dc2 at term 2"} {"level":"info","ts":"2026-02-03T12:55:24.354285Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9ef3b4f68930dc2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.141:2379]}","request-path":"/0/members/9ef3b4f68930dc2/attributes","cluster-id":"b19a5957e6e65747","publish-timeout":"7s"} {"level":"info","ts":"2026-02-03T12:55:24.354309Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-03T12:55:24.354739Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T12:55:24.354841Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-03T12:55:24.357352Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-03T12:55:24.357392Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-03T12:55:24.357435Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b19a5957e6e65747","local-member-id":"9ef3b4f68930dc2","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T12:55:24.357557Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T12:55:24.357607Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T12:55:24.359088Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.141:2379"} {"level":"info","ts":"2026-02-03T12:55:24.360238Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-03T12:55:43.234477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.709628ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-03T12:55:43.756398Z","caller":"traceutil/trace.go:171","msg":"trace[821319569] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"324.798267ms","start":"2026-02-03T12:55:43.431581Z","end":"2026-02-03T12:55:43.756379Z","steps":["trace[821319569] 'process raft request' (duration: 324.671184ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:55:43.756871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T12:55:43.431567Z","time spent":"324.889729ms","remote":"127.0.0.1:36036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T12:55:56.42387Z","caller":"traceutil/trace.go:171","msg":"trace[1615950213] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"281.271597ms","start":"2026-02-03T12:55:56.142584Z","end":"2026-02-03T12:55:56.423856Z","steps":["trace[1615950213] 'process raft request' (duration: 281.064062ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:56:02.994994Z","caller":"traceutil/trace.go:171","msg":"trace[359377154] linearizableReadLoop","detail":"{readStateIndex:566; appliedIndex:565; }","duration":"350.029765ms","start":"2026-02-03T12:56:02.644942Z","end":"2026-02-03T12:56:02.994972Z","steps":["trace[359377154] 'read index received' (duration: 349.8084ms)","trace[359377154] 'applied index is now lower than readState.Index' (duration: 220.545µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T12:56:02.995105Z","caller":"traceutil/trace.go:171","msg":"trace[1239034578] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"367.609078ms","start":"2026-02-03T12:56:02.627485Z","end":"2026-02-03T12:56:02.995094Z","steps":["trace[1239034578] 'process raft request' (duration: 367.366172ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:56:02.995198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T12:56:02.627467Z","time spent":"367.66361ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T12:56:02.99547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.619311ms","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-03T12:56:02.995519Z","caller":"traceutil/trace.go:171","msg":"trace[1312042665] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:550; }","duration":"295.743204ms","start":"2026-02-03T12:56:02.699763Z","end":"2026-02-03T12:56:02.995507Z","steps":["trace[1312042665] 'agreement among raft nodes before linearized reading' (duration: 295.56556ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:56:02.99556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.872077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T12:56:02.995595Z","caller":"traceutil/trace.go:171","msg":"trace[709402030] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:550; }","duration":"166.96477ms","start":"2026-02-03T12:56:02.82862Z","end":"2026-02-03T12:56:02.995585Z","steps":["trace[709402030] 'agreement among raft nodes before linearized reading' (duration: 166.891738ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:56:02.995726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.71771ms","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-03T12:56:02.995754Z","caller":"traceutil/trace.go:171","msg":"trace[346987372] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:550; }","duration":"285.775071ms","start":"2026-02-03T12:56:02.709971Z","end":"2026-02-03T12:56:02.995746Z","steps":["trace[346987372] 'agreement among raft nodes before linearized reading' (duration: 285.695139ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:56:02.995772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.836615ms","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-03T12:56:02.995799Z","caller":"traceutil/trace.go:171","msg":"trace[419103917] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:550; }","duration":"350.904157ms","start":"2026-02-03T12:56:02.644887Z","end":"2026-02-03T12:56:02.995791Z","steps":["trace[419103917] 'agreement among raft nodes before linearized reading' (duration: 350.823265ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:56:02.995827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T12:56:02.644866Z","time spent":"350.954967ms","remote":"127.0.0.1:36036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-03T12:56:02.995913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.728527ms","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-03T12:56:02.995939Z","caller":"traceutil/trace.go:171","msg":"trace[1708148994] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:550; }","duration":"294.766478ms","start":"2026-02-03T12:56:02.701165Z","end":"2026-02-03T12:56:02.995931Z","steps":["trace[1708148994] 'agreement among raft nodes before linearized reading' (duration: 294.695986ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:56:03.19373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.479474ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T12:56:03.194172Z","caller":"traceutil/trace.go:171","msg":"trace[1136958890] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"187.499188ms","start":"2026-02-03T12:56:03.00665Z","end":"2026-02-03T12:56:03.194149Z","steps":["trace[1136958890] 'process raft request' (duration: 49.526901ms)","trace[1136958890] 'compare' (duration: 137.396063ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T12:56:03.194478Z","caller":"traceutil/trace.go:171","msg":"trace[1911022339] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"183.501947ms","start":"2026-02-03T12:56:03.010964Z","end":"2026-02-03T12:56:03.194465Z","steps":["trace[1911022339] 'process raft request' (duration: 182.899271ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:56:03.194595Z","caller":"traceutil/trace.go:171","msg":"trace[2085337766] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"184.983575ms","start":"2026-02-03T12:56:03.009603Z","end":"2026-02-03T12:56:03.194586Z","steps":["trace[2085337766] 'process raft request' (duration: 184.217605ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:56:03.194814Z","caller":"traceutil/trace.go:171","msg":"trace[982229721] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"183.797364ms","start":"2026-02-03T12:56:03.011008Z","end":"2026-02-03T12:56:03.194805Z","steps":["trace[982229721] 'process raft request' (duration: 182.898311ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:56:25.505041Z","caller":"traceutil/trace.go:171","msg":"trace[929669265] linearizableReadLoop","detail":"{readStateIndex:730; appliedIndex:729; }","duration":"195.065989ms","start":"2026-02-03T12:56:25.309955Z","end":"2026-02-03T12:56:25.505021Z","steps":["trace[929669265] 'read index received' (duration: 194.926686ms)","trace[929669265] 'applied index is now lower than readState.Index' (duration: 138.763µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T12:56:25.505242Z","caller":"traceutil/trace.go:171","msg":"trace[1264972500] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"197.173701ms","start":"2026-02-03T12:56:25.308059Z","end":"2026-02-03T12:56:25.505233Z","steps":["trace[1264972500] 'process raft request' (duration: 196.867964ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:56:25.505421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.448078ms","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-03T12:56:25.505443Z","caller":"traceutil/trace.go:171","msg":"trace[108231460] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:708; }","duration":"195.50073ms","start":"2026-02-03T12:56:25.309935Z","end":"2026-02-03T12:56:25.505436Z","steps":["trace[108231460] 'agreement among raft nodes before linearized reading' (duration: 195.410137ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:56:25.505552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.433556ms","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-03T12:56:25.505569Z","caller":"traceutil/trace.go:171","msg":"trace[1836501590] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:708; }","duration":"168.478497ms","start":"2026-02-03T12:56:25.337084Z","end":"2026-02-03T12:56:25.505563Z","steps":["trace[1836501590] 'agreement among raft nodes before linearized reading' (duration: 168.434496ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:56:25.505711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.461167ms","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-03T12:56:25.505727Z","caller":"traceutil/trace.go:171","msg":"trace[1623891595] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:708; }","duration":"166.509877ms","start":"2026-02-03T12:56:25.339213Z","end":"2026-02-03T12:56:25.505723Z","steps":["trace[1623891595] 'agreement among raft nodes before linearized reading' (duration: 166.450466ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:56:25.914188Z","caller":"traceutil/trace.go:171","msg":"trace[2058382852] linearizableReadLoop","detail":"{readStateIndex:733; appliedIndex:731; }","duration":"259.830925ms","start":"2026-02-03T12:56:25.654336Z","end":"2026-02-03T12:56:25.914167Z","steps":["trace[2058382852] 'read index received' (duration: 241.638285ms)","trace[2058382852] 'applied index is now lower than readState.Index' (duration: 18.19186ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T12:56:25.914476Z","caller":"traceutil/trace.go:171","msg":"trace[627900128] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"403.020054ms","start":"2026-02-03T12:56:25.511443Z","end":"2026-02-03T12:56:25.914463Z","steps":["trace[627900128] 'process raft request' (duration: 384.591698ms)","trace[627900128] 'compare' (duration: 18.004685ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T12:56:25.914674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T12:56:25.511434Z","time spent":"403.071845ms","remote":"127.0.0.1:36036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T12:56:25.914851Z","caller":"traceutil/trace.go:171","msg":"trace[673582694] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"402.063579ms","start":"2026-02-03T12:56:25.512777Z","end":"2026-02-03T12:56:25.914841Z","steps":["trace[673582694] 'process raft request' (duration: 401.350401ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:56:25.914902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T12:56:25.512771Z","time spent":"402.1034ms","remote":"127.0.0.1:36036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T12:56:25.915069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.727048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-03T12:56:25.9151Z","caller":"traceutil/trace.go:171","msg":"trace[1964100327] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:711; }","duration":"260.775179ms","start":"2026-02-03T12:56:25.654315Z","end":"2026-02-03T12:56:25.91509Z","steps":["trace[1964100327] 'agreement among raft nodes before linearized reading' (duration: 260.687657ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:56:41.314545Z","caller":"traceutil/trace.go:171","msg":"trace[716981840] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"129.843521ms","start":"2026-02-03T12:56:41.184677Z","end":"2026-02-03T12:56:41.31452Z","steps":["trace[716981840] 'process raft request' (duration: 129.730439ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:56:54.231545Z","caller":"traceutil/trace.go:171","msg":"trace[1699144917] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"115.59585ms","start":"2026-02-03T12:56:54.115933Z","end":"2026-02-03T12:56:54.231529Z","steps":["trace[1699144917] 'process raft request' (duration: 115.375555ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:57:03.604409Z","caller":"traceutil/trace.go:171","msg":"trace[742342796] linearizableReadLoop","detail":"{readStateIndex:907; appliedIndex:906; }","duration":"343.719543ms","start":"2026-02-03T12:57:03.260673Z","end":"2026-02-03T12:57:03.604393Z","steps":["trace[742342796] 'read index received' (duration: 280.202692ms)","trace[742342796] 'applied index is now lower than readState.Index' (duration: 63.515881ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T12:57:03.604525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.843075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T12:57:03.604544Z","caller":"traceutil/trace.go:171","msg":"trace[478088902] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:875; }","duration":"343.913528ms","start":"2026-02-03T12:57:03.260623Z","end":"2026-02-03T12:57:03.604536Z","steps":["trace[478088902] 'agreement among raft nodes before linearized reading' (duration: 343.845656ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:57:03.604574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T12:57:03.260605Z","time spent":"343.96368ms","remote":"127.0.0.1:35996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-02-03T12:57:25.975912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.631754ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T12:57:25.976109Z","caller":"traceutil/trace.go:171","msg":"trace[212856364] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"797.406443ms","start":"2026-02-03T12:57:25.178683Z","end":"2026-02-03T12:57:25.97609Z","steps":["trace[212856364] 'process raft request' (duration: 432.195211ms)","trace[212856364] 'compare' (duration: 302.245304ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T12:57:25.976369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T12:57:25.178665Z","time spent":"797.501694ms","remote":"127.0.0.1:36230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11078,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T12:57:25.976922Z","caller":"traceutil/trace.go:171","msg":"trace[87158467] linearizableReadLoop","detail":"{readStateIndex:1031; appliedIndex:1030; }","duration":"439.513049ms","start":"2026-02-03T12:57:25.536532Z","end":"2026-02-03T12:57:25.976045Z","steps":["trace[87158467] 'read index received' (duration: 74.295247ms)","trace[87158467] 'applied index is now lower than readState.Index' (duration: 365.215171ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T12:57:25.977116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.562984ms","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":"info","ts":"2026-02-03T12:57:25.977158Z","caller":"traceutil/trace.go:171","msg":"trace[1932897956] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:995; }","duration":"440.687127ms","start":"2026-02-03T12:57:25.536458Z","end":"2026-02-03T12:57:25.977146Z","steps":["trace[1932897956] 'agreement among raft nodes before linearized reading' (duration: 440.519353ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:57:25.977335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T12:57:25.53643Z","time spent":"440.890572ms","remote":"127.0.0.1:36162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-02-03T12:57:25.979307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.335395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T12:57:25.979405Z","caller":"traceutil/trace.go:171","msg":"trace[934983787] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:995; }","duration":"291.474559ms","start":"2026-02-03T12:57:25.687912Z","end":"2026-02-03T12:57:25.979387Z","steps":["trace[934983787] 'agreement among raft nodes before linearized reading' (duration: 291.328755ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:57:25.979704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.965762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T12:57:25.979765Z","caller":"traceutil/trace.go:171","msg":"trace[185790050] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:995; }","duration":"149.072715ms","start":"2026-02-03T12:57:25.83068Z","end":"2026-02-03T12:57:25.979753Z","steps":["trace[185790050] 'agreement among raft nodes before linearized reading' (duration: 148.997513ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:57:25.980035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.219363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-02-03T12:57:25.980095Z","caller":"traceutil/trace.go:171","msg":"trace[1576820064] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:995; }","duration":"282.313546ms","start":"2026-02-03T12:57:25.697772Z","end":"2026-02-03T12:57:25.980085Z","steps":["trace[1576820064] 'agreement among raft nodes before linearized reading' (duration: 282.177883ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:59:29.234145Z","caller":"traceutil/trace.go:171","msg":"trace[90903959] linearizableReadLoop","detail":"{readStateIndex:1563; appliedIndex:1562; }","duration":"154.647877ms","start":"2026-02-03T12:59:29.079473Z","end":"2026-02-03T12:59:29.234121Z","steps":["trace[90903959] 'read index received' (duration: 151.874996ms)","trace[90903959] 'applied index is now lower than readState.Index' (duration: 2.772281ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T12:59:29.234323Z","caller":"traceutil/trace.go:171","msg":"trace[1566777758] transaction","detail":"{read_only:false; response_revision:1502; number_of_response:1; }","duration":"201.673993ms","start":"2026-02-03T12:59:29.032641Z","end":"2026-02-03T12:59:29.234315Z","steps":["trace[1566777758] 'process raft request' (duration: 198.767118ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:59:29.234533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.023476ms","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-03T12:59:29.23455Z","caller":"traceutil/trace.go:171","msg":"trace[955237305] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1502; }","duration":"155.098787ms","start":"2026-02-03T12:59:29.079447Z","end":"2026-02-03T12:59:29.234546Z","steps":["trace[955237305] 'agreement among raft nodes before linearized reading' (duration: 155.018575ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T13:00:37.828216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.726106ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T13:00:37.828507Z","caller":"traceutil/trace.go:171","msg":"trace[1378249666] transaction","detail":"{read_only:false; response_revision:1812; number_of_response:1; }","duration":"130.602152ms","start":"2026-02-03T13:00:37.697893Z","end":"2026-02-03T13:00:37.828495Z","steps":["trace[1378249666] 'process raft request' (duration: 11.538647ms)","trace[1378249666] 'compare' (duration: 118.665284ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T13:01:11.550091Z","caller":"traceutil/trace.go:171","msg":"trace[1029680155] transaction","detail":"{read_only:false; response_revision:1958; number_of_response:1; }","duration":"103.071607ms","start":"2026-02-03T13:01:11.446778Z","end":"2026-02-03T13:01:11.54985Z","steps":["trace[1029680155] 'process raft request' (duration: 64.061241ms)","trace[1029680155] 'compare' (duration: 38.895282ms)"],"step_count":2}