{"level":"warn","ts":"2026-02-18T22:48:11.030625Z","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-18T22:48:11.030807Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.217: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.217:2380","--initial-cluster=instance=https://162.253.55.217:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.217:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.217: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-18T22:48:11.030916Z","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-18T22:48:11.030969Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.217:2380"]} {"level":"info","ts":"2026-02-18T22:48:11.031011Z","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-18T22:48:11.031699Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"]} {"level":"info","ts":"2026-02-18T22:48:11.033526Z","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.217:2380"],"listen-peer-urls":["https://162.253.55.217:2380"],"advertise-client-urls":["https://162.253.55.217:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.217: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-18T22:48:11.040389Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.529611ms"} {"level":"info","ts":"2026-02-18T22:48:11.055216Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f1e368e6818bd6f7","cluster-id":"289e52865b7eaeb1"} {"level":"info","ts":"2026-02-18T22:48:11.063531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=()"} {"level":"info","ts":"2026-02-18T22:48:11.063679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became follower at term 0"} {"level":"info","ts":"2026-02-18T22:48:11.066142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f1e368e6818bd6f7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-18T22:48:11.066305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became follower at term 1"} {"level":"info","ts":"2026-02-18T22:48:11.066407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=(17429890322079143671)"} {"level":"warn","ts":"2026-02-18T22:48:11.078471Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-18T22:48:11.095859Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-18T22:48:11.100924Z","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-18T22:48:11.118053Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f1e368e6818bd6f7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-18T22:48:11.118954Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f1e368e6818bd6f7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-18T22:48:11.119155Z","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-18T22:48:11.119235Z","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-18T22:48:11.119298Z","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-18T22:48:11.119537Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=(17429890322079143671)"} {"level":"info","ts":"2026-02-18T22:48:11.119686Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"289e52865b7eaeb1","local-member-id":"f1e368e6818bd6f7","added-peer-id":"f1e368e6818bd6f7","added-peer-peer-urls":["https://162.253.55.217:2380"]} {"level":"info","ts":"2026-02-18T22:48:11.127915Z","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-18T22:48:11.129826Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.217:2380"} {"level":"info","ts":"2026-02-18T22:48:11.130218Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.217:2380"} {"level":"info","ts":"2026-02-18T22:48:11.135785Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f1e368e6818bd6f7","initial-advertise-peer-urls":["https://162.253.55.217:2380"],"listen-peer-urls":["https://162.253.55.217:2380"],"advertise-client-urls":["https://162.253.55.217:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-18T22:48:11.13585Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-18T22:48:11.567173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 is starting a new election at term 1"} {"level":"info","ts":"2026-02-18T22:48:11.56728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-18T22:48:11.567321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 received MsgPreVoteResp from f1e368e6818bd6f7 at term 1"} {"level":"info","ts":"2026-02-18T22:48:11.567358Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became candidate at term 2"} {"level":"info","ts":"2026-02-18T22:48:11.56738Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 received MsgVoteResp from f1e368e6818bd6f7 at term 2"} {"level":"info","ts":"2026-02-18T22:48:11.567414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became leader at term 2"} {"level":"info","ts":"2026-02-18T22:48:11.567439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f1e368e6818bd6f7 elected leader f1e368e6818bd6f7 at term 2"} {"level":"info","ts":"2026-02-18T22:48:11.568792Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f1e368e6818bd6f7","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.217:2379]}","request-path":"/0/members/f1e368e6818bd6f7/attributes","cluster-id":"289e52865b7eaeb1","publish-timeout":"7s"} {"level":"info","ts":"2026-02-18T22:48:11.568836Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T22:48:11.56918Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T22:48:11.578161Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.217:2379"} {"level":"info","ts":"2026-02-18T22:48:11.578233Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T22:48:11.579479Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-18T22:48:11.59434Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"289e52865b7eaeb1","local-member-id":"f1e368e6818bd6f7","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T22:48:11.594486Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T22:48:11.59451Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T22:48:11.598866Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-18T22:48:11.598885Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-02-18T22:48:21.800627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.313706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-18T22:48:21.800818Z","caller":"traceutil/trace.go:171","msg":"trace[1686447200] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:313; }","duration":"271.508638ms","start":"2026-02-18T22:48:21.529252Z","end":"2026-02-18T22:48:21.80076Z","steps":["trace[1686447200] 'range keys from in-memory index tree' (duration: 271.256805ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:48:21.805125Z","caller":"traceutil/trace.go:171","msg":"trace[2101114355] linearizableReadLoop","detail":"{readStateIndex:323; appliedIndex:322; }","duration":"248.813123ms","start":"2026-02-18T22:48:21.556294Z","end":"2026-02-18T22:48:21.805108Z","steps":["trace[2101114355] 'read index received' (duration: 248.662962ms)","trace[2101114355] 'applied index is now lower than readState.Index' (duration: 149.461µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:48:21.805454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.083557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-02-18T22:48:21.805487Z","caller":"traceutil/trace.go:171","msg":"trace[1835642663] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:314; }","duration":"226.150968ms","start":"2026-02-18T22:48:21.579328Z","end":"2026-02-18T22:48:21.805479Z","steps":["trace[1835642663] 'agreement among raft nodes before linearized reading' (duration: 226.085508ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:21.805953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.056963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-02-18T22:48:21.805982Z","caller":"traceutil/trace.go:171","msg":"trace[2080188444] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:314; }","duration":"126.107383ms","start":"2026-02-18T22:48:21.679867Z","end":"2026-02-18T22:48:21.805974Z","steps":["trace[2080188444] 'agreement among raft nodes before linearized reading' (duration: 126.067573ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:21.806175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.313707ms","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-18T22:48:21.806201Z","caller":"traceutil/trace.go:171","msg":"trace[1214580153] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:314; }","duration":"165.353898ms","start":"2026-02-18T22:48:21.64084Z","end":"2026-02-18T22:48:21.806194Z","steps":["trace[1214580153] 'agreement among raft nodes before linearized reading' (duration: 165.310517ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:21.806359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.71355ms","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-18T22:48:21.806385Z","caller":"traceutil/trace.go:171","msg":"trace[1691204965] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:314; }","duration":"176.7735ms","start":"2026-02-18T22:48:21.629604Z","end":"2026-02-18T22:48:21.806378Z","steps":["trace[1691204965] 'agreement among raft nodes before linearized reading' (duration: 176.711829ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:48:21.806879Z","caller":"traceutil/trace.go:171","msg":"trace[806681725] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"276.243272ms","start":"2026-02-18T22:48:21.530628Z","end":"2026-02-18T22:48:21.806871Z","steps":["trace[806681725] 'process raft request' (duration: 274.381268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:21.81307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.771811ms","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-18T22:48:21.813106Z","caller":"traceutil/trace.go:171","msg":"trace[1306329640] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:314; }","duration":"256.806951ms","start":"2026-02-18T22:48:21.556289Z","end":"2026-02-18T22:48:21.813096Z","steps":["trace[1306329640] 'agreement among raft nodes before linearized reading' (duration: 248.932914ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:27.533028Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021460839513455,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:48:27.583482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.93017ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:48:27.583587Z","caller":"traceutil/trace.go:171","msg":"trace[1937806243] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"726.71274ms","start":"2026-02-18T22:48:26.856857Z","end":"2026-02-18T22:48:27.58357Z","steps":["trace[1937806243] 'process raft request' (duration: 174.141676ms)","trace[1937806243] 'compare' (duration: 551.736458ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:48:27.583646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:26.856851Z","time spent":"726.766161ms","remote":"127.0.0.1:48746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3818,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:48:28.985831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.252645219s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:48:28.985991Z","caller":"traceutil/trace.go:171","msg":"trace[554216400] linearizableReadLoop","detail":"{readStateIndex:414; appliedIndex:411; }","duration":"1.953074541s","start":"2026-02-18T22:48:27.032905Z","end":"2026-02-18T22:48:28.98598Z","steps":["trace[554216400] 'read index received' (duration: 700.23994ms)","trace[554216400] 'applied index is now lower than readState.Index' (duration: 1.252833891s)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:48:28.986041Z","caller":"traceutil/trace.go:171","msg":"trace[321705688] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"2.121438028s","start":"2026-02-18T22:48:26.864596Z","end":"2026-02-18T22:48:28.986034Z","steps":["trace[321705688] 'process raft request' (duration: 868.541227ms)","trace[321705688] 'compare' (duration: 1.251474571s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:48:28.986076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:26.864583Z","time spent":"2.121477188s","remote":"127.0.0.1:48366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T22:48:28.986282Z","caller":"traceutil/trace.go:171","msg":"trace[1114963690] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"2.121636869s","start":"2026-02-18T22:48:26.864638Z","end":"2026-02-18T22:48:28.986275Z","steps":["trace[1114963690] 'process raft request' (duration: 2.121268736s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:28.986327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:26.864633Z","time spent":"2.121670249s","remote":"127.0.0.1:48496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4015,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:48:28.986457Z","caller":"traceutil/trace.go:171","msg":"trace[1335040724] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"2.116761745s","start":"2026-02-18T22:48:26.86969Z","end":"2026-02-18T22:48:28.986451Z","steps":["trace[1335040724] 'process raft request' (duration: 2.116265331s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:28.986496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:26.869678Z","time spent":"2.116799295s","remote":"127.0.0.1:48718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4158,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:48:28.986592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.953729246s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-18T22:48:28.986615Z","caller":"traceutil/trace.go:171","msg":"trace[1324268246] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:403; }","duration":"1.953781876s","start":"2026-02-18T22:48:27.032827Z","end":"2026-02-18T22:48:28.986609Z","steps":["trace[1324268246] 'agreement among raft nodes before linearized reading' (duration: 1.953740046s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:28.986637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:27.03281Z","time spent":"1.953822496s","remote":"127.0.0.1:48562","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-18T22:48:28.987057Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.255881761s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-18T22:48:28.987321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.43676866s","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-18T22:48:28.987347Z","caller":"traceutil/trace.go:171","msg":"trace[1735469872] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:404; }","duration":"1.436842101s","start":"2026-02-18T22:48:27.550494Z","end":"2026-02-18T22:48:28.987336Z","steps":["trace[1735469872] 'agreement among raft nodes before linearized reading' (duration: 1.43676807s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:28.987369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:27.550472Z","time spent":"1.436893611s","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-18T22:48:28.987596Z","caller":"traceutil/trace.go:171","msg":"trace[1275969597] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"1.393966762s","start":"2026-02-18T22:48:27.593624Z","end":"2026-02-18T22:48:28.987591Z","steps":["trace[1275969597] 'process raft request' (duration: 1.393552399s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:28.987652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:27.59361Z","time spent":"1.394009512s","remote":"127.0.0.1:48746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3818,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:48:28.987731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.12615325s","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-18T22:48:28.987746Z","caller":"traceutil/trace.go:171","msg":"trace[1949654639] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:404; }","duration":"1.12618411s","start":"2026-02-18T22:48:27.861556Z","end":"2026-02-18T22:48:28.98774Z","steps":["trace[1949654639] 'agreement among raft nodes before linearized reading' (duration: 1.126151429s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:28.987759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:27.861551Z","time spent":"1.12620474s","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T22:48:28.987836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.128525765s","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-18T22:48:28.987848Z","caller":"traceutil/trace.go:171","msg":"trace[420428517] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:404; }","duration":"1.128555365s","start":"2026-02-18T22:48:27.859289Z","end":"2026-02-18T22:48:28.987844Z","steps":["trace[420428517] 'agreement among raft nodes before linearized reading' (duration: 1.128532245s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:28.987861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:27.859271Z","time spent":"1.128587096s","remote":"127.0.0.1:48562","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-18T22:48:28.987916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.137619029s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:48:28.987931Z","caller":"traceutil/trace.go:171","msg":"trace[189535185] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:404; }","duration":"1.137654419s","start":"2026-02-18T22:48:27.85027Z","end":"2026-02-18T22:48:28.987924Z","steps":["trace[189535185] 'agreement among raft nodes before linearized reading' (duration: 1.137635499s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:28.987941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:27.850257Z","time spent":"1.137681729s","remote":"127.0.0.1:48298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T22:48:31.209312Z","caller":"traceutil/trace.go:171","msg":"trace[405449740] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"105.878269ms","start":"2026-02-18T22:48:31.103415Z","end":"2026-02-18T22:48:31.209293Z","steps":["trace[405449740] 'process raft request' (duration: 17.078996ms)","trace[405449740] 'compare' (duration: 88.733253ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:48:39.428053Z","caller":"traceutil/trace.go:171","msg":"trace[1413264356] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"184.099349ms","start":"2026-02-18T22:48:39.243927Z","end":"2026-02-18T22:48:39.428026Z","steps":["trace[1413264356] 'process raft request' (duration: 183.766797ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:40.010558Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021460839513598,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:48:40.364943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.306173ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:48:40.365279Z","caller":"traceutil/trace.go:171","msg":"trace[1916350995] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"929.306153ms","start":"2026-02-18T22:48:39.435953Z","end":"2026-02-18T22:48:40.365259Z","steps":["trace[1916350995] 'process raft request' (duration: 641.362965ms)","trace[1916350995] 'compare' (duration: 286.508928ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:48:40.365348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:39.435939Z","time spent":"929.380523ms","remote":"127.0.0.1:48492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4211,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:48:40.365587Z","caller":"traceutil/trace.go:171","msg":"trace[908133910] linearizableReadLoop","detail":"{readStateIndex:482; appliedIndex:481; }","duration":"855.817984ms","start":"2026-02-18T22:48:39.509756Z","end":"2026-02-18T22:48:40.365574Z","steps":["trace[908133910] 'read index received' (duration: 567.553974ms)","trace[908133910] 'applied index is now lower than readState.Index' (duration: 288.26295ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:48:40.37376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.976337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:48:40.375335Z","caller":"traceutil/trace.go:171","msg":"trace[267868788] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:469; }","duration":"865.533736ms","start":"2026-02-18T22:48:39.509718Z","end":"2026-02-18T22:48:40.375252Z","steps":["trace[267868788] 'agreement among raft nodes before linearized reading' (duration: 855.896574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:40.375618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:39.509702Z","time spent":"865.898189ms","remote":"127.0.0.1:48298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T22:48:47.775838Z","caller":"traceutil/trace.go:171","msg":"trace[211324270] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"496.426784ms","start":"2026-02-18T22:48:47.279397Z","end":"2026-02-18T22:48:47.775823Z","steps":["trace[211324270] 'process raft request' (duration: 447.449176ms)","trace[211324270] 'compare' (duration: 48.849944ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:48:47.775929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:47.279391Z","time spent":"496.490016ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:48:47.776009Z","caller":"traceutil/trace.go:171","msg":"trace[653356593] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:525; }","duration":"298.063303ms","start":"2026-02-18T22:48:47.477921Z","end":"2026-02-18T22:48:47.775984Z","steps":["trace[653356593] 'read index received' (duration: 248.880328ms)","trace[653356593] 'applied index is now lower than readState.Index' (duration: 49.181825ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:48:47.776173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.248389ms","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-18T22:48:47.776192Z","caller":"traceutil/trace.go:171","msg":"trace[245784715] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:512; }","duration":"298.292631ms","start":"2026-02-18T22:48:47.477894Z","end":"2026-02-18T22:48:47.776186Z","steps":["trace[245784715] 'agreement among raft nodes before linearized reading' (duration: 298.211358ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:48:47.77626Z","caller":"traceutil/trace.go:171","msg":"trace[1190206550] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"495.152703ms","start":"2026-02-18T22:48:47.281103Z","end":"2026-02-18T22:48:47.776255Z","steps":["trace[1190206550] 'process raft request' (duration: 494.693068ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:47.776265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.803822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-18T22:48:47.776285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:47.281093Z","time spent":"495.177143ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:48:47.776304Z","caller":"traceutil/trace.go:171","msg":"trace[1294376696] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:512; }","duration":"259.871205ms","start":"2026-02-18T22:48:47.516422Z","end":"2026-02-18T22:48:47.776293Z","steps":["trace[1294376696] 'agreement among raft nodes before linearized reading' (duration: 259.796773ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:47.776441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.502715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-h64fs\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-18T22:48:47.776465Z","caller":"traceutil/trace.go:171","msg":"trace[941713366] range","detail":"{range_begin:/registry/pods/kube-system/cilium-h64fs; range_end:; response_count:1; response_revision:512; }","duration":"185.542405ms","start":"2026-02-18T22:48:47.590912Z","end":"2026-02-18T22:48:47.776454Z","steps":["trace[941713366] 'agreement among raft nodes before linearized reading' (duration: 185.507824ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:48:48.100587Z","caller":"traceutil/trace.go:171","msg":"trace[672836804] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"311.548545ms","start":"2026-02-18T22:48:47.789016Z","end":"2026-02-18T22:48:48.100564Z","steps":["trace[672836804] 'process raft request' (duration: 311.38644ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:48.100719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:47.789002Z","time spent":"311.650678ms","remote":"127.0.0.1:48496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:48:49.951379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:48.523045Z","time spent":"1.428328228s","remote":"127.0.0.1:42390","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-18T22:48:49.951623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.527914359s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:48:49.951658Z","caller":"traceutil/trace.go:171","msg":"trace[1220875308] linearizableReadLoop","detail":"{readStateIndex:529; appliedIndex:528; }","duration":"594.964049ms","start":"2026-02-18T22:48:49.356686Z","end":"2026-02-18T22:48:49.95165Z","steps":["trace[1220875308] 'read index received' (duration: 33.851µs)","trace[1220875308] 'applied index is now lower than readState.Index' (duration: 594.929558ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:48:49.951705Z","caller":"traceutil/trace.go:171","msg":"trace[1620664209] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"2.159782707s","start":"2026-02-18T22:48:47.791917Z","end":"2026-02-18T22:48:49.9517Z","steps":["trace[1620664209] 'process raft request' (duration: 631.745004ms)","trace[1620664209] 'compare' (duration: 1.527524517s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:48:49.951729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:47.791899Z","time spent":"2.159816918s","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:48:49.951878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.364971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-18T22:48:49.951834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.770901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:48:49.951893Z","caller":"traceutil/trace.go:171","msg":"trace[702477753] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:514; }","duration":"160.401481ms","start":"2026-02-18T22:48:49.791487Z","end":"2026-02-18T22:48:49.951888Z","steps":["trace[702477753] 'agreement among raft nodes before linearized reading' (duration: 160.35798ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:48:49.951915Z","caller":"traceutil/trace.go:171","msg":"trace[1218999239] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:514; }","duration":"444.881494ms","start":"2026-02-18T22:48:49.507017Z","end":"2026-02-18T22:48:49.951898Z","steps":["trace[1218999239] 'agreement among raft nodes before linearized reading' (duration: 444.75316ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:49.951957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.275859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-18T22:48:49.951954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:49.507004Z","time spent":"444.941926ms","remote":"127.0.0.1:48298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T22:48:49.951972Z","caller":"traceutil/trace.go:171","msg":"trace[1415147398] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:514; }","duration":"595.31519ms","start":"2026-02-18T22:48:49.356652Z","end":"2026-02-18T22:48:49.951967Z","steps":["trace[1415147398] 'agreement among raft nodes before linearized reading' (duration: 595.289449ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:49.951985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:49.356636Z","time spent":"595.346421ms","remote":"127.0.0.1:48562","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-18T22:48:49.952128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.487461ms","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-18T22:48:49.952143Z","caller":"traceutil/trace.go:171","msg":"trace[686344371] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:514; }","duration":"170.532223ms","start":"2026-02-18T22:48:49.781606Z","end":"2026-02-18T22:48:49.952138Z","steps":["trace[686344371] 'agreement among raft nodes before linearized reading' (duration: 170.488502ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:48:50.157635Z","caller":"traceutil/trace.go:171","msg":"trace[1582256451] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"197.823621ms","start":"2026-02-18T22:48:49.959788Z","end":"2026-02-18T22:48:50.157612Z","steps":["trace[1582256451] 'process raft request' (duration: 194.323902ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:48:50.158735Z","caller":"traceutil/trace.go:171","msg":"trace[1004253855] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:529; }","duration":"198.72896ms","start":"2026-02-18T22:48:49.959992Z","end":"2026-02-18T22:48:50.158721Z","steps":["trace[1004253855] 'read index received' (duration: 193.993092ms)","trace[1004253855] 'applied index is now lower than readState.Index' (duration: 4.735168ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:48:50.158818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.813283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:48:50.158835Z","caller":"traceutil/trace.go:171","msg":"trace[1019599468] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:517; }","duration":"198.854634ms","start":"2026-02-18T22:48:49.959975Z","end":"2026-02-18T22:48:50.15883Z","steps":["trace[1019599468] 'agreement among raft nodes before linearized reading' (duration: 198.797632ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:48:50.158987Z","caller":"traceutil/trace.go:171","msg":"trace[754293051] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"199.075941ms","start":"2026-02-18T22:48:49.959904Z","end":"2026-02-18T22:48:50.15898Z","steps":["trace[754293051] 'process raft request' (duration: 198.72699ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:48:50.159049Z","caller":"traceutil/trace.go:171","msg":"trace[1640514778] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"199.112842ms","start":"2026-02-18T22:48:49.959931Z","end":"2026-02-18T22:48:50.159044Z","steps":["trace[1640514778] 'process raft request' (duration: 198.767152ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:48:52.138356Z","caller":"traceutil/trace.go:171","msg":"trace[235643720] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"173.822216ms","start":"2026-02-18T22:48:51.964518Z","end":"2026-02-18T22:48:52.13834Z","steps":["trace[235643720] 'process raft request' (duration: 173.700832ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:48:52.280683Z","caller":"traceutil/trace.go:171","msg":"trace[1077830404] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"115.63706ms","start":"2026-02-18T22:48:52.165028Z","end":"2026-02-18T22:48:52.280665Z","steps":["trace[1077830404] 'process raft request' (duration: 113.590437ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:48:52.281019Z","caller":"traceutil/trace.go:171","msg":"trace[87588781] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:542; }","duration":"115.385203ms","start":"2026-02-18T22:48:52.165628Z","end":"2026-02-18T22:48:52.281013Z","steps":["trace[87588781] 'read index received' (duration: 113.00036ms)","trace[87588781] 'applied index is now lower than readState.Index' (duration: 2.384093ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:48:52.28113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.488936ms","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-18T22:48:52.281149Z","caller":"traceutil/trace.go:171","msg":"trace[1837348283] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:528; }","duration":"115.539248ms","start":"2026-02-18T22:48:52.165604Z","end":"2026-02-18T22:48:52.281143Z","steps":["trace[1837348283] 'agreement among raft nodes before linearized reading' (duration: 115.430234ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:48:52.281211Z","caller":"traceutil/trace.go:171","msg":"trace[1566533004] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"116.053933ms","start":"2026-02-18T22:48:52.1651Z","end":"2026-02-18T22:48:52.281154Z","steps":["trace[1566533004] 'process raft request' (duration: 115.880248ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:48:54.184764Z","caller":"traceutil/trace.go:171","msg":"trace[817912548] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"239.343765ms","start":"2026-02-18T22:48:53.945391Z","end":"2026-02-18T22:48:54.184734Z","steps":["trace[817912548] 'process raft request' (duration: 226.393817ms)","trace[817912548] 'compare' (duration: 12.521285ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:48:54.280736Z","caller":"traceutil/trace.go:171","msg":"trace[1183235475] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"332.896934ms","start":"2026-02-18T22:48:53.947805Z","end":"2026-02-18T22:48:54.280702Z","steps":["trace[1183235475] 'process raft request' (duration: 332.669017ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:54.280929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:53.947771Z","time spent":"333.063309ms","remote":"127.0.0.1:48366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":712,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T22:48:54.281631Z","caller":"traceutil/trace.go:171","msg":"trace[2027726016] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:583; }","duration":"137.61994ms","start":"2026-02-18T22:48:54.144002Z","end":"2026-02-18T22:48:54.281622Z","steps":["trace[2027726016] 'read index received' (duration: 27.797973ms)","trace[2027726016] 'applied index is now lower than readState.Index' (duration: 109.820967ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:48:54.281769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.758974ms","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-18T22:48:54.281789Z","caller":"traceutil/trace.go:171","msg":"trace[1899881204] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:571; }","duration":"137.832875ms","start":"2026-02-18T22:48:54.14395Z","end":"2026-02-18T22:48:54.281783Z","steps":["trace[1899881204] 'agreement among raft nodes before linearized reading' (duration: 137.703292ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:48:54.281805Z","caller":"traceutil/trace.go:171","msg":"trace[391430969] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"204.037137ms","start":"2026-02-18T22:48:54.07774Z","end":"2026-02-18T22:48:54.281777Z","steps":["trace[391430969] 'process raft request' (duration: 202.908873ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:48:54.28188Z","caller":"traceutil/trace.go:171","msg":"trace[105919443] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"184.497432ms","start":"2026-02-18T22:48:54.097377Z","end":"2026-02-18T22:48:54.281874Z","steps":["trace[105919443] 'process raft request' (duration: 184.09652ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:48:54.317045Z","caller":"traceutil/trace.go:171","msg":"trace[993233533] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"119.847127ms","start":"2026-02-18T22:48:54.197175Z","end":"2026-02-18T22:48:54.317022Z","steps":["trace[993233533] 'process raft request' (duration: 119.584309ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:55.347607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.004147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-02-18T22:48:55.347671Z","caller":"traceutil/trace.go:171","msg":"trace[793798667] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:584; }","duration":"254.09605ms","start":"2026-02-18T22:48:55.093559Z","end":"2026-02-18T22:48:55.347655Z","steps":["trace[793798667] 'range keys from in-memory index tree' (duration: 253.945275ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:48:56.709634Z","caller":"traceutil/trace.go:171","msg":"trace[1155412934] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"104.53275ms","start":"2026-02-18T22:48:56.605084Z","end":"2026-02-18T22:48:56.709617Z","steps":["trace[1155412934] 'process raft request' (duration: 63.903421ms)","trace[1155412934] 'compare' (duration: 40.126274ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:48:56.710142Z","caller":"traceutil/trace.go:171","msg":"trace[1580647580] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"100.417499ms","start":"2026-02-18T22:48:56.609673Z","end":"2026-02-18T22:48:56.710091Z","steps":["trace[1580647580] 'process raft request' (duration: 99.573684ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:56.925083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.89875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-18T22:48:56.925147Z","caller":"traceutil/trace.go:171","msg":"trace[1061949868] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:600; }","duration":"106.030993ms","start":"2026-02-18T22:48:56.819104Z","end":"2026-02-18T22:48:56.925135Z","steps":["trace[1061949868] 'range keys from in-memory index tree' (duration: 105.677123ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:48:57.684153Z","caller":"traceutil/trace.go:171","msg":"trace[1317033491] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"211.227624ms","start":"2026-02-18T22:48:57.472909Z","end":"2026-02-18T22:48:57.684137Z","steps":["trace[1317033491] 'process raft request' (duration: 200.061972ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:48:57.985205Z","caller":"traceutil/trace.go:171","msg":"trace[452122091] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"129.692425ms","start":"2026-02-18T22:48:57.855481Z","end":"2026-02-18T22:48:57.985174Z","steps":["trace[452122091] 'process raft request' (duration: 129.274983ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:57.985421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.772846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-02-18T22:48:57.985456Z","caller":"traceutil/trace.go:171","msg":"trace[489150932] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:619; }","duration":"120.866789ms","start":"2026-02-18T22:48:57.864582Z","end":"2026-02-18T22:48:57.985449Z","steps":["trace[489150932] 'agreement among raft nodes before linearized reading' (duration: 120.737795ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:48:57.985254Z","caller":"traceutil/trace.go:171","msg":"trace[853431872] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:634; }","duration":"120.610301ms","start":"2026-02-18T22:48:57.864619Z","end":"2026-02-18T22:48:57.985229Z","steps":["trace[853431872] 'read index received' (duration: 47.832845ms)","trace[853431872] 'applied index is now lower than readState.Index' (duration: 72.776056ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:48:57.985802Z","caller":"traceutil/trace.go:171","msg":"trace[973222876] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"100.748677ms","start":"2026-02-18T22:48:57.885047Z","end":"2026-02-18T22:48:57.985796Z","steps":["trace[973222876] 'process raft request' (duration: 100.108708ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:49:11.036652Z","caller":"traceutil/trace.go:171","msg":"trace[1609818036] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"106.310049ms","start":"2026-02-18T22:49:10.930303Z","end":"2026-02-18T22:49:11.036613Z","steps":["trace[1609818036] 'process raft request' (duration: 106.165125ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:50:09.118575Z","caller":"traceutil/trace.go:171","msg":"trace[961378748] linearizableReadLoop","detail":"{readStateIndex:955; appliedIndex:953; }","duration":"151.239993ms","start":"2026-02-18T22:50:08.967315Z","end":"2026-02-18T22:50:09.118555Z","steps":["trace[961378748] 'read index received' (duration: 9.063928ms)","trace[961378748] 'applied index is now lower than readState.Index' (duration: 142.175025ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:50:09.119088Z","caller":"traceutil/trace.go:171","msg":"trace[1207066904] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"214.210882ms","start":"2026-02-18T22:50:08.904859Z","end":"2026-02-18T22:50:09.11907Z","steps":["trace[1207066904] 'process raft request' (duration: 213.589533ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:50:09.119005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.670319ms","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-18T22:50:09.120222Z","caller":"traceutil/trace.go:171","msg":"trace[1000829661] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:921; }","duration":"152.891087ms","start":"2026-02-18T22:50:08.96731Z","end":"2026-02-18T22:50:09.120201Z","steps":["trace[1000829661] 'agreement among raft nodes before linearized reading' (duration: 151.471866ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:50:17.180506Z","caller":"traceutil/trace.go:171","msg":"trace[1485499591] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"148.483482ms","start":"2026-02-18T22:50:17.031998Z","end":"2026-02-18T22:50:17.180481Z","steps":["trace[1485499591] 'process raft request' (duration: 147.827943ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:50:17.309318Z","caller":"traceutil/trace.go:171","msg":"trace[1728914524] linearizableReadLoop","detail":"{readStateIndex:985; appliedIndex:984; }","duration":"123.488883ms","start":"2026-02-18T22:50:17.185804Z","end":"2026-02-18T22:50:17.309293Z","steps":["trace[1728914524] 'read index received' (duration: 72.654963ms)","trace[1728914524] 'applied index is now lower than readState.Index' (duration: 50.83291ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:50:17.310262Z","caller":"traceutil/trace.go:171","msg":"trace[1822701459] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"124.879003ms","start":"2026-02-18T22:50:17.185355Z","end":"2026-02-18T22:50:17.310234Z","steps":["trace[1822701459] 'process raft request' (duration: 73.155381ms)","trace[1822701459] 'compare' (duration: 50.304622ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:50:17.310414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.589689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-18T22:50:17.312194Z","caller":"traceutil/trace.go:171","msg":"trace[759886043] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:949; }","duration":"126.353524ms","start":"2026-02-18T22:50:17.185801Z","end":"2026-02-18T22:50:17.312154Z","steps":["trace[759886043] 'agreement among raft nodes before linearized reading' (duration: 124.500597ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:50:17.735228Z","caller":"traceutil/trace.go:171","msg":"trace[1868614790] linearizableReadLoop","detail":"{readStateIndex:986; appliedIndex:985; }","duration":"396.19524ms","start":"2026-02-18T22:50:17.339012Z","end":"2026-02-18T22:50:17.735207Z","steps":["trace[1868614790] 'read index received' (duration: 396.017267ms)","trace[1868614790] 'applied index is now lower than readState.Index' (duration: 177.033µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:50:17.735339Z","caller":"traceutil/trace.go:171","msg":"trace[415874546] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"418.384829ms","start":"2026-02-18T22:50:17.316943Z","end":"2026-02-18T22:50:17.735328Z","steps":["trace[415874546] 'process raft request' (duration: 418.136865ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:50:17.735574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:50:17.316925Z","time spent":"418.44102ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:50:17.735875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.859279ms","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-18T22:50:17.735917Z","caller":"traceutil/trace.go:171","msg":"trace[757938482] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:950; }","duration":"396.968061ms","start":"2026-02-18T22:50:17.338938Z","end":"2026-02-18T22:50:17.735906Z","steps":["trace[757938482] 'agreement among raft nodes before linearized reading' (duration: 396.814469ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:50:17.736205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:50:17.338878Z","time spent":"397.315086ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T22:50:17.736373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.235085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:50:17.736408Z","caller":"traceutil/trace.go:171","msg":"trace[1002532454] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:950; }","duration":"218.300335ms","start":"2026-02-18T22:50:17.518099Z","end":"2026-02-18T22:50:17.736399Z","steps":["trace[1002532454] 'agreement among raft nodes before linearized reading' (duration: 218.248354ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:50:17.736764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.821852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-18T22:50:17.736802Z","caller":"traceutil/trace.go:171","msg":"trace[971143904] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:950; }","duration":"172.908763ms","start":"2026-02-18T22:50:17.563884Z","end":"2026-02-18T22:50:17.736793Z","steps":["trace[971143904] 'agreement among raft nodes before linearized reading' (duration: 172.758161ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:50:17.736912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.739941ms","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-18T22:50:17.737039Z","caller":"traceutil/trace.go:171","msg":"trace[1231278763] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:950; }","duration":"195.892673ms","start":"2026-02-18T22:50:17.541136Z","end":"2026-02-18T22:50:17.737029Z","steps":["trace[1231278763] 'agreement among raft nodes before linearized reading' (duration: 195.752591ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:50:18.876327Z","caller":"traceutil/trace.go:171","msg":"trace[777183877] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"722.237468ms","start":"2026-02-18T22:50:18.154068Z","end":"2026-02-18T22:50:18.876306Z","steps":["trace[777183877] 'process raft request' (duration: 722.095625ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:50:18.876693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:50:18.154029Z","time spent":"722.39321ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:50:18.953967Z","caller":"traceutil/trace.go:171","msg":"trace[774918611] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"399.683045ms","start":"2026-02-18T22:50:18.554263Z","end":"2026-02-18T22:50:18.953946Z","steps":["trace[774918611] 'process raft request' (duration: 399.090776ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:50:18.954102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:50:18.554239Z","time spent":"399.795336ms","remote":"127.0.0.1:48492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5218,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}