{"level":"warn","ts":"2026-02-12T01:49:30.806415Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-12T01:49:30.806672Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.149: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.149:2380","--initial-cluster=instance=https://199.204.45.149:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.149:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.149:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-12T01:49:30.806787Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-12T01:49:30.806906Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.149:2380"]} {"level":"info","ts":"2026-02-12T01:49:30.806978Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-12T01:49:30.807528Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.149:2379"]} {"level":"info","ts":"2026-02-12T01:49:30.807675Z","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.149:2380"],"listen-peer-urls":["https://199.204.45.149:2380"],"advertise-client-urls":["https://199.204.45.149:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.149:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.149:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-12T01:49:30.812022Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.116443ms"} {"level":"info","ts":"2026-02-12T01:49:30.833895Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b1b675f124322401","cluster-id":"913dfbf24a38e0aa"} {"level":"info","ts":"2026-02-12T01:49:30.834Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 switched to configuration voters=()"} {"level":"info","ts":"2026-02-12T01:49:30.834033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 became follower at term 0"} {"level":"info","ts":"2026-02-12T01:49:30.834068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b1b675f124322401 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-12T01:49:30.834114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 became follower at term 1"} {"level":"info","ts":"2026-02-12T01:49:30.834178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 switched to configuration voters=(12805552269029417985)"} {"level":"warn","ts":"2026-02-12T01:49:30.855609Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-12T01:49:30.871826Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-12T01:49:30.880579Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-12T01:49:30.887044Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b1b675f124322401","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-12T01:49:30.887936Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b1b675f124322401","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-12T01:49:30.890387Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-12T01:49:30.89063Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-12T01:49:30.890638Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-12T01:49:30.890601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 switched to configuration voters=(12805552269029417985)"} {"level":"info","ts":"2026-02-12T01:49:30.905926Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"913dfbf24a38e0aa","local-member-id":"b1b675f124322401","added-peer-id":"b1b675f124322401","added-peer-peer-urls":["https://199.204.45.149:2380"]} {"level":"info","ts":"2026-02-12T01:49:30.912138Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-12T01:49:30.912315Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b1b675f124322401","initial-advertise-peer-urls":["https://199.204.45.149:2380"],"listen-peer-urls":["https://199.204.45.149:2380"],"advertise-client-urls":["https://199.204.45.149:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.149:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-12T01:49:30.912361Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-12T01:49:30.912429Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.149:2380"} {"level":"info","ts":"2026-02-12T01:49:30.912476Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.149:2380"} {"level":"info","ts":"2026-02-12T01:49:30.935593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 is starting a new election at term 1"} {"level":"info","ts":"2026-02-12T01:49:30.935691Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-12T01:49:30.935739Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 received MsgPreVoteResp from b1b675f124322401 at term 1"} {"level":"info","ts":"2026-02-12T01:49:30.93578Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 became candidate at term 2"} {"level":"info","ts":"2026-02-12T01:49:30.935802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 received MsgVoteResp from b1b675f124322401 at term 2"} {"level":"info","ts":"2026-02-12T01:49:30.93582Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 became leader at term 2"} {"level":"info","ts":"2026-02-12T01:49:30.935859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b1b675f124322401 elected leader b1b675f124322401 at term 2"} {"level":"info","ts":"2026-02-12T01:49:30.943637Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T01:49:30.951719Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b1b675f124322401","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.149:2379]}","request-path":"/0/members/b1b675f124322401/attributes","cluster-id":"913dfbf24a38e0aa","publish-timeout":"7s"} {"level":"info","ts":"2026-02-12T01:49:30.951828Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-12T01:49:30.95478Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-12T01:49:30.955012Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"913dfbf24a38e0aa","local-member-id":"b1b675f124322401","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T01:49:30.955085Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T01:49:30.955114Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T01:49:30.955325Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-12T01:49:30.956857Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.149:2379"} {"level":"info","ts":"2026-02-12T01:49:30.957173Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-12T01:49:30.957205Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-02-12T01:49:34.757801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.405786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:113"} {"level":"info","ts":"2026-02-12T01:49:34.759129Z","caller":"traceutil/trace.go:171","msg":"trace[931032332] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:232; }","duration":"107.627129ms","start":"2026-02-12T01:49:34.650318Z","end":"2026-02-12T01:49:34.757945Z","steps":["trace[931032332] 'range keys from in-memory index tree' (duration: 107.272255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:49:35.87564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.652158ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-02-12T01:49:35.878284Z","caller":"traceutil/trace.go:171","msg":"trace[2101841749] linearizableReadLoop","detail":"{readStateIndex:241; appliedIndex:239; }","duration":"528.006749ms","start":"2026-02-12T01:49:35.350209Z","end":"2026-02-12T01:49:35.878215Z","steps":["trace[2101841749] 'read index received' (duration: 398.594816ms)","trace[2101841749] 'applied index is now lower than readState.Index' (duration: 129.410273ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T01:49:35.880033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.810532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T01:49:35.88009Z","caller":"traceutil/trace.go:171","msg":"trace[557357748] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:235; }","duration":"529.897743ms","start":"2026-02-12T01:49:35.350173Z","end":"2026-02-12T01:49:35.880071Z","steps":["trace[557357748] 'agreement among raft nodes before linearized reading' (duration: 528.18444ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:49:35.880133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:49:35.350155Z","time spent":"529.970044ms","remote":"127.0.0.1:58628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T01:49:35.881488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:49:34.822179Z","time spent":"1.059279828s","remote":"127.0.0.1:58422","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:49:35.882059Z","caller":"traceutil/trace.go:171","msg":"trace[1053543757] transaction","detail":"{read_only:false; response_revision:235; number_of_response:1; }","duration":"958.340611ms","start":"2026-02-12T01:49:34.923622Z","end":"2026-02-12T01:49:35.881963Z","steps":["trace[1053543757] 'process raft request' (duration: 952.86317ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:49:35.883473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:49:34.923605Z","time spent":"958.509392ms","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":985,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-12T01:49:55.02945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.845393ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T01:49:55.029525Z","caller":"traceutil/trace.go:171","msg":"trace[1391431403] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"195.821533ms","start":"2026-02-12T01:49:54.833693Z","end":"2026-02-12T01:49:55.029515Z","steps":["trace[1391431403] 'process raft request' (duration: 72.752668ms)","trace[1391431403] 'compare' (duration: 122.700722ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T01:50:00.738393Z","caller":"traceutil/trace.go:171","msg":"trace[1077540713] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"645.02932ms","start":"2026-02-12T01:50:00.093345Z","end":"2026-02-12T01:50:00.738374Z","steps":["trace[1077540713] 'process raft request' (duration: 644.892369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:50:00.738558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:50:00.093333Z","time spent":"645.155181ms","remote":"127.0.0.1:58628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T01:50:02.009294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.783417ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T01:50:02.00936Z","caller":"traceutil/trace.go:171","msg":"trace[2054536299] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"724.649814ms","start":"2026-02-12T01:50:01.284699Z","end":"2026-02-12T01:50:02.009349Z","steps":["trace[2054536299] 'process raft request' (duration: 140.755706ms)","trace[2054536299] 'compare' (duration: 583.717887ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T01:50:02.009391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:50:01.28469Z","time spent":"724.687224ms","remote":"127.0.0.1:58628","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:50:07.671052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.600042ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T01:50:07.671113Z","caller":"traceutil/trace.go:171","msg":"trace[1223669483] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"423.343267ms","start":"2026-02-12T01:50:07.24776Z","end":"2026-02-12T01:50:07.671103Z","steps":["trace[1223669483] 'process raft request' (duration: 162.646044ms)","trace[1223669483] 'compare' (duration: 260.505222ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T01:50:07.671142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:50:07.247748Z","time spent":"423.381997ms","remote":"127.0.0.1:58628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T01:50:08.219479Z","caller":"traceutil/trace.go:171","msg":"trace[869175319] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"168.272561ms","start":"2026-02-12T01:50:08.051192Z","end":"2026-02-12T01:50:08.219464Z","steps":["trace[869175319] 'process raft request' (duration: 168.1853ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:50:08.219746Z","caller":"traceutil/trace.go:171","msg":"trace[805235905] linearizableReadLoop","detail":"{readStateIndex:567; appliedIndex:567; }","duration":"165.003433ms","start":"2026-02-12T01:50:08.054737Z","end":"2026-02-12T01:50:08.219741Z","steps":["trace[805235905] 'read index received' (duration: 165.001263ms)","trace[805235905] 'applied index is now lower than readState.Index' (duration: 1.78µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T01:50:08.219781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.033493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T01:50:08.219794Z","caller":"traceutil/trace.go:171","msg":"trace[2054902077] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:549; }","duration":"165.066913ms","start":"2026-02-12T01:50:08.054724Z","end":"2026-02-12T01:50:08.21979Z","steps":["trace[2054902077] 'agreement among raft nodes before linearized reading' (duration: 165.035483ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:50:08.720959Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2594526725773940392,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-12T01:50:08.980696Z","caller":"traceutil/trace.go:171","msg":"trace[1505591728] linearizableReadLoop","detail":"{readStateIndex:568; appliedIndex:567; }","duration":"759.780207ms","start":"2026-02-12T01:50:08.22089Z","end":"2026-02-12T01:50:08.98067Z","steps":["trace[1505591728] 'read index received' (duration: 759.630676ms)","trace[1505591728] 'applied index is now lower than readState.Index' (duration: 148.881µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T01:50:08.980845Z","caller":"traceutil/trace.go:171","msg":"trace[1069966863] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"846.320726ms","start":"2026-02-12T01:50:08.134518Z","end":"2026-02-12T01:50:08.980838Z","steps":["trace[1069966863] 'process raft request' (duration: 846.054095ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:50:08.980934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:50:08.134498Z","time spent":"846.381498ms","remote":"127.0.0.1:58628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T01:50:08.980961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.820082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-12T01:50:08.981004Z","caller":"traceutil/trace.go:171","msg":"trace[548799211] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:550; }","duration":"202.906612ms","start":"2026-02-12T01:50:08.778086Z","end":"2026-02-12T01:50:08.980992Z","steps":["trace[548799211] 'agreement among raft nodes before linearized reading' (duration: 202.780661ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:50:08.981108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.21374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T01:50:08.981132Z","caller":"traceutil/trace.go:171","msg":"trace[1473466993] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:550; }","duration":"760.2532ms","start":"2026-02-12T01:50:08.220871Z","end":"2026-02-12T01:50:08.981124Z","steps":["trace[1473466993] 'agreement among raft nodes before linearized reading' (duration: 760.21844ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:50:08.98115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:50:08.220861Z","time spent":"760.28518ms","remote":"127.0.0.1:58410","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:50:10.014914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.660157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T01:50:10.014969Z","caller":"traceutil/trace.go:171","msg":"trace[969745854] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:551; }","duration":"812.755987ms","start":"2026-02-12T01:50:09.202198Z","end":"2026-02-12T01:50:10.014954Z","steps":["trace[969745854] 'range keys from in-memory index tree' (duration: 812.579277ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:50:10.014996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:50:09.202179Z","time spent":"812.809979ms","remote":"127.0.0.1:58404","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:50:10.015225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.079981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-12T01:50:10.015246Z","caller":"traceutil/trace.go:171","msg":"trace[1314758867] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:551; }","duration":"768.121752ms","start":"2026-02-12T01:50:09.247116Z","end":"2026-02-12T01:50:10.015238Z","steps":["trace[1314758867] 'range keys from in-memory index tree' (duration: 767.983441ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:50:10.015274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:50:09.247103Z","time spent":"768.166282ms","remote":"127.0.0.1:58628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-12T01:50:10.015534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.38641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-12T01:50:10.015554Z","caller":"traceutil/trace.go:171","msg":"trace[765994987] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:551; }","duration":"341.438551ms","start":"2026-02-12T01:50:09.674108Z","end":"2026-02-12T01:50:10.015547Z","steps":["trace[765994987] 'range keys from in-memory index tree' (duration: 341.308249ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:50:10.015589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:50:09.674091Z","time spent":"341.493271ms","remote":"127.0.0.1:58628","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":"info","ts":"2026-02-12T01:50:10.166564Z","caller":"traceutil/trace.go:171","msg":"trace[1598723082] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:569; }","duration":"114.272193ms","start":"2026-02-12T01:50:10.052273Z","end":"2026-02-12T01:50:10.166546Z","steps":["trace[1598723082] 'read index received' (duration: 30.572634ms)","trace[1598723082] 'applied index is now lower than readState.Index' (duration: 83.699019ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T01:50:10.166655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.364263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T01:50:10.16667Z","caller":"traceutil/trace.go:171","msg":"trace[183517173] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:553; }","duration":"114.416553ms","start":"2026-02-12T01:50:10.052249Z","end":"2026-02-12T01:50:10.166666Z","steps":["trace[183517173] 'agreement among raft nodes before linearized reading' (duration: 114.359183ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:50:10.166933Z","caller":"traceutil/trace.go:171","msg":"trace[572692904] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"146.568468ms","start":"2026-02-12T01:50:10.020355Z","end":"2026-02-12T01:50:10.166924Z","steps":["trace[572692904] 'process raft request' (duration: 99.889401ms)","trace[572692904] 'compare' (duration: 46.228464ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T01:50:11.255729Z","caller":"traceutil/trace.go:171","msg":"trace[1658391493] linearizableReadLoop","detail":"{readStateIndex:575; appliedIndex:574; }","duration":"204.02767ms","start":"2026-02-12T01:50:11.051686Z","end":"2026-02-12T01:50:11.255714Z","steps":["trace[1658391493] 'read index received' (duration: 203.876289ms)","trace[1658391493] 'applied index is now lower than readState.Index' (duration: 150.911µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T01:50:11.255848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.135961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T01:50:11.255865Z","caller":"traceutil/trace.go:171","msg":"trace[1362722681] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:556; }","duration":"204.192491ms","start":"2026-02-12T01:50:11.051668Z","end":"2026-02-12T01:50:11.25586Z","steps":["trace[1362722681] 'agreement among raft nodes before linearized reading' (duration: 204.105961ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:50:11.256056Z","caller":"traceutil/trace.go:171","msg":"trace[1481329791] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"210.775389ms","start":"2026-02-12T01:50:11.045275Z","end":"2026-02-12T01:50:11.25605Z","steps":["trace[1481329791] 'process raft request' (duration: 210.355227ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:50:12.105411Z","caller":"traceutil/trace.go:171","msg":"trace[325164113] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"103.481046ms","start":"2026-02-12T01:50:12.001895Z","end":"2026-02-12T01:50:12.105376Z","steps":["trace[325164113] 'process raft request' (duration: 103.379136ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:50:13.077902Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2594526725773940419,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T01:50:13.578015Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2594526725773940419,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T01:50:13.601035Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.351705997s","expected-duration":"1s"} {"level":"info","ts":"2026-02-12T01:50:13.60127Z","caller":"traceutil/trace.go:171","msg":"trace[244920689] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:579; }","duration":"1.02360129s","start":"2026-02-12T01:50:12.577654Z","end":"2026-02-12T01:50:13.601255Z","steps":["trace[244920689] 'read index received' (duration: 1.023480189s)","trace[244920689] 'applied index is now lower than readState.Index' (duration: 120.561µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T01:50:13.601465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.717267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T01:50:13.601489Z","caller":"traceutil/trace.go:171","msg":"trace[1540969725] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:561; }","duration":"547.773227ms","start":"2026-02-12T01:50:13.053704Z","end":"2026-02-12T01:50:13.601477Z","steps":["trace[1540969725] 'agreement among raft nodes before linearized reading' (duration: 547.734457ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:50:13.601505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:50:13.053688Z","time spent":"547.812957ms","remote":"127.0.0.1:58410","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:50:13.601612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.015731ms","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:50:13.601624Z","caller":"traceutil/trace.go:171","msg":"trace[183385461] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:561; }","duration":"339.054311ms","start":"2026-02-12T01:50:13.262566Z","end":"2026-02-12T01:50:13.601621Z","steps":["trace[183385461] 'agreement among raft nodes before linearized reading' (duration: 339.030331ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:50:13.601635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:50:13.262552Z","time spent":"339.080951ms","remote":"127.0.0.1:58628","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":"info","ts":"2026-02-12T01:50:13.601776Z","caller":"traceutil/trace.go:171","msg":"trace[992573316] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"1.352520772s","start":"2026-02-12T01:50:12.24925Z","end":"2026-02-12T01:50:13.601771Z","steps":["trace[992573316] 'process raft request' (duration: 1.351918818s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:50:13.601821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:50:12.249231Z","time spent":"1.352558102s","remote":"127.0.0.1:58628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T01:50:13.603316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.025626291s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-t7zcf\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-12T01:50:13.603374Z","caller":"traceutil/trace.go:171","msg":"trace[1768626296] range","detail":"{range_begin:/registry/pods/kube-system/cilium-t7zcf; range_end:; response_count:1; response_revision:561; }","duration":"1.025739532s","start":"2026-02-12T01:50:12.577622Z","end":"2026-02-12T01:50:13.603361Z","steps":["trace[1768626296] 'agreement among raft nodes before linearized reading' (duration: 1.02369574s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:50:13.603409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:50:12.577606Z","time spent":"1.025797302s","remote":"127.0.0.1:58566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16680,"request content":"key:\"/registry/pods/kube-system/cilium-t7zcf\" "} {"level":"warn","ts":"2026-02-12T01:50:14.554773Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2594526725773940422,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T01:50:15.054894Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2594526725773940422,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T01:50:15.132101Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.479007872s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-12T01:50:15.240546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.140204ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T01:50:15.24064Z","caller":"traceutil/trace.go:171","msg":"trace[2052703494] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"1.626500789s","start":"2026-02-12T01:50:13.614122Z","end":"2026-02-12T01:50:15.240623Z","steps":["trace[2052703494] 'process raft request' (duration: 1.518215384s)","trace[2052703494] 'compare' (duration: 106.057863ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T01:50:15.240695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:50:13.614114Z","time spent":"1.626555049s","remote":"127.0.0.1:58566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T01:50:15.251857Z","caller":"traceutil/trace.go:171","msg":"trace[148413265] linearizableReadLoop","detail":"{readStateIndex:583; appliedIndex:581; }","duration":"1.197788793s","start":"2026-02-12T01:50:14.054051Z","end":"2026-02-12T01:50:15.25184Z","steps":["trace[148413265] 'read index received' (duration: 1.078222225s)","trace[148413265] 'applied index is now lower than readState.Index' (duration: 119.565748ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T01:50:15.252378Z","caller":"traceutil/trace.go:171","msg":"trace[680010986] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"1.140444032s","start":"2026-02-12T01:50:14.111794Z","end":"2026-02-12T01:50:15.252238Z","steps":["trace[680010986] 'process raft request' (duration: 1.139948169s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:50:15.252581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:50:14.111774Z","time spent":"1.140734653s","remote":"127.0.0.1:58628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T01:50:15.253249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.199195881s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T01:50:15.253428Z","caller":"traceutil/trace.go:171","msg":"trace[1054523514] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:564; }","duration":"1.199405902s","start":"2026-02-12T01:50:14.054013Z","end":"2026-02-12T01:50:15.253419Z","steps":["trace[1054523514] 'agreement among raft nodes before linearized reading' (duration: 1.199207081s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:50:15.253524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:50:14.053985Z","time spent":"1.199531263s","remote":"127.0.0.1:58404","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:50:15.253758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.098537556s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-12T01:50:15.254286Z","caller":"traceutil/trace.go:171","msg":"trace[1978241248] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:564; }","duration":"1.099091279s","start":"2026-02-12T01:50:14.155186Z","end":"2026-02-12T01:50:15.254277Z","steps":["trace[1978241248] 'agreement among raft nodes before linearized reading' (duration: 1.098544546s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:50:15.254471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:50:14.155169Z","time spent":"1.09929056s","remote":"127.0.0.1:58628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-12T01:50:15.25549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.104814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.149\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-12T01:50:15.255754Z","caller":"traceutil/trace.go:171","msg":"trace[617086499] range","detail":"{range_begin:/registry/masterleases/199.204.45.149; range_end:; response_count:1; response_revision:564; }","duration":"606.405155ms","start":"2026-02-12T01:50:14.64934Z","end":"2026-02-12T01:50:15.255745Z","steps":["trace[617086499] 'agreement among raft nodes before linearized reading' (duration: 606.122014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:50:15.255906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:50:14.649326Z","time spent":"606.569676ms","remote":"127.0.0.1:58422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.149\" "} {"level":"warn","ts":"2026-02-12T01:50:15.256145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.078269503s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-12T01:50:15.256304Z","caller":"traceutil/trace.go:171","msg":"trace[371226048] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:564; }","duration":"1.078454043s","start":"2026-02-12T01:50:14.177842Z","end":"2026-02-12T01:50:15.256296Z","steps":["trace[371226048] 'agreement among raft nodes before linearized reading' (duration: 1.078276922s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:50:15.256397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:50:14.177818Z","time spent":"1.078569695s","remote":"127.0.0.1:58628","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-12T01:50:17.289322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.353821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T01:50:17.289427Z","caller":"traceutil/trace.go:171","msg":"trace[1669421771] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:0; response_revision:580; }","duration":"427.461891ms","start":"2026-02-12T01:50:16.861927Z","end":"2026-02-12T01:50:17.289389Z","steps":["trace[1669421771] 'range keys from in-memory index tree' (duration: 427.315381ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:50:17.289463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:50:16.861915Z","time spent":"427.539202ms","remote":"127.0.0.1:58628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-12T01:50:17.2897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.522143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T01:50:17.289728Z","caller":"traceutil/trace.go:171","msg":"trace[587358921] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:580; }","duration":"231.549293ms","start":"2026-02-12T01:50:17.05817Z","end":"2026-02-12T01:50:17.28972Z","steps":["trace[587358921] 'range keys from in-memory index tree' (duration: 231.477602ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:50:19.875185Z","caller":"traceutil/trace.go:171","msg":"trace[1306169815] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:651; }","duration":"127.201503ms","start":"2026-02-12T01:50:19.747968Z","end":"2026-02-12T01:50:19.875169Z","steps":["trace[1306169815] 'read index received' (duration: 72.81962ms)","trace[1306169815] 'applied index is now lower than readState.Index' (duration: 54.381143ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T01:50:19.875314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.333464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-02-12T01:50:19.875347Z","caller":"traceutil/trace.go:171","msg":"trace[1266155510] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:632; }","duration":"127.425795ms","start":"2026-02-12T01:50:19.747914Z","end":"2026-02-12T01:50:19.87534Z","steps":["trace[1266155510] 'agreement among raft nodes before linearized reading' (duration: 127.313643ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:50:19.875447Z","caller":"traceutil/trace.go:171","msg":"trace[1360480244] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"129.628886ms","start":"2026-02-12T01:50:19.745813Z","end":"2026-02-12T01:50:19.875442Z","steps":["trace[1360480244] 'process raft request' (duration: 75.02253ms)","trace[1360480244] 'compare' (duration: 54.257522ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T01:50:19.87961Z","caller":"traceutil/trace.go:171","msg":"trace[532467341] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"103.706827ms","start":"2026-02-12T01:50:19.775892Z","end":"2026-02-12T01:50:19.879599Z","steps":["trace[532467341] 'process raft request' (duration: 103.167972ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:51:18.194044Z","caller":"traceutil/trace.go:171","msg":"trace[1855071604] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"134.822609ms","start":"2026-02-12T01:51:18.059204Z","end":"2026-02-12T01:51:18.194027Z","steps":["trace[1855071604] 'process raft request' (duration: 134.576454ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:51:34.203546Z","caller":"traceutil/trace.go:171","msg":"trace[1454257418] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"124.35151ms","start":"2026-02-12T01:51:34.079179Z","end":"2026-02-12T01:51:34.20353Z","steps":["trace[1454257418] 'process raft request' (duration: 124.014644ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:51:44.143185Z","caller":"traceutil/trace.go:171","msg":"trace[1239214349] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"174.984097ms","start":"2026-02-12T01:51:43.968188Z","end":"2026-02-12T01:51:44.143172Z","steps":["trace[1239214349] 'process raft request' (duration: 174.678111ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:51:50.141283Z","caller":"traceutil/trace.go:171","msg":"trace[1941447225] linearizableReadLoop","detail":"{readStateIndex:1071; appliedIndex:1070; }","duration":"104.787604ms","start":"2026-02-12T01:51:50.036476Z","end":"2026-02-12T01:51:50.141263Z","steps":["trace[1941447225] 'read index received' (duration: 41.035004ms)","trace[1941447225] 'applied index is now lower than readState.Index' (duration: 63.75113ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T01:51:50.141575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.074379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" limit:500 ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-02-12T01:51:50.141654Z","caller":"traceutil/trace.go:171","msg":"trace[537378158] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:1; response_revision:1027; }","duration":"105.181381ms","start":"2026-02-12T01:51:50.036454Z","end":"2026-02-12T01:51:50.141635Z","steps":["trace[537378158] 'agreement among raft nodes before linearized reading' (duration: 105.036259ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:51:50.53074Z","caller":"traceutil/trace.go:171","msg":"trace[238055352] linearizableReadLoop","detail":"{readStateIndex:1074; appliedIndex:1073; }","duration":"106.705397ms","start":"2026-02-12T01:51:50.423996Z","end":"2026-02-12T01:51:50.530701Z","steps":["trace[238055352] 'read index received' (duration: 99.432734ms)","trace[238055352] 'applied index is now lower than readState.Index' (duration: 7.271753ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T01:51:50.531138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.118094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-12T01:51:50.531208Z","caller":"traceutil/trace.go:171","msg":"trace[1290685028] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1030; }","duration":"107.224286ms","start":"2026-02-12T01:51:50.42397Z","end":"2026-02-12T01:51:50.531195Z","steps":["trace[1290685028] 'agreement among raft nodes before linearized reading' (duration: 106.968301ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:51:50.531339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.800044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-12T01:51:50.531383Z","caller":"traceutil/trace.go:171","msg":"trace[760195816] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1030; }","duration":"101.863586ms","start":"2026-02-12T01:51:50.429507Z","end":"2026-02-12T01:51:50.531371Z","steps":["trace[760195816] 'agreement among raft nodes before linearized reading' (duration: 101.733493ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:51:50.531463Z","caller":"traceutil/trace.go:171","msg":"trace[134370544] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"150.864085ms","start":"2026-02-12T01:51:50.380585Z","end":"2026-02-12T01:51:50.531449Z","steps":["trace[134370544] 'process raft request' (duration: 142.999732ms)"],"step_count":1}