{"level":"warn","ts":"2026-02-12T01:48:47.220713Z","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:48:47.22085Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.204: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://162.253.55.204:2380","--initial-cluster=instance=https://162.253.55.204:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.204:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.204: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:48:47.220982Z","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:48:47.221015Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.204:2380"]} {"level":"info","ts":"2026-02-12T01:48:47.221059Z","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:48:47.22182Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.204:2379"]} {"level":"info","ts":"2026-02-12T01:48:47.224379Z","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://162.253.55.204:2380"],"listen-peer-urls":["https://162.253.55.204:2380"],"advertise-client-urls":["https://162.253.55.204:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.204:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.204: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:48:47.229996Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.878092ms"} {"level":"info","ts":"2026-02-12T01:48:47.256904Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a11c20306510c1f7","cluster-id":"97da89d0cbebd2b5"} {"level":"info","ts":"2026-02-12T01:48:47.257074Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 switched to configuration voters=()"} {"level":"info","ts":"2026-02-12T01:48:47.25724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became follower at term 0"} {"level":"info","ts":"2026-02-12T01:48:47.257411Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a11c20306510c1f7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-12T01:48:47.257565Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became follower at term 1"} {"level":"info","ts":"2026-02-12T01:48:47.257738Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 switched to configuration voters=(11609189331680412151)"} {"level":"warn","ts":"2026-02-12T01:48:47.274774Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-12T01:48:47.287747Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-12T01:48:47.297679Z","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:48:47.306733Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a11c20306510c1f7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-12T01:48:47.307471Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a11c20306510c1f7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-12T01:48:47.307658Z","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:48:47.307711Z","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:48:47.307729Z","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:48:47.308306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 switched to configuration voters=(11609189331680412151)"} {"level":"info","ts":"2026-02-12T01:48:47.308527Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"97da89d0cbebd2b5","local-member-id":"a11c20306510c1f7","added-peer-id":"a11c20306510c1f7","added-peer-peer-urls":["https://162.253.55.204:2380"]} {"level":"info","ts":"2026-02-12T01:48:47.325024Z","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:48:47.325699Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a11c20306510c1f7","initial-advertise-peer-urls":["https://162.253.55.204:2380"],"listen-peer-urls":["https://162.253.55.204:2380"],"advertise-client-urls":["https://162.253.55.204:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.204:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-12T01:48:47.32611Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-12T01:48:47.325321Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.204:2380"} {"level":"info","ts":"2026-02-12T01:48:47.326489Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.204:2380"} {"level":"info","ts":"2026-02-12T01:48:47.762668Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 is starting a new election at term 1"} {"level":"info","ts":"2026-02-12T01:48:47.762705Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-12T01:48:47.762719Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 received MsgPreVoteResp from a11c20306510c1f7 at term 1"} {"level":"info","ts":"2026-02-12T01:48:47.76273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became candidate at term 2"} {"level":"info","ts":"2026-02-12T01:48:47.762735Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 received MsgVoteResp from a11c20306510c1f7 at term 2"} {"level":"info","ts":"2026-02-12T01:48:47.762744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became leader at term 2"} {"level":"info","ts":"2026-02-12T01:48:47.762774Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a11c20306510c1f7 elected leader a11c20306510c1f7 at term 2"} {"level":"info","ts":"2026-02-12T01:48:47.768698Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a11c20306510c1f7","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.204:2379]}","request-path":"/0/members/a11c20306510c1f7/attributes","cluster-id":"97da89d0cbebd2b5","publish-timeout":"7s"} {"level":"info","ts":"2026-02-12T01:48:47.769133Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-12T01:48:47.769453Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T01:48:47.769582Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-12T01:48:47.76977Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-12T01:48:47.769783Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-12T01:48:47.770964Z","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:48:47.77192Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.204:2379"} {"level":"info","ts":"2026-02-12T01:48:47.771984Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"97da89d0cbebd2b5","local-member-id":"a11c20306510c1f7","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T01:48:47.772029Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T01:48:47.772043Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-12T01:48:57.547788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.678381ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T01:48:57.547909Z","caller":"traceutil/trace.go:171","msg":"trace[1665735789] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"280.978933ms","start":"2026-02-12T01:48:57.26692Z","end":"2026-02-12T01:48:57.547899Z","steps":["trace[1665735789] 'process raft request' (duration: 280.939012ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:48:57.548005Z","caller":"traceutil/trace.go:171","msg":"trace[1991564044] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"312.487393ms","start":"2026-02-12T01:48:57.235471Z","end":"2026-02-12T01:48:57.547959Z","steps":["trace[1991564044] 'process raft request' (duration: 31.325719ms)","trace[1991564044] 'compare' (duration: 280.58563ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T01:48:57.548085Z","caller":"traceutil/trace.go:171","msg":"trace[1770127694] linearizableReadLoop","detail":"{readStateIndex:395; appliedIndex:394; }","duration":"281.205474ms","start":"2026-02-12T01:48:57.266871Z","end":"2026-02-12T01:48:57.548077Z","steps":["trace[1770127694] 'read index received' (duration: 1.725683ms)","trace[1770127694] 'applied index is now lower than readState.Index' (duration: 279.479231ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T01:48:57.548194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.319345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-12T01:48:57.54822Z","caller":"traceutil/trace.go:171","msg":"trace[333739293] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:388; }","duration":"281.368146ms","start":"2026-02-12T01:48:57.266847Z","end":"2026-02-12T01:48:57.548215Z","steps":["trace[333739293] 'agreement among raft nodes before linearized reading' (duration: 281.297796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:48:57.548187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:48:57.235463Z","time spent":"312.625314ms","remote":"127.0.0.1:59572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4019,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T01:48:57.548302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.097666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-5dd5756b68\" ","response":"range_response_count:1 size:3797"} {"level":"info","ts":"2026-02-12T01:48:57.54832Z","caller":"traceutil/trace.go:171","msg":"trace[1900269386] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-5dd5756b68; range_end:; response_count:1; response_revision:388; }","duration":"280.137636ms","start":"2026-02-12T01:48:57.268179Z","end":"2026-02-12T01:48:57.548316Z","steps":["trace[1900269386] 'agreement among raft nodes before linearized reading' (duration: 280.107126ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:48:57.548418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.791142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-02-12T01:48:57.548435Z","caller":"traceutil/trace.go:171","msg":"trace[1329216472] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:388; }","duration":"251.832262ms","start":"2026-02-12T01:48:57.296599Z","end":"2026-02-12T01:48:57.548431Z","steps":["trace[1329216472] 'agreement among raft nodes before linearized reading' (duration: 251.804392ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:49:03.207693Z","caller":"traceutil/trace.go:171","msg":"trace[1715371933] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"306.678913ms","start":"2026-02-12T01:49:02.900968Z","end":"2026-02-12T01:49:03.207647Z","steps":["trace[1715371933] 'process raft request' (duration: 306.421051ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:49:03.207878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:49:02.900948Z","time spent":"306.837074ms","remote":"127.0.0.1:59308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4583,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T01:49:03.208507Z","caller":"traceutil/trace.go:171","msg":"trace[482334166] linearizableReadLoop","detail":"{readStateIndex:423; appliedIndex:423; }","duration":"257.219083ms","start":"2026-02-12T01:49:02.951272Z","end":"2026-02-12T01:49:03.208491Z","steps":["trace[482334166] 'read index received' (duration: 257.201853ms)","trace[482334166] 'applied index is now lower than readState.Index' (duration: 16.24µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T01:49:03.208785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.511835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T01:49:03.208822Z","caller":"traceutil/trace.go:171","msg":"trace[1252789297] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:413; }","duration":"257.593546ms","start":"2026-02-12T01:49:02.951218Z","end":"2026-02-12T01:49:03.208811Z","steps":["trace[1252789297] 'agreement among raft nodes before linearized reading' (duration: 257.532466ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:49:03.708723Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976811833988291982,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T01:49:03.975305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.471205ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T01:49:03.975355Z","caller":"traceutil/trace.go:171","msg":"trace[1942660811] linearizableReadLoop","detail":"{readStateIndex:424; appliedIndex:423; }","duration":"766.777255ms","start":"2026-02-12T01:49:03.20857Z","end":"2026-02-12T01:49:03.975347Z","steps":["trace[1942660811] 'read index received' (duration: 510.235199ms)","trace[1942660811] 'applied index is now lower than readState.Index' (duration: 256.541426ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T01:49:03.975507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"984.861449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4013"} {"level":"info","ts":"2026-02-12T01:49:03.975525Z","caller":"traceutil/trace.go:171","msg":"trace[410319886] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:414; }","duration":"984.90431ms","start":"2026-02-12T01:49:02.990616Z","end":"2026-02-12T01:49:03.97552Z","steps":["trace[410319886] 'agreement among raft nodes before linearized reading' (duration: 984.856429ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:49:03.975539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:49:02.990587Z","time spent":"984.94826ms","remote":"127.0.0.1:59300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4037,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-02-12T01:49:03.975626Z","caller":"traceutil/trace.go:171","msg":"trace[751601042] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"883.098174ms","start":"2026-02-12T01:49:03.092523Z","end":"2026-02-12T01:49:03.975621Z","steps":["trace[751601042] 'process raft request' (duration: 626.267085ms)","trace[751601042] 'compare' (duration: 256.300374ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T01:49:03.975651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:49:03.092504Z","time spent":"883.134184ms","remote":"127.0.0.1:59386","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:49:03.975806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.377095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7237"} {"level":"info","ts":"2026-02-12T01:49:03.975819Z","caller":"traceutil/trace.go:171","msg":"trace[518616268] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:414; }","duration":"761.422565ms","start":"2026-02-12T01:49:03.214393Z","end":"2026-02-12T01:49:03.975815Z","steps":["trace[518616268] 'agreement among raft nodes before linearized reading' (duration: 761.394055ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:49:03.975831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:49:03.214377Z","time spent":"761.451205ms","remote":"127.0.0.1:59308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7261,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-02-12T01:49:03.975956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.518339ms","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:49:03.975969Z","caller":"traceutil/trace.go:171","msg":"trace[1517954758] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:414; }","duration":"343.568209ms","start":"2026-02-12T01:49:03.632397Z","end":"2026-02-12T01:49:03.975965Z","steps":["trace[1517954758] 'agreement among raft nodes before linearized reading' (duration: 343.545429ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:49:03.975982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:49:03.632377Z","time spent":"343.60248ms","remote":"127.0.0.1:59386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-12T01:49:03.976031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.294061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T01:49:03.976042Z","caller":"traceutil/trace.go:171","msg":"trace[288602989] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:414; }","duration":"758.318492ms","start":"2026-02-12T01:49:03.21772Z","end":"2026-02-12T01:49:03.976039Z","steps":["trace[288602989] 'agreement among raft nodes before linearized reading' (duration: 758.301751ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:49:03.976052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:49:03.217714Z","time spent":"758.336152ms","remote":"127.0.0.1:59114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-12T01:49:18.621656Z","caller":"traceutil/trace.go:171","msg":"trace[287085844] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"264.983224ms","start":"2026-02-12T01:49:18.356655Z","end":"2026-02-12T01:49:18.621639Z","steps":["trace[287085844] 'process raft request' (duration: 264.877754ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:49:20.578869Z","caller":"traceutil/trace.go:171","msg":"trace[1580852625] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"496.931607ms","start":"2026-02-12T01:49:20.081925Z","end":"2026-02-12T01:49:20.578856Z","steps":["trace[1580852625] 'process raft request' (duration: 496.593085ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:49:20.578985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:49:20.081915Z","time spent":"497.007679ms","remote":"127.0.0.1:59386","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:49:20.578682Z","caller":"traceutil/trace.go:171","msg":"trace[1838261822] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:531; }","duration":"440.546985ms","start":"2026-02-12T01:49:20.138115Z","end":"2026-02-12T01:49:20.578662Z","steps":["trace[1838261822] 'read index received' (duration: 440.522555ms)","trace[1838261822] 'applied index is now lower than readState.Index' (duration: 23.79µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T01:49:20.579427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.304731ms","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:49:20.579461Z","caller":"traceutil/trace.go:171","msg":"trace[1865215342] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:519; }","duration":"441.357441ms","start":"2026-02-12T01:49:20.138091Z","end":"2026-02-12T01:49:20.579448Z","steps":["trace[1865215342] 'agreement among raft nodes before linearized reading' (duration: 441.300981ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:49:20.579489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:49:20.138078Z","time spent":"441.403752ms","remote":"127.0.0.1:59386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-12T01:49:20.605626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.827987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-12T01:49:20.605658Z","caller":"traceutil/trace.go:171","msg":"trace[2088490424] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:520; }","duration":"463.869198ms","start":"2026-02-12T01:49:20.14178Z","end":"2026-02-12T01:49:20.60565Z","steps":["trace[2088490424] 'agreement among raft nodes before linearized reading' (duration: 463.787467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:49:20.605677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:49:20.141774Z","time spent":"463.898199ms","remote":"127.0.0.1:59386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T01:49:20.605772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.633743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-12T01:49:20.605788Z","caller":"traceutil/trace.go:171","msg":"trace[10909721] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:520; }","duration":"457.658394ms","start":"2026-02-12T01:49:20.148124Z","end":"2026-02-12T01:49:20.605783Z","steps":["trace[10909721] 'agreement among raft nodes before linearized reading' (duration: 457.614103ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:49:20.605803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:49:20.14812Z","time spent":"457.679604ms","remote":"127.0.0.1:59386","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:49:26.116752Z","caller":"traceutil/trace.go:171","msg":"trace[1930900556] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:572; }","duration":"143.279368ms","start":"2026-02-12T01:49:25.97345Z","end":"2026-02-12T01:49:26.116729Z","steps":["trace[1930900556] 'read index received' (duration: 143.010436ms)","trace[1930900556] 'applied index is now lower than readState.Index' (duration: 267.932µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T01:49:26.116888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.416399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T01:49:26.11692Z","caller":"traceutil/trace.go:171","msg":"trace[540209535] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:559; }","duration":"143.497809ms","start":"2026-02-12T01:49:25.973412Z","end":"2026-02-12T01:49:26.11691Z","steps":["trace[540209535] 'agreement among raft nodes before linearized reading' (duration: 143.414759ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:49:26.117252Z","caller":"traceutil/trace.go:171","msg":"trace[2030693832] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"1.063137835s","start":"2026-02-12T01:49:25.054102Z","end":"2026-02-12T01:49:26.117239Z","steps":["trace[2030693832] 'process raft request' (duration: 1.06241935s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:49:26.117344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:49:25.054088Z","time spent":"1.063191486s","remote":"127.0.0.1:59308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T01:49:27.092633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.606155ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-12T01:49:27.092699Z","caller":"traceutil/trace.go:171","msg":"trace[1911703689] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:573; }","duration":"496.180083ms","start":"2026-02-12T01:49:26.596508Z","end":"2026-02-12T01:49:27.092688Z","steps":["trace[1911703689] 'read index received' (duration: 77.771µs)","trace[1911703689] 'applied index is now lower than readState.Index' (duration: 496.101472ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T01:49:27.092825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.311244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-12T01:49:27.092841Z","caller":"traceutil/trace.go:171","msg":"trace[825653087] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:559; }","duration":"496.364274ms","start":"2026-02-12T01:49:26.596471Z","end":"2026-02-12T01:49:27.092836Z","steps":["trace[825653087] 'agreement among raft nodes before linearized reading' (duration: 496.269453ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:49:27.092859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:49:26.596449Z","time spent":"496.405824ms","remote":"127.0.0.1:59386","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:49:27.092928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.04775ms","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:49:27.092983Z","caller":"traceutil/trace.go:171","msg":"trace[1730619225] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:559; }","duration":"468.163761ms","start":"2026-02-12T01:49:26.624806Z","end":"2026-02-12T01:49:27.092969Z","steps":["trace[1730619225] 'agreement among raft nodes before linearized reading' (duration: 467.98579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:49:27.093015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:49:26.624792Z","time spent":"468.214602ms","remote":"127.0.0.1:59386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-12T01:49:27.093097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.396768ms","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:49:27.093114Z","caller":"traceutil/trace.go:171","msg":"trace[1449850322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:559; }","duration":"457.425588ms","start":"2026-02-12T01:49:26.635684Z","end":"2026-02-12T01:49:27.093109Z","steps":["trace[1449850322] 'agreement among raft nodes before linearized reading' (duration: 457.379857ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:49:27.093127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:49:26.635677Z","time spent":"457.447468ms","remote":"127.0.0.1:59386","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:49:27.093185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.716798ms","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:49:27.093195Z","caller":"traceutil/trace.go:171","msg":"trace[1546530036] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:559; }","duration":"461.736758ms","start":"2026-02-12T01:49:26.631455Z","end":"2026-02-12T01:49:27.093192Z","steps":["trace[1546530036] 'agreement among raft nodes before linearized reading' (duration: 461.704698ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:49:27.093206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:49:26.63145Z","time spent":"461.753338ms","remote":"127.0.0.1:59386","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:49:28.357667Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.261552268s","expected-duration":"1s"} {"level":"info","ts":"2026-02-12T01:49:28.357975Z","caller":"traceutil/trace.go:171","msg":"trace[1481485165] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"1.261896279s","start":"2026-02-12T01:49:27.096059Z","end":"2026-02-12T01:49:28.357955Z","steps":["trace[1481485165] 'process raft request' (duration: 1.261764959s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:49:28.358098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:49:27.096046Z","time spent":"1.26197537s","remote":"127.0.0.1:59386","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:49:29.233333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.625146ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T01:49:29.233379Z","caller":"traceutil/trace.go:171","msg":"trace[325637781] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:560; }","duration":"333.678566ms","start":"2026-02-12T01:49:28.899689Z","end":"2026-02-12T01:49:29.233367Z","steps":["trace[325637781] 'range keys from in-memory index tree' (duration: 333.615746ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:49:29.23366Z","caller":"traceutil/trace.go:171","msg":"trace[1849364231] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"2.136693513s","start":"2026-02-12T01:49:27.096945Z","end":"2026-02-12T01:49:29.233638Z","steps":["trace[1849364231] 'process raft request' (duration: 2.132030641s)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:49:29.233779Z","caller":"traceutil/trace.go:171","msg":"trace[1406249735] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"2.135992377s","start":"2026-02-12T01:49:27.097779Z","end":"2026-02-12T01:49:29.233772Z","steps":["trace[1406249735] 'process raft request' (duration: 2.135814935s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:49:29.23379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:49:27.096929Z","time spent":"2.136800283s","remote":"127.0.0.1:59386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T01:49:29.233837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:49:27.097774Z","time spent":"2.136023157s","remote":"127.0.0.1:59386","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:49:29.233961Z","caller":"traceutil/trace.go:171","msg":"trace[1359750683] linearizableReadLoop","detail":"{readStateIndex:578; appliedIndex:575; }","duration":"1.265017516s","start":"2026-02-12T01:49:27.968937Z","end":"2026-02-12T01:49:29.233955Z","steps":["trace[1359750683] 'read index received' (duration: 389.714151ms)","trace[1359750683] 'applied index is now lower than readState.Index' (duration: 875.302855ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T01:49:29.233993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.265055506s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T01:49:29.234004Z","caller":"traceutil/trace.go:171","msg":"trace[556368098] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:563; }","duration":"1.265091636s","start":"2026-02-12T01:49:27.968908Z","end":"2026-02-12T01:49:29.234Z","steps":["trace[556368098] 'agreement among raft nodes before linearized reading' (duration: 1.265064236s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:49:29.234016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:49:27.968894Z","time spent":"1.265119516s","remote":"127.0.0.1:59114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-12T01:49:29.234158Z","caller":"traceutil/trace.go:171","msg":"trace[282049982] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"2.135872276s","start":"2026-02-12T01:49:27.098275Z","end":"2026-02-12T01:49:29.234147Z","steps":["trace[282049982] 'process raft request' (duration: 2.135456883s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:49:29.236426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:49:27.09827Z","time spent":"2.135930096s","remote":"127.0.0.1:59386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T01:49:30.471834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.398036ms","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:49:30.471877Z","caller":"traceutil/trace.go:171","msg":"trace[1662939764] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:567; }","duration":"109.484317ms","start":"2026-02-12T01:49:30.362383Z","end":"2026-02-12T01:49:30.471868Z","steps":["trace[1662939764] 'range keys from in-memory index tree' (duration: 109.265626ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:49:31.859978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.983795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.204\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-12T01:49:31.860022Z","caller":"traceutil/trace.go:171","msg":"trace[1902526083] range","detail":"{range_begin:/registry/masterleases/162.253.55.204; range_end:; response_count:1; response_revision:579; }","duration":"522.060735ms","start":"2026-02-12T01:49:31.337951Z","end":"2026-02-12T01:49:31.860012Z","steps":["trace[1902526083] 'range keys from in-memory index tree' (duration: 521.848933ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:49:31.860042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:49:31.337927Z","time spent":"522.110775ms","remote":"127.0.0.1:59134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.204\" "} {"level":"warn","ts":"2026-02-12T01:49:31.860334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.102378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-12T01:49:31.86035Z","caller":"traceutil/trace.go:171","msg":"trace[452644469] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:579; }","duration":"214.149528ms","start":"2026-02-12T01:49:31.646195Z","end":"2026-02-12T01:49:31.860345Z","steps":["trace[452644469] 'range keys from in-memory index tree' (duration: 213.981457ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:49:49.452779Z","caller":"traceutil/trace.go:171","msg":"trace[1944728569] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"132.286061ms","start":"2026-02-12T01:49:49.320476Z","end":"2026-02-12T01:49:49.452763Z","steps":["trace[1944728569] 'process raft request' (duration: 132.140221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:50:55.994878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.345734ms","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:55.994936Z","caller":"traceutil/trace.go:171","msg":"trace[2145260833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:992; }","duration":"185.435885ms","start":"2026-02-12T01:50:55.809485Z","end":"2026-02-12T01:50:55.994921Z","steps":["trace[2145260833] 'range keys from in-memory index tree' (duration: 185.190233ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:50:55.994948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.426679ms","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:50:55.994971Z","caller":"traceutil/trace.go:171","msg":"trace[1344677333] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:992; }","duration":"176.48713ms","start":"2026-02-12T01:50:55.818477Z","end":"2026-02-12T01:50:55.994964Z","steps":["trace[1344677333] 'range keys from in-memory index tree' (duration: 176.348149ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:50:56.315954Z","caller":"traceutil/trace.go:171","msg":"trace[702685592] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"222.002738ms","start":"2026-02-12T01:50:56.093932Z","end":"2026-02-12T01:50:56.315935Z","steps":["trace[702685592] 'process raft request' (duration: 216.582765ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:50:57.22194Z","caller":"traceutil/trace.go:171","msg":"trace[1259401006] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"701.458882ms","start":"2026-02-12T01:50:56.520463Z","end":"2026-02-12T01:50:57.221921Z","steps":["trace[1259401006] 'process raft request' (duration: 701.342571ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:50:57.222212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:50:56.520441Z","time spent":"701.557003ms","remote":"127.0.0.1:59386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T01:50:57.226593Z","caller":"traceutil/trace.go:171","msg":"trace[2078931760] linearizableReadLoop","detail":"{readStateIndex:1033; appliedIndex:1032; }","duration":"303.705248ms","start":"2026-02-12T01:50:56.922878Z","end":"2026-02-12T01:50:57.226583Z","steps":["trace[2078931760] 'read index received' (duration: 299.807545ms)","trace[2078931760] 'applied index is now lower than readState.Index' (duration: 3.897083ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T01:50:57.226843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.9584ms","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:50:57.226876Z","caller":"traceutil/trace.go:171","msg":"trace[2130706295] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:997; }","duration":"304.02701ms","start":"2026-02-12T01:50:56.922841Z","end":"2026-02-12T01:50:57.226868Z","steps":["trace[2130706295] 'agreement among raft nodes before linearized reading' (duration: 303.922259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:50:57.22692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:50:56.922828Z","time spent":"304.0858ms","remote":"127.0.0.1:59386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-12T01:50:57.227246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.335676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-12T01:50:57.227304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.974919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T01:50:57.227385Z","caller":"traceutil/trace.go:171","msg":"trace[478271346] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:997; }","duration":"254.522117ms","start":"2026-02-12T01:50:56.972835Z","end":"2026-02-12T01:50:57.227357Z","steps":["trace[478271346] 'agreement among raft nodes before linearized reading' (duration: 254.312465ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:50:57.227409Z","caller":"traceutil/trace.go:171","msg":"trace[894545542] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:997; }","duration":"103.09496ms","start":"2026-02-12T01:50:57.124306Z","end":"2026-02-12T01:50:57.227401Z","steps":["trace[894545542] 'agreement among raft nodes before linearized reading' (duration: 102.976519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:50:57.227979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.775164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-12T01:50:57.230423Z","caller":"traceutil/trace.go:171","msg":"trace[758865183] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:997; }","duration":"114.262209ms","start":"2026-02-12T01:50:57.116147Z","end":"2026-02-12T01:50:57.230409Z","steps":["trace[758865183] 'agreement among raft nodes before linearized reading' (duration: 111.399992ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:51:32.153006Z","caller":"traceutil/trace.go:171","msg":"trace[1062710055] linearizableReadLoop","detail":"{readStateIndex:1142; appliedIndex:1141; }","duration":"130.149312ms","start":"2026-02-12T01:51:32.022833Z","end":"2026-02-12T01:51:32.152983Z","steps":["trace[1062710055] 'read index received' (duration: 130.005282ms)","trace[1062710055] 'applied index is now lower than readState.Index' (duration: 143.15µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T01:51:32.153259Z","caller":"traceutil/trace.go:171","msg":"trace[990988890] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"140.636046ms","start":"2026-02-12T01:51:32.012607Z","end":"2026-02-12T01:51:32.153243Z","steps":["trace[990988890] 'process raft request' (duration: 140.247524ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:51:32.15358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.727086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-pmzwx\" ","response":"range_response_count:1 size:4848"} {"level":"info","ts":"2026-02-12T01:51:32.15374Z","caller":"traceutil/trace.go:171","msg":"trace[1441023628] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-pmzwx; range_end:; response_count:1; response_revision:1098; }","duration":"130.886796ms","start":"2026-02-12T01:51:32.022813Z","end":"2026-02-12T01:51:32.1537Z","steps":["trace[1441023628] 'agreement among raft nodes before linearized reading' (duration: 130.653085ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:51:32.625748Z","caller":"traceutil/trace.go:171","msg":"trace[2026222056] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"149.932143ms","start":"2026-02-12T01:51:32.475791Z","end":"2026-02-12T01:51:32.625723Z","steps":["trace[2026222056] 'process raft request' (duration: 122.886618ms)","trace[2026222056] 'compare' (duration: 26.837204ms)"],"step_count":2}