{"level":"warn","ts":"2026-02-12T01:54:02.479324Z","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:54:02.479404Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.65: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.65:2380","--initial-cluster=instance=https://199.204.45.65:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.65:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.65: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:54:02.479474Z","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:54:02.479486Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.65:2380"]} {"level":"info","ts":"2026-02-12T01:54:02.479504Z","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:54:02.48005Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"]} {"level":"info","ts":"2026-02-12T01:54:02.480115Z","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.65:2380"],"listen-peer-urls":["https://199.204.45.65:2380"],"advertise-client-urls":["https://199.204.45.65:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.65: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:54:02.483869Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.593535ms"} {"level":"info","ts":"2026-02-12T01:54:02.490013Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cf8c3a138c9a77a2","cluster-id":"c278183e1f634dff"} {"level":"info","ts":"2026-02-12T01:54:02.490084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=()"} {"level":"info","ts":"2026-02-12T01:54:02.490103Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became follower at term 0"} {"level":"info","ts":"2026-02-12T01:54:02.490113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cf8c3a138c9a77a2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-12T01:54:02.490121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became follower at term 1"} {"level":"info","ts":"2026-02-12T01:54:02.490153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=(14955392318228297634)"} {"level":"warn","ts":"2026-02-12T01:54:02.497429Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-12T01:54:02.502998Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-12T01:54:02.509227Z","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:54:02.511931Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cf8c3a138c9a77a2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-12T01:54:02.513406Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cf8c3a138c9a77a2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-12T01:54:02.513525Z","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:54:02.513568Z","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:54:02.513585Z","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:54:02.51378Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=(14955392318228297634)"} {"level":"info","ts":"2026-02-12T01:54:02.513863Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c278183e1f634dff","local-member-id":"cf8c3a138c9a77a2","added-peer-id":"cf8c3a138c9a77a2","added-peer-peer-urls":["https://199.204.45.65:2380"]} {"level":"info","ts":"2026-02-12T01:54:02.516736Z","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:54:02.516898Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cf8c3a138c9a77a2","initial-advertise-peer-urls":["https://199.204.45.65:2380"],"listen-peer-urls":["https://199.204.45.65:2380"],"advertise-client-urls":["https://199.204.45.65:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-12T01:54:02.516959Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-12T01:54:02.517012Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.65:2380"} {"level":"info","ts":"2026-02-12T01:54:02.517032Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.65:2380"} {"level":"info","ts":"2026-02-12T01:54:03.491817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 is starting a new election at term 1"} {"level":"info","ts":"2026-02-12T01:54:03.49187Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-12T01:54:03.491904Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 received MsgPreVoteResp from cf8c3a138c9a77a2 at term 1"} {"level":"info","ts":"2026-02-12T01:54:03.491916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became candidate at term 2"} {"level":"info","ts":"2026-02-12T01:54:03.491925Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 received MsgVoteResp from cf8c3a138c9a77a2 at term 2"} {"level":"info","ts":"2026-02-12T01:54:03.491948Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became leader at term 2"} {"level":"info","ts":"2026-02-12T01:54:03.491955Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cf8c3a138c9a77a2 elected leader cf8c3a138c9a77a2 at term 2"} {"level":"info","ts":"2026-02-12T01:54:03.494217Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cf8c3a138c9a77a2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.65:2379]}","request-path":"/0/members/cf8c3a138c9a77a2/attributes","cluster-id":"c278183e1f634dff","publish-timeout":"7s"} {"level":"info","ts":"2026-02-12T01:54:03.494395Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T01:54:03.494991Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-12T01:54:03.495287Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-12T01:54:03.495441Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-12T01:54:03.495454Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-12T01:54:03.495469Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c278183e1f634dff","local-member-id":"cf8c3a138c9a77a2","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T01:54:03.495528Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T01:54:03.495543Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T01:54:03.500457Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.65:2379"} {"level":"info","ts":"2026-02-12T01:54:03.508348Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-12T01:54:06.643711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.257206ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-12T01:54:06.645007Z","caller":"traceutil/trace.go:171","msg":"trace[126641282] transaction","detail":"{read_only:false; response_revision:128; number_of_response:1; }","duration":"233.587233ms","start":"2026-02-12T01:54:06.411407Z","end":"2026-02-12T01:54:06.644994Z","steps":["trace[126641282] 'process raft request' (duration: 52.652738ms)","trace[126641282] 'compare' (duration: 179.061681ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T01:54:06.646766Z","caller":"traceutil/trace.go:171","msg":"trace[1423257981] transaction","detail":"{read_only:false; response_revision:129; number_of_response:1; }","duration":"233.679406ms","start":"2026-02-12T01:54:06.413061Z","end":"2026-02-12T01:54:06.64674Z","steps":["trace[1423257981] 'process raft request' (duration: 231.897054ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:54:06.647117Z","caller":"traceutil/trace.go:171","msg":"trace[1656168437] transaction","detail":"{read_only:false; response_revision:130; number_of_response:1; }","duration":"205.199816ms","start":"2026-02-12T01:54:06.441907Z","end":"2026-02-12T01:54:06.647107Z","steps":["trace[1656168437] 'process raft request' (duration: 204.796796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:54:13.987982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.993322ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-12T01:54:13.988064Z","caller":"traceutil/trace.go:171","msg":"trace[530671630] linearizableReadLoop","detail":"{readStateIndex:351; appliedIndex:350; }","duration":"384.580703ms","start":"2026-02-12T01:54:13.60347Z","end":"2026-02-12T01:54:13.988051Z","steps":["trace[530671630] 'read index received' (duration: 36.484195ms)","trace[530671630] 'applied index is now lower than readState.Index' (duration: 348.095838ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T01:54:13.988173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.680655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5260"} {"level":"info","ts":"2026-02-12T01:54:13.988195Z","caller":"traceutil/trace.go:171","msg":"trace[2039892338] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:339; }","duration":"384.743027ms","start":"2026-02-12T01:54:13.603446Z","end":"2026-02-12T01:54:13.98819Z","steps":["trace[2039892338] 'agreement among raft nodes before linearized reading' (duration: 384.643465ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:54:13.988216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:54:13.603436Z","time spent":"384.775037ms","remote":"127.0.0.1:54438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5284,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"info","ts":"2026-02-12T01:54:13.988443Z","caller":"traceutil/trace.go:171","msg":"trace[1213667594] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"421.284843ms","start":"2026-02-12T01:54:13.567151Z","end":"2026-02-12T01:54:13.988436Z","steps":["trace[1213667594] 'process raft request' (duration: 207.789369ms)","trace[1213667594] 'compare' (duration: 212.890989ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T01:54:13.988483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:54:13.567135Z","time spent":"421.331204ms","remote":"127.0.0.1:54450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":201,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-12T01:54:14.368375Z","caller":"traceutil/trace.go:171","msg":"trace[1864384218] linearizableReadLoop","detail":"{readStateIndex:352; appliedIndex:351; }","duration":"372.438603ms","start":"2026-02-12T01:54:13.995913Z","end":"2026-02-12T01:54:14.368352Z","steps":["trace[1864384218] 'read index received' (duration: 362.865219ms)","trace[1864384218] 'applied index is now lower than readState.Index' (duration: 9.572274ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T01:54:14.368505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.584246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-02-12T01:54:14.368539Z","caller":"traceutil/trace.go:171","msg":"trace[403072257] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:340; }","duration":"372.664179ms","start":"2026-02-12T01:54:13.995865Z","end":"2026-02-12T01:54:14.368529Z","steps":["trace[403072257] 'agreement among raft nodes before linearized reading' (duration: 372.568386ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:54:14.368588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:54:13.995848Z","time spent":"372.71602ms","remote":"127.0.0.1:54450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":240,"request content":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" "} {"level":"info","ts":"2026-02-12T01:54:14.368874Z","caller":"traceutil/trace.go:171","msg":"trace[1011815256] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"373.240003ms","start":"2026-02-12T01:54:13.99559Z","end":"2026-02-12T01:54:14.36883Z","steps":["trace[1011815256] 'process raft request' (duration: 363.250169ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:54:14.369355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:54:13.995573Z","time spent":"373.395496ms","remote":"127.0.0.1:54438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5073,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T01:54:16.806658Z","caller":"traceutil/trace.go:171","msg":"trace[1006936163] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"125.218661ms","start":"2026-02-12T01:54:16.673568Z","end":"2026-02-12T01:54:16.798786Z","steps":["trace[1006936163] 'process raft request' (duration: 49.220931ms)","trace[1006936163] 'compare' (duration: 75.877077ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T01:54:23.172773Z","caller":"traceutil/trace.go:171","msg":"trace[292491510] linearizableReadLoop","detail":"{readStateIndex:443; appliedIndex:442; }","duration":"341.45246ms","start":"2026-02-12T01:54:22.831292Z","end":"2026-02-12T01:54:23.172745Z","steps":["trace[292491510] 'read index received' (duration: 341.187374ms)","trace[292491510] 'applied index is now lower than readState.Index' (duration: 263.906µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T01:54:23.173396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.067105ms","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:54:23.173455Z","caller":"traceutil/trace.go:171","msg":"trace[58412236] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:429; }","duration":"342.189818ms","start":"2026-02-12T01:54:22.831252Z","end":"2026-02-12T01:54:23.173442Z","steps":["trace[58412236] 'agreement among raft nodes before linearized reading' (duration: 342.031744ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:54:23.173729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:54:22.831228Z","time spent":"342.485584ms","remote":"127.0.0.1:54500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-12T01:54:23.174392Z","caller":"traceutil/trace.go:171","msg":"trace[175406710] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"382.789625ms","start":"2026-02-12T01:54:22.791589Z","end":"2026-02-12T01:54:23.174379Z","steps":["trace[175406710] 'process raft request' (duration: 380.990823ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:54:23.175111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:54:22.791568Z","time spent":"382.868028ms","remote":"127.0.0.1:54710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4158,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T01:54:23.175579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.943847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T01:54:23.175662Z","caller":"traceutil/trace.go:171","msg":"trace[1116868754] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:429; }","duration":"248.05456ms","start":"2026-02-12T01:54:22.927585Z","end":"2026-02-12T01:54:23.175639Z","steps":["trace[1116868754] 'agreement among raft nodes before linearized reading' (duration: 247.916627ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:54:28.650266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:54:28.347754Z","time spent":"302.510489ms","remote":"127.0.0.1:54292","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-12T01:54:37.519095Z","caller":"traceutil/trace.go:171","msg":"trace[1918326458] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"218.720626ms","start":"2026-02-12T01:54:37.30036Z","end":"2026-02-12T01:54:37.519081Z","steps":["trace[1918326458] 'process raft request' (duration: 218.389898ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:54:41.334173Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620624502241918692,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T01:54:41.834889Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620624502241918692,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T01:54:42.105572Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.471035967s","expected-duration":"1s"} {"level":"info","ts":"2026-02-12T01:54:42.105918Z","caller":"traceutil/trace.go:171","msg":"trace[1519487818] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"1.471469988s","start":"2026-02-12T01:54:40.63443Z","end":"2026-02-12T01:54:42.1059Z","steps":["trace[1519487818] 'process raft request' (duration: 1.471355655s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:54:42.105999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:54:40.634412Z","time spent":"1.471540869s","remote":"127.0.0.1:54312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-12T01:54:42.148962Z","caller":"traceutil/trace.go:171","msg":"trace[163158743] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:612; }","duration":"1.315506473s","start":"2026-02-12T01:54:40.833441Z","end":"2026-02-12T01:54:42.148947Z","steps":["trace[163158743] 'read index received' (duration: 1.272838522s)","trace[163158743] 'applied index is now lower than readState.Index' (duration: 42.667401ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T01:54:42.149189Z","caller":"traceutil/trace.go:171","msg":"trace[919632700] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"946.54365ms","start":"2026-02-12T01:54:41.202636Z","end":"2026-02-12T01:54:42.14918Z","steps":["trace[919632700] 'process raft request' (duration: 946.199522ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:54:42.149275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:54:41.202618Z","time spent":"946.605122ms","remote":"127.0.0.1:54500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T01:54:42.149519Z","caller":"traceutil/trace.go:171","msg":"trace[605631163] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"887.354235ms","start":"2026-02-12T01:54:41.262158Z","end":"2026-02-12T01:54:42.149512Z","steps":["trace[605631163] 'process raft request' (duration: 886.758381ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:54:42.149556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:54:41.262146Z","time spent":"887.389265ms","remote":"127.0.0.1:54426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4407,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T01:54:42.149694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.316250811s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T01:54:42.149717Z","caller":"traceutil/trace.go:171","msg":"trace[859392132] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:596; }","duration":"1.316311001s","start":"2026-02-12T01:54:40.833398Z","end":"2026-02-12T01:54:42.149709Z","steps":["trace[859392132] 'agreement among raft nodes before linearized reading' (duration: 1.316275721s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:54:42.149775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:54:40.833383Z","time spent":"1.316385934s","remote":"127.0.0.1:54312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-12T01:54:42.156359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.227797685s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T01:54:42.156404Z","caller":"traceutil/trace.go:171","msg":"trace[8299533] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:597; }","duration":"1.227869107s","start":"2026-02-12T01:54:40.928523Z","end":"2026-02-12T01:54:42.156392Z","steps":["trace[8299533] 'agreement among raft nodes before linearized reading' (duration: 1.227789135s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:54:42.156428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:54:40.928511Z","time spent":"1.227912507s","remote":"127.0.0.1:54272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T01:54:42.156765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.840397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-02-12T01:54:42.156789Z","caller":"traceutil/trace.go:171","msg":"trace[289175317] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:597; }","duration":"123.889658ms","start":"2026-02-12T01:54:42.03289Z","end":"2026-02-12T01:54:42.15678Z","steps":["trace[289175317] 'agreement among raft nodes before linearized reading' (duration: 123.810696ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:54:42.156898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.222927ms","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-12T01:54:42.156923Z","caller":"traceutil/trace.go:171","msg":"trace[167801488] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:597; }","duration":"236.266519ms","start":"2026-02-12T01:54:41.920643Z","end":"2026-02-12T01:54:42.156909Z","steps":["trace[167801488] 'agreement among raft nodes before linearized reading' (duration: 236.216968ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:54:42.157034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.214859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-02-12T01:54:42.157053Z","caller":"traceutil/trace.go:171","msg":"trace[1645918526] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:597; }","duration":"323.26649ms","start":"2026-02-12T01:54:41.83378Z","end":"2026-02-12T01:54:42.157047Z","steps":["trace[1645918526] 'agreement among raft nodes before linearized reading' (duration: 323.214139ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:54:42.157069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:54:41.833766Z","time spent":"323.29952ms","remote":"127.0.0.1:54312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9344,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-02-12T01:54:42.157172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.540973ms","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:54:42.157189Z","caller":"traceutil/trace.go:171","msg":"trace[1825032534] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:597; }","duration":"523.581914ms","start":"2026-02-12T01:54:41.633602Z","end":"2026-02-12T01:54:42.157184Z","steps":["trace[1825032534] 'agreement among raft nodes before linearized reading' (duration: 523.531393ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:54:42.157205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:54:41.633589Z","time spent":"523.611515ms","remote":"127.0.0.1:54312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4204,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-02-12T01:54:42.157298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.50752ms","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-12T01:54:42.157315Z","caller":"traceutil/trace.go:171","msg":"trace[789415275] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:597; }","duration":"609.536171ms","start":"2026-02-12T01:54:41.547774Z","end":"2026-02-12T01:54:42.15731Z","steps":["trace[789415275] 'agreement among raft nodes before linearized reading' (duration: 609.49141ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:54:42.157332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:54:41.547767Z","time spent":"609.560831ms","remote":"127.0.0.1:54500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T01:54:42.157435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.461205ms","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-12T01:54:42.157451Z","caller":"traceutil/trace.go:171","msg":"trace[251627032] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:597; }","duration":"614.499326ms","start":"2026-02-12T01:54:41.542947Z","end":"2026-02-12T01:54:42.157446Z","steps":["trace[251627032] 'agreement among raft nodes before linearized reading' (duration: 614.444315ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:54:42.157465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:54:41.542936Z","time spent":"614.525527ms","remote":"127.0.0.1:54500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-12T01:54:42.157562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.273459ms","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:54:42.157579Z","caller":"traceutil/trace.go:171","msg":"trace[343204851] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:597; }","duration":"629.31032ms","start":"2026-02-12T01:54:41.528263Z","end":"2026-02-12T01:54:42.157574Z","steps":["trace[343204851] 'agreement among raft nodes before linearized reading' (duration: 629.263059ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:54:42.157597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:54:41.528253Z","time spent":"629.340511ms","remote":"127.0.0.1:54500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-12T01:54:42.15769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.043351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-12T01:54:42.157705Z","caller":"traceutil/trace.go:171","msg":"trace[1611451810] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:597; }","duration":"724.081502ms","start":"2026-02-12T01:54:41.433619Z","end":"2026-02-12T01:54:42.1577Z","steps":["trace[1611451810] 'agreement among raft nodes before linearized reading' (duration: 724.036441ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:54:42.157721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:54:41.433606Z","time spent":"724.111533ms","remote":"127.0.0.1:54312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5610,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-02-12T01:54:42.157816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"924.519899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-02-12T01:54:42.157832Z","caller":"traceutil/trace.go:171","msg":"trace[478736017] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:597; }","duration":"924.558099ms","start":"2026-02-12T01:54:41.233269Z","end":"2026-02-12T01:54:42.157827Z","steps":["trace[478736017] 'agreement among raft nodes before linearized reading' (duration: 924.514598ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:54:42.157847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:54:41.233259Z","time spent":"924.58475ms","remote":"127.0.0.1:54312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4107,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-02-12T01:54:42.157939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.035742583s","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:54:42.157955Z","caller":"traceutil/trace.go:171","msg":"trace[1885871810] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:597; }","duration":"1.035780164s","start":"2026-02-12T01:54:41.12217Z","end":"2026-02-12T01:54:42.15795Z","steps":["trace[1885871810] 'agreement among raft nodes before linearized reading' (duration: 1.035737253s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:54:42.157972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:54:41.12216Z","time spent":"1.035808224s","remote":"127.0.0.1:54500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-12T01:54:42.158045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.116185661s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T01:54:42.158061Z","caller":"traceutil/trace.go:171","msg":"trace[1137038204] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:597; }","duration":"1.116201361s","start":"2026-02-12T01:54:41.041855Z","end":"2026-02-12T01:54:42.158056Z","steps":["trace[1137038204] 'agreement among raft nodes before linearized reading' (duration: 1.116176841s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:54:42.158077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:54:41.041821Z","time spent":"1.116252743s","remote":"127.0.0.1:54312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-12T01:54:42.564881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.994146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-02-12T01:54:42.56493Z","caller":"traceutil/trace.go:171","msg":"trace[1519990820] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:603; }","duration":"132.073738ms","start":"2026-02-12T01:54:42.432844Z","end":"2026-02-12T01:54:42.564918Z","steps":["trace[1519990820] 'range keys from in-memory index tree' (duration: 131.869382ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:54:44.368292Z","caller":"traceutil/trace.go:171","msg":"trace[1338784911] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"388.758417ms","start":"2026-02-12T01:54:43.979519Z","end":"2026-02-12T01:54:44.368277Z","steps":["trace[1338784911] 'process raft request' (duration: 388.600713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:54:44.368404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:54:43.979506Z","time spent":"388.843939ms","remote":"127.0.0.1:54312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188480,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T01:54:44.402681Z","caller":"traceutil/trace.go:171","msg":"trace[1754202147] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:627; }","duration":"214.400258ms","start":"2026-02-12T01:54:44.188261Z","end":"2026-02-12T01:54:44.402662Z","steps":["trace[1754202147] 'read index received' (duration: 180.260975ms)","trace[1754202147] 'applied index is now lower than readState.Index' (duration: 34.137973ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T01:54:44.403053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.772218ms","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:54:44.403682Z","caller":"traceutil/trace.go:171","msg":"trace[1458241876] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:609; }","duration":"215.437233ms","start":"2026-02-12T01:54:44.188236Z","end":"2026-02-12T01:54:44.403673Z","steps":["trace[1458241876] 'agreement among raft nodes before linearized reading' (duration: 214.676315ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:54:44.403289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.078488ms","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-12T01:54:44.404285Z","caller":"traceutil/trace.go:171","msg":"trace[1092750861] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:609; }","duration":"211.088792ms","start":"2026-02-12T01:54:44.193186Z","end":"2026-02-12T01:54:44.404275Z","steps":["trace[1092750861] 'agreement among raft nodes before linearized reading' (duration: 210.031197ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:54:44.403357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.774303ms","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-12T01:54:44.404955Z","caller":"traceutil/trace.go:171","msg":"trace[420366693] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:609; }","duration":"193.37412ms","start":"2026-02-12T01:54:44.211571Z","end":"2026-02-12T01:54:44.404945Z","steps":["trace[420366693] 'agreement among raft nodes before linearized reading' (duration: 191.731962ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:54:44.403422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.860425ms","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-12T01:54:44.406023Z","caller":"traceutil/trace.go:171","msg":"trace[138382616] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:609; }","duration":"194.436215ms","start":"2026-02-12T01:54:44.211545Z","end":"2026-02-12T01:54:44.405981Z","steps":["trace[138382616] 'agreement among raft nodes before linearized reading' (duration: 191.824065ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:54:44.403466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.51282ms","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:54:44.406675Z","caller":"traceutil/trace.go:171","msg":"trace[1372755121] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:609; }","duration":"198.737875ms","start":"2026-02-12T01:54:44.207928Z","end":"2026-02-12T01:54:44.406666Z","steps":["trace[1372755121] 'agreement among raft nodes before linearized reading' (duration: 195.50648ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:54:48.671873Z","caller":"traceutil/trace.go:171","msg":"trace[636736351] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"237.03868ms","start":"2026-02-12T01:54:48.434819Z","end":"2026-02-12T01:54:48.671858Z","steps":["trace[636736351] 'read index received' (duration: 236.934698ms)","trace[636736351] 'applied index is now lower than readState.Index' (duration: 103.362µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T01:54:48.671939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:54:48.348122Z","time spent":"323.814334ms","remote":"127.0.0.1:54292","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-12T01:54:48.672134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.303677ms","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:54:48.672152Z","caller":"traceutil/trace.go:171","msg":"trace[876299674] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:621; }","duration":"237.351118ms","start":"2026-02-12T01:54:48.434795Z","end":"2026-02-12T01:54:48.672146Z","steps":["trace[876299674] 'agreement among raft nodes before linearized reading' (duration: 237.303257ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:54:48.672266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.884097ms","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-12T01:54:48.672278Z","caller":"traceutil/trace.go:171","msg":"trace[1535071465] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:621; }","duration":"233.906698ms","start":"2026-02-12T01:54:48.438368Z","end":"2026-02-12T01:54:48.672274Z","steps":["trace[1535071465] 'agreement among raft nodes before linearized reading' (duration: 233.851996ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:54:48.672385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.926564ms","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-12T01:54:48.672398Z","caller":"traceutil/trace.go:171","msg":"trace[105603041] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:621; }","duration":"210.939135ms","start":"2026-02-12T01:54:48.461455Z","end":"2026-02-12T01:54:48.672394Z","steps":["trace[105603041] 'agreement among raft nodes before linearized reading' (duration: 210.902274ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:54:48.67248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.299103ms","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:54:48.672492Z","caller":"traceutil/trace.go:171","msg":"trace[1957025836] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:621; }","duration":"211.321964ms","start":"2026-02-12T01:54:48.461167Z","end":"2026-02-12T01:54:48.672489Z","steps":["trace[1957025836] 'agreement among raft nodes before linearized reading' (duration: 211.274553ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:54:48.672573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.221215ms","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-12T01:54:48.672586Z","caller":"traceutil/trace.go:171","msg":"trace[1805988686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:621; }","duration":"215.247175ms","start":"2026-02-12T01:54:48.457335Z","end":"2026-02-12T01:54:48.672582Z","steps":["trace[1805988686] 'agreement among raft nodes before linearized reading' (duration: 215.212164ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:54:49.11203Z","caller":"traceutil/trace.go:171","msg":"trace[1556259051] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:642; }","duration":"408.084099ms","start":"2026-02-12T01:54:48.703927Z","end":"2026-02-12T01:54:49.112011Z","steps":["trace[1556259051] 'read index received' (duration: 407.766682ms)","trace[1556259051] 'applied index is now lower than readState.Index' (duration: 316.667µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T01:54:49.112264Z","caller":"traceutil/trace.go:171","msg":"trace[648385297] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"435.821793ms","start":"2026-02-12T01:54:48.676421Z","end":"2026-02-12T01:54:49.112243Z","steps":["trace[648385297] 'process raft request' (duration: 435.352322ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:54:49.112369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:54:48.676413Z","time spent":"435.881874ms","remote":"127.0.0.1:54500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T01:54:49.11252Z","caller":"traceutil/trace.go:171","msg":"trace[726182117] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"436.054568ms","start":"2026-02-12T01:54:48.676457Z","end":"2026-02-12T01:54:49.112511Z","steps":["trace[726182117] 'process raft request' (duration: 435.423773ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:54:49.113132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.195745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-02-12T01:54:49.11321Z","caller":"traceutil/trace.go:171","msg":"trace[767807325] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:627; }","duration":"409.292167ms","start":"2026-02-12T01:54:48.703906Z","end":"2026-02-12T01:54:49.113198Z","steps":["trace[767807325] 'agreement among raft nodes before linearized reading' (duration: 409.144964ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:54:49.113278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:54:48.703896Z","time spent":"409.369379ms","remote":"127.0.0.1:54418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-02-12T01:54:49.113545Z","caller":"traceutil/trace.go:171","msg":"trace[1467920329] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"435.904795ms","start":"2026-02-12T01:54:48.677629Z","end":"2026-02-12T01:54:49.113533Z","steps":["trace[1467920329] 'process raft request' (duration: 434.285967ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:54:49.113713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:54:48.677623Z","time spent":"435.948676ms","remote":"127.0.0.1:54500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T01:54:49.11389Z","caller":"traceutil/trace.go:171","msg":"trace[1402146587] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"436.221953ms","start":"2026-02-12T01:54:48.677657Z","end":"2026-02-12T01:54:49.113879Z","steps":["trace[1402146587] 'process raft request' (duration: 434.288718ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:54:49.11428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:54:48.677654Z","time spent":"436.574031ms","remote":"127.0.0.1:54500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T01:54:49.114591Z","caller":"traceutil/trace.go:171","msg":"trace[1549398027] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"433.878178ms","start":"2026-02-12T01:54:48.680525Z","end":"2026-02-12T01:54:49.114403Z","steps":["trace[1549398027] 'process raft request' (duration: 431.445141ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:54:49.114586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:54:48.676453Z","time spent":"436.103159ms","remote":"127.0.0.1:54500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T01:54:49.11469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:54:48.680519Z","time spent":"434.101623ms","remote":"127.0.0.1:54500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T01:54:49.114871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.791844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T01:54:49.114901Z","caller":"traceutil/trace.go:171","msg":"trace[451162362] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"183.841286ms","start":"2026-02-12T01:54:48.931047Z","end":"2026-02-12T01:54:49.114889Z","steps":["trace[451162362] 'agreement among raft nodes before linearized reading' (duration: 183.779175ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:54:50.224134Z","caller":"traceutil/trace.go:171","msg":"trace[112488342] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"291.789769ms","start":"2026-02-12T01:54:49.932325Z","end":"2026-02-12T01:54:50.224115Z","steps":["trace[112488342] 'read index received' (duration: 291.569954ms)","trace[112488342] 'applied index is now lower than readState.Index' (duration: 218.895µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T01:54:50.22426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.902451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T01:54:50.224285Z","caller":"traceutil/trace.go:171","msg":"trace[1436770032] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:631; }","duration":"292.000384ms","start":"2026-02-12T01:54:49.932274Z","end":"2026-02-12T01:54:50.224275Z","steps":["trace[1436770032] 'agreement among raft nodes before linearized reading' (duration: 291.927262ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:54:50.224456Z","caller":"traceutil/trace.go:171","msg":"trace[1573416005] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"479.054074ms","start":"2026-02-12T01:54:49.745378Z","end":"2026-02-12T01:54:50.224432Z","steps":["trace[1573416005] 'process raft request' (duration: 478.554003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:54:50.224561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:54:49.74536Z","time spent":"479.142696ms","remote":"127.0.0.1:54438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T01:54:51.646317Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620624502241918817,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-12T01:54:51.866432Z","caller":"traceutil/trace.go:171","msg":"trace[1808509058] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"721.036852ms","start":"2026-02-12T01:54:51.14537Z","end":"2026-02-12T01:54:51.866407Z","steps":["trace[1808509058] 'process raft request' (duration: 720.91869ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:54:51.866655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:54:51.145361Z","time spent":"721.204746ms","remote":"127.0.0.1:54500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T01:54:51.880625Z","caller":"traceutil/trace.go:171","msg":"trace[995270697] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:652; }","duration":"735.093538ms","start":"2026-02-12T01:54:51.145511Z","end":"2026-02-12T01:54:51.880604Z","steps":["trace[995270697] 'read index received' (duration: 720.918749ms)","trace[995270697] 'applied index is now lower than readState.Index' (duration: 14.173579ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T01:54:51.880678Z","caller":"traceutil/trace.go:171","msg":"trace[1469861464] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"735.115569ms","start":"2026-02-12T01:54:51.145535Z","end":"2026-02-12T01:54:51.880651Z","steps":["trace[1469861464] 'process raft request' (duration: 734.959785ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:54:51.880709Z","caller":"traceutil/trace.go:171","msg":"trace[2065736342] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"733.754647ms","start":"2026-02-12T01:54:51.146947Z","end":"2026-02-12T01:54:51.880701Z","steps":["trace[2065736342] 'process raft request' (duration: 733.631784ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:54:51.880755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:54:51.145531Z","time spent":"735.17984ms","remote":"127.0.0.1:54500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T01:54:51.88079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:54:51.146941Z","time spent":"733.789128ms","remote":"127.0.0.1:54500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T01:54:51.880957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.442486ms","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-12T01:54:51.880977Z","caller":"traceutil/trace.go:171","msg":"trace[1075911128] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:634; }","duration":"735.468767ms","start":"2026-02-12T01:54:51.145501Z","end":"2026-02-12T01:54:51.88097Z","steps":["trace[1075911128] 'agreement among raft nodes before linearized reading' (duration: 735.373015ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:54:51.880994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:54:51.145496Z","time spent":"735.494208ms","remote":"127.0.0.1:54500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-12T01:54:51.881101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.439176ms","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-12T01:54:51.881116Z","caller":"traceutil/trace.go:171","msg":"trace[1848315388] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:634; }","duration":"735.461167ms","start":"2026-02-12T01:54:51.14565Z","end":"2026-02-12T01:54:51.881111Z","steps":["trace[1848315388] 'agreement among raft nodes before linearized reading' (duration: 735.415346ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:54:51.881136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:54:51.145645Z","time spent":"735.485987ms","remote":"127.0.0.1:54500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T01:54:58.053459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.078879ms","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:54:58.05352Z","caller":"traceutil/trace.go:171","msg":"trace[1803392363] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:670; }","duration":"148.177741ms","start":"2026-02-12T01:54:57.905328Z","end":"2026-02-12T01:54:58.053506Z","steps":["trace[1803392363] 'range keys from in-memory index tree' (duration: 147.958375ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:54:58.053533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.305726ms","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-12T01:54:58.053586Z","caller":"traceutil/trace.go:171","msg":"trace[795105944] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:670; }","duration":"107.391908ms","start":"2026-02-12T01:54:57.94618Z","end":"2026-02-12T01:54:58.053572Z","steps":["trace[795105944] 'range keys from in-memory index tree' (duration: 107.180573ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:54:58.053699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.541265ms","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-12T01:54:58.053719Z","caller":"traceutil/trace.go:171","msg":"trace[1258526678] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:670; }","duration":"105.577155ms","start":"2026-02-12T01:54:57.948133Z","end":"2026-02-12T01:54:58.05371Z","steps":["trace[1258526678] 'range keys from in-memory index tree' (duration: 105.480633ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:54:58.053767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.430114ms","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:54:58.05379Z","caller":"traceutil/trace.go:171","msg":"trace[1370704717] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:670; }","duration":"168.489205ms","start":"2026-02-12T01:54:57.885295Z","end":"2026-02-12T01:54:58.053784Z","steps":["trace[1370704717] 'range keys from in-memory index tree' (duration: 168.342102ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:54:58.053923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.666915ms","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-12T01:54:58.053946Z","caller":"traceutil/trace.go:171","msg":"trace[177304507] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:670; }","duration":"152.702357ms","start":"2026-02-12T01:54:57.901236Z","end":"2026-02-12T01:54:58.053938Z","steps":["trace[177304507] 'range keys from in-memory index tree' (duration: 152.607794ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:54:58.216901Z","caller":"traceutil/trace.go:171","msg":"trace[576826158] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"151.252393ms","start":"2026-02-12T01:54:58.065634Z","end":"2026-02-12T01:54:58.216887Z","steps":["trace[576826158] 'process raft request' (duration: 151.221292ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:54:58.217104Z","caller":"traceutil/trace.go:171","msg":"trace[4489850] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"154.155441ms","start":"2026-02-12T01:54:58.062942Z","end":"2026-02-12T01:54:58.217098Z","steps":["trace[4489850] 'process raft request' (duration: 153.877034ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:54:58.217165Z","caller":"traceutil/trace.go:171","msg":"trace[1439675484] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"156.931745ms","start":"2026-02-12T01:54:58.060229Z","end":"2026-02-12T01:54:58.21716Z","steps":["trace[1439675484] 'process raft request' (duration: 133.562129ms)","trace[1439675484] 'compare' (duration: 22.856344ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T01:54:58.217303Z","caller":"traceutil/trace.go:171","msg":"trace[846642387] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"151.947409ms","start":"2026-02-12T01:54:58.06535Z","end":"2026-02-12T01:54:58.217297Z","steps":["trace[846642387] 'process raft request' (duration: 151.490979ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:54:58.536687Z","caller":"traceutil/trace.go:171","msg":"trace[503671747] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"110.979842ms","start":"2026-02-12T01:54:58.42569Z","end":"2026-02-12T01:54:58.536669Z","steps":["trace[503671747] 'process raft request' (duration: 20.759945ms)","trace[503671747] 'compare' (duration: 90.123975ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T01:55:13.334944Z","caller":"traceutil/trace.go:171","msg":"trace[1707861564] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"308.324416ms","start":"2026-02-12T01:55:13.026602Z","end":"2026-02-12T01:55:13.334926Z","steps":["trace[1707861564] 'process raft request' (duration: 308.230834ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:55:13.335058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:55:13.026566Z","time spent":"308.437429ms","remote":"127.0.0.1:54334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":817,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-12T01:55:40.755175Z","caller":"traceutil/trace.go:171","msg":"trace[605519729] linearizableReadLoop","detail":"{readStateIndex:905; appliedIndex:904; }","duration":"227.867446ms","start":"2026-02-12T01:55:40.527297Z","end":"2026-02-12T01:55:40.755164Z","steps":["trace[605519729] 'read index received' (duration: 227.796414ms)","trace[605519729] 'applied index is now lower than readState.Index' (duration: 70.312µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T01:55:40.755248Z","caller":"traceutil/trace.go:171","msg":"trace[879069259] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"393.031155ms","start":"2026-02-12T01:55:40.362211Z","end":"2026-02-12T01:55:40.755242Z","steps":["trace[879069259] 'process raft request' (duration: 392.875961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:55:40.755305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:55:40.362201Z","time spent":"393.057706ms","remote":"127.0.0.1:54500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T01:55:40.755411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.109192ms","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:55:40.755426Z","caller":"traceutil/trace.go:171","msg":"trace[812583671] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:872; }","duration":"228.152813ms","start":"2026-02-12T01:55:40.527269Z","end":"2026-02-12T01:55:40.755422Z","steps":["trace[812583671] 'agreement among raft nodes before linearized reading' (duration: 228.087381ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:55:40.887495Z","caller":"traceutil/trace.go:171","msg":"trace[665530363] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"119.582129ms","start":"2026-02-12T01:55:40.767899Z","end":"2026-02-12T01:55:40.887481Z","steps":["trace[665530363] 'process raft request' (duration: 90.549414ms)","trace[665530363] 'compare' (duration: 28.937473ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T01:55:40.88763Z","caller":"traceutil/trace.go:171","msg":"trace[2036985378] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"118.697548ms","start":"2026-02-12T01:55:40.768928Z","end":"2026-02-12T01:55:40.887626Z","steps":["trace[2036985378] 'process raft request' (duration: 118.533384ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:55:40.887707Z","caller":"traceutil/trace.go:171","msg":"trace[432787397] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"108.711297ms","start":"2026-02-12T01:55:40.77899Z","end":"2026-02-12T01:55:40.887701Z","steps":["trace[432787397] 'process raft request' (duration: 108.612895ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:56:07.325834Z","caller":"traceutil/trace.go:171","msg":"trace[1941157696] linearizableReadLoop","detail":"{readStateIndex:1018; appliedIndex:1017; }","duration":"114.475786ms","start":"2026-02-12T01:56:07.211343Z","end":"2026-02-12T01:56:07.325819Z","steps":["trace[1941157696] 'read index received' (duration: 114.356633ms)","trace[1941157696] 'applied index is now lower than readState.Index' (duration: 118.613µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T01:56:07.326012Z","caller":"traceutil/trace.go:171","msg":"trace[837256988] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"150.096992ms","start":"2026-02-12T01:56:07.175909Z","end":"2026-02-12T01:56:07.326006Z","steps":["trace[837256988] 'process raft request' (duration: 149.831236ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:56:07.326179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.832764ms","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:56:07.326198Z","caller":"traceutil/trace.go:171","msg":"trace[144189382] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:980; }","duration":"114.908156ms","start":"2026-02-12T01:56:07.211283Z","end":"2026-02-12T01:56:07.326191Z","steps":["trace[144189382] 'agreement among raft nodes before linearized reading' (duration: 114.819514ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:56:07.326285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.822391ms","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:56:07.326298Z","caller":"traceutil/trace.go:171","msg":"trace[1159759764] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:980; }","duration":"110.855182ms","start":"2026-02-12T01:56:07.215439Z","end":"2026-02-12T01:56:07.326294Z","steps":["trace[1159759764] 'agreement among raft nodes before linearized reading' (duration: 110.817761ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:56:13.582183Z","caller":"traceutil/trace.go:171","msg":"trace[1512015650] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"204.781589ms","start":"2026-02-12T01:56:13.377387Z","end":"2026-02-12T01:56:13.582168Z","steps":["trace[1512015650] 'process raft request' (duration: 204.702248ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:56:13.582541Z","caller":"traceutil/trace.go:171","msg":"trace[363441656] linearizableReadLoop","detail":"{readStateIndex:1043; appliedIndex:1043; }","duration":"203.96807ms","start":"2026-02-12T01:56:13.378539Z","end":"2026-02-12T01:56:13.582507Z","steps":["trace[363441656] 'read index received' (duration: 203.94361ms)","trace[363441656] 'applied index is now lower than readState.Index' (duration: 24.03µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T01:56:13.582646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.152644ms","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-12T01:56:13.582683Z","caller":"traceutil/trace.go:171","msg":"trace[1038506348] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1003; }","duration":"204.218426ms","start":"2026-02-12T01:56:13.378458Z","end":"2026-02-12T01:56:13.582677Z","steps":["trace[1038506348] 'agreement among raft nodes before linearized reading' (duration: 204.127414ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:56:14.082765Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620624502241919717,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-12T01:56:14.289614Z","caller":"traceutil/trace.go:171","msg":"trace[280642831] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"790.363582ms","start":"2026-02-12T01:56:13.499235Z","end":"2026-02-12T01:56:14.289599Z","steps":["trace[280642831] 'process raft request' (duration: 790.337331ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:56:14.289712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:56:13.499215Z","time spent":"790.440793ms","remote":"127.0.0.1:54426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5260,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T01:56:14.289884Z","caller":"traceutil/trace.go:171","msg":"trace[362491469] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"910.303843ms","start":"2026-02-12T01:56:13.379571Z","end":"2026-02-12T01:56:14.289875Z","steps":["trace[362491469] 'process raft request' (duration: 909.918054ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:56:14.289916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:56:13.379557Z","time spent":"910.343164ms","remote":"127.0.0.1:54500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T01:56:14.337562Z","caller":"traceutil/trace.go:171","msg":"trace[1938700201] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"749.939734ms","start":"2026-02-12T01:56:13.587595Z","end":"2026-02-12T01:56:14.337535Z","steps":["trace[1938700201] 'process raft request' (duration: 749.75137ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:56:14.337718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:56:13.587568Z","time spent":"750.066136ms","remote":"127.0.0.1:54500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T01:56:14.350409Z","caller":"traceutil/trace.go:171","msg":"trace[876808919] linearizableReadLoop","detail":"{readStateIndex:1048; appliedIndex:1045; }","duration":"767.795158ms","start":"2026-02-12T01:56:13.582575Z","end":"2026-02-12T01:56:14.35037Z","steps":["trace[876808919] 'read index received' (duration: 707.548431ms)","trace[876808919] 'applied index is now lower than readState.Index' (duration: 60.245927ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T01:56:14.35054Z","caller":"traceutil/trace.go:171","msg":"trace[1563177202] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"627.211747ms","start":"2026-02-12T01:56:13.723258Z","end":"2026-02-12T01:56:14.35047Z","steps":["trace[1563177202] 'process raft request' (duration: 626.996182ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:56:14.351975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.098334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T01:56:14.352011Z","caller":"traceutil/trace.go:171","msg":"trace[258852632] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1007; }","duration":"420.166585ms","start":"2026-02-12T01:56:13.931837Z","end":"2026-02-12T01:56:14.352004Z","steps":["trace[258852632] 'agreement among raft nodes before linearized reading' (duration: 420.117994ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:56:14.352029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:56:13.931821Z","time spent":"420.204196ms","remote":"127.0.0.1:54264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T01:56:14.352039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:56:13.723234Z","time spent":"628.703282ms","remote":"127.0.0.1:54500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T01:56:14.350622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"844.315743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-12T01:56:14.352188Z","caller":"traceutil/trace.go:171","msg":"trace[1173032981] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1007; }","duration":"845.93872ms","start":"2026-02-12T01:56:13.506245Z","end":"2026-02-12T01:56:14.352184Z","steps":["trace[1173032981] 'agreement among raft nodes before linearized reading' (duration: 844.323643ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:56:14.352207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:56:13.506229Z","time spent":"845.971481ms","remote":"127.0.0.1:54312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":11,"response size":31,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "}