{"level":"warn","ts":"2026-02-09T02:38:08.089246Z","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-09T02:38:08.089334Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.3: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.3:2380","--initial-cluster=instance=https://199.204.45.3:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.3:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.3: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-09T02:38:08.089406Z","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-09T02:38:08.089414Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-02-09T02:38:08.089431Z","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-09T02:38:08.090165Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"]} {"level":"info","ts":"2026-02-09T02:38:08.090326Z","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.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.3: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-09T02:38:08.096237Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.63026ms"} {"level":"info","ts":"2026-02-09T02:38:08.104232Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"597401b06ce1cf3d","cluster-id":"dfc54657ad8a2432"} {"level":"info","ts":"2026-02-09T02:38:08.104373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=()"} {"level":"info","ts":"2026-02-09T02:38:08.104432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 0"} {"level":"info","ts":"2026-02-09T02:38:08.104893Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 597401b06ce1cf3d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-09T02:38:08.104944Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 1"} {"level":"info","ts":"2026-02-09T02:38:08.105009Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"warn","ts":"2026-02-09T02:38:08.115607Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-09T02:38:08.151933Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-09T02:38:08.1595Z","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-09T02:38:08.165933Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"597401b06ce1cf3d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-09T02:38:08.168938Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"597401b06ce1cf3d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-09T02:38:08.169132Z","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-09T02:38:08.169209Z","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-09T02:38:08.169257Z","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-09T02:38:08.169518Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"info","ts":"2026-02-09T02:38:08.169643Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","added-peer-id":"597401b06ce1cf3d","added-peer-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-02-09T02:38:08.197831Z","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-09T02:38:08.198032Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"597401b06ce1cf3d","initial-advertise-peer-urls":["https://199.204.45.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-09T02:38:08.198059Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-09T02:38:08.198162Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-02-09T02:38:08.198173Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-02-09T02:38:08.805288Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d is starting a new election at term 1"} {"level":"info","ts":"2026-02-09T02:38:08.805328Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became pre-candidate at term 1"} {"level":"info","ts":"2026-02-09T02:38:08.805357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgPreVoteResp from 597401b06ce1cf3d at term 1"} {"level":"info","ts":"2026-02-09T02:38:08.805368Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became candidate at term 2"} {"level":"info","ts":"2026-02-09T02:38:08.805373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgVoteResp from 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-02-09T02:38:08.805405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became leader at term 2"} {"level":"info","ts":"2026-02-09T02:38:08.805412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 597401b06ce1cf3d elected leader 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-02-09T02:38:08.807872Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"597401b06ce1cf3d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.3:2379]}","request-path":"/0/members/597401b06ce1cf3d/attributes","cluster-id":"dfc54657ad8a2432","publish-timeout":"7s"} {"level":"info","ts":"2026-02-09T02:38:08.80798Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-09T02:38:08.808248Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T02:38:08.808813Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-09T02:38:08.809028Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-09T02:38:08.809137Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-09T02:38:08.810477Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.3:2379"} {"level":"info","ts":"2026-02-09T02:38:08.811194Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-09T02:38:08.8113Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T02:38:08.811335Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T02:38:08.811347Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T02:38:43.839079Z","caller":"traceutil/trace.go:171","msg":"trace[1585731738] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"187.916647ms","start":"2026-02-09T02:38:43.651144Z","end":"2026-02-09T02:38:43.83906Z","steps":["trace[1585731738] 'process raft request' (duration: 187.779804ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T02:38:45.897746Z","caller":"traceutil/trace.go:171","msg":"trace[1333577371] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"144.796393ms","start":"2026-02-09T02:38:45.752936Z","end":"2026-02-09T02:38:45.897732Z","steps":["trace[1333577371] 'process raft request' (duration: 144.725721ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T02:38:45.903988Z","caller":"traceutil/trace.go:171","msg":"trace[1418934829] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"150.172666ms","start":"2026-02-09T02:38:45.753805Z","end":"2026-02-09T02:38:45.903978Z","steps":["trace[1418934829] 'process raft request' (duration: 150.103204ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T02:39:04.94915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.173858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T02:39:04.949271Z","caller":"traceutil/trace.go:171","msg":"trace[540275169] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:681; }","duration":"557.335393ms","start":"2026-02-09T02:39:04.391916Z","end":"2026-02-09T02:39:04.949252Z","steps":["trace[540275169] 'range keys from in-memory index tree' (duration: 557.121787ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T02:39:04.949311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T02:39:04.3919Z","time spent":"557.400244ms","remote":"127.0.0.1:35110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-09T02:39:04.956356Z","caller":"traceutil/trace.go:171","msg":"trace[735843247] linearizableReadLoop","detail":"{readStateIndex:707; appliedIndex:706; }","duration":"352.104235ms","start":"2026-02-09T02:39:04.604238Z","end":"2026-02-09T02:39:04.956342Z","steps":["trace[735843247] 'read index received' (duration: 351.958581ms)","trace[735843247] 'applied index is now lower than readState.Index' (duration: 144.874µs)"],"step_count":2} {"level":"info","ts":"2026-02-09T02:39:04.958157Z","caller":"traceutil/trace.go:171","msg":"trace[1198408089] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"418.857876ms","start":"2026-02-09T02:39:04.539286Z","end":"2026-02-09T02:39:04.958144Z","steps":["trace[1198408089] 'process raft request' (duration: 416.955816ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T02:39:04.958613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T02:39:04.539262Z","time spent":"419.015041ms","remote":"127.0.0.1:35372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T02:39:04.959358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.123924ms","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-09T02:39:04.959379Z","caller":"traceutil/trace.go:171","msg":"trace[2141310240] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:682; }","duration":"355.170586ms","start":"2026-02-09T02:39:04.604202Z","end":"2026-02-09T02:39:04.959373Z","steps":["trace[2141310240] 'agreement among raft nodes before linearized reading' (duration: 352.193737ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T02:39:04.959393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T02:39:04.604186Z","time spent":"355.203496ms","remote":"127.0.0.1:35372","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-09T02:39:05.466511Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933263739253878458,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-09T02:39:05.729273Z","caller":"traceutil/trace.go:171","msg":"trace[519823901] linearizableReadLoop","detail":"{readStateIndex:708; appliedIndex:707; }","duration":"763.34134ms","start":"2026-02-09T02:39:04.965907Z","end":"2026-02-09T02:39:05.729248Z","steps":["trace[519823901] 'read index received' (duration: 763.211517ms)","trace[519823901] 'applied index is now lower than readState.Index' (duration: 129.193µs)"],"step_count":2} {"level":"info","ts":"2026-02-09T02:39:05.729436Z","caller":"traceutil/trace.go:171","msg":"trace[290132392] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"764.736467ms","start":"2026-02-09T02:39:04.964692Z","end":"2026-02-09T02:39:05.729429Z","steps":["trace[290132392] 'process raft request' (duration: 764.468661ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T02:39:05.729511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T02:39:04.964682Z","time spent":"764.772848ms","remote":"127.0.0.1:35372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T02:39:05.729681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.771171ms","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-09T02:39:05.729701Z","caller":"traceutil/trace.go:171","msg":"trace[132930633] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:683; }","duration":"763.817293ms","start":"2026-02-09T02:39:04.965877Z","end":"2026-02-09T02:39:05.729694Z","steps":["trace[132930633] 'agreement among raft nodes before linearized reading' (duration: 763.73785ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T02:39:05.72972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T02:39:04.965861Z","time spent":"763.854074ms","remote":"127.0.0.1:35372","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-09T02:39:05.729832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.863017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-09T02:39:05.729849Z","caller":"traceutil/trace.go:171","msg":"trace[677576093] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:683; }","duration":"733.90886ms","start":"2026-02-09T02:39:04.995935Z","end":"2026-02-09T02:39:05.729844Z","steps":["trace[677576093] 'agreement among raft nodes before linearized reading' (duration: 733.856838ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T02:39:05.729864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T02:39:04.995919Z","time spent":"733.9414ms","remote":"127.0.0.1:35372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-09T02:39:05.729977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.059989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T02:39:05.729997Z","caller":"traceutil/trace.go:171","msg":"trace[938849051] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:683; }","duration":"336.10784ms","start":"2026-02-09T02:39:05.393883Z","end":"2026-02-09T02:39:05.729991Z","steps":["trace[938849051] 'agreement among raft nodes before linearized reading' (duration: 336.07642ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T02:39:05.730012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T02:39:05.393866Z","time spent":"336.141991ms","remote":"127.0.0.1:35110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-09T02:39:05.730152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.395824ms","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-09T02:39:05.730172Z","caller":"traceutil/trace.go:171","msg":"trace[1585030441] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:683; }","duration":"733.426536ms","start":"2026-02-09T02:39:04.996739Z","end":"2026-02-09T02:39:05.730166Z","steps":["trace[1585030441] 'agreement among raft nodes before linearized reading' (duration: 733.374274ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T02:39:05.730188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T02:39:04.996733Z","time spent":"733.451166ms","remote":"127.0.0.1:35372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-09T02:39:18.147668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.275942ms","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-09T02:39:18.14772Z","caller":"traceutil/trace.go:171","msg":"trace[1446210048] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:722; }","duration":"288.366614ms","start":"2026-02-09T02:39:17.859341Z","end":"2026-02-09T02:39:18.147707Z","steps":["trace[1446210048] 'range keys from in-memory index tree' (duration: 288.123648ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T02:39:18.147871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.33047ms","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-02-09T02:39:18.147866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.961958ms","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-09T02:39:18.147918Z","caller":"traceutil/trace.go:171","msg":"trace[57868574] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:722; }","duration":"116.044819ms","start":"2026-02-09T02:39:18.031859Z","end":"2026-02-09T02:39:18.147904Z","steps":["trace[57868574] 'range keys from in-memory index tree' (duration: 115.859695ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T02:39:18.147928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.705201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-09T02:39:18.147945Z","caller":"traceutil/trace.go:171","msg":"trace[2130873140] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:722; }","duration":"283.743251ms","start":"2026-02-09T02:39:17.864197Z","end":"2026-02-09T02:39:18.14794Z","steps":["trace[2130873140] 'range keys from in-memory index tree' (duration: 283.640388ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T02:39:18.147888Z","caller":"traceutil/trace.go:171","msg":"trace[714896187] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:722; }","duration":"286.381962ms","start":"2026-02-09T02:39:17.861501Z","end":"2026-02-09T02:39:18.147883Z","steps":["trace[714896187] 'range keys from in-memory index tree' (duration: 286.223647ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T02:39:23.939192Z","caller":"traceutil/trace.go:171","msg":"trace[845046853] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"113.174065ms","start":"2026-02-09T02:39:23.826002Z","end":"2026-02-09T02:39:23.939176Z","steps":["trace[845046853] 'process raft request' (duration: 113.071851ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T02:39:24.308448Z","caller":"traceutil/trace.go:171","msg":"trace[2126431431] linearizableReadLoop","detail":"{readStateIndex:769; appliedIndex:768; }","duration":"131.958993ms","start":"2026-02-09T02:39:24.17647Z","end":"2026-02-09T02:39:24.308429Z","steps":["trace[2126431431] 'read index received' (duration: 131.831879ms)","trace[2126431431] 'applied index is now lower than readState.Index' (duration: 126.584µs)"],"step_count":2} {"level":"info","ts":"2026-02-09T02:39:24.308737Z","caller":"traceutil/trace.go:171","msg":"trace[472548291] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"134.881819ms","start":"2026-02-09T02:39:24.173849Z","end":"2026-02-09T02:39:24.308731Z","steps":["trace[472548291] 'process raft request' (duration: 134.50124ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T02:39:24.308913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.421135ms","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-09T02:39:24.308931Z","caller":"traceutil/trace.go:171","msg":"trace[1791914641] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:740; }","duration":"132.467876ms","start":"2026-02-09T02:39:24.176458Z","end":"2026-02-09T02:39:24.308926Z","steps":["trace[1791914641] 'agreement among raft nodes before linearized reading' (duration: 132.384874ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T02:39:24.309152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.864528ms","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-09T02:39:24.309166Z","caller":"traceutil/trace.go:171","msg":"trace[2030129344] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:740; }","duration":"126.889878ms","start":"2026-02-09T02:39:24.182272Z","end":"2026-02-09T02:39:24.309162Z","steps":["trace[2030129344] 'agreement among raft nodes before linearized reading' (duration: 126.847887ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T02:39:24.309239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.144564ms","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-09T02:39:24.309331Z","caller":"traceutil/trace.go:171","msg":"trace[1938224160] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:740; }","duration":"127.254208ms","start":"2026-02-09T02:39:24.182049Z","end":"2026-02-09T02:39:24.309303Z","steps":["trace[1938224160] 'agreement among raft nodes before linearized reading' (duration: 126.984312ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T02:39:25.600973Z","caller":"traceutil/trace.go:171","msg":"trace[393916184] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"260.600886ms","start":"2026-02-09T02:39:25.340351Z","end":"2026-02-09T02:39:25.600952Z","steps":["trace[393916184] 'process raft request' (duration: 260.480913ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T02:39:36.635109Z","caller":"traceutil/trace.go:171","msg":"trace[83519318] linearizableReadLoop","detail":"{readStateIndex:858; appliedIndex:858; }","duration":"240.758688ms","start":"2026-02-09T02:39:36.394328Z","end":"2026-02-09T02:39:36.635087Z","steps":["trace[83519318] 'read index received' (duration: 240.749078ms)","trace[83519318] 'applied index is now lower than readState.Index' (duration: 7.95µs)"],"step_count":2} {"level":"info","ts":"2026-02-09T02:39:36.635103Z","caller":"traceutil/trace.go:171","msg":"trace[1291316765] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"278.417435ms","start":"2026-02-09T02:39:36.356669Z","end":"2026-02-09T02:39:36.635087Z","steps":["trace[1291316765] 'process raft request' (duration: 278.325683ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T02:39:36.635414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.656813ms","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-09T02:39:36.635467Z","caller":"traceutil/trace.go:171","msg":"trace[1261688536] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:825; }","duration":"196.744435ms","start":"2026-02-09T02:39:36.438714Z","end":"2026-02-09T02:39:36.635459Z","steps":["trace[1261688536] 'agreement among raft nodes before linearized reading' (duration: 196.503218ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T02:39:36.635501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.159769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T02:39:36.635522Z","caller":"traceutil/trace.go:171","msg":"trace[298212505] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:825; }","duration":"241.214401ms","start":"2026-02-09T02:39:36.394302Z","end":"2026-02-09T02:39:36.635516Z","steps":["trace[298212505] 'agreement among raft nodes before linearized reading' (duration: 241.17129ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T02:39:36.635714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.136913ms","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-09T02:39:36.635729Z","caller":"traceutil/trace.go:171","msg":"trace[462112005] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:825; }","duration":"195.150613ms","start":"2026-02-09T02:39:36.440573Z","end":"2026-02-09T02:39:36.635724Z","steps":["trace[462112005] 'agreement among raft nodes before linearized reading' (duration: 195.085472ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T02:39:36.635813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.31776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-09T02:39:36.636857Z","caller":"traceutil/trace.go:171","msg":"trace[226659222] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:825; }","duration":"155.380591ms","start":"2026-02-09T02:39:36.480466Z","end":"2026-02-09T02:39:36.635847Z","steps":["trace[226659222] 'agreement among raft nodes before linearized reading' (duration: 155.297509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T02:39:38.154998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.241424ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-09T02:39:38.155061Z","caller":"traceutil/trace.go:171","msg":"trace[1044369880] linearizableReadLoop","detail":"{readStateIndex:862; appliedIndex:861; }","duration":"164.926491ms","start":"2026-02-09T02:39:37.990124Z","end":"2026-02-09T02:39:38.15505Z","steps":["trace[1044369880] 'read index received' (duration: 39.121µs)","trace[1044369880] 'applied index is now lower than readState.Index' (duration: 164.88656ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T02:39:38.155167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.043284ms","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-09T02:39:38.15518Z","caller":"traceutil/trace.go:171","msg":"trace[1133576481] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:828; }","duration":"165.090586ms","start":"2026-02-09T02:39:37.990085Z","end":"2026-02-09T02:39:38.155176Z","steps":["trace[1133576481] 'agreement among raft nodes before linearized reading' (duration: 164.991133ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T02:39:38.259705Z","caller":"traceutil/trace.go:171","msg":"trace[589645213] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"101.604052ms","start":"2026-02-09T02:39:38.158085Z","end":"2026-02-09T02:39:38.259689Z","steps":["trace[589645213] 'process raft request' (duration: 101.493849ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T02:39:38.692657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.287702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T02:39:38.692702Z","caller":"traceutil/trace.go:171","msg":"trace[1281598528] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:829; }","duration":"296.364984ms","start":"2026-02-09T02:39:38.396327Z","end":"2026-02-09T02:39:38.692692Z","steps":["trace[1281598528] 'range keys from in-memory index tree' (duration: 296.241901ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T02:39:38.933179Z","caller":"traceutil/trace.go:171","msg":"trace[346153209] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"233.754015ms","start":"2026-02-09T02:39:38.69941Z","end":"2026-02-09T02:39:38.933164Z","steps":["trace[346153209] 'process raft request' (duration: 233.702554ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T02:39:38.933293Z","caller":"traceutil/trace.go:171","msg":"trace[1493794720] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"233.908358ms","start":"2026-02-09T02:39:38.699381Z","end":"2026-02-09T02:39:38.933289Z","steps":["trace[1493794720] 'process raft request' (duration: 233.63342ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T02:39:38.933339Z","caller":"traceutil/trace.go:171","msg":"trace[1069985030] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"233.898838ms","start":"2026-02-09T02:39:38.699436Z","end":"2026-02-09T02:39:38.933335Z","steps":["trace[1069985030] 'process raft request' (duration: 233.703473ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T02:39:45.051776Z","caller":"traceutil/trace.go:171","msg":"trace[281691893] linearizableReadLoop","detail":"{readStateIndex:882; appliedIndex:881; }","duration":"101.855968ms","start":"2026-02-09T02:39:44.949905Z","end":"2026-02-09T02:39:45.051761Z","steps":["trace[281691893] 'read index received' (duration: 101.730795ms)","trace[281691893] 'applied index is now lower than readState.Index' (duration: 124.463µs)"],"step_count":2} {"level":"info","ts":"2026-02-09T02:39:45.051859Z","caller":"traceutil/trace.go:171","msg":"trace[1065516497] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"260.576417ms","start":"2026-02-09T02:39:44.791276Z","end":"2026-02-09T02:39:45.051853Z","steps":["trace[1065516497] 'process raft request' (duration: 260.391903ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T02:39:45.052027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.110435ms","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-09T02:39:45.052043Z","caller":"traceutil/trace.go:171","msg":"trace[392557322] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:847; }","duration":"102.161097ms","start":"2026-02-09T02:39:44.949877Z","end":"2026-02-09T02:39:45.052038Z","steps":["trace[392557322] 'agreement among raft nodes before linearized reading' (duration: 102.082225ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T02:39:45.170265Z","caller":"traceutil/trace.go:171","msg":"trace[328283500] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"113.434412ms","start":"2026-02-09T02:39:45.056812Z","end":"2026-02-09T02:39:45.170246Z","steps":["trace[328283500] 'process raft request' (duration: 83.963699ms)","trace[328283500] 'compare' (duration: 29.38943ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T02:39:45.170422Z","caller":"traceutil/trace.go:171","msg":"trace[973472517] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"113.548225ms","start":"2026-02-09T02:39:45.056856Z","end":"2026-02-09T02:39:45.170404Z","steps":["trace[973472517] 'process raft request' (duration: 113.369771ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T02:39:51.22943Z","caller":"traceutil/trace.go:171","msg":"trace[1153912398] linearizableReadLoop","detail":"{readStateIndex:899; appliedIndex:898; }","duration":"130.894409ms","start":"2026-02-09T02:39:51.098525Z","end":"2026-02-09T02:39:51.22942Z","steps":["trace[1153912398] 'read index received' (duration: 130.748836ms)","trace[1153912398] 'applied index is now lower than readState.Index' (duration: 144.793µs)"],"step_count":2} {"level":"info","ts":"2026-02-09T02:39:51.229579Z","caller":"traceutil/trace.go:171","msg":"trace[1258975907] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"152.003303ms","start":"2026-02-09T02:39:51.07757Z","end":"2026-02-09T02:39:51.229573Z","steps":["trace[1258975907] 'process raft request' (duration: 151.733835ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T02:39:51.229708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.173357ms","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-09T02:39:51.229725Z","caller":"traceutil/trace.go:171","msg":"trace[2065210107] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:863; }","duration":"131.218549ms","start":"2026-02-09T02:39:51.0985Z","end":"2026-02-09T02:39:51.229719Z","steps":["trace[2065210107] 'agreement among raft nodes before linearized reading' (duration: 131.156297ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T02:39:52.191486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T02:39:51.879815Z","time spent":"311.669088ms","remote":"127.0.0.1:35130","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-09T02:39:52.325962Z","caller":"traceutil/trace.go:171","msg":"trace[168075302] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"130.843329ms","start":"2026-02-09T02:39:52.195106Z","end":"2026-02-09T02:39:52.32595Z","steps":["trace[168075302] 'process raft request' (duration: 129.715829ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T02:39:55.741478Z","caller":"traceutil/trace.go:171","msg":"trace[430577959] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"121.946155ms","start":"2026-02-09T02:39:55.619509Z","end":"2026-02-09T02:39:55.741455Z","steps":["trace[430577959] 'process raft request' (duration: 121.321668ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T02:39:55.946535Z","caller":"traceutil/trace.go:171","msg":"trace[984414595] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"197.652729ms","start":"2026-02-09T02:39:55.748864Z","end":"2026-02-09T02:39:55.946516Z","steps":["trace[984414595] 'process raft request' (duration: 178.180338ms)","trace[984414595] 'compare' (duration: 19.389299ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T02:40:09.517593Z","caller":"traceutil/trace.go:171","msg":"trace[1111770092] linearizableReadLoop","detail":"{readStateIndex:1019; appliedIndex:1018; }","duration":"163.387972ms","start":"2026-02-09T02:40:09.354176Z","end":"2026-02-09T02:40:09.517564Z","steps":["trace[1111770092] 'read index received' (duration: 163.222038ms)","trace[1111770092] 'applied index is now lower than readState.Index' (duration: 164.994µs)"],"step_count":2} {"level":"info","ts":"2026-02-09T02:40:09.517751Z","caller":"traceutil/trace.go:171","msg":"trace[187061867] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"206.601003ms","start":"2026-02-09T02:40:09.311139Z","end":"2026-02-09T02:40:09.51774Z","steps":["trace[187061867] 'process raft request' (duration: 206.254655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T02:40:09.518006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.803422ms","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-09T02:40:09.518041Z","caller":"traceutil/trace.go:171","msg":"trace[273276426] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:979; }","duration":"163.863134ms","start":"2026-02-09T02:40:09.35417Z","end":"2026-02-09T02:40:09.518033Z","steps":["trace[273276426] 'agreement among raft nodes before linearized reading' (duration: 163.7159ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T02:40:09.518342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.09324ms","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":"warn","ts":"2026-02-09T02:40:09.518454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.3884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T02:40:09.518483Z","caller":"traceutil/trace.go:171","msg":"trace[920424991] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:979; }","duration":"121.41454ms","start":"2026-02-09T02:40:09.397057Z","end":"2026-02-09T02:40:09.518471Z","steps":["trace[920424991] 'agreement among raft nodes before linearized reading' (duration: 121.368529ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T02:40:09.51857Z","caller":"traceutil/trace.go:171","msg":"trace[483030987] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:979; }","duration":"164.254304ms","start":"2026-02-09T02:40:09.354206Z","end":"2026-02-09T02:40:09.51846Z","steps":["trace[483030987] 'agreement among raft nodes before linearized reading' (duration: 163.934276ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T02:40:09.518704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.538214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-09T02:40:09.518727Z","caller":"traceutil/trace.go:171","msg":"trace[304114907] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:979; }","duration":"163.574816ms","start":"2026-02-09T02:40:09.355145Z","end":"2026-02-09T02:40:09.51872Z","steps":["trace[304114907] 'agreement among raft nodes before linearized reading' (duration: 163.509264ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T02:40:10.152161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.408734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-09T02:40:10.152215Z","caller":"traceutil/trace.go:171","msg":"trace[1112747938] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:982; }","duration":"365.514487ms","start":"2026-02-09T02:40:09.786688Z","end":"2026-02-09T02:40:10.152203Z","steps":["trace[1112747938] 'range keys from in-memory index tree' (duration: 365.199039ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T02:40:10.152252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T02:40:09.786638Z","time spent":"365.608239ms","remote":"127.0.0.1:35372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-02-09T02:40:10.729076Z","caller":"traceutil/trace.go:171","msg":"trace[234511601] linearizableReadLoop","detail":"{readStateIndex:1023; appliedIndex:1022; }","duration":"168.048612ms","start":"2026-02-09T02:40:10.560973Z","end":"2026-02-09T02:40:10.729022Z","steps":["trace[234511601] 'read index received' (duration: 167.669563ms)","trace[234511601] 'applied index is now lower than readState.Index' (duration: 377.449µs)"],"step_count":2} {"level":"info","ts":"2026-02-09T02:40:10.729409Z","caller":"traceutil/trace.go:171","msg":"trace[391118384] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"553.322188ms","start":"2026-02-09T02:40:10.176057Z","end":"2026-02-09T02:40:10.72938Z","steps":["trace[391118384] 'process raft request' (duration: 552.656481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T02:40:10.729575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T02:40:10.17602Z","time spent":"553.466422ms","remote":"127.0.0.1:35372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T02:40:10.72982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.815173ms","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-09T02:40:10.730006Z","caller":"traceutil/trace.go:171","msg":"trace[2134780306] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:983; }","duration":"169.06276ms","start":"2026-02-09T02:40:10.560928Z","end":"2026-02-09T02:40:10.729991Z","steps":["trace[2134780306] 'agreement among raft nodes before linearized reading' (duration: 168.476395ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T02:40:10.898411Z","caller":"traceutil/trace.go:171","msg":"trace[60955943] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"159.425337ms","start":"2026-02-09T02:40:10.738116Z","end":"2026-02-09T02:40:10.897541Z","steps":["trace[60955943] 'process raft request' (duration: 159.213372ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T02:40:10.904093Z","caller":"traceutil/trace.go:171","msg":"trace[2042162473] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"165.754483ms","start":"2026-02-09T02:40:10.738325Z","end":"2026-02-09T02:40:10.904079Z","steps":["trace[2042162473] 'process raft request' (duration: 165.033034ms)"],"step_count":1}