{"level":"warn","ts":"2026-02-12T01:49:24.705302Z","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-12T01:49:24.705402Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.157: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.157:2380","--initial-cluster=instance=https://199.204.45.157:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.157:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.157: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-12T01:49:24.705473Z","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-12T01:49:24.706621Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-02-12T01:49:24.70672Z","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-12T01:49:24.70752Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"]} {"level":"info","ts":"2026-02-12T01:49:24.707594Z","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.157:2380"],"listen-peer-urls":["https://199.204.45.157:2380"],"advertise-client-urls":["https://199.204.45.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.157: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-12T01:49:24.720087Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"12.290247ms"} {"level":"info","ts":"2026-02-12T01:49:24.732653Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5f170c75a1d97cc","cluster-id":"44a3176ec85feb9"} {"level":"info","ts":"2026-02-12T01:49:24.732724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=()"} {"level":"info","ts":"2026-02-12T01:49:24.732747Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 0"} {"level":"info","ts":"2026-02-12T01:49:24.732755Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5f170c75a1d97cc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-12T01:49:24.732767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 1"} {"level":"info","ts":"2026-02-12T01:49:24.732794Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"warn","ts":"2026-02-12T01:49:24.739891Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-12T01:49:24.741782Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-12T01:49:24.751374Z","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-12T01:49:24.756798Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5f170c75a1d97cc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-12T01:49:24.766316Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5f170c75a1d97cc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-12T01:49:24.767265Z","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-12T01:49:24.767285Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-02-12T01:49:24.767802Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-02-12T01:49:24.767459Z","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-12T01:49:24.767818Z","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-12T01:49:24.767826Z","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-12T01:49:24.767694Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"info","ts":"2026-02-12T01:49:24.768064Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"44a3176ec85feb9","local-member-id":"5f170c75a1d97cc","added-peer-id":"5f170c75a1d97cc","added-peer-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-02-12T01:49:24.769172Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5f170c75a1d97cc","initial-advertise-peer-urls":["https://199.204.45.157:2380"],"listen-peer-urls":["https://199.204.45.157:2380"],"advertise-client-urls":["https://199.204.45.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-12T01:49:24.769196Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-12T01:49:25.533503Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc is starting a new election at term 1"} {"level":"info","ts":"2026-02-12T01:49:25.533547Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became pre-candidate at term 1"} {"level":"info","ts":"2026-02-12T01:49:25.533567Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgPreVoteResp from 5f170c75a1d97cc at term 1"} {"level":"info","ts":"2026-02-12T01:49:25.533578Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became candidate at term 2"} {"level":"info","ts":"2026-02-12T01:49:25.533583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgVoteResp from 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-02-12T01:49:25.533591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became leader at term 2"} {"level":"info","ts":"2026-02-12T01:49:25.533598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5f170c75a1d97cc elected leader 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-02-12T01:49:25.536046Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T01:49:25.538819Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5f170c75a1d97cc","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.157:2379]}","request-path":"/0/members/5f170c75a1d97cc/attributes","cluster-id":"44a3176ec85feb9","publish-timeout":"7s"} {"level":"info","ts":"2026-02-12T01:49:25.538841Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-12T01:49:25.539339Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-12T01:49:25.541911Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-12T01:49:25.551006Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.157:2379"} {"level":"info","ts":"2026-02-12T01:49:25.565434Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-12T01:49:25.565454Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-12T01:49:25.575893Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"44a3176ec85feb9","local-member-id":"5f170c75a1d97cc","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T01:49:25.575989Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T01:49:25.576013Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-12T01:49:43.862656Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938289460406353831,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T01:49:44.357376Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.498520355s","expected-duration":"1s"} {"level":"info","ts":"2026-02-12T01:49:44.357748Z","caller":"traceutil/trace.go:171","msg":"trace[1614973715] linearizableReadLoop","detail":"{readStateIndex:434; appliedIndex:433; }","duration":"995.877714ms","start":"2026-02-12T01:49:43.36185Z","end":"2026-02-12T01:49:44.357728Z","steps":["trace[1614973715] 'read index received' (duration: 995.736073ms)","trace[1614973715] 'applied index is now lower than readState.Index' (duration: 140.771µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T01:49:44.357847Z","caller":"traceutil/trace.go:171","msg":"trace[2067253086] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"1.499057451s","start":"2026-02-12T01:49:42.858783Z","end":"2026-02-12T01:49:44.35784Z","steps":["trace[2067253086] 'process raft request' (duration: 1.498792628s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:49:44.358083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"996.210828ms","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-12T01:49:44.35816Z","caller":"traceutil/trace.go:171","msg":"trace[2098587288] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:421; }","duration":"996.330619ms","start":"2026-02-12T01:49:43.361808Z","end":"2026-02-12T01:49:44.358139Z","steps":["trace[2098587288] 'agreement among raft nodes before linearized reading' (duration: 996.210758ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:49:44.358199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:49:43.361789Z","time spent":"996.401499ms","remote":"127.0.0.1:59654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-12T01:49:44.35824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:49:42.858774Z","time spent":"1.499094031s","remote":"127.0.0.1:59836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4158,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T01:49:44.862813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.028283ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-12T01:49:44.863074Z","caller":"traceutil/trace.go:171","msg":"trace[482252718] linearizableReadLoop","detail":"{readStateIndex:435; appliedIndex:434; }","duration":"505.25151ms","start":"2026-02-12T01:49:44.357808Z","end":"2026-02-12T01:49:44.86306Z","steps":["trace[482252718] 'read index received' (duration: 256.827684ms)","trace[482252718] 'applied index is now lower than readState.Index' (duration: 248.422216ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T01:49:44.863118Z","caller":"traceutil/trace.go:171","msg":"trace[408353608] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"492.745471ms","start":"2026-02-12T01:49:44.370359Z","end":"2026-02-12T01:49:44.863104Z","steps":["trace[408353608] 'process raft request' (duration: 492.695761ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:49:44.863206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.958837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-02-12T01:49:44.863199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:49:44.37035Z","time spent":"492.802491ms","remote":"127.0.0.1:59868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3656,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-12T01:49:44.863227Z","caller":"traceutil/trace.go:171","msg":"trace[511112067] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:423; }","duration":"709.026087ms","start":"2026-02-12T01:49:44.154194Z","end":"2026-02-12T01:49:44.86322Z","steps":["trace[511112067] 'agreement among raft nodes before linearized reading' (duration: 708.940596ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:49:44.863258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:49:44.154175Z","time spent":"709.077247ms","remote":"127.0.0.1:59654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-12T01:49:44.863429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.432390953s","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-12T01:49:44.863451Z","caller":"traceutil/trace.go:171","msg":"trace[916936952] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:423; }","duration":"1.432431683s","start":"2026-02-12T01:49:43.431012Z","end":"2026-02-12T01:49:44.863444Z","steps":["trace[916936952] 'agreement among raft nodes before linearized reading' (duration: 1.432372773s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:49:44.863473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:49:43.431001Z","time spent":"1.432466034s","remote":"127.0.0.1:59654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-12T01:49:44.863505Z","caller":"traceutil/trace.go:171","msg":"trace[1272723246] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"495.040308ms","start":"2026-02-12T01:49:44.368454Z","end":"2026-02-12T01:49:44.863495Z","steps":["trace[1272723246] 'process raft request' (duration: 494.465414ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:49:44.86357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:49:44.368436Z","time spent":"495.099318ms","remote":"127.0.0.1:59654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T01:49:44.863585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.472621761s","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-12T01:49:44.86361Z","caller":"traceutil/trace.go:171","msg":"trace[1531479912] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:423; }","duration":"1.472667501s","start":"2026-02-12T01:49:43.390934Z","end":"2026-02-12T01:49:44.863602Z","steps":["trace[1531479912] 'agreement among raft nodes before linearized reading' (duration: 1.472624501s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:49:44.863631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:49:43.390924Z","time spent":"1.472701441s","remote":"127.0.0.1:59654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-12T01:49:58.227567Z","caller":"traceutil/trace.go:171","msg":"trace[946683148] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"167.794319ms","start":"2026-02-12T01:49:58.059753Z","end":"2026-02-12T01:49:58.227548Z","steps":["trace[946683148] 'process raft request' (duration: 167.641482ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:50:05.368195Z","caller":"traceutil/trace.go:171","msg":"trace[239322583] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:573; }","duration":"341.113302ms","start":"2026-02-12T01:50:05.027065Z","end":"2026-02-12T01:50:05.368178Z","steps":["trace[239322583] 'read index received' (duration: 340.908064ms)","trace[239322583] 'applied index is now lower than readState.Index' (duration: 204.588µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T01:50:05.36846Z","caller":"traceutil/trace.go:171","msg":"trace[856205866] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"356.465979ms","start":"2026-02-12T01:50:05.011985Z","end":"2026-02-12T01:50:05.36845Z","steps":["trace[856205866] 'process raft request' (duration: 356.043591ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:50:05.368528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:50:05.011968Z","time spent":"356.512021ms","remote":"127.0.0.1:59572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T01:50:05.368709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.647694ms","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-12T01:50:05.368732Z","caller":"traceutil/trace.go:171","msg":"trace[1589678786] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:555; }","duration":"341.703527ms","start":"2026-02-12T01:50:05.02702Z","end":"2026-02-12T01:50:05.368723Z","steps":["trace[1589678786] 'agreement among raft nodes before linearized reading' (duration: 341.630704ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:50:05.36875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:50:05.027007Z","time spent":"341.739248ms","remote":"127.0.0.1:59654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-12T01:50:05.368866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.412882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-12T01:50:05.368882Z","caller":"traceutil/trace.go:171","msg":"trace[471320320] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:555; }","duration":"310.460034ms","start":"2026-02-12T01:50:05.058417Z","end":"2026-02-12T01:50:05.368877Z","steps":["trace[471320320] 'agreement among raft nodes before linearized reading' (duration: 310.414252ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:50:05.368898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:50:05.058401Z","time spent":"310.493636ms","remote":"127.0.0.1:59654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-12T01:50:05.369085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.516054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-12T01:50:05.369104Z","caller":"traceutil/trace.go:171","msg":"trace[1239895917] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:555; }","duration":"281.543726ms","start":"2026-02-12T01:50:05.087555Z","end":"2026-02-12T01:50:05.369099Z","steps":["trace[1239895917] 'agreement among raft nodes before linearized reading' (duration: 281.492944ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:50:05.369205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.65885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-12T01:50:05.369221Z","caller":"traceutil/trace.go:171","msg":"trace[1448160792] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:555; }","duration":"281.701301ms","start":"2026-02-12T01:50:05.087515Z","end":"2026-02-12T01:50:05.369216Z","steps":["trace[1448160792] 'agreement among raft nodes before linearized reading' (duration: 281.65507ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:50:06.364597Z","caller":"traceutil/trace.go:171","msg":"trace[298852409] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"988.442914ms","start":"2026-02-12T01:50:05.376137Z","end":"2026-02-12T01:50:06.36458Z","steps":["trace[298852409] 'process raft request' (duration: 988.32435ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:50:06.364702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:50:05.376125Z","time spent":"988.517758ms","remote":"127.0.0.1:59654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T01:50:06.377843Z","caller":"traceutil/trace.go:171","msg":"trace[286221547] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"1.000407647s","start":"2026-02-12T01:50:05.377424Z","end":"2026-02-12T01:50:06.377831Z","steps":["trace[286221547] 'process raft request' (duration: 1.000387597s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:50:06.377907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:50:05.377418Z","time spent":"1.00045566s","remote":"127.0.0.1:59654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T01:50:06.378064Z","caller":"traceutil/trace.go:171","msg":"trace[427817193] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"1.001786523s","start":"2026-02-12T01:50:05.376271Z","end":"2026-02-12T01:50:06.378058Z","steps":["trace[427817193] 'process raft request' (duration: 1.001458991s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:50:06.378099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:50:05.376266Z","time spent":"1.001813334s","remote":"127.0.0.1:59654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T01:50:06.378184Z","caller":"traceutil/trace.go:171","msg":"trace[980513537] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"1.00170635s","start":"2026-02-12T01:50:05.376471Z","end":"2026-02-12T01:50:06.378177Z","steps":["trace[980513537] 'process raft request' (duration: 1.001307934s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:50:06.378227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:50:05.376465Z","time spent":"1.001734771s","remote":"127.0.0.1:59654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T01:50:08.881432Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938289460406354109,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-12T01:50:09.360784Z","caller":"traceutil/trace.go:171","msg":"trace[1713027705] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"990.02014ms","start":"2026-02-12T01:50:08.370735Z","end":"2026-02-12T01:50:09.360755Z","steps":["trace[1713027705] 'process raft request' (duration: 989.887165ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:50:09.360959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:50:08.370723Z","time spent":"990.146305ms","remote":"127.0.0.1:59654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T01:50:09.398883Z","caller":"traceutil/trace.go:171","msg":"trace[1658872352] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:579; }","duration":"1.017867911s","start":"2026-02-12T01:50:08.380998Z","end":"2026-02-12T01:50:09.398866Z","steps":["trace[1658872352] 'read index received' (duration: 980.472779ms)","trace[1658872352] 'applied index is now lower than readState.Index' (duration: 37.394472ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T01:50:09.399087Z","caller":"traceutil/trace.go:171","msg":"trace[1712495651] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"1.003304636s","start":"2026-02-12T01:50:08.395776Z","end":"2026-02-12T01:50:09.399081Z","steps":["trace[1712495651] 'process raft request' (duration: 1.003005215s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:50:09.39915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:50:08.395763Z","time spent":"1.003338978s","remote":"127.0.0.1:59654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T01:50:09.399407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.018108039s","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-12T01:50:09.399435Z","caller":"traceutil/trace.go:171","msg":"trace[180158402] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:561; }","duration":"1.018148211s","start":"2026-02-12T01:50:08.38128Z","end":"2026-02-12T01:50:09.399428Z","steps":["trace[180158402] 'agreement among raft nodes before linearized reading' (duration: 1.018046638s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:50:09.399477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:50:08.381275Z","time spent":"1.018174382s","remote":"127.0.0.1:59654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-12T01:50:09.399459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.018410652s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-12T01:50:09.399535Z","caller":"traceutil/trace.go:171","msg":"trace[1292187815] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:561; }","duration":"1.018535896s","start":"2026-02-12T01:50:08.380981Z","end":"2026-02-12T01:50:09.399517Z","steps":["trace[1292187815] 'agreement among raft nodes before linearized reading' (duration: 1.018283176s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:50:09.399566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.304537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T01:50:09.399583Z","caller":"traceutil/trace.go:171","msg":"trace[1165108482] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:561; }","duration":"555.334247ms","start":"2026-02-12T01:50:08.844244Z","end":"2026-02-12T01:50:09.399579Z","steps":["trace[1165108482] 'agreement among raft nodes before linearized reading' (duration: 555.296375ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:50:09.399583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:50:08.380974Z","time spent":"1.01859576s","remote":"127.0.0.1:59654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-12T01:50:09.399596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:50:08.844238Z","time spent":"555.355158ms","remote":"127.0.0.1:59356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T01:50:09.399693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.677845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.157\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-12T01:50:09.399707Z","caller":"traceutil/trace.go:171","msg":"trace[1510537726] range","detail":"{range_begin:/registry/masterleases/199.204.45.157; range_end:; response_count:1; response_revision:561; }","duration":"559.716777ms","start":"2026-02-12T01:50:08.839986Z","end":"2026-02-12T01:50:09.399703Z","steps":["trace[1510537726] 'agreement among raft nodes before linearized reading' (duration: 559.674975ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:50:09.399722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:50:08.839973Z","time spent":"559.745188ms","remote":"127.0.0.1:59390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.157\" "} {"level":"warn","ts":"2026-02-12T01:50:09.399825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.017468046s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-12T01:50:09.399843Z","caller":"traceutil/trace.go:171","msg":"trace[843916691] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:561; }","duration":"1.017498017s","start":"2026-02-12T01:50:08.382339Z","end":"2026-02-12T01:50:09.399838Z","steps":["trace[843916691] 'agreement among raft nodes before linearized reading' (duration: 1.017444405s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:50:09.39986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:50:08.382333Z","time spent":"1.017523168s","remote":"127.0.0.1:59654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-12T01:50:10.25331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.17145ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T01:50:10.253373Z","caller":"traceutil/trace.go:171","msg":"trace[745423453] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"227.028556ms","start":"2026-02-12T01:50:10.026333Z","end":"2026-02-12T01:50:10.253362Z","steps":["trace[745423453] 'process raft request' (duration: 120.754002ms)","trace[745423453] 'compare' (duration: 106.083817ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T01:50:11.701469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.406146ms","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-12T01:50:11.701545Z","caller":"traceutil/trace.go:171","msg":"trace[2025634005] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:574; }","duration":"336.51897ms","start":"2026-02-12T01:50:11.365009Z","end":"2026-02-12T01:50:11.701528Z","steps":["trace[2025634005] 'range keys from in-memory index tree' (duration: 336.355944ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:50:11.701587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:50:11.364995Z","time spent":"336.582922ms","remote":"127.0.0.1:59654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-12T01:50:11.701724Z","caller":"traceutil/trace.go:171","msg":"trace[100445106] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:594; }","duration":"263.476334ms","start":"2026-02-12T01:50:11.43823Z","end":"2026-02-12T01:50:11.701706Z","steps":["trace[100445106] 'read index received' (duration: 240.207374ms)","trace[100445106] 'applied index is now lower than readState.Index' (duration: 23.26777ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T01:50:11.701955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.155033ms","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-12T01:50:11.701988Z","caller":"traceutil/trace.go:171","msg":"trace[152551440] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:575; }","duration":"263.196585ms","start":"2026-02-12T01:50:11.438781Z","end":"2026-02-12T01:50:11.701978Z","steps":["trace[152551440] 'agreement among raft nodes before linearized reading' (duration: 263.126552ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:50:11.702036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.799227ms","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-12T01:50:11.70207Z","caller":"traceutil/trace.go:171","msg":"trace[179378140] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:575; }","duration":"263.86559ms","start":"2026-02-12T01:50:11.438194Z","end":"2026-02-12T01:50:11.70206Z","steps":["trace[179378140] 'agreement among raft nodes before linearized reading' (duration: 263.803867ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:50:11.702126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.931454ms","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-12T01:50:11.702151Z","caller":"traceutil/trace.go:171","msg":"trace[1546291403] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:575; }","duration":"262.971185ms","start":"2026-02-12T01:50:11.439173Z","end":"2026-02-12T01:50:11.702144Z","steps":["trace[1546291403] 'agreement among raft nodes before linearized reading' (duration: 262.926523ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:50:11.702234Z","caller":"traceutil/trace.go:171","msg":"trace[1321149023] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"308.489911ms","start":"2026-02-12T01:50:11.393704Z","end":"2026-02-12T01:50:11.702194Z","steps":["trace[1321149023] 'process raft request' (duration: 284.788624ms)","trace[1321149023] 'compare' (duration: 22.844085ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T01:50:11.70231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:50:11.393679Z","time spent":"308.579973ms","remote":"127.0.0.1:59654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T01:50:14.504811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.649757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-02-12T01:50:14.50505Z","caller":"traceutil/trace.go:171","msg":"trace[1190064407] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:633; }","duration":"226.952928ms","start":"2026-02-12T01:50:14.278077Z","end":"2026-02-12T01:50:14.50503Z","steps":["trace[1190064407] 'range keys from in-memory index tree' (duration: 226.46264ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:51:11.236412Z","caller":"traceutil/trace.go:171","msg":"trace[2087403679] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"126.719309ms","start":"2026-02-12T01:51:11.109373Z","end":"2026-02-12T01:51:11.236093Z","steps":["trace[2087403679] 'process raft request' (duration: 126.524435ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:51:20.555576Z","caller":"traceutil/trace.go:171","msg":"trace[1720734479] linearizableReadLoop","detail":"{readStateIndex:946; appliedIndex:945; }","duration":"148.890114ms","start":"2026-02-12T01:51:20.406663Z","end":"2026-02-12T01:51:20.555553Z","steps":["trace[1720734479] 'read index received' (duration: 78.699257ms)","trace[1720734479] 'applied index is now lower than readState.Index' (duration: 70.189957ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T01:51:20.556038Z","caller":"traceutil/trace.go:171","msg":"trace[55148540] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"168.260898ms","start":"2026-02-12T01:51:20.38776Z","end":"2026-02-12T01:51:20.556021Z","steps":["trace[55148540] 'process raft request' (duration: 97.673002ms)","trace[55148540] 'compare' (duration: 70.025795ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T01:51:20.556184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.503726ms","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-12T01:51:20.557666Z","caller":"traceutil/trace.go:171","msg":"trace[1647415299] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:909; }","duration":"151.010066ms","start":"2026-02-12T01:51:20.406639Z","end":"2026-02-12T01:51:20.557649Z","steps":["trace[1647415299] 'agreement among raft nodes before linearized reading' (duration: 149.435385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:51:36.720963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.024316ms","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-12T01:51:36.72126Z","caller":"traceutil/trace.go:171","msg":"trace[1273597330] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:989; }","duration":"262.348992ms","start":"2026-02-12T01:51:36.458895Z","end":"2026-02-12T01:51:36.721244Z","steps":["trace[1273597330] 'range keys from in-memory index tree' (duration: 261.908654ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:51:58.747637Z","caller":"traceutil/trace.go:171","msg":"trace[70415995] linearizableReadLoop","detail":"{readStateIndex:1099; appliedIndex:1098; }","duration":"130.112905ms","start":"2026-02-12T01:51:58.617509Z","end":"2026-02-12T01:51:58.747622Z","steps":["trace[70415995] 'read index received' (duration: 118.98081ms)","trace[70415995] 'applied index is now lower than readState.Index' (duration: 11.131305ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T01:51:58.747896Z","caller":"traceutil/trace.go:171","msg":"trace[1701252710] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"155.729158ms","start":"2026-02-12T01:51:58.592126Z","end":"2026-02-12T01:51:58.747856Z","steps":["trace[1701252710] 'process raft request' (duration: 144.354229ms)","trace[1701252710] 'compare' (duration: 10.979863ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T01:51:58.748765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.311964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/local-path-storage/\" range_end:\"/registry/ingress/local-path-storage0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T01:51:58.748864Z","caller":"traceutil/trace.go:171","msg":"trace[1409040861] range","detail":"{range_begin:/registry/ingress/local-path-storage/; range_end:/registry/ingress/local-path-storage0; response_count:0; response_revision:1054; }","duration":"129.381946ms","start":"2026-02-12T01:51:58.619427Z","end":"2026-02-12T01:51:58.748809Z","steps":["trace[1409040861] 'agreement among raft nodes before linearized reading' (duration: 129.296744ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:51:58.748012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.481941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/local-path-storage/\" range_end:\"/registry/services/endpoints/local-path-storage0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T01:51:58.749196Z","caller":"traceutil/trace.go:171","msg":"trace[884451237] range","detail":"{range_begin:/registry/services/endpoints/local-path-storage/; range_end:/registry/services/endpoints/local-path-storage0; response_count:0; response_revision:1054; }","duration":"131.720311ms","start":"2026-02-12T01:51:58.617464Z","end":"2026-02-12T01:51:58.749184Z","steps":["trace[884451237] 'agreement among raft nodes before linearized reading' (duration: 130.495322ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:52:02.899081Z","caller":"traceutil/trace.go:171","msg":"trace[280759185] linearizableReadLoop","detail":"{readStateIndex:1112; appliedIndex:1111; }","duration":"131.259055ms","start":"2026-02-12T01:52:02.767801Z","end":"2026-02-12T01:52:02.89906Z","steps":["trace[280759185] 'read index received' (duration: 131.090962ms)","trace[280759185] 'applied index is now lower than readState.Index' (duration: 167.113µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T01:52:02.899257Z","caller":"traceutil/trace.go:171","msg":"trace[1062196897] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"133.711693ms","start":"2026-02-12T01:52:02.765532Z","end":"2026-02-12T01:52:02.899243Z","steps":["trace[1062196897] 'process raft request' (duration: 133.406118ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:52:02.899572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.988869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system::extension-apiserver-authentication-reader\" ","response":"range_response_count:1 size:856"} {"level":"info","ts":"2026-02-12T01:52:02.899756Z","caller":"traceutil/trace.go:171","msg":"trace[2118440359] range","detail":"{range_begin:/registry/rolebindings/kube-system/system::extension-apiserver-authentication-reader; range_end:; response_count:1; response_revision:1066; }","duration":"105.207162ms","start":"2026-02-12T01:52:02.794526Z","end":"2026-02-12T01:52:02.899733Z","steps":["trace[2118440359] 'agreement among raft nodes before linearized reading' (duration: 104.966898ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:52:02.899821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.001796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/kubeadm:nodes-kubeadm-config\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-02-12T01:52:02.900225Z","caller":"traceutil/trace.go:171","msg":"trace[1429629239] range","detail":"{range_begin:/registry/rolebindings/kube-system/kubeadm:nodes-kubeadm-config; range_end:; response_count:1; response_revision:1066; }","duration":"132.415963ms","start":"2026-02-12T01:52:02.767795Z","end":"2026-02-12T01:52:02.900211Z","steps":["trace[1429629239] 'agreement among raft nodes before linearized reading' (duration: 131.964245ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:52:03.165805Z","caller":"traceutil/trace.go:171","msg":"trace[3721825] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"101.65266ms","start":"2026-02-12T01:52:03.064131Z","end":"2026-02-12T01:52:03.165783Z","steps":["trace[3721825] 'process raft request' (duration: 41.72499ms)","trace[3721825] 'compare' (duration: 59.580034ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T01:52:03.457955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.148843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system:controller:cloud-provider\" ","response":"range_response_count:1 size:733"} {"level":"info","ts":"2026-02-12T01:52:03.458097Z","caller":"traceutil/trace.go:171","msg":"trace[1155152036] range","detail":"{range_begin:/registry/rolebindings/kube-system/system:controller:cloud-provider; range_end:; response_count:1; response_revision:1069; }","duration":"114.332115ms","start":"2026-02-12T01:52:03.343745Z","end":"2026-02-12T01:52:03.458077Z","steps":["trace[1155152036] 'range keys from in-memory index tree' (duration: 114.067661ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:52:03.458483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.686538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system:controller:bootstrap-signer\" ","response":"range_response_count:1 size:741"} {"level":"info","ts":"2026-02-12T01:52:03.458689Z","caller":"traceutil/trace.go:171","msg":"trace[73728733] range","detail":"{range_begin:/registry/rolebindings/kube-system/system:controller:bootstrap-signer; range_end:; response_count:1; response_revision:1069; }","duration":"134.917581ms","start":"2026-02-12T01:52:03.323758Z","end":"2026-02-12T01:52:03.458675Z","steps":["trace[73728733] 'range keys from in-memory index tree' (duration: 134.616137ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:52:03.74788Z","caller":"traceutil/trace.go:171","msg":"trace[424291325] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"107.666033ms","start":"2026-02-12T01:52:03.640184Z","end":"2026-02-12T01:52:03.74785Z","steps":["trace[424291325] 'process raft request' (duration: 107.51946ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:52:05.317827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.572334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/attachdetach-controller\" ","response":"range_response_count:1 size:212"} {"level":"info","ts":"2026-02-12T01:52:05.317984Z","caller":"traceutil/trace.go:171","msg":"trace[63619148] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/attachdetach-controller; range_end:; response_count:1; response_revision:1075; }","duration":"170.689946ms","start":"2026-02-12T01:52:05.147212Z","end":"2026-02-12T01:52:05.317902Z","steps":["trace[63619148] 'range keys from in-memory index tree' (duration: 170.491803ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:52:05.318516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.469067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-12T01:52:05.318567Z","caller":"traceutil/trace.go:171","msg":"trace[1793043236] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1075; }","duration":"148.552318ms","start":"2026-02-12T01:52:05.170002Z","end":"2026-02-12T01:52:05.318554Z","steps":["trace[1793043236] 'range keys from in-memory index tree' (duration: 148.364246ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:52:05.319808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.744866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/sh.helm.release.v1.cilium.v1\" ","response":"range_response_count:1 size:317274"} {"level":"info","ts":"2026-02-12T01:52:05.319892Z","caller":"traceutil/trace.go:171","msg":"trace[1721113724] range","detail":"{range_begin:/registry/secrets/kube-system/sh.helm.release.v1.cilium.v1; range_end:; response_count:1; response_revision:1075; }","duration":"147.855228ms","start":"2026-02-12T01:52:05.172024Z","end":"2026-02-12T01:52:05.319879Z","steps":["trace[1721113724] 'range keys from in-memory index tree' (duration: 146.584169ms)"],"step_count":1}