{"level":"warn","ts":"2026-05-20T03:06:43.478285Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-20T03:06:43.478387Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.53: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.53:2380","--initial-cluster=instance=https://199.204.45.53:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.53:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.53: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-05-20T03:06:43.478473Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-20T03:06:43.478489Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-05-20T03:06:43.478509Z","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-05-20T03:06:43.479328Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"]} {"level":"info","ts":"2026-05-20T03:06:43.479442Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.53: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-05-20T03:06:43.482804Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.092242ms"} {"level":"info","ts":"2026-05-20T03:06:43.489675Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c47ca1a7b1fe506b","cluster-id":"95bd21e158fabf07"} {"level":"info","ts":"2026-05-20T03:06:43.489754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=()"} {"level":"info","ts":"2026-05-20T03:06:43.48978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 0"} {"level":"info","ts":"2026-05-20T03:06:43.48979Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c47ca1a7b1fe506b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-20T03:06:43.489802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 1"} {"level":"info","ts":"2026-05-20T03:06:43.489839Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"warn","ts":"2026-05-20T03:06:43.497396Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-20T03:06:43.498425Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-20T03:06:43.499378Z","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-05-20T03:06:43.50147Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c47ca1a7b1fe506b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-20T03:06:43.501637Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c47ca1a7b1fe506b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-20T03:06:43.501993Z","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-05-20T03:06:43.502549Z","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-05-20T03:06:43.502564Z","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-05-20T03:06:43.50287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"info","ts":"2026-05-20T03:06:43.50304Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","added-peer-id":"c47ca1a7b1fe506b","added-peer-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-05-20T03:06:43.505696Z","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-05-20T03:06:43.505828Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-05-20T03:06:43.505897Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-05-20T03:06:43.505996Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c47ca1a7b1fe506b","initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-20T03:06:43.506039Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-20T03:06:43.792819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b is starting a new election at term 1"} {"level":"info","ts":"2026-05-20T03:06:43.792902Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became pre-candidate at term 1"} {"level":"info","ts":"2026-05-20T03:06:43.792966Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgPreVoteResp from c47ca1a7b1fe506b at term 1"} {"level":"info","ts":"2026-05-20T03:06:43.792988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became candidate at term 2"} {"level":"info","ts":"2026-05-20T03:06:43.793Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgVoteResp from c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-05-20T03:06:43.793035Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became leader at term 2"} {"level":"info","ts":"2026-05-20T03:06:43.793049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c47ca1a7b1fe506b elected leader c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-05-20T03:06:43.796202Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c47ca1a7b1fe506b","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.53:2379]}","request-path":"/0/members/c47ca1a7b1fe506b/attributes","cluster-id":"95bd21e158fabf07","publish-timeout":"7s"} {"level":"info","ts":"2026-05-20T03:06:43.796213Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-20T03:06:43.796294Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-20T03:06:43.796499Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-20T03:06:43.796525Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-20T03:06:43.799305Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T03:06:43.799744Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-20T03:06:43.800143Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.53:2379"} {"level":"info","ts":"2026-05-20T03:06:43.800447Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T03:06:43.800543Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T03:06:43.800574Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T03:06:59.511435Z","caller":"traceutil/trace.go:171","msg":"trace[616445851] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"128.52043ms","start":"2026-05-20T03:06:59.382895Z","end":"2026-05-20T03:06:59.511415Z","steps":["trace[616445851] 'process raft request' (duration: 128.314707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:06:59.784905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.994113ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T03:06:59.785015Z","caller":"traceutil/trace.go:171","msg":"trace[196074964] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"269.283752ms","start":"2026-05-20T03:06:59.515712Z","end":"2026-05-20T03:06:59.784996Z","steps":["trace[196074964] 'process raft request' (duration: 42.393754ms)","trace[196074964] 'compare' (duration: 225.565206ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:07:19.969523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.017648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-20T03:07:19.969603Z","caller":"traceutil/trace.go:171","msg":"trace[1363531324] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:581; }","duration":"106.127659ms","start":"2026-05-20T03:07:19.863456Z","end":"2026-05-20T03:07:19.969584Z","steps":["trace[1363531324] 'range keys from in-memory index tree' (duration: 105.674423ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:19.970103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.909067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qntlz\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-20T03:07:19.970321Z","caller":"traceutil/trace.go:171","msg":"trace[1142482446] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qntlz; range_end:; response_count:1; response_revision:581; }","duration":"131.146711ms","start":"2026-05-20T03:07:19.839124Z","end":"2026-05-20T03:07:19.970271Z","steps":["trace[1142482446] 'range keys from in-memory index tree' (duration: 129.9919ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:07:20.054997Z","caller":"traceutil/trace.go:171","msg":"trace[548680672] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"123.238642ms","start":"2026-05-20T03:07:19.931738Z","end":"2026-05-20T03:07:20.054977Z","steps":["trace[548680672] 'process raft request' (duration: 122.764283ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:07:20.192623Z","caller":"traceutil/trace.go:171","msg":"trace[310222515] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"208.848842ms","start":"2026-05-20T03:07:19.983745Z","end":"2026-05-20T03:07:20.192594Z","steps":["trace[310222515] 'process raft request' (duration: 208.76615ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:07:20.192648Z","caller":"traceutil/trace.go:171","msg":"trace[1783547984] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"219.102837ms","start":"2026-05-20T03:07:19.973514Z","end":"2026-05-20T03:07:20.192616Z","steps":["trace[1783547984] 'process raft request' (duration: 218.822902ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:07:20.816872Z","caller":"traceutil/trace.go:171","msg":"trace[1552474276] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"196.4108ms","start":"2026-05-20T03:07:20.620424Z","end":"2026-05-20T03:07:20.816835Z","steps":["trace[1552474276] 'process raft request' (duration: 195.835859ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:07:21.0095Z","caller":"traceutil/trace.go:171","msg":"trace[1224123298] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"130.732823ms","start":"2026-05-20T03:07:20.878746Z","end":"2026-05-20T03:07:21.009479Z","steps":["trace[1224123298] 'process raft request' (duration: 104.477058ms)","trace[1224123298] 'compare' (duration: 26.128533ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:07:21.009728Z","caller":"traceutil/trace.go:171","msg":"trace[1019362061] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"113.084125ms","start":"2026-05-20T03:07:20.896359Z","end":"2026-05-20T03:07:21.009443Z","steps":["trace[1019362061] 'read index received' (duration: 86.924771ms)","trace[1019362061] 'applied index is now lower than readState.Index' (duration: 26.158634ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:07:21.009772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.40114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-20T03:07:21.009796Z","caller":"traceutil/trace.go:171","msg":"trace[51694964] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:588; }","duration":"113.469051ms","start":"2026-05-20T03:07:20.89632Z","end":"2026-05-20T03:07:21.009789Z","steps":["trace[51694964] 'agreement among raft nodes before linearized reading' (duration: 113.259997ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:08:08.388436Z","caller":"traceutil/trace.go:171","msg":"trace[608995326] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"552.383877ms","start":"2026-05-20T03:08:07.836033Z","end":"2026-05-20T03:08:08.388417Z","steps":["trace[608995326] 'process raft request' (duration: 552.265474ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:08:08.38858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:08:07.836012Z","time spent":"552.490659ms","remote":"127.0.0.1:37022","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-05-20T03:08:09.058447Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794899357644859931,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-20T03:08:09.105747Z","caller":"traceutil/trace.go:171","msg":"trace[1542958713] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"587.531842ms","start":"2026-05-20T03:08:08.518198Z","end":"2026-05-20T03:08:09.10573Z","steps":["trace[1542958713] 'process raft request' (duration: 587.4441ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:08:09.105841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:08:08.51818Z","time spent":"587.612324ms","remote":"127.0.0.1:37022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:08:09.107594Z","caller":"traceutil/trace.go:171","msg":"trace[1010273764] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"560.582293ms","start":"2026-05-20T03:08:08.546994Z","end":"2026-05-20T03:08:09.107576Z","steps":["trace[1010273764] 'process raft request' (duration: 560.46932ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:08:09.107589Z","caller":"traceutil/trace.go:171","msg":"trace[816384929] linearizableReadLoop","detail":"{readStateIndex:1014; appliedIndex:1012; }","duration":"549.905495ms","start":"2026-05-20T03:08:08.55765Z","end":"2026-05-20T03:08:09.107556Z","steps":["trace[816384929] 'read index received' (duration: 548.032116ms)","trace[816384929] 'applied index is now lower than readState.Index' (duration: 1.872549ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:08:09.107642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:08:08.54698Z","time spent":"560.638474ms","remote":"127.0.0.1:37022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:08:09.107882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.931205ms","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-05-20T03:08:09.107902Z","caller":"traceutil/trace.go:171","msg":"trace[728453079] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:979; }","duration":"452.988547ms","start":"2026-05-20T03:08:08.654906Z","end":"2026-05-20T03:08:09.107895Z","steps":["trace[728453079] 'agreement among raft nodes before linearized reading' (duration: 452.935025ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:08:09.107921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:08:08.654889Z","time spent":"453.027477ms","remote":"127.0.0.1:37022","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-05-20T03:08:09.107734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.066798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-20T03:08:09.108343Z","caller":"traceutil/trace.go:171","msg":"trace[1490889374] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:979; }","duration":"550.737652ms","start":"2026-05-20T03:08:08.557598Z","end":"2026-05-20T03:08:09.108336Z","steps":["trace[1490889374] 'agreement among raft nodes before linearized reading' (duration: 550.043068ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:08:09.108366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:08:08.55758Z","time spent":"550.779473ms","remote":"127.0.0.1:37022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-20T03:08:11.038586Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794899357644859943,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T03:08:11.539274Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794899357644859943,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T03:08:12.039797Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794899357644859943,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T03:08:12.540242Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794899357644859943,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T03:08:12.626345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.088065677s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-20T03:08:12.62642Z","caller":"traceutil/trace.go:171","msg":"trace[1143804808] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.08818792s","start":"2026-05-20T03:08:10.538211Z","end":"2026-05-20T03:08:12.626399Z","steps":["trace[1143804808] 'agreement among raft nodes before linearized reading' (duration: 2.088101128s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:08:12.62647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:08:10.538191Z","time spent":"2.088269892s","remote":"127.0.0.1:36788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-20T03:08:13.04135Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794899357644859943,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T03:08:13.541793Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794899357644859943,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T03:08:13.760176Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.362482348s","expected-duration":"1s"} {"level":"info","ts":"2026-05-20T03:08:13.760556Z","caller":"traceutil/trace.go:171","msg":"trace[1236716796] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"3.362948408s","start":"2026-05-20T03:08:10.397583Z","end":"2026-05-20T03:08:13.760531Z","steps":["trace[1236716796] 'process raft request' (duration: 3.362797655s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:08:13.760683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:08:10.397566Z","time spent":"3.36303884s","remote":"127.0.0.1:37022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:08:13.7704Z","caller":"traceutil/trace.go:171","msg":"trace[1002669168] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"2.657486954s","start":"2026-05-20T03:08:11.112889Z","end":"2026-05-20T03:08:13.770376Z","steps":["trace[1002669168] 'process raft request' (duration: 2.657320571s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:08:13.770567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:08:11.112854Z","time spent":"2.657615296s","remote":"127.0.0.1:37022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:08:13.781234Z","caller":"traceutil/trace.go:171","msg":"trace[1105461135] linearizableReadLoop","detail":"{readStateIndex:1021; appliedIndex:1018; }","duration":"3.242941393s","start":"2026-05-20T03:08:10.538265Z","end":"2026-05-20T03:08:13.781207Z","steps":["trace[1105461135] 'read index received' (duration: 3.222102814s)","trace[1105461135] 'applied index is now lower than readState.Index' (duration: 20.837109ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:08:13.781504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.671685436s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-20T03:08:13.781532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.513172297s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-20T03:08:13.781536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.152926076s","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-05-20T03:08:13.781533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.144988843s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:08:13.781586Z","caller":"traceutil/trace.go:171","msg":"trace[980952668] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:985; }","duration":"2.513260629s","start":"2026-05-20T03:08:11.26831Z","end":"2026-05-20T03:08:13.781571Z","steps":["trace[980952668] 'agreement among raft nodes before linearized reading' (duration: 2.513123836s)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:08:13.781591Z","caller":"traceutil/trace.go:171","msg":"trace[2042682490] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:985; }","duration":"1.153061169s","start":"2026-05-20T03:08:12.628517Z","end":"2026-05-20T03:08:13.781578Z","steps":["trace[2042682490] 'agreement among raft nodes before linearized reading' (duration: 1.152884215s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:08:13.781614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:08:11.268279Z","time spent":"2.513329159s","remote":"127.0.0.1:37022","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-05-20T03:08:13.781612Z","caller":"traceutil/trace.go:171","msg":"trace[996316096] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:985; }","duration":"1.145066804s","start":"2026-05-20T03:08:12.636528Z","end":"2026-05-20T03:08:13.781595Z","steps":["trace[996316096] 'agreement among raft nodes before linearized reading' (duration: 1.144959312s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:08:13.781627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:08:12.628488Z","time spent":"1.15313122s","remote":"127.0.0.1:37022","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-05-20T03:08:13.781645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:08:12.636483Z","time spent":"1.145154606s","remote":"127.0.0.1:36792","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-05-20T03:08:13.781551Z","caller":"traceutil/trace.go:171","msg":"trace[138852835] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:985; }","duration":"2.671778618s","start":"2026-05-20T03:08:11.109762Z","end":"2026-05-20T03:08:13.78154Z","steps":["trace[138852835] 'agreement among raft nodes before linearized reading' (duration: 2.671607214s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:08:13.781698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.660300262s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-20T03:08:13.781731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:08:11.109742Z","time spent":"2.671976011s","remote":"127.0.0.1:37022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-20T03:08:13.781769Z","caller":"traceutil/trace.go:171","msg":"trace[433299007] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:985; }","duration":"2.660414815s","start":"2026-05-20T03:08:11.121336Z","end":"2026-05-20T03:08:13.781751Z","steps":["trace[433299007] 'agreement among raft nodes before linearized reading' (duration: 2.660187271s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:08:13.781813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:08:11.12132Z","time spent":"2.660484436s","remote":"127.0.0.1:37022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-20T03:09:20.171365Z","caller":"traceutil/trace.go:171","msg":"trace[196785217] linearizableReadLoop","detail":"{readStateIndex:1258; appliedIndex:1257; }","duration":"140.843431ms","start":"2026-05-20T03:09:20.030502Z","end":"2026-05-20T03:09:20.171345Z","steps":["trace[196785217] 'read index received' (duration: 140.694448ms)","trace[196785217] 'applied index is now lower than readState.Index' (duration: 148.033µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:09:20.171609Z","caller":"traceutil/trace.go:171","msg":"trace[786835049] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"172.557213ms","start":"2026-05-20T03:09:19.999031Z","end":"2026-05-20T03:09:20.171588Z","steps":["trace[786835049] 'process raft request' (duration: 172.205026ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:09:20.171778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.252891ms","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-05-20T03:09:20.171896Z","caller":"traceutil/trace.go:171","msg":"trace[1579572450] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1208; }","duration":"141.437885ms","start":"2026-05-20T03:09:20.030427Z","end":"2026-05-20T03:09:20.171865Z","steps":["trace[1579572450] 'agreement among raft nodes before linearized reading' (duration: 141.122837ms)"],"step_count":1}