{"level":"warn","ts":"2026-02-14T01:53:38.696339Z","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-14T01:53:38.696423Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.49: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.49:2380","--initial-cluster=instance=https://162.253.55.49:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.49:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.49: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-14T01:53:38.69656Z","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-14T01:53:38.696576Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.49:2380"]} {"level":"info","ts":"2026-02-14T01:53:38.6966Z","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-14T01:53:38.697757Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.49:2379"]} {"level":"info","ts":"2026-02-14T01:53:38.697872Z","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.49:2380"],"listen-peer-urls":["https://162.253.55.49:2380"],"advertise-client-urls":["https://162.253.55.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.49: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-14T01:53:38.702201Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.072188ms"} {"level":"info","ts":"2026-02-14T01:53:38.716554Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6a9ba61e55fef5be","cluster-id":"9612db6e19ac6ce7"} {"level":"info","ts":"2026-02-14T01:53:38.716597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=()"} {"level":"info","ts":"2026-02-14T01:53:38.716657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became follower at term 0"} {"level":"info","ts":"2026-02-14T01:53:38.716682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6a9ba61e55fef5be [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-14T01:53:38.716692Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became follower at term 1"} {"level":"info","ts":"2026-02-14T01:53:38.716745Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=(7681916238632515006)"} {"level":"warn","ts":"2026-02-14T01:53:38.72252Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-14T01:53:38.723922Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-14T01:53:38.725029Z","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-14T01:53:38.72772Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6a9ba61e55fef5be","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-14T01:53:38.732244Z","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-14T01:53:38.732292Z","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-14T01:53:38.732303Z","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-14T01:53:38.732685Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6a9ba61e55fef5be","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-14T01:53:38.733414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=(7681916238632515006)"} {"level":"info","ts":"2026-02-14T01:53:38.733567Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9612db6e19ac6ce7","local-member-id":"6a9ba61e55fef5be","added-peer-id":"6a9ba61e55fef5be","added-peer-peer-urls":["https://162.253.55.49:2380"]} {"level":"info","ts":"2026-02-14T01:53:38.733903Z","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-14T01:53:38.734039Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.49:2380"} {"level":"info","ts":"2026-02-14T01:53:38.734057Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.49:2380"} {"level":"info","ts":"2026-02-14T01:53:38.735182Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6a9ba61e55fef5be","initial-advertise-peer-urls":["https://162.253.55.49:2380"],"listen-peer-urls":["https://162.253.55.49:2380"],"advertise-client-urls":["https://162.253.55.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-14T01:53:38.735216Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-14T01:53:39.719003Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be is starting a new election at term 1"} {"level":"info","ts":"2026-02-14T01:53:39.719051Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became pre-candidate at term 1"} {"level":"info","ts":"2026-02-14T01:53:39.71908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be received MsgPreVoteResp from 6a9ba61e55fef5be at term 1"} {"level":"info","ts":"2026-02-14T01:53:39.71909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became candidate at term 2"} {"level":"info","ts":"2026-02-14T01:53:39.719096Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be received MsgVoteResp from 6a9ba61e55fef5be at term 2"} {"level":"info","ts":"2026-02-14T01:53:39.719104Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became leader at term 2"} {"level":"info","ts":"2026-02-14T01:53:39.719111Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6a9ba61e55fef5be elected leader 6a9ba61e55fef5be at term 2"} {"level":"info","ts":"2026-02-14T01:53:39.72508Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6a9ba61e55fef5be","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.49:2379]}","request-path":"/0/members/6a9ba61e55fef5be/attributes","cluster-id":"9612db6e19ac6ce7","publish-timeout":"7s"} {"level":"info","ts":"2026-02-14T01:53:39.725106Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-14T01:53:39.725363Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-14T01:53:39.725426Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-14T01:53:39.726857Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-14T01:53:39.726993Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-14T01:53:39.727005Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-14T01:53:39.733535Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.49:2379"} {"level":"info","ts":"2026-02-14T01:53:39.733588Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9612db6e19ac6ce7","local-member-id":"6a9ba61e55fef5be","cluster-version":"3.5"} {"level":"info","ts":"2026-02-14T01:53:39.733624Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-14T01:53:39.733638Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-14T01:53:43.857243Z","caller":"traceutil/trace.go:171","msg":"trace[2014702334] transaction","detail":"{read_only:false; response_revision:225; number_of_response:1; }","duration":"160.66865ms","start":"2026-02-14T01:53:43.696545Z","end":"2026-02-14T01:53:43.857214Z","steps":["trace[2014702334] 'process raft request' (duration: 96.47539ms)","trace[2014702334] 'compare' (duration: 64.071827ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T01:53:45.165598Z","caller":"traceutil/trace.go:171","msg":"trace[1221594028] transaction","detail":"{read_only:false; response_revision:262; number_of_response:1; }","duration":"183.646101ms","start":"2026-02-14T01:53:44.981745Z","end":"2026-02-14T01:53:45.165391Z","steps":["trace[1221594028] 'process raft request' (duration: 183.562119ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:53:45.165659Z","caller":"traceutil/trace.go:171","msg":"trace[993117814] transaction","detail":"{read_only:false; response_revision:261; number_of_response:1; }","duration":"269.137624ms","start":"2026-02-14T01:53:44.896495Z","end":"2026-02-14T01:53:45.165633Z","steps":["trace[993117814] 'process raft request' (duration: 223.407078ms)","trace[993117814] 'compare' (duration: 45.04058ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T01:53:51.442387Z","caller":"traceutil/trace.go:171","msg":"trace[40560202] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"100.73698ms","start":"2026-02-14T01:53:51.341628Z","end":"2026-02-14T01:53:51.442365Z","steps":["trace[40560202] 'process raft request' (duration: 25.153932ms)","trace[40560202] 'compare' (duration: 75.21366ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:54:00.640381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"813.80735ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T01:54:00.640485Z","caller":"traceutil/trace.go:171","msg":"trace[2080990776] linearizableReadLoop","detail":"{readStateIndex:431; appliedIndex:430; }","duration":"1.070620245s","start":"2026-02-14T01:53:59.569855Z","end":"2026-02-14T01:54:00.640475Z","steps":["trace[2080990776] 'read index received' (duration: 256.342354ms)","trace[2080990776] 'applied index is now lower than readState.Index' (duration: 814.276911ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T01:54:00.640537Z","caller":"traceutil/trace.go:171","msg":"trace[640452107] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"1.080748773s","start":"2026-02-14T01:53:59.559781Z","end":"2026-02-14T01:54:00.64053Z","steps":["trace[640452107] 'process raft request' (duration: 266.463262ms)","trace[640452107] 'compare' (duration: 813.707018ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:54:00.640571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:53:59.559757Z","time spent":"1.080798114s","remote":"127.0.0.1:40396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T01:54:00.64063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.069470988s","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-14T01:54:00.640683Z","caller":"traceutil/trace.go:171","msg":"trace[1007531686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:416; }","duration":"1.06955407s","start":"2026-02-14T01:53:59.571115Z","end":"2026-02-14T01:54:00.640669Z","steps":["trace[1007531686] 'agreement among raft nodes before linearized reading' (duration: 1.069451077s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:00.640711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:53:59.571107Z","time spent":"1.06959769s","remote":"127.0.0.1:40396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-14T01:54:00.64076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.070903532s","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-14T01:54:00.640778Z","caller":"traceutil/trace.go:171","msg":"trace[1789436141] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:416; }","duration":"1.070943333s","start":"2026-02-14T01:53:59.569829Z","end":"2026-02-14T01:54:00.640772Z","steps":["trace[1789436141] 'agreement among raft nodes before linearized reading' (duration: 1.070900982s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:00.640793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:53:59.569821Z","time spent":"1.070969083s","remote":"127.0.0.1:40396","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-14T01:54:00.640837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.355286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T01:54:00.640856Z","caller":"traceutil/trace.go:171","msg":"trace[506729482] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:416; }","duration":"257.395728ms","start":"2026-02-14T01:54:00.383454Z","end":"2026-02-14T01:54:00.64085Z","steps":["trace[506729482] 'agreement among raft nodes before linearized reading' (duration: 257.367667ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:54:00.815141Z","caller":"traceutil/trace.go:171","msg":"trace[657875855] linearizableReadLoop","detail":"{readStateIndex:432; appliedIndex:431; }","duration":"170.185283ms","start":"2026-02-14T01:54:00.644939Z","end":"2026-02-14T01:54:00.815124Z","steps":["trace[657875855] 'read index received' (duration: 170.122522ms)","trace[657875855] 'applied index is now lower than readState.Index' (duration: 61.881µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T01:54:00.815249Z","caller":"traceutil/trace.go:171","msg":"trace[762075559] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"170.311407ms","start":"2026-02-14T01:54:00.644921Z","end":"2026-02-14T01:54:00.815233Z","steps":["trace[762075559] 'process raft request' (duration: 170.04091ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:00.815323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.369467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T01:54:00.8156Z","caller":"traceutil/trace.go:171","msg":"trace[1809144909] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:417; }","duration":"170.669555ms","start":"2026-02-14T01:54:00.644915Z","end":"2026-02-14T01:54:00.815584Z","steps":["trace[1809144909] 'agreement among raft nodes before linearized reading' (duration: 170.379638ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:54:00.816527Z","caller":"traceutil/trace.go:171","msg":"trace[1922874351] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"171.508345ms","start":"2026-02-14T01:54:00.645004Z","end":"2026-02-14T01:54:00.816513Z","steps":["trace[1922874351] 'process raft request' (duration: 171.411602ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:11.383355Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17707762694599450646,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-14T01:54:11.849402Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.053898239s","expected-duration":"1s"} {"level":"info","ts":"2026-02-14T01:54:11.849715Z","caller":"traceutil/trace.go:171","msg":"trace[1231302369] linearizableReadLoop","detail":"{readStateIndex:492; appliedIndex:491; }","duration":"966.999524ms","start":"2026-02-14T01:54:10.882695Z","end":"2026-02-14T01:54:11.849694Z","steps":["trace[1231302369] 'read index received' (duration: 966.83369ms)","trace[1231302369] 'applied index is now lower than readState.Index' (duration: 164.974µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T01:54:11.849961Z","caller":"traceutil/trace.go:171","msg":"trace[1470330305] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"1.054509274s","start":"2026-02-14T01:54:10.79544Z","end":"2026-02-14T01:54:11.84995Z","steps":["trace[1470330305] 'process raft request' (duration: 1.054139165s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:11.85005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:10.795431Z","time spent":"1.054558795s","remote":"127.0.0.1:40288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4210,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T01:54:11.850311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"967.611548ms","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-14T01:54:11.850345Z","caller":"traceutil/trace.go:171","msg":"trace[811411004] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:475; }","duration":"967.641569ms","start":"2026-02-14T01:54:10.882691Z","end":"2026-02-14T01:54:11.850332Z","steps":["trace[811411004] 'agreement among raft nodes before linearized reading' (duration: 967.536377ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:11.850372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:10.882649Z","time spent":"967.717311ms","remote":"127.0.0.1:40396","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-14T01:54:11.850534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"935.882983ms","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-14T01:54:11.850561Z","caller":"traceutil/trace.go:171","msg":"trace[1019103893] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:475; }","duration":"935.910393ms","start":"2026-02-14T01:54:10.914643Z","end":"2026-02-14T01:54:11.850553Z","steps":["trace[1019103893] 'agreement among raft nodes before linearized reading' (duration: 935.841612ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:11.850585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:10.914603Z","time spent":"935.976855ms","remote":"127.0.0.1:40396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-14T01:54:11.850802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.456019ms","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-14T01:54:11.850833Z","caller":"traceutil/trace.go:171","msg":"trace[2109813933] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:475; }","duration":"246.511861ms","start":"2026-02-14T01:54:11.604313Z","end":"2026-02-14T01:54:11.850825Z","steps":["trace[2109813933] 'agreement among raft nodes before linearized reading' (duration: 246.460949ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:11.850953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.462772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T01:54:11.850979Z","caller":"traceutil/trace.go:171","msg":"trace[694896962] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:475; }","duration":"465.562244ms","start":"2026-02-14T01:54:11.385407Z","end":"2026-02-14T01:54:11.850969Z","steps":["trace[694896962] 'agreement among raft nodes before linearized reading' (duration: 465.524133ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:11.851049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:11.385392Z","time spent":"465.603685ms","remote":"127.0.0.1:43428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-14T01:54:14.383119Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17707762694599450666,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-14T01:54:14.565375Z","caller":"traceutil/trace.go:171","msg":"trace[1592504646] linearizableReadLoop","detail":"{readStateIndex:500; appliedIndex:499; }","duration":"682.787776ms","start":"2026-02-14T01:54:13.882569Z","end":"2026-02-14T01:54:14.565356Z","steps":["trace[1592504646] 'read index received' (duration: 682.655353ms)","trace[1592504646] 'applied index is now lower than readState.Index' (duration: 131.773µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T01:54:14.565614Z","caller":"traceutil/trace.go:171","msg":"trace[2014857380] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"693.188351ms","start":"2026-02-14T01:54:13.872418Z","end":"2026-02-14T01:54:14.565607Z","steps":["trace[2014857380] 'process raft request' (duration: 692.851433ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:14.565691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:13.872409Z","time spent":"693.228592ms","remote":"127.0.0.1:40396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T01:54:14.565849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.276148ms","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-14T01:54:14.565869Z","caller":"traceutil/trace.go:171","msg":"trace[1207172767] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:482; }","duration":"683.313029ms","start":"2026-02-14T01:54:13.88255Z","end":"2026-02-14T01:54:14.565863Z","steps":["trace[1207172767] 'agreement among raft nodes before linearized reading' (duration: 683.239278ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:14.565888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:13.882542Z","time spent":"683.34198ms","remote":"127.0.0.1:40396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-14T01:54:14.565991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.9499ms","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-14T01:54:14.566007Z","caller":"traceutil/trace.go:171","msg":"trace[1831919396] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:482; }","duration":"682.974481ms","start":"2026-02-14T01:54:13.883028Z","end":"2026-02-14T01:54:14.566002Z","steps":["trace[1831919396] 'agreement among raft nodes before linearized reading' (duration: 682.926569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:14.566022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:13.883023Z","time spent":"682.995612ms","remote":"127.0.0.1:40396","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-14T01:54:15.181722Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17707762694599450669,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-14T01:54:15.448503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.215963ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T01:54:15.448564Z","caller":"traceutil/trace.go:171","msg":"trace[644711452] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"878.378288ms","start":"2026-02-14T01:54:14.570174Z","end":"2026-02-14T01:54:15.448553Z","steps":["trace[644711452] 'process raft request' (duration: 754.068093ms)","trace[644711452] 'compare' (duration: 123.936966ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:54:15.448592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:14.570163Z","time spent":"878.417188ms","remote":"127.0.0.1:40396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T01:54:15.45578Z","caller":"traceutil/trace.go:171","msg":"trace[1470380602] linearizableReadLoop","detail":"{readStateIndex:502; appliedIndex:500; }","duration":"774.74686ms","start":"2026-02-14T01:54:14.681024Z","end":"2026-02-14T01:54:15.455771Z","steps":["trace[1470380602] 'read index received' (duration: 643.225205ms)","trace[1470380602] 'applied index is now lower than readState.Index' (duration: 131.520925ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T01:54:15.455842Z","caller":"traceutil/trace.go:171","msg":"trace[315071609] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"885.563137ms","start":"2026-02-14T01:54:14.570273Z","end":"2026-02-14T01:54:15.455836Z","steps":["trace[315071609] 'process raft request' (duration: 885.416054ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:15.455881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:14.570269Z","time spent":"885.586128ms","remote":"127.0.0.1:40396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T01:54:15.455939Z","caller":"traceutil/trace.go:171","msg":"trace[1901507633] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"218.955982ms","start":"2026-02-14T01:54:15.236978Z","end":"2026-02-14T01:54:15.455934Z","steps":["trace[1901507633] 'process raft request' (duration: 218.767988ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:15.456136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.118408ms","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-14T01:54:15.456156Z","caller":"traceutil/trace.go:171","msg":"trace[1674547124] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:485; }","duration":"775.174599ms","start":"2026-02-14T01:54:14.680975Z","end":"2026-02-14T01:54:15.45615Z","steps":["trace[1674547124] 'agreement among raft nodes before linearized reading' (duration: 775.135769ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:15.456175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:14.680956Z","time spent":"775.21434ms","remote":"127.0.0.1:40396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-14T01:54:17.901529Z","caller":"traceutil/trace.go:171","msg":"trace[1935582429] linearizableReadLoop","detail":"{readStateIndex:506; appliedIndex:505; }","duration":"440.206069ms","start":"2026-02-14T01:54:17.461301Z","end":"2026-02-14T01:54:17.901507Z","steps":["trace[1935582429] 'read index received' (duration: 440.177628ms)","trace[1935582429] 'applied index is now lower than readState.Index' (duration: 27.541µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T01:54:17.901636Z","caller":"traceutil/trace.go:171","msg":"trace[431837965] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"444.921379ms","start":"2026-02-14T01:54:17.456705Z","end":"2026-02-14T01:54:17.901627Z","steps":["trace[431837965] 'process raft request' (duration: 444.702654ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:17.901726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:17.45669Z","time spent":"444.969801ms","remote":"127.0.0.1:40396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T01:54:17.901793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.468585ms","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-14T01:54:17.901862Z","caller":"traceutil/trace.go:171","msg":"trace[1683581137] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:488; }","duration":"440.559247ms","start":"2026-02-14T01:54:17.461284Z","end":"2026-02-14T01:54:17.901843Z","steps":["trace[1683581137] 'agreement among raft nodes before linearized reading' (duration: 440.443855ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:17.901902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:17.461277Z","time spent":"440.617399ms","remote":"127.0.0.1:40396","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-14T01:54:17.909017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.483419ms","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-14T01:54:17.909049Z","caller":"traceutil/trace.go:171","msg":"trace[1206257383] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:488; }","duration":"444.5296ms","start":"2026-02-14T01:54:17.464511Z","end":"2026-02-14T01:54:17.909041Z","steps":["trace[1206257383] 'agreement among raft nodes before linearized reading' (duration: 444.475519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:17.909078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:17.464506Z","time spent":"444.567761ms","remote":"127.0.0.1:40396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-14T01:54:19.993178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.486025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-kmdd9\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-14T01:54:19.993269Z","caller":"traceutil/trace.go:171","msg":"trace[865862838] range","detail":"{range_begin:/registry/pods/kube-system/cilium-kmdd9; range_end:; response_count:1; response_revision:494; }","duration":"239.598648ms","start":"2026-02-14T01:54:19.753653Z","end":"2026-02-14T01:54:19.993251Z","steps":["trace[865862838] 'range keys from in-memory index tree' (duration: 238.478042ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:19.993607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.347767ms","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-14T01:54:19.993647Z","caller":"traceutil/trace.go:171","msg":"trace[130447218] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:494; }","duration":"504.408009ms","start":"2026-02-14T01:54:19.489227Z","end":"2026-02-14T01:54:19.993635Z","steps":["trace[130447218] 'range keys from in-memory index tree' (duration: 504.290166ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:19.993677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:19.489207Z","time spent":"504.4612ms","remote":"127.0.0.1:40234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-02-14T01:54:20.324775Z","caller":"traceutil/trace.go:171","msg":"trace[2066484016] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"323.85031ms","start":"2026-02-14T01:54:20.0009Z","end":"2026-02-14T01:54:20.324751Z","steps":["trace[2066484016] 'process raft request' (duration: 323.693386ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:20.324946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:20.000889Z","time spent":"323.974883ms","remote":"127.0.0.1:40396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T01:54:20.598369Z","caller":"traceutil/trace.go:171","msg":"trace[615736418] linearizableReadLoop","detail":"{readStateIndex:517; appliedIndex:514; }","duration":"213.721439ms","start":"2026-02-14T01:54:20.384632Z","end":"2026-02-14T01:54:20.598353Z","steps":["trace[615736418] 'read index received' (duration: 213.515014ms)","trace[615736418] 'applied index is now lower than readState.Index' (duration: 205.925µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T01:54:20.598587Z","caller":"traceutil/trace.go:171","msg":"trace[1697220282] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"597.429767ms","start":"2026-02-14T01:54:20.00115Z","end":"2026-02-14T01:54:20.59858Z","steps":["trace[1697220282] 'process raft request' (duration: 597.039588ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:20.598672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:20.001142Z","time spent":"597.460178ms","remote":"127.0.0.1:40396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T01:54:20.598765Z","caller":"traceutil/trace.go:171","msg":"trace[1026601360] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"597.567811ms","start":"2026-02-14T01:54:20.001192Z","end":"2026-02-14T01:54:20.59876Z","steps":["trace[1026601360] 'process raft request' (duration: 597.080799ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:20.598788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:20.001187Z","time spent":"597.587741ms","remote":"127.0.0.1:40396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T01:54:20.598898Z","caller":"traceutil/trace.go:171","msg":"trace[1410908793] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"591.723804ms","start":"2026-02-14T01:54:20.00717Z","end":"2026-02-14T01:54:20.598894Z","steps":["trace[1410908793] 'process raft request' (duration: 591.15497ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:20.598921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:20.007164Z","time spent":"591.744894ms","remote":"127.0.0.1:40294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T01:54:20.598984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.351794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T01:54:20.598999Z","caller":"traceutil/trace.go:171","msg":"trace[1809327586] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:498; }","duration":"214.402995ms","start":"2026-02-14T01:54:20.384591Z","end":"2026-02-14T01:54:20.598994Z","steps":["trace[1809327586] 'agreement among raft nodes before linearized reading' (duration: 214.377405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:27.07931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.648452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T01:54:27.079639Z","caller":"traceutil/trace.go:171","msg":"trace[1471116805] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:587; }","duration":"102.026771ms","start":"2026-02-14T01:54:26.977591Z","end":"2026-02-14T01:54:27.079618Z","steps":["trace[1471116805] 'agreement among raft nodes before linearized reading' (duration: 101.618141ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:54:27.080051Z","caller":"traceutil/trace.go:171","msg":"trace[1018826587] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"102.233256ms","start":"2026-02-14T01:54:26.977801Z","end":"2026-02-14T01:54:27.080034Z","steps":["trace[1018826587] 'process raft request' (duration: 100.791192ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:27.080362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.702976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T01:54:27.080484Z","caller":"traceutil/trace.go:171","msg":"trace[435302459] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:587; }","duration":"102.865221ms","start":"2026-02-14T01:54:26.977606Z","end":"2026-02-14T01:54:27.080471Z","steps":["trace[435302459] 'agreement among raft nodes before linearized reading' (duration: 102.698887ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:54:27.309866Z","caller":"traceutil/trace.go:171","msg":"trace[1927367586] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"185.72663ms","start":"2026-02-14T01:54:27.124118Z","end":"2026-02-14T01:54:27.309845Z","steps":["trace[1927367586] 'process raft request' (duration: 152.58364ms)","trace[1927367586] 'compare' (duration: 32.925635ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T01:54:27.310708Z","caller":"traceutil/trace.go:171","msg":"trace[1629513515] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"186.500099ms","start":"2026-02-14T01:54:27.124176Z","end":"2026-02-14T01:54:27.310676Z","steps":["trace[1629513515] 'process raft request' (duration: 185.607588ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:54:27.312484Z","caller":"traceutil/trace.go:171","msg":"trace[850432781] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:610; }","duration":"160.353923ms","start":"2026-02-14T01:54:27.152085Z","end":"2026-02-14T01:54:27.312439Z","steps":["trace[850432781] 'read index received' (duration: 124.623752ms)","trace[850432781] 'applied index is now lower than readState.Index' (duration: 35.729491ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:54:27.314689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.439962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-dgw2k\" ","response":"range_response_count:1 size:3813"} {"level":"info","ts":"2026-02-14T01:54:27.315283Z","caller":"traceutil/trace.go:171","msg":"trace[112006751] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-dgw2k; range_end:; response_count:1; response_revision:593; }","duration":"163.212551ms","start":"2026-02-14T01:54:27.152061Z","end":"2026-02-14T01:54:27.315273Z","steps":["trace[112006751] 'agreement among raft nodes before linearized reading' (duration: 162.34013ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:54:27.314945Z","caller":"traceutil/trace.go:171","msg":"trace[1294807003] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"162.995376ms","start":"2026-02-14T01:54:27.151938Z","end":"2026-02-14T01:54:27.314934Z","steps":["trace[1294807003] 'process raft request' (duration: 158.695485ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:54:27.314969Z","caller":"traceutil/trace.go:171","msg":"trace[1286405903] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"126.239511ms","start":"2026-02-14T01:54:27.188724Z","end":"2026-02-14T01:54:27.314964Z","steps":["trace[1286405903] 'process raft request' (duration: 123.68284ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:54:27.654882Z","caller":"traceutil/trace.go:171","msg":"trace[522873090] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:618; }","duration":"136.655206ms","start":"2026-02-14T01:54:27.518202Z","end":"2026-02-14T01:54:27.654857Z","steps":["trace[522873090] 'read index received' (duration: 136.432371ms)","trace[522873090] 'applied index is now lower than readState.Index' (duration: 221.745µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T01:54:27.655045Z","caller":"traceutil/trace.go:171","msg":"trace[464659229] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"156.212826ms","start":"2026-02-14T01:54:27.49882Z","end":"2026-02-14T01:54:27.655033Z","steps":["trace[464659229] 'process raft request' (duration: 155.841537ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:27.655185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.520953ms","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-14T01:54:27.655257Z","caller":"traceutil/trace.go:171","msg":"trace[335043132] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:598; }","duration":"136.630505ms","start":"2026-02-14T01:54:27.518607Z","end":"2026-02-14T01:54:27.655237Z","steps":["trace[335043132] 'agreement among raft nodes before linearized reading' (duration: 136.481421ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:27.655452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.24443ms","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-14T01:54:27.655479Z","caller":"traceutil/trace.go:171","msg":"trace[1397431662] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:598; }","duration":"137.297821ms","start":"2026-02-14T01:54:27.518173Z","end":"2026-02-14T01:54:27.655471Z","steps":["trace[1397431662] 'agreement among raft nodes before linearized reading' (duration: 137.24925ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:54:28.45746Z","caller":"traceutil/trace.go:171","msg":"trace[189980849] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"101.420526ms","start":"2026-02-14T01:54:28.356008Z","end":"2026-02-14T01:54:28.457428Z","steps":["trace[189980849] 'process raft request' (duration: 101.315443ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:54:28.478182Z","caller":"traceutil/trace.go:171","msg":"trace[581472531] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:621; }","duration":"110.394538ms","start":"2026-02-14T01:54:28.367773Z","end":"2026-02-14T01:54:28.478167Z","steps":["trace[581472531] 'read index received' (duration: 89.576098ms)","trace[581472531] 'applied index is now lower than readState.Index' (duration: 20.81788ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T01:54:28.478255Z","caller":"traceutil/trace.go:171","msg":"trace[2053219338] transaction","detail":"{read_only:false; number_of_response:0; response_revision:600; }","duration":"119.737347ms","start":"2026-02-14T01:54:28.358513Z","end":"2026-02-14T01:54:28.47825Z","steps":["trace[2053219338] 'process raft request' (duration: 119.599184ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:28.478513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.723646ms","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-14T01:54:28.478532Z","caller":"traceutil/trace.go:171","msg":"trace[1120664944] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:600; }","duration":"110.767566ms","start":"2026-02-14T01:54:28.367758Z","end":"2026-02-14T01:54:28.478525Z","steps":["trace[1120664944] 'agreement among raft nodes before linearized reading' (duration: 110.636683ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:54:39.023985Z","caller":"traceutil/trace.go:171","msg":"trace[143473344] linearizableReadLoop","detail":"{readStateIndex:702; appliedIndex:700; }","duration":"140.334332ms","start":"2026-02-14T01:54:38.883635Z","end":"2026-02-14T01:54:39.02397Z","steps":["trace[143473344] 'read index received' (duration: 27.447016ms)","trace[143473344] 'applied index is now lower than readState.Index' (duration: 112.886766ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:54:39.024287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.630779ms","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-14T01:54:39.02436Z","caller":"traceutil/trace.go:171","msg":"trace[887686308] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:677; }","duration":"140.741572ms","start":"2026-02-14T01:54:38.883612Z","end":"2026-02-14T01:54:39.024353Z","steps":["trace[887686308] 'agreement among raft nodes before linearized reading' (duration: 140.592299ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:54:39.024131Z","caller":"traceutil/trace.go:171","msg":"trace[2046894030] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"175.49987ms","start":"2026-02-14T01:54:38.848623Z","end":"2026-02-14T01:54:39.024123Z","steps":["trace[2046894030] 'process raft request' (duration: 106.67412ms)","trace[2046894030] 'compare' (duration: 68.591824ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:54:39.247005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.499868ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T01:54:39.247065Z","caller":"traceutil/trace.go:171","msg":"trace[1284674784] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"204.008971ms","start":"2026-02-14T01:54:39.043045Z","end":"2026-02-14T01:54:39.247054Z","steps":["trace[1284674784] 'process raft request' (duration: 88.39865ms)","trace[1284674784] 'compare' (duration: 115.398526ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:54:41.221689Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17707762694599451050,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-14T01:54:41.600122Z","caller":"traceutil/trace.go:171","msg":"trace[1464836672] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:704; }","duration":"878.588475ms","start":"2026-02-14T01:54:40.721512Z","end":"2026-02-14T01:54:41.6001Z","steps":["trace[1464836672] 'read index received' (duration: 878.457082ms)","trace[1464836672] 'applied index is now lower than readState.Index' (duration: 130.873µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T01:54:41.600274Z","caller":"traceutil/trace.go:171","msg":"trace[1810887388] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"918.488774ms","start":"2026-02-14T01:54:40.681779Z","end":"2026-02-14T01:54:41.600267Z","steps":["trace[1810887388] 'process raft request' (duration: 918.227078ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:41.600334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:40.681766Z","time spent":"918.522744ms","remote":"127.0.0.1:40396","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-14T01:54:41.600469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.954073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T01:54:41.600487Z","caller":"traceutil/trace.go:171","msg":"trace[867486112] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:680; }","duration":"878.982934ms","start":"2026-02-14T01:54:40.721498Z","end":"2026-02-14T01:54:41.600481Z","steps":["trace[867486112] 'agreement among raft nodes before linearized reading' (duration: 878.953733ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:41.600502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:40.721493Z","time spent":"879.004354ms","remote":"127.0.0.1:40482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-14T01:54:41.600617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.71304ms","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-14T01:54:41.600629Z","caller":"traceutil/trace.go:171","msg":"trace[1422182242] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:680; }","duration":"686.75338ms","start":"2026-02-14T01:54:40.913872Z","end":"2026-02-14T01:54:41.600625Z","steps":["trace[1422182242] 'agreement among raft nodes before linearized reading' (duration: 686.694599ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:41.60064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:40.913858Z","time spent":"686.779552ms","remote":"127.0.0.1:40396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-14T01:54:41.600824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.709329ms","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-14T01:54:41.600842Z","caller":"traceutil/trace.go:171","msg":"trace[1972386986] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:680; }","duration":"513.736649ms","start":"2026-02-14T01:54:41.087101Z","end":"2026-02-14T01:54:41.600838Z","steps":["trace[1972386986] 'agreement among raft nodes before linearized reading' (duration: 513.691978ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:41.600854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:41.087096Z","time spent":"513.75517ms","remote":"127.0.0.1:40396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-14T01:54:41.600902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.873931ms","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-14T01:54:41.600912Z","caller":"traceutil/trace.go:171","msg":"trace[1033840030] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:680; }","duration":"351.894801ms","start":"2026-02-14T01:54:41.249014Z","end":"2026-02-14T01:54:41.600909Z","steps":["trace[1033840030] 'agreement among raft nodes before linearized reading' (duration: 351.86479ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:41.600922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:41.249008Z","time spent":"351.911861ms","remote":"127.0.0.1:40396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-14T01:54:41.601017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.599587ms","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-14T01:54:41.601028Z","caller":"traceutil/trace.go:171","msg":"trace[1809943235] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:680; }","duration":"553.636889ms","start":"2026-02-14T01:54:41.047388Z","end":"2026-02-14T01:54:41.601025Z","steps":["trace[1809943235] 'agreement among raft nodes before linearized reading' (duration: 553.602018ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:41.601039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:41.047375Z","time spent":"553.661929ms","remote":"127.0.0.1:40396","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-14T01:54:43.161505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.74615ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T01:54:43.161581Z","caller":"traceutil/trace.go:171","msg":"trace[1453495495] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"1.556056765s","start":"2026-02-14T01:54:41.60551Z","end":"2026-02-14T01:54:43.161567Z","steps":["trace[1453495495] 'process raft request' (duration: 948.196052ms)","trace[1453495495] 'compare' (duration: 607.581787ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:54:43.161624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:41.605499Z","time spent":"1.556109776s","remote":"127.0.0.1:40396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T01:54:43.517499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.110747ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T01:54:43.517768Z","caller":"traceutil/trace.go:171","msg":"trace[1333326627] linearizableReadLoop","detail":"{readStateIndex:709; appliedIndex:705; }","duration":"1.187612876s","start":"2026-02-14T01:54:42.330134Z","end":"2026-02-14T01:54:43.517747Z","steps":["trace[1333326627] 'read index received' (duration: 223.52977ms)","trace[1333326627] 'applied index is now lower than readState.Index' (duration: 964.082206ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T01:54:43.517994Z","caller":"traceutil/trace.go:171","msg":"trace[929908593] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"1.094428653s","start":"2026-02-14T01:54:42.423511Z","end":"2026-02-14T01:54:43.51794Z","steps":["trace[929908593] 'process raft request' (duration: 1.094192358s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:43.518079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.187936863s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-14T01:54:43.518115Z","caller":"traceutil/trace.go:171","msg":"trace[1397311233] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:685; }","duration":"1.188001145s","start":"2026-02-14T01:54:42.330103Z","end":"2026-02-14T01:54:43.518104Z","steps":["trace[1397311233] 'agreement among raft nodes before linearized reading' (duration: 1.187932024s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:43.518152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:42.330084Z","time spent":"1.188058326s","remote":"127.0.0.1:40458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":13,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-02-14T01:54:43.518187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:42.423498Z","time spent":"1.094578677s","remote":"127.0.0.1:40396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T01:54:43.518346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.12871964s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T01:54:43.518376Z","caller":"traceutil/trace.go:171","msg":"trace[235667751] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:685; }","duration":"1.128770831s","start":"2026-02-14T01:54:42.389595Z","end":"2026-02-14T01:54:43.518365Z","steps":["trace[235667751] 'agreement among raft nodes before linearized reading' (duration: 1.12872903s)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:54:43.518407Z","caller":"traceutil/trace.go:171","msg":"trace[1294595760] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"1.912180475s","start":"2026-02-14T01:54:41.60622Z","end":"2026-02-14T01:54:43.518401Z","steps":["trace[1294595760] 'process raft request' (duration: 1.911370356s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:43.518449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:41.606187Z","time spent":"1.912243277s","remote":"127.0.0.1:40396","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-14T01:54:43.518561Z","caller":"traceutil/trace.go:171","msg":"trace[385121067] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"1.911648783s","start":"2026-02-14T01:54:41.606903Z","end":"2026-02-14T01:54:43.518552Z","steps":["trace[385121067] 'process raft request' (duration: 1.910770222s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:43.518595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:41.606897Z","time spent":"1.911675653s","remote":"127.0.0.1:40396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T01:54:43.518596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.064024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-14T01:54:43.518631Z","caller":"traceutil/trace.go:171","msg":"trace[1588583012] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:685; }","duration":"128.147585ms","start":"2026-02-14T01:54:43.390474Z","end":"2026-02-14T01:54:43.518622Z","steps":["trace[1588583012] 'agreement among raft nodes before linearized reading' (duration: 128.056973ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:43.518407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:42.389581Z","time spent":"1.128820152s","remote":"127.0.0.1:43428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-14T01:54:43.518791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.289985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-14T01:54:43.518382Z","caller":"traceutil/trace.go:171","msg":"trace[464328542] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"1.91277517s","start":"2026-02-14T01:54:41.605597Z","end":"2026-02-14T01:54:43.518373Z","steps":["trace[464328542] 'process raft request' (duration: 1.77169896s)","trace[464328542] 'compare' (duration: 139.80162ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:54:43.518866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:41.605594Z","time spent":"1.913251021s","remote":"127.0.0.1:40396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T01:54:43.518919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.209678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.49\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-14T01:54:43.518946Z","caller":"traceutil/trace.go:171","msg":"trace[744208447] range","detail":"{range_begin:/registry/masterleases/162.253.55.49; range_end:; response_count:1; response_revision:685; }","duration":"808.26164ms","start":"2026-02-14T01:54:42.710677Z","end":"2026-02-14T01:54:43.518939Z","steps":["trace[744208447] 'agreement among raft nodes before linearized reading' (duration: 808.160038ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:43.518977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:42.710659Z","time spent":"808.308551ms","remote":"127.0.0.1:43484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.49\" "} {"level":"info","ts":"2026-02-14T01:54:43.518818Z","caller":"traceutil/trace.go:171","msg":"trace[1318463866] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:685; }","duration":"144.371557ms","start":"2026-02-14T01:54:43.374439Z","end":"2026-02-14T01:54:43.518811Z","steps":["trace[1318463866] 'agreement among raft nodes before linearized reading' (duration: 144.319526ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:44.223366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.401002ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-02-14T01:54:44.223515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:43.526754Z","time spent":"696.757684ms","remote":"127.0.0.1:43484","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-14T01:54:44.226266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.250865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T01:54:44.226354Z","caller":"traceutil/trace.go:171","msg":"trace[1995429212] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:685; }","duration":"694.391518ms","start":"2026-02-14T01:54:43.531938Z","end":"2026-02-14T01:54:44.226329Z","steps":["trace[1995429212] 'agreement among raft nodes before linearized reading' (duration: 694.219174ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:44.226406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:43.531919Z","time spent":"694.478251ms","remote":"127.0.0.1:43428","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-14T01:54:44.223473Z","caller":"traceutil/trace.go:171","msg":"trace[1240391951] linearizableReadLoop","detail":"{readStateIndex:711; appliedIndex:710; }","duration":"691.47213ms","start":"2026-02-14T01:54:43.531982Z","end":"2026-02-14T01:54:44.223454Z","steps":["trace[1240391951] 'read index received' (duration: 205.906044ms)","trace[1240391951] 'applied index is now lower than readState.Index' (duration: 485.564646ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:54:44.22705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.456799ms","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-14T01:54:44.227086Z","caller":"traceutil/trace.go:171","msg":"trace[1752462038] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:685; }","duration":"620.549201ms","start":"2026-02-14T01:54:43.606525Z","end":"2026-02-14T01:54:44.227074Z","steps":["trace[1752462038] 'agreement among raft nodes before linearized reading' (duration: 620.359236ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:44.227114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:43.606498Z","time spent":"620.608623ms","remote":"127.0.0.1:40396","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":"info","ts":"2026-02-14T01:54:44.774011Z","caller":"traceutil/trace.go:171","msg":"trace[1636281313] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"522.158917ms","start":"2026-02-14T01:54:44.251813Z","end":"2026-02-14T01:54:44.773972Z","steps":["trace[1636281313] 'process raft request' (duration: 521.954062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:44.774222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:44.251783Z","time spent":"522.324992ms","remote":"127.0.0.1:43484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T01:54:45.178219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.172085ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T01:54:45.178305Z","caller":"traceutil/trace.go:171","msg":"trace[1821596145] linearizableReadLoop","detail":"{readStateIndex:713; appliedIndex:712; }","duration":"401.722052ms","start":"2026-02-14T01:54:44.776569Z","end":"2026-02-14T01:54:45.178291Z","steps":["trace[1821596145] 'read index received' (duration: 143.385053ms)","trace[1821596145] 'applied index is now lower than readState.Index' (duration: 258.335769ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T01:54:45.178483Z","caller":"traceutil/trace.go:171","msg":"trace[661602049] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"926.4699ms","start":"2026-02-14T01:54:44.252005Z","end":"2026-02-14T01:54:45.178475Z","steps":["trace[661602049] 'process raft request' (duration: 667.958148ms)","trace[661602049] 'compare' (duration: 257.940149ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:54:45.178524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:44.25198Z","time spent":"926.521622ms","remote":"127.0.0.1:40396","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-14T01:54:45.178674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.105542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-02-14T01:54:45.178695Z","caller":"traceutil/trace.go:171","msg":"trace[2002562246] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:687; }","duration":"402.164753ms","start":"2026-02-14T01:54:44.776521Z","end":"2026-02-14T01:54:45.178686Z","steps":["trace[2002562246] 'agreement among raft nodes before linearized reading' (duration: 402.116952ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:45.178715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:44.776502Z","time spent":"402.209254ms","remote":"127.0.0.1:40280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-02-14T01:54:45.321311Z","caller":"traceutil/trace.go:171","msg":"trace[1852258810] linearizableReadLoop","detail":"{readStateIndex:714; appliedIndex:713; }","duration":"138.505549ms","start":"2026-02-14T01:54:45.182789Z","end":"2026-02-14T01:54:45.321295Z","steps":["trace[1852258810] 'read index received' (duration: 114.987096ms)","trace[1852258810] 'applied index is now lower than readState.Index' (duration: 23.517623ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:54:45.321417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.608522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-14T01:54:45.321434Z","caller":"traceutil/trace.go:171","msg":"trace[539854047] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:688; }","duration":"138.649913ms","start":"2026-02-14T01:54:45.182779Z","end":"2026-02-14T01:54:45.321429Z","steps":["trace[539854047] 'agreement among raft nodes before linearized reading' (duration: 138.578701ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:54:45.321593Z","caller":"traceutil/trace.go:171","msg":"trace[277312593] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"139.213916ms","start":"2026-02-14T01:54:45.182372Z","end":"2026-02-14T01:54:45.321586Z","steps":["trace[277312593] 'process raft request' (duration: 115.409066ms)","trace[277312593] 'compare' (duration: 23.425851ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T01:54:59.485398Z","caller":"traceutil/trace.go:171","msg":"trace[1442386642] linearizableReadLoop","detail":"{readStateIndex:761; appliedIndex:760; }","duration":"101.145268ms","start":"2026-02-14T01:54:59.384224Z","end":"2026-02-14T01:54:59.48537Z","steps":["trace[1442386642] 'read index received' (duration: 100.929553ms)","trace[1442386642] 'applied index is now lower than readState.Index' (duration: 212.455µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T01:54:59.485536Z","caller":"traceutil/trace.go:171","msg":"trace[1495042962] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"252.985951ms","start":"2026-02-14T01:54:59.232536Z","end":"2026-02-14T01:54:59.485522Z","steps":["trace[1495042962] 'process raft request' (duration: 252.641083ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:59.485653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.317062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T01:54:59.485742Z","caller":"traceutil/trace.go:171","msg":"trace[366164545] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:732; }","duration":"101.424274ms","start":"2026-02-14T01:54:59.3843Z","end":"2026-02-14T01:54:59.485724Z","steps":["trace[366164545] 'agreement among raft nodes before linearized reading' (duration: 101.297231ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:59.486053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.813983ms","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-14T01:54:59.486099Z","caller":"traceutil/trace.go:171","msg":"trace[1913928999] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:732; }","duration":"101.905806ms","start":"2026-02-14T01:54:59.38418Z","end":"2026-02-14T01:54:59.486086Z","steps":["trace[1913928999] 'agreement among raft nodes before linearized reading' (duration: 101.758922ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:55:02.912259Z","caller":"traceutil/trace.go:171","msg":"trace[1309426211] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"140.367393ms","start":"2026-02-14T01:55:02.771863Z","end":"2026-02-14T01:55:02.912231Z","steps":["trace[1309426211] 'process raft request' (duration: 101.475045ms)","trace[1309426211] 'compare' (duration: 38.58166ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:55:03.808831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.483109ms","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-14T01:55:03.808904Z","caller":"traceutil/trace.go:171","msg":"trace[250863933] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:742; }","duration":"310.598912ms","start":"2026-02-14T01:55:03.498287Z","end":"2026-02-14T01:55:03.808886Z","steps":["trace[250863933] 'range keys from in-memory index tree' (duration: 310.373146ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:03.808938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:03.498266Z","time spent":"310.664393ms","remote":"127.0.0.1:40396","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-14T01:55:03.80911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.420857ms","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-14T01:55:03.809137Z","caller":"traceutil/trace.go:171","msg":"trace[79400821] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:742; }","duration":"282.488818ms","start":"2026-02-14T01:55:03.526639Z","end":"2026-02-14T01:55:03.809128Z","steps":["trace[79400821] 'range keys from in-memory index tree' (duration: 282.326794ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:03.810112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.123779ms","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-14T01:55:03.810155Z","caller":"traceutil/trace.go:171","msg":"trace[167494887] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:742; }","duration":"146.209502ms","start":"2026-02-14T01:55:03.663936Z","end":"2026-02-14T01:55:03.810145Z","steps":["trace[167494887] 'range keys from in-memory index tree' (duration: 146.013526ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:03.810489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.465056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T01:55:03.810532Z","caller":"traceutil/trace.go:171","msg":"trace[2037587001] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:742; }","duration":"132.541838ms","start":"2026-02-14T01:55:03.677977Z","end":"2026-02-14T01:55:03.810519Z","steps":["trace[2037587001] 'count revisions from in-memory index tree' (duration: 132.397035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:04.349968Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17707762694599451239,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-14T01:55:04.379339Z","caller":"traceutil/trace.go:171","msg":"trace[501947038] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"565.586731ms","start":"2026-02-14T01:55:03.813731Z","end":"2026-02-14T01:55:04.379318Z","steps":["trace[501947038] 'process raft request' (duration: 565.451567ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:04.379453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:03.813718Z","time spent":"565.678112ms","remote":"127.0.0.1:40396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T01:55:05.019281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:04.505304Z","time spent":"513.967092ms","remote":"127.0.0.1:50150","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-14T01:55:05.01994Z","caller":"traceutil/trace.go:171","msg":"trace[320047039] linearizableReadLoop","detail":"{readStateIndex:777; appliedIndex:773; }","duration":"1.17310308s","start":"2026-02-14T01:55:03.846803Z","end":"2026-02-14T01:55:05.019906Z","steps":["trace[320047039] 'read index received' (duration: 532.579761ms)","trace[320047039] 'applied index is now lower than readState.Index' (duration: 640.522039ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T01:55:05.020665Z","caller":"traceutil/trace.go:171","msg":"trace[603470454] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"1.204344087s","start":"2026-02-14T01:55:03.816307Z","end":"2026-02-14T01:55:05.020651Z","steps":["trace[603470454] 'process raft request' (duration: 1.202929604s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:05.020828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:03.816299Z","time spent":"1.204408369s","remote":"127.0.0.1:40396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T01:55:05.021111Z","caller":"traceutil/trace.go:171","msg":"trace[1297688421] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"1.204738837s","start":"2026-02-14T01:55:03.816361Z","end":"2026-02-14T01:55:05.0211Z","steps":["trace[1297688421] 'process raft request' (duration: 1.203403285s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:05.021171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:03.816357Z","time spent":"1.204778598s","remote":"127.0.0.1:40396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T01:55:05.021435Z","caller":"traceutil/trace.go:171","msg":"trace[485187371] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"1.205001892s","start":"2026-02-14T01:55:03.816419Z","end":"2026-02-14T01:55:05.021421Z","steps":["trace[485187371] 'process raft request' (duration: 1.203405895s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:05.021529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:03.816414Z","time spent":"1.205078075s","remote":"127.0.0.1:40396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T01:55:05.021663Z","caller":"traceutil/trace.go:171","msg":"trace[1605768329] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"1.204351598s","start":"2026-02-14T01:55:03.817303Z","end":"2026-02-14T01:55:05.021654Z","steps":["trace[1605768329] 'process raft request' (duration: 1.202561276s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:05.02172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:03.817295Z","time spent":"1.204390509s","remote":"127.0.0.1:40396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T01:55:05.021933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.175126247s","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-14T01:55:05.022051Z","caller":"traceutil/trace.go:171","msg":"trace[1712486423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:747; }","duration":"1.175178139s","start":"2026-02-14T01:55:03.846777Z","end":"2026-02-14T01:55:05.021955Z","steps":["trace[1712486423] 'agreement among raft nodes before linearized reading' (duration: 1.175040646s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:05.022088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:03.846766Z","time spent":"1.175313402s","remote":"127.0.0.1:40396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-14T01:55:05.022474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.6336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T01:55:05.022661Z","caller":"traceutil/trace.go:171","msg":"trace[643225679] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:747; }","duration":"637.878836ms","start":"2026-02-14T01:55:04.384766Z","end":"2026-02-14T01:55:05.022645Z","steps":["trace[643225679] 'agreement among raft nodes before linearized reading' (duration: 637.61784ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:05.022716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:04.384753Z","time spent":"637.955888ms","remote":"127.0.0.1:43428","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-14T01:55:05.303195Z","caller":"traceutil/trace.go:171","msg":"trace[2020663166] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"272.941092ms","start":"2026-02-14T01:55:05.03023Z","end":"2026-02-14T01:55:05.303171Z","steps":["trace[2020663166] 'process raft request' (duration: 269.825999ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:55:18.496176Z","caller":"traceutil/trace.go:171","msg":"trace[263710098] linearizableReadLoop","detail":"{readStateIndex:869; appliedIndex:868; }","duration":"113.181361ms","start":"2026-02-14T01:55:18.382976Z","end":"2026-02-14T01:55:18.496158Z","steps":["trace[263710098] 'read index received' (duration: 56.7935ms)","trace[263710098] 'applied index is now lower than readState.Index' (duration: 56.387001ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:55:18.49626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.271323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T01:55:18.496286Z","caller":"traceutil/trace.go:171","msg":"trace[2093977713] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:834; }","duration":"113.327855ms","start":"2026-02-14T01:55:18.382943Z","end":"2026-02-14T01:55:18.496271Z","steps":["trace[2093977713] 'agreement among raft nodes before linearized reading' (duration: 113.278444ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:20.085384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.498214ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T01:55:20.085496Z","caller":"traceutil/trace.go:171","msg":"trace[845535342] linearizableReadLoop","detail":"{readStateIndex:873; appliedIndex:871; }","duration":"701.728313ms","start":"2026-02-14T01:55:19.383755Z","end":"2026-02-14T01:55:20.085483Z","steps":["trace[845535342] 'read index received' (duration: 12.018484ms)","trace[845535342] 'applied index is now lower than readState.Index' (duration: 689.709089ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T01:55:20.085645Z","caller":"traceutil/trace.go:171","msg":"trace[1881435369] transaction","detail":"{read_only:false; response_revision:837; number_of_response:1; }","duration":"821.864249ms","start":"2026-02-14T01:55:19.263773Z","end":"2026-02-14T01:55:20.085637Z","steps":["trace[1881435369] 'process raft request' (duration: 132.037167ms)","trace[1881435369] 'compare' (duration: 689.31881ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:55:20.085684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:19.263762Z","time spent":"821.900869ms","remote":"127.0.0.1:40396","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-14T01:55:20.085791Z","caller":"traceutil/trace.go:171","msg":"trace[1043999631] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"820.537647ms","start":"2026-02-14T01:55:19.265246Z","end":"2026-02-14T01:55:20.085784Z","steps":["trace[1043999631] 'process raft request' (duration: 820.205959ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:20.085822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:19.26524Z","time spent":"820.564178ms","remote":"127.0.0.1:40396","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-14T01:55:20.085946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.196704ms","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-14T01:55:20.085964Z","caller":"traceutil/trace.go:171","msg":"trace[381251725] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:838; }","duration":"702.242074ms","start":"2026-02-14T01:55:19.383715Z","end":"2026-02-14T01:55:20.085957Z","steps":["trace[381251725] 'agreement among raft nodes before linearized reading' (duration: 702.170433ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:20.085983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:19.383687Z","time spent":"702.290586ms","remote":"127.0.0.1:40396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-14T01:55:20.086068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.236331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T01:55:20.086084Z","caller":"traceutil/trace.go:171","msg":"trace[1686705777] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:838; }","duration":"701.267642ms","start":"2026-02-14T01:55:19.384811Z","end":"2026-02-14T01:55:20.086079Z","steps":["trace[1686705777] 'agreement among raft nodes before linearized reading' (duration: 701.242672ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:20.086111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:19.384805Z","time spent":"701.290583ms","remote":"127.0.0.1:43428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-14T01:55:21.02674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.883067ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T01:55:21.026872Z","caller":"traceutil/trace.go:171","msg":"trace[1745830258] linearizableReadLoop","detail":"{readStateIndex:874; appliedIndex:873; }","duration":"455.764988ms","start":"2026-02-14T01:55:20.57109Z","end":"2026-02-14T01:55:21.026855Z","steps":["trace[1745830258] 'read index received' (duration: 27.501µs)","trace[1745830258] 'applied index is now lower than readState.Index' (duration: 455.736007ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T01:55:21.027032Z","caller":"traceutil/trace.go:171","msg":"trace[1358923662] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"935.44657ms","start":"2026-02-14T01:55:20.091577Z","end":"2026-02-14T01:55:21.027024Z","steps":["trace[1358923662] 'process raft request' (duration: 305.193744ms)","trace[1358923662] 'compare' (duration: 629.431567ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:55:21.027072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:20.091567Z","time spent":"935.482971ms","remote":"127.0.0.1:40396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T01:55:21.027283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.189058ms","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-14T01:55:21.027305Z","caller":"traceutil/trace.go:171","msg":"trace[1476757701] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:839; }","duration":"456.238549ms","start":"2026-02-14T01:55:20.57106Z","end":"2026-02-14T01:55:21.027298Z","steps":["trace[1476757701] 'agreement among raft nodes before linearized reading' (duration: 456.151427ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:21.027324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:20.571046Z","time spent":"456.27391ms","remote":"127.0.0.1:40396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-14T01:55:21.027419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.481119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T01:55:21.027435Z","caller":"traceutil/trace.go:171","msg":"trace[210166559] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:839; }","duration":"399.524321ms","start":"2026-02-14T01:55:20.627906Z","end":"2026-02-14T01:55:21.02743Z","steps":["trace[210166559] 'agreement among raft nodes before linearized reading' (duration: 399.49481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:21.027454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:20.627893Z","time spent":"399.557221ms","remote":"127.0.0.1:40262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-02-14T01:55:21.180517Z","caller":"traceutil/trace.go:171","msg":"trace[337994290] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"148.474425ms","start":"2026-02-14T01:55:21.032027Z","end":"2026-02-14T01:55:21.180501Z","steps":["trace[337994290] 'process raft request' (duration: 148.326552ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:55:23.539661Z","caller":"traceutil/trace.go:171","msg":"trace[63649840] linearizableReadLoop","detail":"{readStateIndex:882; appliedIndex:881; }","duration":"270.690639ms","start":"2026-02-14T01:55:23.26895Z","end":"2026-02-14T01:55:23.53964Z","steps":["trace[63649840] 'read index received' (duration: 270.378691ms)","trace[63649840] 'applied index is now lower than readState.Index' (duration: 310.948µs)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:55:23.539866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.893304ms","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-14T01:55:23.539898Z","caller":"traceutil/trace.go:171","msg":"trace[1653833578] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:846; }","duration":"270.966585ms","start":"2026-02-14T01:55:23.268922Z","end":"2026-02-14T01:55:23.539889Z","steps":["trace[1653833578] 'agreement among raft nodes before linearized reading' (duration: 270.797531ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:55:23.540192Z","caller":"traceutil/trace.go:171","msg":"trace[1678825366] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"353.877503ms","start":"2026-02-14T01:55:23.186305Z","end":"2026-02-14T01:55:23.540182Z","steps":["trace[1678825366] 'process raft request' (duration: 353.228248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:23.540264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:23.186296Z","time spent":"353.918894ms","remote":"127.0.0.1:40396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T01:55:29.523698Z","caller":"traceutil/trace.go:171","msg":"trace[683316489] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"456.359602ms","start":"2026-02-14T01:55:29.067323Z","end":"2026-02-14T01:55:29.523682Z","steps":["trace[683316489] 'process raft request' (duration: 456.26431ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:29.523791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:29.067307Z","time spent":"456.436074ms","remote":"127.0.0.1:40396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T01:55:30.076133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.129742ms","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-14T01:55:30.076185Z","caller":"traceutil/trace.go:171","msg":"trace[1840455804] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:862; }","duration":"477.189024ms","start":"2026-02-14T01:55:29.598982Z","end":"2026-02-14T01:55:30.076171Z","steps":["trace[1840455804] 'range keys from in-memory index tree' (duration: 477.01033ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:30.076207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:29.598942Z","time spent":"477.260755ms","remote":"127.0.0.1:40396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-14T01:55:30.076513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.405245ms","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-14T01:55:30.076585Z","caller":"traceutil/trace.go:171","msg":"trace[499997088] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:862; }","duration":"440.510838ms","start":"2026-02-14T01:55:29.636057Z","end":"2026-02-14T01:55:30.076568Z","steps":["trace[499997088] 'range keys from in-memory index tree' (duration: 440.214021ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:30.076627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:29.636043Z","time spent":"440.575609ms","remote":"127.0.0.1:40396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-14T01:55:30.397346Z","caller":"traceutil/trace.go:171","msg":"trace[93919191] linearizableReadLoop","detail":"{readStateIndex:902; appliedIndex:901; }","duration":"230.710075ms","start":"2026-02-14T01:55:30.16662Z","end":"2026-02-14T01:55:30.39733Z","steps":["trace[93919191] 'read index received' (duration: 219.986442ms)","trace[93919191] 'applied index is now lower than readState.Index' (duration: 10.723123ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T01:55:30.397524Z","caller":"traceutil/trace.go:171","msg":"trace[2140650791] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"230.938351ms","start":"2026-02-14T01:55:30.166576Z","end":"2026-02-14T01:55:30.397514Z","steps":["trace[2140650791] 'process raft request' (duration: 220.180507ms)","trace[2140650791] 'compare' (duration: 10.332294ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:55:30.397674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.040923ms","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-14T01:55:30.397692Z","caller":"traceutil/trace.go:171","msg":"trace[122237017] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:865; }","duration":"231.092504ms","start":"2026-02-14T01:55:30.166594Z","end":"2026-02-14T01:55:30.397686Z","steps":["trace[122237017] 'agreement among raft nodes before linearized reading' (duration: 231.017022ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:55:33.640893Z","caller":"traceutil/trace.go:171","msg":"trace[786971802] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"103.715778ms","start":"2026-02-14T01:55:33.537161Z","end":"2026-02-14T01:55:33.640876Z","steps":["trace[786971802] 'process raft request' (duration: 103.617715ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:35.027021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.82941ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T01:55:35.027086Z","caller":"traceutil/trace.go:171","msg":"trace[768419642] linearizableReadLoop","detail":"{readStateIndex:913; appliedIndex:912; }","duration":"644.050093ms","start":"2026-02-14T01:55:34.383024Z","end":"2026-02-14T01:55:35.027074Z","steps":["trace[768419642] 'read index received' (duration: 27.811µs)","trace[768419642] 'applied index is now lower than readState.Index' (duration: 644.021552ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T01:55:35.02724Z","caller":"traceutil/trace.go:171","msg":"trace[563221273] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"826.850428ms","start":"2026-02-14T01:55:34.20038Z","end":"2026-02-14T01:55:35.02723Z","steps":["trace[563221273] 'process raft request' (duration: 59.756781ms)","trace[563221273] 'compare' (duration: 766.462252ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:55:35.027282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:34.20037Z","time spent":"826.889728ms","remote":"127.0.0.1:40396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T01:55:35.027405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.380121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T01:55:35.027422Z","caller":"traceutil/trace.go:171","msg":"trace[1157969614] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:875; }","duration":"644.421611ms","start":"2026-02-14T01:55:34.382995Z","end":"2026-02-14T01:55:35.027417Z","steps":["trace[1157969614] 'agreement among raft nodes before linearized reading' (duration: 644.3923ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:35.027439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:34.382976Z","time spent":"644.459022ms","remote":"127.0.0.1:43428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-14T01:55:35.027843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.175979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-14T01:55:35.027876Z","caller":"traceutil/trace.go:171","msg":"trace[1685671719] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:875; }","duration":"165.23234ms","start":"2026-02-14T01:55:34.86263Z","end":"2026-02-14T01:55:35.027862Z","steps":["trace[1685671719] 'agreement among raft nodes before linearized reading' (duration: 165.171079ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:35.032664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.557573ms","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-14T01:55:35.032723Z","caller":"traceutil/trace.go:171","msg":"trace[849671684] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:875; }","duration":"602.654305ms","start":"2026-02-14T01:55:34.430055Z","end":"2026-02-14T01:55:35.032709Z","steps":["trace[849671684] 'agreement among raft nodes before linearized reading' (duration: 597.467882ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:35.032755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:34.430042Z","time spent":"602.706317ms","remote":"127.0.0.1:40396","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-14T01:55:35.032975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.068764ms","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-14T01:55:35.032994Z","caller":"traceutil/trace.go:171","msg":"trace[1086210271] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:875; }","duration":"597.098724ms","start":"2026-02-14T01:55:34.43589Z","end":"2026-02-14T01:55:35.032989Z","steps":["trace[1086210271] 'agreement among raft nodes before linearized reading' (duration: 597.023103ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:35.033007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:34.435886Z","time spent":"597.118575ms","remote":"127.0.0.1:40396","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-14T01:55:35.641742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.187473ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T01:55:35.641841Z","caller":"traceutil/trace.go:171","msg":"trace[392714498] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"604.430948ms","start":"2026-02-14T01:55:35.03739Z","end":"2026-02-14T01:55:35.641821Z","steps":["trace[392714498] 'process raft request' (duration: 427.095471ms)","trace[392714498] 'compare' (duration: 177.03602ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:55:35.641903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:35.037378Z","time spent":"604.494779ms","remote":"127.0.0.1:40396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T01:55:35.679775Z","caller":"traceutil/trace.go:171","msg":"trace[1509372127] linearizableReadLoop","detail":"{readStateIndex:915; appliedIndex:913; }","duration":"296.410026ms","start":"2026-02-14T01:55:35.383354Z","end":"2026-02-14T01:55:35.679764Z","steps":["trace[1509372127] 'read index received' (duration: 81.140055ms)","trace[1509372127] 'applied index is now lower than readState.Index' (duration: 215.269341ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T01:55:35.679916Z","caller":"traceutil/trace.go:171","msg":"trace[928762181] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"642.430655ms","start":"2026-02-14T01:55:35.037479Z","end":"2026-02-14T01:55:35.679909Z","steps":["trace[928762181] 'process raft request' (duration: 642.22584ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:35.679961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:35.037475Z","time spent":"642.457565ms","remote":"127.0.0.1:40396","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-14T01:55:35.680056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.706724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T01:55:35.680074Z","caller":"traceutil/trace.go:171","msg":"trace[1994716731] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:877; }","duration":"296.750034ms","start":"2026-02-14T01:55:35.383318Z","end":"2026-02-14T01:55:35.680068Z","steps":["trace[1994716731] 'agreement among raft nodes before linearized reading' (duration: 296.721334ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:55:35.841778Z","caller":"traceutil/trace.go:171","msg":"trace[1303268955] linearizableReadLoop","detail":"{readStateIndex:916; appliedIndex:915; }","duration":"161.943353ms","start":"2026-02-14T01:55:35.679815Z","end":"2026-02-14T01:55:35.841758Z","steps":["trace[1303268955] 'read index received' (duration: 126.204389ms)","trace[1303268955] 'applied index is now lower than readState.Index' (duration: 35.738304ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T01:55:35.841959Z","caller":"traceutil/trace.go:171","msg":"trace[1793700268] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"272.309498ms","start":"2026-02-14T01:55:35.56964Z","end":"2026-02-14T01:55:35.841949Z","steps":["trace[1793700268] 'process raft request' (duration: 236.441762ms)","trace[1793700268] 'compare' (duration: 35.351014ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:55:35.842149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.447836ms","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-14T01:55:35.84217Z","caller":"traceutil/trace.go:171","msg":"trace[986275448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:878; }","duration":"193.505097ms","start":"2026-02-14T01:55:35.648659Z","end":"2026-02-14T01:55:35.842164Z","steps":["trace[986275448] 'agreement among raft nodes before linearized reading' (duration: 193.415355ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:35.842271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.6317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T01:55:35.842291Z","caller":"traceutil/trace.go:171","msg":"trace[1833502576] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:878; }","duration":"115.67443ms","start":"2026-02-14T01:55:35.726608Z","end":"2026-02-14T01:55:35.842283Z","steps":["trace[1833502576] 'agreement among raft nodes before linearized reading' (duration: 115.642969ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:55:45.954729Z","caller":"traceutil/trace.go:171","msg":"trace[319527155] linearizableReadLoop","detail":"{readStateIndex:971; appliedIndex:970; }","duration":"240.190329ms","start":"2026-02-14T01:55:45.714518Z","end":"2026-02-14T01:55:45.954708Z","steps":["trace[319527155] 'read index received' (duration: 235.14286ms)","trace[319527155] 'applied index is now lower than readState.Index' (duration: 5.046649ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T01:55:45.955035Z","caller":"traceutil/trace.go:171","msg":"trace[1400978853] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"255.138153ms","start":"2026-02-14T01:55:45.699885Z","end":"2026-02-14T01:55:45.955024Z","steps":["trace[1400978853] 'process raft request' (duration: 254.686802ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:45.95527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.753593ms","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-14T01:55:45.955997Z","caller":"traceutil/trace.go:171","msg":"trace[520489365] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:931; }","duration":"241.48409ms","start":"2026-02-14T01:55:45.714496Z","end":"2026-02-14T01:55:45.95598Z","steps":["trace[520489365] 'agreement among raft nodes before linearized reading' (duration: 240.688942ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:48.951105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.615139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-14T01:55:48.951209Z","caller":"traceutil/trace.go:171","msg":"trace[271242321] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:948; }","duration":"731.733312ms","start":"2026-02-14T01:55:48.219455Z","end":"2026-02-14T01:55:48.951188Z","steps":["trace[271242321] 'range keys from in-memory index tree' (duration: 731.441685ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:48.951257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:48.219406Z","time spent":"731.833984ms","remote":"127.0.0.1:40396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-14T01:55:48.951356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.734374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T01:55:48.951388Z","caller":"traceutil/trace.go:171","msg":"trace[1727282911] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:948; }","duration":"565.796285ms","start":"2026-02-14T01:55:48.385581Z","end":"2026-02-14T01:55:48.951377Z","steps":["trace[1727282911] 'range keys from in-memory index tree' (duration: 565.682233ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:48.951414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:48.385565Z","time spent":"565.841587ms","remote":"127.0.0.1:43428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-14T01:55:48.951575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.049292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-14T01:55:48.951605Z","caller":"traceutil/trace.go:171","msg":"trace[1944187932] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:948; }","duration":"458.157924ms","start":"2026-02-14T01:55:48.493438Z","end":"2026-02-14T01:55:48.951596Z","steps":["trace[1944187932] 'range keys from in-memory index tree' (duration: 457.358426ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:48.95163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:48.493417Z","time spent":"458.205336ms","remote":"127.0.0.1:40396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-14T01:55:49.639457Z","caller":"traceutil/trace.go:171","msg":"trace[1500946178] linearizableReadLoop","detail":"{readStateIndex:991; appliedIndex:990; }","duration":"408.271737ms","start":"2026-02-14T01:55:49.231164Z","end":"2026-02-14T01:55:49.639435Z","steps":["trace[1500946178] 'read index received' (duration: 389.032763ms)","trace[1500946178] 'applied index is now lower than readState.Index' (duration: 19.236004ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T01:55:49.639697Z","caller":"traceutil/trace.go:171","msg":"trace[539614700] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"678.323811ms","start":"2026-02-14T01:55:48.961361Z","end":"2026-02-14T01:55:49.639684Z","steps":["trace[539614700] 'process raft request' (duration: 658.885042ms)","trace[539614700] 'compare' (duration: 18.722292ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:55:49.639792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:48.961351Z","time spent":"678.374552ms","remote":"127.0.0.1:40396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T01:55:49.640068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.897802ms","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-14T01:55:49.640105Z","caller":"traceutil/trace.go:171","msg":"trace[139869617] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:950; }","duration":"408.974544ms","start":"2026-02-14T01:55:49.231119Z","end":"2026-02-14T01:55:49.640094Z","steps":["trace[139869617] 'agreement among raft nodes before linearized reading' (duration: 408.852941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:49.640137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:49.231027Z","time spent":"409.102737ms","remote":"127.0.0.1:40396","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-14T01:55:49.640397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.159422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T01:55:49.640428Z","caller":"traceutil/trace.go:171","msg":"trace[918404608] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:950; }","duration":"252.222613ms","start":"2026-02-14T01:55:49.388197Z","end":"2026-02-14T01:55:49.640419Z","steps":["trace[918404608] 'agreement among raft nodes before linearized reading' (duration: 252.172432ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:50.043503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.948909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T01:55:50.043558Z","caller":"traceutil/trace.go:171","msg":"trace[1745006901] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:951; }","duration":"161.050821ms","start":"2026-02-14T01:55:49.882496Z","end":"2026-02-14T01:55:50.043547Z","steps":["trace[1745006901] 'count revisions from in-memory index tree' (duration: 160.883257ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:55:50.271635Z","caller":"traceutil/trace.go:171","msg":"trace[1744543326] linearizableReadLoop","detail":"{readStateIndex:993; appliedIndex:992; }","duration":"199.50569ms","start":"2026-02-14T01:55:50.072117Z","end":"2026-02-14T01:55:50.271622Z","steps":["trace[1744543326] 'read index received' (duration: 199.398547ms)","trace[1744543326] 'applied index is now lower than readState.Index' (duration: 106.633µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T01:55:50.271806Z","caller":"traceutil/trace.go:171","msg":"trace[1932023094] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"220.060735ms","start":"2026-02-14T01:55:50.05174Z","end":"2026-02-14T01:55:50.2718Z","steps":["trace[1932023094] 'process raft request' (duration: 219.810339ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:50.271938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.811637ms","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-14T01:55:50.271953Z","caller":"traceutil/trace.go:171","msg":"trace[395088817] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:952; }","duration":"199.867608ms","start":"2026-02-14T01:55:50.072081Z","end":"2026-02-14T01:55:50.271949Z","steps":["trace[395088817] 'agreement among raft nodes before linearized reading' (duration: 199.803067ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:50.272033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.662223ms","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-14T01:55:50.272044Z","caller":"traceutil/trace.go:171","msg":"trace[2008296621] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:952; }","duration":"199.693493ms","start":"2026-02-14T01:55:50.072347Z","end":"2026-02-14T01:55:50.272041Z","steps":["trace[2008296621] 'agreement among raft nodes before linearized reading' (duration: 199.660333ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:55:50.454083Z","caller":"traceutil/trace.go:171","msg":"trace[1077695255] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"175.749578ms","start":"2026-02-14T01:55:50.278316Z","end":"2026-02-14T01:55:50.454066Z","steps":["trace[1077695255] 'process raft request' (duration: 175.649465ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:50.911208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.930227ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T01:55:50.911273Z","caller":"traceutil/trace.go:171","msg":"trace[1264526956] linearizableReadLoop","detail":"{readStateIndex:995; appliedIndex:994; }","duration":"521.989062ms","start":"2026-02-14T01:55:50.389272Z","end":"2026-02-14T01:55:50.911262Z","steps":["trace[1264526956] 'read index received' (duration: 65.182429ms)","trace[1264526956] 'applied index is now lower than readState.Index' (duration: 456.805923ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T01:55:50.911407Z","caller":"traceutil/trace.go:171","msg":"trace[637018120] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"596.100921ms","start":"2026-02-14T01:55:50.315298Z","end":"2026-02-14T01:55:50.911399Z","steps":["trace[637018120] 'process raft request' (duration: 404.923088ms)","trace[637018120] 'compare' (duration: 190.723392ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:55:50.911445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:50.315281Z","time spent":"596.143172ms","remote":"127.0.0.1:40396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T01:55:50.911596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.32058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T01:55:50.911616Z","caller":"traceutil/trace.go:171","msg":"trace[1766979962] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:954; }","duration":"522.36254ms","start":"2026-02-14T01:55:50.389248Z","end":"2026-02-14T01:55:50.91161Z","steps":["trace[1766979962] 'agreement among raft nodes before linearized reading' (duration: 522.328419ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:50.911633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:50.389234Z","time spent":"522.395751ms","remote":"127.0.0.1:43428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-14T01:55:50.911733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.390268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T01:55:50.911752Z","caller":"traceutil/trace.go:171","msg":"trace[2127271144] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:954; }","duration":"316.438959ms","start":"2026-02-14T01:55:50.595307Z","end":"2026-02-14T01:55:50.911746Z","steps":["trace[2127271144] 'agreement among raft nodes before linearized reading' (duration: 316.403838ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:50.911773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:50.595292Z","time spent":"316.47519ms","remote":"127.0.0.1:47128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-14T01:55:50.911983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.046614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-14T01:55:50.912002Z","caller":"traceutil/trace.go:171","msg":"trace[305786262] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:954; }","duration":"306.075655ms","start":"2026-02-14T01:55:50.605921Z","end":"2026-02-14T01:55:50.911996Z","steps":["trace[305786262] 'agreement among raft nodes before linearized reading' (duration: 306.043634ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:50.912019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:50.605915Z","time spent":"306.098836ms","remote":"127.0.0.1:40580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":3,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-02-14T01:55:51.36783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.841768ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T01:55:51.367904Z","caller":"traceutil/trace.go:171","msg":"trace[2092502440] linearizableReadLoop","detail":"{readStateIndex:996; appliedIndex:995; }","duration":"351.182619ms","start":"2026-02-14T01:55:51.016709Z","end":"2026-02-14T01:55:51.367891Z","steps":["trace[2092502440] 'read index received' (duration: 126.164438ms)","trace[2092502440] 'applied index is now lower than readState.Index' (duration: 225.017181ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T01:55:51.367968Z","caller":"traceutil/trace.go:171","msg":"trace[124116967] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"360.462799ms","start":"2026-02-14T01:55:51.007497Z","end":"2026-02-14T01:55:51.36796Z","steps":["trace[124116967] 'process raft request' (duration: 135.418827ms)","trace[124116967] 'compare' (duration: 224.764235ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:55:51.367997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:51.007486Z","time spent":"360.494529ms","remote":"127.0.0.1:40396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T01:55:51.368087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.394015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T01:55:51.3681Z","caller":"traceutil/trace.go:171","msg":"trace[1713062754] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:955; }","duration":"351.470396ms","start":"2026-02-14T01:55:51.016625Z","end":"2026-02-14T01:55:51.368095Z","steps":["trace[1713062754] 'agreement among raft nodes before linearized reading' (duration: 351.439775ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:51.368118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:51.016593Z","time spent":"351.518967ms","remote":"127.0.0.1:40550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-02-14T01:55:51.368269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.687025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-02-14T01:55:51.368286Z","caller":"traceutil/trace.go:171","msg":"trace[113895854] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:955; }","duration":"166.756006ms","start":"2026-02-14T01:55:51.201523Z","end":"2026-02-14T01:55:51.368279Z","steps":["trace[113895854] 'agreement among raft nodes before linearized reading' (duration: 166.685885ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:55:51.57006Z","caller":"traceutil/trace.go:171","msg":"trace[910354213] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"194.418719ms","start":"2026-02-14T01:55:51.375617Z","end":"2026-02-14T01:55:51.570036Z","steps":["trace[910354213] 'process raft request' (duration: 192.372201ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:56:59.038447Z","caller":"traceutil/trace.go:171","msg":"trace[1361244373] linearizableReadLoop","detail":"{readStateIndex:1308; appliedIndex:1307; }","duration":"300.079562ms","start":"2026-02-14T01:56:58.738341Z","end":"2026-02-14T01:56:59.038421Z","steps":["trace[1361244373] 'read index received' (duration: 299.810365ms)","trace[1361244373] 'applied index is now lower than readState.Index' (duration: 267.717µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T01:56:59.038631Z","caller":"traceutil/trace.go:171","msg":"trace[1969856423] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"410.539562ms","start":"2026-02-14T01:56:58.628072Z","end":"2026-02-14T01:56:59.038612Z","steps":["trace[1969856423] 'process raft request' (duration: 410.105852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:56:59.038753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:56:58.628056Z","time spent":"410.619605ms","remote":"127.0.0.1:40396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T01:56:59.043752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.403286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-14T01:56:59.043924Z","caller":"traceutil/trace.go:171","msg":"trace[1252279131] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1253; }","duration":"305.507978ms","start":"2026-02-14T01:56:58.738291Z","end":"2026-02-14T01:56:59.043799Z","steps":["trace[1252279131] 'agreement among raft nodes before linearized reading' (duration: 301.000023ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:56:59.044474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:56:58.738271Z","time spent":"306.158394ms","remote":"127.0.0.1:40396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-14T01:58:59.526329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.423853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T01:58:59.526424Z","caller":"traceutil/trace.go:171","msg":"trace[1527653001] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1773; }","duration":"138.567426ms","start":"2026-02-14T01:58:59.387838Z","end":"2026-02-14T01:58:59.526405Z","steps":["trace[1527653001] 'range keys from in-memory index tree' (duration: 138.359771ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:58:59.71657Z","caller":"traceutil/trace.go:171","msg":"trace[2007951178] linearizableReadLoop","detail":"{readStateIndex:1856; appliedIndex:1855; }","duration":"114.326483ms","start":"2026-02-14T01:58:59.602228Z","end":"2026-02-14T01:58:59.716554Z","steps":["trace[2007951178] 'read index received' (duration: 35.511177ms)","trace[2007951178] 'applied index is now lower than readState.Index' (duration: 78.814656ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:58:59.716711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.465596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-14T01:58:59.716727Z","caller":"traceutil/trace.go:171","msg":"trace[1635839803] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1776; }","duration":"114.513686ms","start":"2026-02-14T01:58:59.602208Z","end":"2026-02-14T01:58:59.716722Z","steps":["trace[1635839803] 'agreement among raft nodes before linearized reading' (duration: 114.402904ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:58:59.716804Z","caller":"traceutil/trace.go:171","msg":"trace[1873779600] transaction","detail":"{read_only:false; response_revision:1776; number_of_response:1; }","duration":"116.253717ms","start":"2026-02-14T01:58:59.600545Z","end":"2026-02-14T01:58:59.716799Z","steps":["trace[1873779600] 'process raft request' (duration: 37.271818ms)","trace[1873779600] 'compare' (duration: 78.665092ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:58:59.932015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.81583ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T01:58:59.932081Z","caller":"traceutil/trace.go:171","msg":"trace[2045031327] transaction","detail":"{read_only:false; response_revision:1779; number_of_response:1; }","duration":"118.794706ms","start":"2026-02-14T01:58:59.813277Z","end":"2026-02-14T01:58:59.932072Z","steps":["trace[2045031327] 'compare' (duration: 113.741069ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:59:00.309384Z","caller":"traceutil/trace.go:171","msg":"trace[219764916] transaction","detail":"{read_only:false; response_revision:1780; number_of_response:1; }","duration":"350.413729ms","start":"2026-02-14T01:58:59.958945Z","end":"2026-02-14T01:59:00.309359Z","steps":["trace[219764916] 'process raft request' (duration: 331.271813ms)","trace[219764916] 'compare' (duration: 18.815068ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:59:00.30978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:58:59.958934Z","time spent":"350.743577ms","remote":"127.0.0.1:40396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}