{"level":"warn","ts":"2026-02-18T05:16:16.518556Z","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-18T05:16:16.518633Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.62: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.62:2380","--initial-cluster=instance=https://162.253.55.62:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.62:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.62: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-18T05:16:16.520609Z","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-18T05:16:16.520625Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.62:2380"]} {"level":"info","ts":"2026-02-18T05:16:16.52177Z","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-18T05:16:16.52546Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"]} {"level":"info","ts":"2026-02-18T05:16:16.525635Z","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.62:2380"],"listen-peer-urls":["https://162.253.55.62:2380"],"advertise-client-urls":["https://162.253.55.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.62: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-18T05:16:16.531675Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.749925ms"} {"level":"info","ts":"2026-02-18T05:16:16.53931Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"791a7e25b533cd26","cluster-id":"be484d2748da72cd"} {"level":"info","ts":"2026-02-18T05:16:16.539397Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=()"} {"level":"info","ts":"2026-02-18T05:16:16.53942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 0"} {"level":"info","ts":"2026-02-18T05:16:16.539472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 791a7e25b533cd26 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-18T05:16:16.539483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 1"} {"level":"info","ts":"2026-02-18T05:16:16.53954Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"} {"level":"warn","ts":"2026-02-18T05:16:16.550722Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-18T05:16:16.564376Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-18T05:16:16.568592Z","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-18T05:16:16.576472Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"791a7e25b533cd26","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-18T05:16:16.578848Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"} {"level":"info","ts":"2026-02-18T05:16:16.580462Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"be484d2748da72cd","local-member-id":"791a7e25b533cd26","added-peer-id":"791a7e25b533cd26","added-peer-peer-urls":["https://162.253.55.62:2380"]} {"level":"info","ts":"2026-02-18T05:16:16.58057Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"791a7e25b533cd26","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-18T05:16:16.580757Z","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-18T05:16:16.580779Z","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-18T05:16:16.580787Z","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-18T05:16:16.584649Z","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-18T05:16:16.588332Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.62:2380"} {"level":"info","ts":"2026-02-18T05:16:16.588345Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.62:2380"} {"level":"info","ts":"2026-02-18T05:16:16.59026Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"791a7e25b533cd26","initial-advertise-peer-urls":["https://162.253.55.62:2380"],"listen-peer-urls":["https://162.253.55.62:2380"],"advertise-client-urls":["https://162.253.55.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-18T05:16:16.590283Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-18T05:16:17.341787Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 is starting a new election at term 1"} {"level":"info","ts":"2026-02-18T05:16:17.341823Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-18T05:16:17.34184Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgPreVoteResp from 791a7e25b533cd26 at term 1"} {"level":"info","ts":"2026-02-18T05:16:17.34185Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became candidate at term 2"} {"level":"info","ts":"2026-02-18T05:16:17.341855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgVoteResp from 791a7e25b533cd26 at term 2"} {"level":"info","ts":"2026-02-18T05:16:17.341863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became leader at term 2"} {"level":"info","ts":"2026-02-18T05:16:17.341869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 791a7e25b533cd26 elected leader 791a7e25b533cd26 at term 2"} {"level":"info","ts":"2026-02-18T05:16:17.342879Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"791a7e25b533cd26","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.62:2379]}","request-path":"/0/members/791a7e25b533cd26/attributes","cluster-id":"be484d2748da72cd","publish-timeout":"7s"} {"level":"info","ts":"2026-02-18T05:16:17.342933Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T05:16:17.343232Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T05:16:17.343528Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T05:16:17.348913Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-18T05:16:17.364948Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.62:2379"} {"level":"info","ts":"2026-02-18T05:16:17.365092Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"be484d2748da72cd","local-member-id":"791a7e25b533cd26","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T05:16:17.36518Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T05:16:17.365249Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T05:16:17.409912Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-18T05:16:17.40993Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-02-18T05:16:28.819577Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674828208316023,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:16:29.319841Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674828208316023,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:16:29.533672Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.227884959s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T05:16:29.533981Z","caller":"traceutil/trace.go:171","msg":"trace[238933383] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"1.228241058s","start":"2026-02-18T05:16:28.305721Z","end":"2026-02-18T05:16:29.533962Z","steps":["trace[238933383] 'process raft request' (duration: 1.228115925s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:29.534471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:16:28.305712Z","time spent":"1.22831342s","remote":"127.0.0.1:55180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7021,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:16:29.539255Z","caller":"traceutil/trace.go:171","msg":"trace[1450754071] linearizableReadLoop","detail":"{readStateIndex:413; appliedIndex:412; }","duration":"1.22022574s","start":"2026-02-18T05:16:28.319019Z","end":"2026-02-18T05:16:29.539244Z","steps":["trace[1450754071] 'read index received' (duration: 1.216089782s)","trace[1450754071] 'applied index is now lower than readState.Index' (duration: 4.135568ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:16:29.539393Z","caller":"traceutil/trace.go:171","msg":"trace[2099018607] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"250.623909ms","start":"2026-02-18T05:16:29.288763Z","end":"2026-02-18T05:16:29.539387Z","steps":["trace[2099018607] 'process raft request' (duration: 250.437484ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:29.539507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.220482277s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:16:29.539527Z","caller":"traceutil/trace.go:171","msg":"trace[1144750882] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:405; }","duration":"1.220533197s","start":"2026-02-18T05:16:28.318987Z","end":"2026-02-18T05:16:29.53952Z","steps":["trace[1144750882] 'agreement among raft nodes before linearized reading' (duration: 1.220495726s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:29.539545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:16:28.318972Z","time spent":"1.220569279s","remote":"127.0.0.1:54980","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-18T05:16:29.539657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.735717ms","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-18T05:16:29.539674Z","caller":"traceutil/trace.go:171","msg":"trace[699647806] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:405; }","duration":"502.828499ms","start":"2026-02-18T05:16:29.03684Z","end":"2026-02-18T05:16:29.539668Z","steps":["trace[699647806] 'agreement among raft nodes before linearized reading' (duration: 502.796068ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:29.539689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:16:29.036822Z","time spent":"502.86334ms","remote":"127.0.0.1:55292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T05:16:29.539769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.662889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-18T05:16:29.539785Z","caller":"traceutil/trace.go:171","msg":"trace[126672921] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:405; }","duration":"397.763542ms","start":"2026-02-18T05:16:29.142016Z","end":"2026-02-18T05:16:29.53978Z","steps":["trace[126672921] 'agreement among raft nodes before linearized reading' (duration: 397.737241ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:29.5398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:16:29.142001Z","time spent":"397.794703ms","remote":"127.0.0.1:55292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T05:16:29.539879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.153787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-18T05:16:29.539895Z","caller":"traceutil/trace.go:171","msg":"trace[2107255287] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:405; }","duration":"455.192728ms","start":"2026-02-18T05:16:29.084697Z","end":"2026-02-18T05:16:29.53989Z","steps":["trace[2107255287] 'agreement among raft nodes before linearized reading' (duration: 455.165597ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:29.539909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:16:29.084683Z","time spent":"455.223078ms","remote":"127.0.0.1:55292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T05:16:29.539985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.110728ms","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-18T05:16:29.540003Z","caller":"traceutil/trace.go:171","msg":"trace[1172472486] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:405; }","duration":"500.17133ms","start":"2026-02-18T05:16:29.039826Z","end":"2026-02-18T05:16:29.539997Z","steps":["trace[1172472486] 'agreement among raft nodes before linearized reading' (duration: 500.144739ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:29.540017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:16:29.039801Z","time spent":"500.212571ms","remote":"127.0.0.1:55292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-18T05:16:50.334455Z","caller":"traceutil/trace.go:171","msg":"trace[1411774413] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"272.655303ms","start":"2026-02-18T05:16:50.061784Z","end":"2026-02-18T05:16:50.334439Z","steps":["trace[1411774413] 'process raft request' (duration: 272.368546ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:50.821715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.704681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.62\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-18T05:16:50.82182Z","caller":"traceutil/trace.go:171","msg":"trace[1350582419] range","detail":"{range_begin:/registry/masterleases/162.253.55.62; range_end:; response_count:1; response_revision:526; }","duration":"392.885266ms","start":"2026-02-18T05:16:50.428918Z","end":"2026-02-18T05:16:50.821803Z","steps":["trace[1350582419] 'range keys from in-memory index tree' (duration: 392.614828ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:50.821895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:16:50.428905Z","time spent":"392.979758ms","remote":"127.0.0.1:54998","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.62\" "} {"level":"info","ts":"2026-02-18T05:16:55.859266Z","caller":"traceutil/trace.go:171","msg":"trace[81309469] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"468.062843ms","start":"2026-02-18T05:16:55.391181Z","end":"2026-02-18T05:16:55.859243Z","steps":["trace[81309469] 'process raft request' (duration: 467.880269ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:55.859952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:16:55.391155Z","time spent":"468.190246ms","remote":"127.0.0.1:55180","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-18T05:16:56.583538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.256061ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-18T05:16:56.583633Z","caller":"traceutil/trace.go:171","msg":"trace[338239281] linearizableReadLoop","detail":"{readStateIndex:554; appliedIndex:553; }","duration":"880.479679ms","start":"2026-02-18T05:16:55.703137Z","end":"2026-02-18T05:16:56.583616Z","steps":["trace[338239281] 'read index received' (duration: 156.250161ms)","trace[338239281] 'applied index is now lower than readState.Index' (duration: 724.228078ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:16:56.583849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"880.714334ms","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-18T05:16:56.583879Z","caller":"traceutil/trace.go:171","msg":"trace[1148266011] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:540; }","duration":"880.773606ms","start":"2026-02-18T05:16:55.703095Z","end":"2026-02-18T05:16:56.583868Z","steps":["trace[1148266011] 'agreement among raft nodes before linearized reading' (duration: 880.636583ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:56.583916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:16:55.703078Z","time spent":"880.830897ms","remote":"127.0.0.1:55292","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-18T05:16:56.584054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"880.096109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-18T05:16:56.584097Z","caller":"traceutil/trace.go:171","msg":"trace[860417798] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:540; }","duration":"880.174961ms","start":"2026-02-18T05:16:55.703907Z","end":"2026-02-18T05:16:56.584082Z","steps":["trace[860417798] 'agreement among raft nodes before linearized reading' (duration: 880.066428ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:56.584131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:16:55.703887Z","time spent":"880.236232ms","remote":"127.0.0.1:55292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T05:16:56.584218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.506582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:16:56.584245Z","caller":"traceutil/trace.go:171","msg":"trace[264423652] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:540; }","duration":"261.556304ms","start":"2026-02-18T05:16:56.322679Z","end":"2026-02-18T05:16:56.584235Z","steps":["trace[264423652] 'agreement among raft nodes before linearized reading' (duration: 261.518363ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:56.584412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.456089ms","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-18T05:16:56.584449Z","caller":"traceutil/trace.go:171","msg":"trace[1351514795] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:540; }","duration":"877.514931ms","start":"2026-02-18T05:16:55.706925Z","end":"2026-02-18T05:16:56.58444Z","steps":["trace[1351514795] 'agreement among raft nodes before linearized reading' (duration: 877.423589ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:56.584477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:16:55.706915Z","time spent":"877.555322ms","remote":"127.0.0.1:55292","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-18T05:16:56.584516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.279724ms","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-18T05:16:56.584545Z","caller":"traceutil/trace.go:171","msg":"trace[1912442941] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:540; }","duration":"852.338386ms","start":"2026-02-18T05:16:55.732198Z","end":"2026-02-18T05:16:56.584537Z","steps":["trace[1912442941] 'agreement among raft nodes before linearized reading' (duration: 852.248733ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:56.58457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:16:55.732189Z","time spent":"852.375736ms","remote":"127.0.0.1:55292","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-18T05:16:58.878127Z","caller":"traceutil/trace.go:171","msg":"trace[2106799397] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:573; }","duration":"273.113485ms","start":"2026-02-18T05:16:58.604996Z","end":"2026-02-18T05:16:58.87811Z","steps":["trace[2106799397] 'read index received' (duration: 264.687356ms)","trace[2106799397] 'applied index is now lower than readState.Index' (duration: 8.425469ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:16:58.878259Z","caller":"traceutil/trace.go:171","msg":"trace[1080284396] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"332.650566ms","start":"2026-02-18T05:16:58.545601Z","end":"2026-02-18T05:16:58.878252Z","steps":["trace[1080284396] 'process raft request' (duration: 324.075252ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:58.878325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:16:58.545578Z","time spent":"332.705648ms","remote":"127.0.0.1:55012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T05:16:58.878533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.530656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-18T05:16:58.878554Z","caller":"traceutil/trace.go:171","msg":"trace[1445639988] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:560; }","duration":"273.581178ms","start":"2026-02-18T05:16:58.604967Z","end":"2026-02-18T05:16:58.878548Z","steps":["trace[1445639988] 'agreement among raft nodes before linearized reading' (duration: 273.462674ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:16:58.999032Z","caller":"traceutil/trace.go:171","msg":"trace[1591766848] linearizableReadLoop","detail":"{readStateIndex:576; appliedIndex:574; }","duration":"120.842628ms","start":"2026-02-18T05:16:58.878174Z","end":"2026-02-18T05:16:58.999016Z","steps":["trace[1591766848] 'read index received' (duration: 120.113319ms)","trace[1591766848] 'applied index is now lower than readState.Index' (duration: 728.589µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:16:58.999063Z","caller":"traceutil/trace.go:171","msg":"trace[1830918748] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"129.604747ms","start":"2026-02-18T05:16:58.869442Z","end":"2026-02-18T05:16:58.999047Z","steps":["trace[1830918748] 'process raft request' (duration: 129.548325ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:58.999207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.123507ms","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-18T05:16:58.999239Z","caller":"traceutil/trace.go:171","msg":"trace[2089023670] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:562; }","duration":"394.2042ms","start":"2026-02-18T05:16:58.605028Z","end":"2026-02-18T05:16:58.999232Z","steps":["trace[2089023670] 'agreement among raft nodes before linearized reading' (duration: 394.052446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:58.999262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:16:58.605008Z","time spent":"394.248241ms","remote":"127.0.0.1:55292","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":"info","ts":"2026-02-18T05:16:58.9993Z","caller":"traceutil/trace.go:171","msg":"trace[1321518026] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"329.916335ms","start":"2026-02-18T05:16:58.669372Z","end":"2026-02-18T05:16:58.999289Z","steps":["trace[1321518026] 'process raft request' (duration: 328.908579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:58.999355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:16:58.669348Z","time spent":"329.969257ms","remote":"127.0.0.1:55012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T05:16:58.999921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.111205ms","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-18T05:16:58.999946Z","caller":"traceutil/trace.go:171","msg":"trace[1004174523] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:562; }","duration":"392.151036ms","start":"2026-02-18T05:16:58.607788Z","end":"2026-02-18T05:16:58.999939Z","steps":["trace[1004174523] 'agreement among raft nodes before linearized reading' (duration: 392.052804ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:58.999964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:16:58.607783Z","time spent":"392.177498ms","remote":"127.0.0.1:55292","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-18T05:16:59.000145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.662089ms","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-18T05:16:59.000163Z","caller":"traceutil/trace.go:171","msg":"trace[2064198924] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:562; }","duration":"392.703541ms","start":"2026-02-18T05:16:58.607454Z","end":"2026-02-18T05:16:59.000158Z","steps":["trace[2064198924] 'agreement among raft nodes before linearized reading' (duration: 392.652869ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:59.000179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:16:58.607448Z","time spent":"392.727881ms","remote":"127.0.0.1:55292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-18T05:16:59.004181Z","caller":"traceutil/trace.go:171","msg":"trace[2112209696] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"107.137881ms","start":"2026-02-18T05:16:58.897034Z","end":"2026-02-18T05:16:59.004172Z","steps":["trace[2112209696] 'process raft request' (duration: 107.10784ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:16:59.004403Z","caller":"traceutil/trace.go:171","msg":"trace[232711948] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"111.816923ms","start":"2026-02-18T05:16:58.892579Z","end":"2026-02-18T05:16:59.004396Z","steps":["trace[232711948] 'process raft request' (duration: 111.477034ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:16:59.004512Z","caller":"traceutil/trace.go:171","msg":"trace[187127234] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"111.760792ms","start":"2026-02-18T05:16:58.892745Z","end":"2026-02-18T05:16:59.004506Z","steps":["trace[187127234] 'process raft request' (duration: 111.372932ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:17:10.623536Z","caller":"traceutil/trace.go:171","msg":"trace[625416464] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"171.876278ms","start":"2026-02-18T05:17:10.451646Z","end":"2026-02-18T05:17:10.623522Z","steps":["trace[625416464] 'process raft request' (duration: 170.996585ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:17:16.773018Z","caller":"traceutil/trace.go:171","msg":"trace[1660453342] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"119.834702ms","start":"2026-02-18T05:17:16.653144Z","end":"2026-02-18T05:17:16.772978Z","steps":["trace[1660453342] 'process raft request' (duration: 119.704209ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:17:29.026706Z","caller":"traceutil/trace.go:171","msg":"trace[989886507] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"136.327531ms","start":"2026-02-18T05:17:28.890363Z","end":"2026-02-18T05:17:29.02669Z","steps":["trace[989886507] 'process raft request' (duration: 136.238569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:17:30.341128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.043153847s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-18T05:17:30.341178Z","caller":"traceutil/trace.go:171","msg":"trace[206366202] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:777; }","duration":"1.04325267s","start":"2026-02-18T05:17:29.297915Z","end":"2026-02-18T05:17:30.341168Z","steps":["trace[206366202] 'range keys from in-memory index tree' (duration: 1.043035314s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:17:30.3412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:17:29.297896Z","time spent":"1.04329859s","remote":"127.0.0.1:55292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T05:17:30.34132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.041928625s","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-18T05:17:30.341341Z","caller":"traceutil/trace.go:171","msg":"trace[2116727647] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:777; }","duration":"1.041972175s","start":"2026-02-18T05:17:29.299362Z","end":"2026-02-18T05:17:30.341334Z","steps":["trace[2116727647] 'range keys from in-memory index tree' (duration: 1.041849683s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:17:30.341362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:17:29.299347Z","time spent":"1.042008677s","remote":"127.0.0.1:55292","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-18T05:17:30.341483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.041668158s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-18T05:17:30.341502Z","caller":"traceutil/trace.go:171","msg":"trace[1451594498] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:777; }","duration":"1.041695358s","start":"2026-02-18T05:17:29.299801Z","end":"2026-02-18T05:17:30.341496Z","steps":["trace[1451594498] 'range keys from in-memory index tree' (duration: 1.041612596s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:17:30.341521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:17:29.299794Z","time spent":"1.041722599s","remote":"127.0.0.1:55292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T05:17:30.341636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.037374665s","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-18T05:17:30.341655Z","caller":"traceutil/trace.go:171","msg":"trace[1694667954] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:777; }","duration":"1.037428716s","start":"2026-02-18T05:17:29.304219Z","end":"2026-02-18T05:17:30.341648Z","steps":["trace[1694667954] 'range keys from in-memory index tree' (duration: 1.037309642s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:17:30.341689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:17:29.304209Z","time spent":"1.037475507s","remote":"127.0.0.1:55292","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-18T05:17:30.854244Z","caller":"traceutil/trace.go:171","msg":"trace[619990874] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"501.973743ms","start":"2026-02-18T05:17:30.352243Z","end":"2026-02-18T05:17:30.854217Z","steps":["trace[619990874] 'process raft request' (duration: 501.85149ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:17:30.854392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:17:30.352232Z","time spent":"502.076205ms","remote":"127.0.0.1:55292","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-18T05:17:30.855358Z","caller":"traceutil/trace.go:171","msg":"trace[1574728461] linearizableReadLoop","detail":"{readStateIndex:803; appliedIndex:800; }","duration":"424.008129ms","start":"2026-02-18T05:17:30.43134Z","end":"2026-02-18T05:17:30.855348Z","steps":["trace[1574728461] 'read index received' (duration: 423.900586ms)","trace[1574728461] 'applied index is now lower than readState.Index' (duration: 107.033µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:17:30.855459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.114501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.62\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-18T05:17:30.855484Z","caller":"traceutil/trace.go:171","msg":"trace[1446239590] range","detail":"{range_begin:/registry/masterleases/162.253.55.62; range_end:; response_count:1; response_revision:781; }","duration":"424.169243ms","start":"2026-02-18T05:17:30.431308Z","end":"2026-02-18T05:17:30.855478Z","steps":["trace[1446239590] 'agreement among raft nodes before linearized reading' (duration: 424.077991ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:17:30.855503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:17:30.431295Z","time spent":"424.203144ms","remote":"127.0.0.1:54998","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.62\" "} {"level":"info","ts":"2026-02-18T05:17:30.855911Z","caller":"traceutil/trace.go:171","msg":"trace[1665499557] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"503.459591ms","start":"2026-02-18T05:17:30.352442Z","end":"2026-02-18T05:17:30.855902Z","steps":["trace[1665499557] 'process raft request' (duration: 502.824874ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:17:30.855961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:17:30.352437Z","time spent":"503.498902ms","remote":"127.0.0.1:55292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:17:30.856015Z","caller":"traceutil/trace.go:171","msg":"trace[1104724000] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"503.532513ms","start":"2026-02-18T05:17:30.352477Z","end":"2026-02-18T05:17:30.856009Z","steps":["trace[1104724000] 'process raft request' (duration: 502.830325ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:17:30.856047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:17:30.352473Z","time spent":"503.558335ms","remote":"127.0.0.1:55292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:17:30.856104Z","caller":"traceutil/trace.go:171","msg":"trace[464672742] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"503.594935ms","start":"2026-02-18T05:17:30.352505Z","end":"2026-02-18T05:17:30.8561Z","steps":["trace[464672742] 'process raft request' (duration: 502.824015ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:17:30.856137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:17:30.352501Z","time spent":"503.621966ms","remote":"127.0.0.1:55292","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-18T05:17:41.34662Z","caller":"traceutil/trace.go:171","msg":"trace[2128618512] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"198.422717ms","start":"2026-02-18T05:17:41.148182Z","end":"2026-02-18T05:17:41.346605Z","steps":["trace[2128618512] 'process raft request' (duration: 198.13151ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:17:41.826385Z","caller":"traceutil/trace.go:171","msg":"trace[480917627] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"192.11842ms","start":"2026-02-18T05:17:41.634244Z","end":"2026-02-18T05:17:41.826363Z","steps":["trace[480917627] 'process raft request' (duration: 162.831819ms)","trace[480917627] 'compare' (duration: 29.089076ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:17:43.321901Z","caller":"traceutil/trace.go:171","msg":"trace[1697346631] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"406.477617ms","start":"2026-02-18T05:17:42.915399Z","end":"2026-02-18T05:17:43.321877Z","steps":["trace[1697346631] 'process raft request' (duration: 406.282191ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:17:43.322056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:17:42.915361Z","time spent":"406.61368ms","remote":"127.0.0.1:55292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:17:43.322746Z","caller":"traceutil/trace.go:171","msg":"trace[1813264271] linearizableReadLoop","detail":"{readStateIndex:838; appliedIndex:838; }","duration":"392.988652ms","start":"2026-02-18T05:17:42.929744Z","end":"2026-02-18T05:17:43.322732Z","steps":["trace[1813264271] 'read index received' (duration: 392.978212ms)","trace[1813264271] 'applied index is now lower than readState.Index' (duration: 9.25µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:17:43.322959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.206078ms","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-18T05:17:43.323011Z","caller":"traceutil/trace.go:171","msg":"trace[700377519] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:813; }","duration":"393.273689ms","start":"2026-02-18T05:17:42.929728Z","end":"2026-02-18T05:17:43.323002Z","steps":["trace[700377519] 'agreement among raft nodes before linearized reading' (duration: 393.150476ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:17:43.323043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:17:42.92972Z","time spent":"393.31625ms","remote":"127.0.0.1:55292","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-18T05:17:43.323417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.440204ms","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-18T05:17:43.323456Z","caller":"traceutil/trace.go:171","msg":"trace[1094643858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:813; }","duration":"393.493075ms","start":"2026-02-18T05:17:42.929954Z","end":"2026-02-18T05:17:43.323447Z","steps":["trace[1094643858] 'agreement among raft nodes before linearized reading' (duration: 393.394162ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:17:43.323485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:17:42.929947Z","time spent":"393.531556ms","remote":"127.0.0.1:55292","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-18T05:17:43.323756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.29632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-18T05:17:43.323794Z","caller":"traceutil/trace.go:171","msg":"trace[1499589364] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:813; }","duration":"393.342761ms","start":"2026-02-18T05:17:42.930444Z","end":"2026-02-18T05:17:43.323786Z","steps":["trace[1499589364] 'agreement among raft nodes before linearized reading' (duration: 393.248378ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:17:43.323822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:17:42.930439Z","time spent":"393.377482ms","remote":"127.0.0.1:55292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-18T05:17:43.454288Z","caller":"traceutil/trace.go:171","msg":"trace[276132357] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"123.519154ms","start":"2026-02-18T05:17:43.33075Z","end":"2026-02-18T05:17:43.454269Z","steps":["trace[276132357] 'process raft request' (duration: 123.389301ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:17:43.456724Z","caller":"traceutil/trace.go:171","msg":"trace[620850763] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"124.280813ms","start":"2026-02-18T05:17:43.332435Z","end":"2026-02-18T05:17:43.456716Z","steps":["trace[620850763] 'process raft request' (duration: 124.053487ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:17:43.457077Z","caller":"traceutil/trace.go:171","msg":"trace[1360065792] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"124.586862ms","start":"2026-02-18T05:17:43.332483Z","end":"2026-02-18T05:17:43.457069Z","steps":["trace[1360065792] 'process raft request' (duration: 124.076368ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:17:43.457213Z","caller":"traceutil/trace.go:171","msg":"trace[1364141866] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"101.650027ms","start":"2026-02-18T05:17:43.355558Z","end":"2026-02-18T05:17:43.457208Z","steps":["trace[1364141866] 'process raft request' (duration: 101.024071ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:17:43.456979Z","caller":"traceutil/trace.go:171","msg":"trace[1210219465] linearizableReadLoop","detail":"{readStateIndex:841; appliedIndex:839; }","duration":"106.665509ms","start":"2026-02-18T05:17:43.350305Z","end":"2026-02-18T05:17:43.45697Z","steps":["trace[1210219465] 'read index received' (duration: 104.357018ms)","trace[1210219465] 'applied index is now lower than readState.Index' (duration: 2.307921ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:17:43.457466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.151782ms","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-18T05:17:43.457493Z","caller":"traceutil/trace.go:171","msg":"trace[648883467] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:817; }","duration":"107.213384ms","start":"2026-02-18T05:17:43.350273Z","end":"2026-02-18T05:17:43.457487Z","steps":["trace[648883467] 'agreement among raft nodes before linearized reading' (duration: 107.08601ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:17:44.314927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.432617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T05:17:44.315002Z","caller":"traceutil/trace.go:171","msg":"trace[2070457895] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:818; }","duration":"392.52086ms","start":"2026-02-18T05:17:43.922463Z","end":"2026-02-18T05:17:44.314984Z","steps":["trace[2070457895] 'count revisions from in-memory index tree' (duration: 392.362645ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:17:44.315042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:17:43.922419Z","time spent":"392.613252ms","remote":"127.0.0.1:55484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-02-18T05:17:49.6583Z","caller":"traceutil/trace.go:171","msg":"trace[1602344276] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"145.349209ms","start":"2026-02-18T05:17:49.5129Z","end":"2026-02-18T05:17:49.658249Z","steps":["trace[1602344276] 'process raft request' (duration: 145.322708ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:17:49.658717Z","caller":"traceutil/trace.go:171","msg":"trace[1196676392] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"148.834801ms","start":"2026-02-18T05:17:49.509874Z","end":"2026-02-18T05:17:49.658709Z","steps":["trace[1196676392] 'process raft request' (duration: 148.03283ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:17:49.659306Z","caller":"traceutil/trace.go:171","msg":"trace[620653597] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"147.792613ms","start":"2026-02-18T05:17:49.511504Z","end":"2026-02-18T05:17:49.659297Z","steps":["trace[620653597] 'process raft request' (duration: 146.685544ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:17:55.33052Z","caller":"traceutil/trace.go:171","msg":"trace[1870157821] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"114.460044ms","start":"2026-02-18T05:17:55.216046Z","end":"2026-02-18T05:17:55.330506Z","steps":["trace[1870157821] 'process raft request' (duration: 114.312541ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:17:55.388691Z","caller":"traceutil/trace.go:171","msg":"trace[1456720589] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"130.169739ms","start":"2026-02-18T05:17:55.258496Z","end":"2026-02-18T05:17:55.388666Z","steps":["trace[1456720589] 'process raft request' (duration: 129.876681ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:17:55.388723Z","caller":"traceutil/trace.go:171","msg":"trace[1931908600] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"130.2051ms","start":"2026-02-18T05:17:55.258501Z","end":"2026-02-18T05:17:55.388706Z","steps":["trace[1931908600] 'process raft request' (duration: 129.965003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:17:58.225378Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674828208317086,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T05:17:58.468483Z","caller":"traceutil/trace.go:171","msg":"trace[1850659801] linearizableReadLoop","detail":"{readStateIndex:935; appliedIndex:934; }","duration":"743.533135ms","start":"2026-02-18T05:17:57.724933Z","end":"2026-02-18T05:17:58.468466Z","steps":["trace[1850659801] 'read index received' (duration: 743.445543ms)","trace[1850659801] 'applied index is now lower than readState.Index' (duration: 86.992µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:17:58.46856Z","caller":"traceutil/trace.go:171","msg":"trace[314335474] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"895.96489ms","start":"2026-02-18T05:17:57.572591Z","end":"2026-02-18T05:17:58.468555Z","steps":["trace[314335474] 'process raft request' (duration: 895.774515ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:17:58.468636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:17:57.572579Z","time spent":"895.998061ms","remote":"127.0.0.1:55292","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-18T05:17:58.468767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.729001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-18T05:17:58.46881Z","caller":"traceutil/trace.go:171","msg":"trace[1069441553] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:904; }","duration":"740.789462ms","start":"2026-02-18T05:17:57.72801Z","end":"2026-02-18T05:17:58.468799Z","steps":["trace[1069441553] 'agreement among raft nodes before linearized reading' (duration: 740.668139ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:17:58.468888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.096697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-18T05:17:58.468897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:17:57.728005Z","time spent":"740.887075ms","remote":"127.0.0.1:55292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-18T05:17:58.468909Z","caller":"traceutil/trace.go:171","msg":"trace[2049828853] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:904; }","duration":"149.141909ms","start":"2026-02-18T05:17:58.319761Z","end":"2026-02-18T05:17:58.468903Z","steps":["trace[2049828853] 'agreement among raft nodes before linearized reading' (duration: 149.089157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:17:58.469025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.523884ms","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-18T05:17:58.469039Z","caller":"traceutil/trace.go:171","msg":"trace[654080796] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:904; }","duration":"716.545334ms","start":"2026-02-18T05:17:57.752489Z","end":"2026-02-18T05:17:58.469035Z","steps":["trace[654080796] 'agreement among raft nodes before linearized reading' (duration: 716.502763ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:17:58.469074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:17:57.752485Z","time spent":"716.585915ms","remote":"127.0.0.1:55292","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-18T05:17:58.469052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.118831ms","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-18T05:17:58.469153Z","caller":"traceutil/trace.go:171","msg":"trace[1755429423] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:904; }","duration":"744.240533ms","start":"2026-02-18T05:17:57.724907Z","end":"2026-02-18T05:17:58.469148Z","steps":["trace[1755429423] 'agreement among raft nodes before linearized reading' (duration: 744.086269ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:17:58.469155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.673657ms","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-18T05:17:58.46917Z","caller":"traceutil/trace.go:171","msg":"trace[902500360] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:904; }","duration":"716.701649ms","start":"2026-02-18T05:17:57.752464Z","end":"2026-02-18T05:17:58.469166Z","steps":["trace[902500360] 'agreement among raft nodes before linearized reading' (duration: 716.659968ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:17:58.469186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:17:57.752457Z","time spent":"716.725589ms","remote":"127.0.0.1:55292","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-18T05:17:58.469172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:17:57.724897Z","time spent":"744.267924ms","remote":"127.0.0.1:55292","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-18T05:17:59.46222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.391235ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:17:59.46234Z","caller":"traceutil/trace.go:171","msg":"trace[454363399] linearizableReadLoop","detail":"{readStateIndex:939; appliedIndex:935; }","duration":"842.688517ms","start":"2026-02-18T05:17:58.619642Z","end":"2026-02-18T05:17:59.462331Z","steps":["trace[454363399] 'read index received' (duration: 409.098866ms)","trace[454363399] 'applied index is now lower than readState.Index' (duration: 433.589081ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:17:59.4624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"842.755978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:17:59.462414Z","caller":"traceutil/trace.go:171","msg":"trace[1778328548] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:908; }","duration":"842.771839ms","start":"2026-02-18T05:17:58.619636Z","end":"2026-02-18T05:17:59.462408Z","steps":["trace[1778328548] 'agreement among raft nodes before linearized reading' (duration: 842.719587ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:17:59.462431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:17:58.619583Z","time spent":"842.84355ms","remote":"127.0.0.1:55344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-02-18T05:17:59.462577Z","caller":"traceutil/trace.go:171","msg":"trace[377706562] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"989.299158ms","start":"2026-02-18T05:17:58.473272Z","end":"2026-02-18T05:17:59.462571Z","steps":["trace[377706562] 'process raft request' (duration: 555.504272ms)","trace[377706562] 'compare' (duration: 433.331364ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:17:59.462604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:17:58.473262Z","time spent":"989.327529ms","remote":"127.0.0.1:55292","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-18T05:17:59.462665Z","caller":"traceutil/trace.go:171","msg":"trace[1424677910] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"988.092036ms","start":"2026-02-18T05:17:58.474567Z","end":"2026-02-18T05:17:59.462659Z","steps":["trace[1424677910] 'process raft request' (duration: 987.709986ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:17:59.462688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:17:58.474553Z","time spent":"988.121287ms","remote":"127.0.0.1:55292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:17:59.462746Z","caller":"traceutil/trace.go:171","msg":"trace[1195795313] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"986.30223ms","start":"2026-02-18T05:17:58.47644Z","end":"2026-02-18T05:17:59.462742Z","steps":["trace[1195795313] 'process raft request' (duration: 985.861958ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:17:59.462767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:17:58.476433Z","time spent":"986.32208ms","remote":"127.0.0.1:55292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:17:59.462823Z","caller":"traceutil/trace.go:171","msg":"trace[2121389637] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"974.861668ms","start":"2026-02-18T05:17:58.487958Z","end":"2026-02-18T05:17:59.46282Z","steps":["trace[2121389637] 'process raft request' (duration: 974.357234ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:17:59.462845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:17:58.487944Z","time spent":"974.888579ms","remote":"127.0.0.1:55292","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-18T05:18:00.902357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.670757ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:18:00.902479Z","caller":"traceutil/trace.go:171","msg":"trace[1696281995] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:908; }","duration":"145.816961ms","start":"2026-02-18T05:18:00.756644Z","end":"2026-02-18T05:18:00.902461Z","steps":["trace[1696281995] 'range keys from in-memory index tree' (duration: 145.652267ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:18:00.903002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.555026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:18:00.903033Z","caller":"traceutil/trace.go:171","msg":"trace[1801917460] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:908; }","duration":"579.624848ms","start":"2026-02-18T05:18:00.323399Z","end":"2026-02-18T05:18:00.903024Z","steps":["trace[1801917460] 'range keys from in-memory index tree' (duration: 579.499094ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:18:00.903064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:18:00.323382Z","time spent":"579.675059ms","remote":"127.0.0.1:54980","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-18T05:18:00.903299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.163714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.62\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-18T05:18:00.903325Z","caller":"traceutil/trace.go:171","msg":"trace[1518864543] range","detail":"{range_begin:/registry/masterleases/162.253.55.62; range_end:; response_count:1; response_revision:908; }","duration":"470.210276ms","start":"2026-02-18T05:18:00.433106Z","end":"2026-02-18T05:18:00.903316Z","steps":["trace[1518864543] 'range keys from in-memory index tree' (duration: 470.045671ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:18:00.90335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:18:00.433095Z","time spent":"470.248476ms","remote":"127.0.0.1:54998","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.62\" "} {"level":"warn","ts":"2026-02-18T05:18:00.903514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.313985ms","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-18T05:18:00.903539Z","caller":"traceutil/trace.go:171","msg":"trace[1983983290] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:908; }","duration":"430.374396ms","start":"2026-02-18T05:18:00.473157Z","end":"2026-02-18T05:18:00.903532Z","steps":["trace[1983983290] 'range keys from in-memory index tree' (duration: 430.235633ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:18:00.903564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:18:00.473145Z","time spent":"430.413087ms","remote":"127.0.0.1:55292","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":"info","ts":"2026-02-18T05:18:01.300549Z","caller":"traceutil/trace.go:171","msg":"trace[126623337] linearizableReadLoop","detail":"{readStateIndex:940; appliedIndex:939; }","duration":"392.987352ms","start":"2026-02-18T05:18:00.907541Z","end":"2026-02-18T05:18:01.300529Z","steps":["trace[126623337] 'read index received' (duration: 392.866498ms)","trace[126623337] 'applied index is now lower than readState.Index' (duration: 120.154µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:18:01.300614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:18:00.907377Z","time spent":"393.235618ms","remote":"127.0.0.1:54998","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-18T05:18:01.300843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.28895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:18:01.30088Z","caller":"traceutil/trace.go:171","msg":"trace[338043760] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:908; }","duration":"393.33077ms","start":"2026-02-18T05:18:00.907526Z","end":"2026-02-18T05:18:01.300857Z","steps":["trace[338043760] 'agreement among raft nodes before linearized reading' (duration: 393.286259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:18:01.300896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:18:00.907519Z","time spent":"393.373672ms","remote":"127.0.0.1:54972","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-18T05:18:01.800677Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674828208317103,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:18:02.301337Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674828208317103,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:18:02.50083Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.200526022s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T05:18:02.501191Z","caller":"traceutil/trace.go:171","msg":"trace[374184979] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"1.591515271s","start":"2026-02-18T05:18:00.909662Z","end":"2026-02-18T05:18:02.501177Z","steps":["trace[374184979] 'process raft request' (duration: 1.591398388s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:18:02.501432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:18:00.909642Z","time spent":"1.591725997s","remote":"127.0.0.1:55292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:18:02.504824Z","caller":"traceutil/trace.go:171","msg":"trace[882432802] linearizableReadLoop","detail":"{readStateIndex:942; appliedIndex:940; }","duration":"1.20422351s","start":"2026-02-18T05:18:01.300591Z","end":"2026-02-18T05:18:02.504815Z","steps":["trace[882432802] 'read index received' (duration: 1.200522082s)","trace[882432802] 'applied index is now lower than readState.Index' (duration: 3.700608ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:18:02.504909Z","caller":"traceutil/trace.go:171","msg":"trace[1457286341] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"1.202827773s","start":"2026-02-18T05:18:01.302076Z","end":"2026-02-18T05:18:02.504904Z","steps":["trace[1457286341] 'process raft request' (duration: 1.202643118s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:18:02.504946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:18:01.30205Z","time spent":"1.202872524s","remote":"127.0.0.1:54998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:18:02.505136Z","caller":"traceutil/trace.go:171","msg":"trace[897931293] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"161.199566ms","start":"2026-02-18T05:18:02.343928Z","end":"2026-02-18T05:18:02.505128Z","steps":["trace[897931293] 'process raft request' (duration: 160.847067ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:18:02.505227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.316384943s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T05:18:02.505243Z","caller":"traceutil/trace.go:171","msg":"trace[1828996098] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:911; }","duration":"1.316431965s","start":"2026-02-18T05:18:01.188806Z","end":"2026-02-18T05:18:02.505238Z","steps":["trace[1828996098] 'agreement among raft nodes before linearized reading' (duration: 1.316398994s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:18:02.505258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:18:01.188791Z","time spent":"1.316462736s","remote":"127.0.0.1:55454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-02-18T05:18:02.505972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.038640588s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-18T05:18:02.505996Z","caller":"traceutil/trace.go:171","msg":"trace[1299691350] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:911; }","duration":"1.03868675s","start":"2026-02-18T05:18:01.467304Z","end":"2026-02-18T05:18:02.50599Z","steps":["trace[1299691350] 'agreement among raft nodes before linearized reading' (duration: 1.038018902s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:18:02.506011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:18:01.46729Z","time spent":"1.03871732s","remote":"127.0.0.1:55292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T05:18:02.506437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.661277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:18:02.506459Z","caller":"traceutil/trace.go:171","msg":"trace[12754404] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:911; }","duration":"186.710418ms","start":"2026-02-18T05:18:02.319743Z","end":"2026-02-18T05:18:02.506454Z","steps":["trace[12754404] 'agreement among raft nodes before linearized reading' (duration: 186.675367ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:18:02.50918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.035956138s","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-18T05:18:02.509205Z","caller":"traceutil/trace.go:171","msg":"trace[683578237] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:911; }","duration":"1.035994148s","start":"2026-02-18T05:18:01.473206Z","end":"2026-02-18T05:18:02.5092Z","steps":["trace[683578237] 'agreement among raft nodes before linearized reading' (duration: 1.035920606s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:18:02.50923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:18:01.473187Z","time spent":"1.03603072s","remote":"127.0.0.1:55292","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-18T05:18:02.509375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.041167865s","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-18T05:18:02.509395Z","caller":"traceutil/trace.go:171","msg":"trace[1965317473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:911; }","duration":"1.041201985s","start":"2026-02-18T05:18:01.468189Z","end":"2026-02-18T05:18:02.509391Z","steps":["trace[1965317473] 'agreement among raft nodes before linearized reading' (duration: 1.041151794s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:18:02.509413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:18:01.468182Z","time spent":"1.041228007s","remote":"127.0.0.1:55292","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-18T05:18:02.50954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.042050598s","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-18T05:18:02.509559Z","caller":"traceutil/trace.go:171","msg":"trace[1032175555] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:911; }","duration":"1.04210963s","start":"2026-02-18T05:18:01.467446Z","end":"2026-02-18T05:18:02.509555Z","steps":["trace[1032175555] 'agreement among raft nodes before linearized reading' (duration: 1.042059628s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:18:02.509573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:18:01.467429Z","time spent":"1.04214078s","remote":"127.0.0.1:55292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-18T05:18:06.62227Z","caller":"traceutil/trace.go:171","msg":"trace[347731136] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"105.794107ms","start":"2026-02-18T05:18:06.516453Z","end":"2026-02-18T05:18:06.622247Z","steps":["trace[347731136] 'process raft request' (duration: 105.52555ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:18:06.781771Z","caller":"traceutil/trace.go:171","msg":"trace[400572135] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"136.047483ms","start":"2026-02-18T05:18:06.645707Z","end":"2026-02-18T05:18:06.781755Z","steps":["trace[400572135] 'process raft request' (duration: 136.024103ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:18:06.781997Z","caller":"traceutil/trace.go:171","msg":"trace[588250650] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"144.584408ms","start":"2026-02-18T05:18:06.637405Z","end":"2026-02-18T05:18:06.78199Z","steps":["trace[588250650] 'process raft request' (duration: 71.851203ms)","trace[588250650] 'compare' (duration: 72.223232ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:18:06.782029Z","caller":"traceutil/trace.go:171","msg":"trace[1512920614] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"137.229634ms","start":"2026-02-18T05:18:06.644778Z","end":"2026-02-18T05:18:06.782007Z","steps":["trace[1512920614] 'process raft request' (duration: 136.933266ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:18:06.782211Z","caller":"traceutil/trace.go:171","msg":"trace[2074861104] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"144.66527ms","start":"2026-02-18T05:18:06.637539Z","end":"2026-02-18T05:18:06.782204Z","steps":["trace[2074861104] 'process raft request' (duration: 144.144526ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:18:14.101853Z","caller":"traceutil/trace.go:171","msg":"trace[138377035] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"140.395349ms","start":"2026-02-18T05:18:13.961444Z","end":"2026-02-18T05:18:14.10184Z","steps":["trace[138377035] 'process raft request' (duration: 140.175283ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:18:15.176563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.186653ms","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-18T05:18:15.17661Z","caller":"traceutil/trace.go:171","msg":"trace[999170197] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:945; }","duration":"282.271405ms","start":"2026-02-18T05:18:14.894328Z","end":"2026-02-18T05:18:15.1766Z","steps":["trace[999170197] 'range keys from in-memory index tree' (duration: 282.07766ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:18:15.176735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.173813ms","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":"warn","ts":"2026-02-18T05:18:15.176809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.85962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-18T05:18:15.176826Z","caller":"traceutil/trace.go:171","msg":"trace[2140333007] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:945; }","duration":"345.896981ms","start":"2026-02-18T05:18:14.830924Z","end":"2026-02-18T05:18:15.176821Z","steps":["trace[2140333007] 'range keys from in-memory index tree' (duration: 345.802129ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:18:15.17684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:18:14.83091Z","time spent":"345.925532ms","remote":"127.0.0.1:55292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T05:18:15.176944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.85018ms","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-18T05:18:15.176957Z","caller":"traceutil/trace.go:171","msg":"trace[1178165427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:945; }","duration":"345.87128ms","start":"2026-02-18T05:18:14.831082Z","end":"2026-02-18T05:18:15.176953Z","steps":["trace[1178165427] 'range keys from in-memory index tree' (duration: 345.816199ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:18:15.17697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:18:14.831078Z","time spent":"345.887971ms","remote":"127.0.0.1:55292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-18T05:18:15.176797Z","caller":"traceutil/trace.go:171","msg":"trace[1448597821] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:945; }","duration":"282.249205ms","start":"2026-02-18T05:18:14.894532Z","end":"2026-02-18T05:18:15.176781Z","steps":["trace[1448597821] 'range keys from in-memory index tree' (duration: 282.068149ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:18:19.418285Z","caller":"traceutil/trace.go:171","msg":"trace[533647589] linearizableReadLoop","detail":"{readStateIndex:991; appliedIndex:990; }","duration":"162.915751ms","start":"2026-02-18T05:18:19.255346Z","end":"2026-02-18T05:18:19.418262Z","steps":["trace[533647589] 'read index received' (duration: 162.794518ms)","trace[533647589] 'applied index is now lower than readState.Index' (duration: 120.263µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:18:19.418402Z","caller":"traceutil/trace.go:171","msg":"trace[685253732] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"193.915028ms","start":"2026-02-18T05:18:19.224478Z","end":"2026-02-18T05:18:19.418393Z","steps":["trace[685253732] 'process raft request' (duration: 193.60496ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:18:19.418609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.665295ms","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-18T05:18:19.418676Z","caller":"traceutil/trace.go:171","msg":"trace[1597201868] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:956; }","duration":"162.760338ms","start":"2026-02-18T05:18:19.255896Z","end":"2026-02-18T05:18:19.418657Z","steps":["trace[1597201868] 'agreement among raft nodes before linearized reading' (duration: 162.577983ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:18:19.418766Z","caller":"traceutil/trace.go:171","msg":"trace[2084441351] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:956; }","duration":"100.001124ms","start":"2026-02-18T05:18:19.318754Z","end":"2026-02-18T05:18:19.418755Z","steps":["trace[2084441351] 'agreement among raft nodes before linearized reading' (duration: 99.976084ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:18:19.418922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.727667ms","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-18T05:18:19.418951Z","caller":"traceutil/trace.go:171","msg":"trace[1730774071] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:956; }","duration":"162.778087ms","start":"2026-02-18T05:18:19.256164Z","end":"2026-02-18T05:18:19.418942Z","steps":["trace[1730774071] 'agreement among raft nodes before linearized reading' (duration: 162.695175ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:18:19.419109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.744873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-18T05:18:19.419147Z","caller":"traceutil/trace.go:171","msg":"trace[1168013349] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:956; }","duration":"163.810245ms","start":"2026-02-18T05:18:19.255324Z","end":"2026-02-18T05:18:19.419135Z","steps":["trace[1168013349] 'agreement among raft nodes before linearized reading' (duration: 163.578779ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:18:19.960673Z","caller":"traceutil/trace.go:171","msg":"trace[1387235720] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"536.919992ms","start":"2026-02-18T05:18:19.423731Z","end":"2026-02-18T05:18:19.960651Z","steps":["trace[1387235720] 'process raft request' (duration: 536.816869ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:18:19.961013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:18:19.423718Z","time spent":"537.025075ms","remote":"127.0.0.1:55292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:18:19.964552Z","caller":"traceutil/trace.go:171","msg":"trace[1646498300] linearizableReadLoop","detail":"{readStateIndex:994; appliedIndex:992; }","duration":"414.95269ms","start":"2026-02-18T05:18:19.549573Z","end":"2026-02-18T05:18:19.964526Z","steps":["trace[1646498300] 'read index received' (duration: 412.080444ms)","trace[1646498300] 'applied index is now lower than readState.Index' (duration: 2.871356ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:18:19.964717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.126984ms","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-18T05:18:19.964746Z","caller":"traceutil/trace.go:171","msg":"trace[554934974] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:959; }","duration":"415.214117ms","start":"2026-02-18T05:18:19.549524Z","end":"2026-02-18T05:18:19.964738Z","steps":["trace[554934974] 'agreement among raft nodes before linearized reading' (duration: 415.084993ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:18:19.964778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:18:19.549503Z","time spent":"415.267009ms","remote":"127.0.0.1:55138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"info","ts":"2026-02-18T05:18:19.965097Z","caller":"traceutil/trace.go:171","msg":"trace[2053217548] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"518.751764ms","start":"2026-02-18T05:18:19.446335Z","end":"2026-02-18T05:18:19.965086Z","steps":["trace[2053217548] 'process raft request' (duration: 518.055176ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:18:19.965169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:18:19.446317Z","time spent":"518.805465ms","remote":"127.0.0.1:55292","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-18T05:18:19.965317Z","caller":"traceutil/trace.go:171","msg":"trace[1271819206] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"518.901758ms","start":"2026-02-18T05:18:19.446406Z","end":"2026-02-18T05:18:19.965308Z","steps":["trace[1271819206] 'process raft request' (duration: 518.084486ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:18:19.965368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:18:19.446389Z","time spent":"518.95017ms","remote":"127.0.0.1:55292","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-18T05:18:26.89656Z","caller":"traceutil/trace.go:171","msg":"trace[570390501] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"143.316095ms","start":"2026-02-18T05:18:26.75322Z","end":"2026-02-18T05:18:26.896536Z","steps":["trace[570390501] 'process raft request' (duration: 142.945706ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:18:28.507903Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674828208317331,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:18:29.008033Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674828208317331,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:18:29.319205Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.319730241s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T05:18:29.319451Z","caller":"traceutil/trace.go:171","msg":"trace[758058220] linearizableReadLoop","detail":"{readStateIndex:1038; appliedIndex:1037; }","duration":"1.312327456s","start":"2026-02-18T05:18:28.007089Z","end":"2026-02-18T05:18:29.319417Z","steps":["trace[758058220] 'read index received' (duration: 1.312200502s)","trace[758058220] 'applied index is now lower than readState.Index' (duration: 126.334µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:18:29.319515Z","caller":"traceutil/trace.go:171","msg":"trace[1497877818] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"1.320090911s","start":"2026-02-18T05:18:27.999409Z","end":"2026-02-18T05:18:29.319499Z","steps":["trace[1497877818] 'process raft request' (duration: 1.319923386s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:18:29.319592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.3124913s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-18T05:18:29.319602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:18:27.9994Z","time spent":"1.320146522s","remote":"127.0.0.1:55292","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-18T05:18:29.319616Z","caller":"traceutil/trace.go:171","msg":"trace[470144181] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1001; }","duration":"1.312530352s","start":"2026-02-18T05:18:28.007078Z","end":"2026-02-18T05:18:29.319608Z","steps":["trace[470144181] 'agreement among raft nodes before linearized reading' (duration: 1.312418699s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:18:29.319637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:18:28.007072Z","time spent":"1.312559132s","remote":"127.0.0.1:55292","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-18T05:18:29.319726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.303850023s","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-18T05:18:29.319741Z","caller":"traceutil/trace.go:171","msg":"trace[854867986] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1001; }","duration":"1.303875273s","start":"2026-02-18T05:18:28.015861Z","end":"2026-02-18T05:18:29.319736Z","steps":["trace[854867986] 'agreement among raft nodes before linearized reading' (duration: 1.303827402s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:18:29.319756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:18:28.015856Z","time spent":"1.303896483s","remote":"127.0.0.1:55292","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-18T05:18:29.319835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.214375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T05:18:29.319852Z","caller":"traceutil/trace.go:171","msg":"trace[1301895638] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1001; }","duration":"104.235316ms","start":"2026-02-18T05:18:29.215612Z","end":"2026-02-18T05:18:29.319848Z","steps":["trace[1301895638] 'agreement among raft nodes before linearized reading' (duration: 104.182555ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:18:29.319995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.581136ms","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-18T05:18:29.320013Z","caller":"traceutil/trace.go:171","msg":"trace[379791452] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1001; }","duration":"418.633947ms","start":"2026-02-18T05:18:28.901373Z","end":"2026-02-18T05:18:29.320007Z","steps":["trace[379791452] 'agreement among raft nodes before linearized reading' (duration: 418.584996ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:18:29.320028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:18:28.901361Z","time spent":"418.663438ms","remote":"127.0.0.1:55292","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-18T05:18:29.320075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.111813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:18:29.320093Z","caller":"traceutil/trace.go:171","msg":"trace[26171772] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1001; }","duration":"470.149134ms","start":"2026-02-18T05:18:28.849939Z","end":"2026-02-18T05:18:29.320088Z","steps":["trace[26171772] 'agreement among raft nodes before linearized reading' (duration: 470.122534ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:18:29.320108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:18:28.849929Z","time spent":"470.174605ms","remote":"127.0.0.1:55274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-02-18T05:18:29.320217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.248676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T05:18:29.320232Z","caller":"traceutil/trace.go:171","msg":"trace[704052895] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1001; }","duration":"473.304107ms","start":"2026-02-18T05:18:28.846924Z","end":"2026-02-18T05:18:29.320228Z","steps":["trace[704052895] 'agreement among raft nodes before linearized reading' (duration: 473.273846ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:18:29.320249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:18:28.846903Z","time spent":"473.341398ms","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-18T05:18:29.320279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.02043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:18:29.320306Z","caller":"traceutil/trace.go:171","msg":"trace[1032081422] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1001; }","duration":"896.073342ms","start":"2026-02-18T05:18:28.424223Z","end":"2026-02-18T05:18:29.320297Z","steps":["trace[1032081422] 'agreement among raft nodes before linearized reading' (duration: 896.035341ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:18:29.320325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:18:28.424208Z","time spent":"896.113212ms","remote":"127.0.0.1:54980","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-18T05:18:29.519138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.391629ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:18:29.519731Z","caller":"traceutil/trace.go:171","msg":"trace[1956446330] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"195.1451ms","start":"2026-02-18T05:18:29.324566Z","end":"2026-02-18T05:18:29.519711Z","steps":["trace[1956446330] 'process raft request' (duration: 79.103244ms)","trace[1956446330] 'compare' (duration: 115.163413ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:18:29.520159Z","caller":"traceutil/trace.go:171","msg":"trace[1773095059] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"192.831539ms","start":"2026-02-18T05:18:29.327321Z","end":"2026-02-18T05:18:29.520153Z","steps":["trace[1773095059] 'process raft request' (duration: 192.11842ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:18:29.520818Z","caller":"traceutil/trace.go:171","msg":"trace[2007593100] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"192.411588ms","start":"2026-02-18T05:18:29.328391Z","end":"2026-02-18T05:18:29.520803Z","steps":["trace[2007593100] 'process raft request' (duration: 191.291689ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:18:30.005868Z","caller":"traceutil/trace.go:171","msg":"trace[1218981639] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"164.629157ms","start":"2026-02-18T05:18:29.841073Z","end":"2026-02-18T05:18:30.005702Z","steps":["trace[1218981639] 'process raft request' (duration: 157.316414ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:18:30.849271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:18:30.436275Z","time spent":"412.993028ms","remote":"127.0.0.1:54998","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-18T05:18:30.983748Z","caller":"traceutil/trace.go:171","msg":"trace[1934303017] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"133.376963ms","start":"2026-02-18T05:18:30.850343Z","end":"2026-02-18T05:18:30.98372Z","steps":["trace[1934303017] 'process raft request' (duration: 40.500906ms)","trace[1934303017] 'compare' (duration: 92.723953ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:18:31.467355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.2944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-18T05:18:31.467426Z","caller":"traceutil/trace.go:171","msg":"trace[1843773817] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1006; }","duration":"114.398813ms","start":"2026-02-18T05:18:31.353009Z","end":"2026-02-18T05:18:31.467408Z","steps":["trace[1843773817] 'range keys from in-memory index tree' (duration: 114.160817ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:18:31.86738Z","caller":"traceutil/trace.go:171","msg":"trace[1797661221] linearizableReadLoop","detail":"{readStateIndex:1045; appliedIndex:1044; }","duration":"342.250785ms","start":"2026-02-18T05:18:31.525103Z","end":"2026-02-18T05:18:31.867354Z","steps":["trace[1797661221] 'read index received' (duration: 342.015269ms)","trace[1797661221] 'applied index is now lower than readState.Index' (duration: 234.646µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:18:31.868298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.174879ms","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-18T05:18:31.868333Z","caller":"traceutil/trace.go:171","msg":"trace[725035074] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1007; }","duration":"343.270372ms","start":"2026-02-18T05:18:31.525056Z","end":"2026-02-18T05:18:31.868326Z","steps":["trace[725035074] 'agreement among raft nodes before linearized reading' (duration: 342.573333ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:18:31.868361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:18:31.525036Z","time spent":"343.319493ms","remote":"127.0.0.1:55292","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":"info","ts":"2026-02-18T05:18:31.868533Z","caller":"traceutil/trace.go:171","msg":"trace[309864803] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"396.833353ms","start":"2026-02-18T05:18:31.471693Z","end":"2026-02-18T05:18:31.868526Z","steps":["trace[309864803] 'process raft request' (duration: 395.504788ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:18:31.868613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:18:31.471674Z","time spent":"396.878964ms","remote":"127.0.0.1:55292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:18:32.04536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.604246ms","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-18T05:18:32.045418Z","caller":"traceutil/trace.go:171","msg":"trace[1116020729] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1007; }","duration":"519.686579ms","start":"2026-02-18T05:18:31.525716Z","end":"2026-02-18T05:18:32.045402Z","steps":["trace[1116020729] 'agreement among raft nodes before linearized reading' (duration: 343.363724ms)","trace[1116020729] 'range keys from in-memory index tree' (duration: 176.215471ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:18:32.045447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:18:31.525707Z","time spent":"519.73403ms","remote":"127.0.0.1:55292","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-18T05:18:32.045805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.059266ms","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-18T05:18:32.045837Z","caller":"traceutil/trace.go:171","msg":"trace[1014907108] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1007; }","duration":"425.123468ms","start":"2026-02-18T05:18:31.620705Z","end":"2026-02-18T05:18:32.045829Z","steps":["trace[1014907108] 'agreement among raft nodes before linearized reading' (duration: 248.381963ms)","trace[1014907108] 'range keys from in-memory index tree' (duration: 176.16673ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:18:32.04595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:18:31.62069Z","time spent":"425.252091ms","remote":"127.0.0.1:55292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-18T05:18:32.098895Z","caller":"traceutil/trace.go:171","msg":"trace[844225178] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"226.228139ms","start":"2026-02-18T05:18:31.872652Z","end":"2026-02-18T05:18:32.09888Z","steps":["trace[844225178] 'process raft request' (duration: 226.138477ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:18:33.37682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.179193ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:18:33.376955Z","caller":"traceutil/trace.go:171","msg":"trace[1301547836] linearizableReadLoop","detail":"{readStateIndex:1050; appliedIndex:1049; }","duration":"963.699054ms","start":"2026-02-18T05:18:32.413239Z","end":"2026-02-18T05:18:33.376939Z","steps":["trace[1301547836] 'read index received' (duration: 77.288876ms)","trace[1301547836] 'applied index is now lower than readState.Index' (duration: 886.408718ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:18:33.377033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"963.786226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:18:33.377059Z","caller":"traceutil/trace.go:171","msg":"trace[776490074] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1012; }","duration":"963.840608ms","start":"2026-02-18T05:18:32.413208Z","end":"2026-02-18T05:18:33.377049Z","steps":["trace[776490074] 'agreement among raft nodes before linearized reading' (duration: 963.773606ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:18:33.377026Z","caller":"traceutil/trace.go:171","msg":"trace[579231693] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"1.274268534s","start":"2026-02-18T05:18:32.102731Z","end":"2026-02-18T05:18:33.376999Z","steps":["trace[579231693] 'process raft request' (duration: 387.829415ms)","trace[579231693] 'compare' (duration: 885.912985ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:18:33.377093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:18:32.413194Z","time spent":"963.88801ms","remote":"127.0.0.1:54980","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-18T05:18:33.377167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:18:32.102717Z","time spent":"1.274376076s","remote":"127.0.0.1:55292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:18:33.407749Z","caller":"traceutil/trace.go:171","msg":"trace[1726562529] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"392.53806ms","start":"2026-02-18T05:18:33.015193Z","end":"2026-02-18T05:18:33.407731Z","steps":["trace[1726562529] 'process raft request' (duration: 392.17135ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:18:33.40785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:18:33.015177Z","time spent":"392.620932ms","remote":"127.0.0.1:55292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:18:33.991497Z","caller":"traceutil/trace.go:171","msg":"trace[1087769017] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"110.419978ms","start":"2026-02-18T05:18:33.881057Z","end":"2026-02-18T05:18:33.991477Z","steps":["trace[1087769017] 'process raft request' (duration: 109.870883ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:18:41.621825Z","caller":"traceutil/trace.go:171","msg":"trace[2063093017] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"120.357ms","start":"2026-02-18T05:18:41.501443Z","end":"2026-02-18T05:18:41.6218Z","steps":["trace[2063093017] 'process raft request' (duration: 120.199856ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:18:50.464062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.460162ms","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-18T05:18:50.464142Z","caller":"traceutil/trace.go:171","msg":"trace[1151083672] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1109; }","duration":"109.574825ms","start":"2026-02-18T05:18:50.354552Z","end":"2026-02-18T05:18:50.464127Z","steps":["trace[1151083672] 'range keys from in-memory index tree' (duration: 109.327838ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:18:50.651447Z","caller":"traceutil/trace.go:171","msg":"trace[1397682158] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"114.347401ms","start":"2026-02-18T05:18:50.537073Z","end":"2026-02-18T05:18:50.651421Z","steps":["trace[1397682158] 'process raft request' (duration: 29.218962ms)","trace[1397682158] 'compare' (duration: 84.944594ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:18:50.998499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.848709ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:18:50.998704Z","caller":"traceutil/trace.go:171","msg":"trace[494410543] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1113; }","duration":"242.064295ms","start":"2026-02-18T05:18:50.756626Z","end":"2026-02-18T05:18:50.998691Z","steps":["trace[494410543] 'range keys from in-memory index tree' (duration: 241.835578ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:18:51.876165Z","caller":"traceutil/trace.go:171","msg":"trace[958443997] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"175.454046ms","start":"2026-02-18T05:18:51.700693Z","end":"2026-02-18T05:18:51.876147Z","steps":["trace[958443997] 'process raft request' (duration: 175.22885ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:18:51.998704Z","caller":"traceutil/trace.go:171","msg":"trace[506391545] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"117.188637ms","start":"2026-02-18T05:18:51.881498Z","end":"2026-02-18T05:18:51.998687Z","steps":["trace[506391545] 'process raft request' (duration: 65.382417ms)","trace[506391545] 'compare' (duration: 51.483721ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:18:54.429125Z","caller":"traceutil/trace.go:171","msg":"trace[1307408353] linearizableReadLoop","detail":"{readStateIndex:1167; appliedIndex:1166; }","duration":"110.041957ms","start":"2026-02-18T05:18:54.31906Z","end":"2026-02-18T05:18:54.429102Z","steps":["trace[1307408353] 'read index received' (duration: 109.41747ms)","trace[1307408353] 'applied index is now lower than readState.Index' (duration: 623.407µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:18:54.429737Z","caller":"traceutil/trace.go:171","msg":"trace[641928072] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"120.152494ms","start":"2026-02-18T05:18:54.30957Z","end":"2026-02-18T05:18:54.429723Z","steps":["trace[641928072] 'process raft request' (duration: 119.041935ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:18:54.430005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.92647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-18T05:18:54.430075Z","caller":"traceutil/trace.go:171","msg":"trace[1801588096] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1125; }","duration":"111.017452ms","start":"2026-02-18T05:18:54.319046Z","end":"2026-02-18T05:18:54.430064Z","steps":["trace[1801588096] 'agreement among raft nodes before linearized reading' (duration: 110.854598ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:18:54.625678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.381908ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:18:54.625875Z","caller":"traceutil/trace.go:171","msg":"trace[1984879289] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"186.18396ms","start":"2026-02-18T05:18:54.439678Z","end":"2026-02-18T05:18:54.625862Z","steps":["trace[1984879289] 'process raft request' (duration: 67.552315ms)","trace[1984879289] 'compare' (duration: 118.311957ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:18:55.075896Z","caller":"traceutil/trace.go:171","msg":"trace[1165852258] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"128.620478ms","start":"2026-02-18T05:18:54.947243Z","end":"2026-02-18T05:18:55.075863Z","steps":["trace[1165852258] 'process raft request' (duration: 40.936901ms)","trace[1165852258] 'compare' (duration: 87.459941ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:18:56.606368Z","caller":"traceutil/trace.go:171","msg":"trace[1086157941] transaction","detail":"{read_only:false; response_revision:1135; number_of_response:1; }","duration":"101.753289ms","start":"2026-02-18T05:18:56.504593Z","end":"2026-02-18T05:18:56.606346Z","steps":["trace[1086157941] 'process raft request' (duration: 51.491431ms)","trace[1086157941] 'compare' (duration: 50.180085ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:18:56.820825Z","caller":"traceutil/trace.go:171","msg":"trace[346045360] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"111.681211ms","start":"2026-02-18T05:18:56.709125Z","end":"2026-02-18T05:18:56.820806Z","steps":["trace[346045360] 'process raft request' (duration: 111.65443ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:18:56.820944Z","caller":"traceutil/trace.go:171","msg":"trace[526202923] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"117.551606ms","start":"2026-02-18T05:18:56.703372Z","end":"2026-02-18T05:18:56.820924Z","steps":["trace[526202923] 'process raft request' (duration: 32.990352ms)","trace[526202923] 'compare' (duration: 84.336758ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:18:58.832213Z","caller":"traceutil/trace.go:171","msg":"trace[1932344258] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"122.302641ms","start":"2026-02-18T05:18:58.709897Z","end":"2026-02-18T05:18:58.8322Z","steps":["trace[1932344258] 'process raft request' (duration: 101.336347ms)","trace[1932344258] 'compare' (duration: 20.607115ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:19:00.884138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.58016ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:19:00.884213Z","caller":"traceutil/trace.go:171","msg":"trace[1462104940] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1151; }","duration":"127.663383ms","start":"2026-02-18T05:19:00.756537Z","end":"2026-02-18T05:19:00.884201Z","steps":["trace[1462104940] 'range keys from in-memory index tree' (duration: 127.56699ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:00.884303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.213651ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:19:00.884589Z","caller":"traceutil/trace.go:171","msg":"trace[725257074] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"204.583245ms","start":"2026-02-18T05:19:00.679995Z","end":"2026-02-18T05:19:00.884578Z","steps":["trace[725257074] 'process raft request' (duration: 77.041525ms)","trace[725257074] 'compare' (duration: 127.152109ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:19:02.249335Z","caller":"traceutil/trace.go:171","msg":"trace[2097508428] transaction","detail":"{read_only:false; response_revision:1157; number_of_response:1; }","duration":"138.084539ms","start":"2026-02-18T05:19:02.111196Z","end":"2026-02-18T05:19:02.24928Z","steps":["trace[2097508428] 'process raft request' (duration: 77.827257ms)","trace[2097508428] 'compare' (duration: 60.157349ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:19:02.250326Z","caller":"traceutil/trace.go:171","msg":"trace[1778470260] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"138.561921ms","start":"2026-02-18T05:19:02.111747Z","end":"2026-02-18T05:19:02.250309Z","steps":["trace[1778470260] 'process raft request' (duration: 138.490419ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:03.045197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.412245ms","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-18T05:19:03.045289Z","caller":"traceutil/trace.go:171","msg":"trace[7997204] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1159; }","duration":"147.548938ms","start":"2026-02-18T05:19:02.89772Z","end":"2026-02-18T05:19:03.045269Z","steps":["trace[7997204] 'range keys from in-memory index tree' (duration: 147.207319ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:03.045634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.06701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-18T05:19:03.045657Z","caller":"traceutil/trace.go:171","msg":"trace[1071225607] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1159; }","duration":"157.109741ms","start":"2026-02-18T05:19:02.888535Z","end":"2026-02-18T05:19:03.045645Z","steps":["trace[1071225607] 'range keys from in-memory index tree' (duration: 156.985108ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:19:03.278096Z","caller":"traceutil/trace.go:171","msg":"trace[1722707271] transaction","detail":"{read_only:false; response_revision:1161; number_of_response:1; }","duration":"221.057821ms","start":"2026-02-18T05:19:03.056997Z","end":"2026-02-18T05:19:03.278055Z","steps":["trace[1722707271] 'process raft request' (duration: 129.308006ms)","trace[1722707271] 'compare' (duration: 91.526299ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:19:03.278399Z","caller":"traceutil/trace.go:171","msg":"trace[681772937] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"216.966683ms","start":"2026-02-18T05:19:03.061403Z","end":"2026-02-18T05:19:03.27837Z","steps":["trace[681772937] 'process raft request' (duration: 216.888451ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:19:03.279068Z","caller":"traceutil/trace.go:171","msg":"trace[1518110525] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"221.922574ms","start":"2026-02-18T05:19:03.057131Z","end":"2026-02-18T05:19:03.279053Z","steps":["trace[1518110525] 'process raft request' (duration: 220.880376ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:19:04.241041Z","caller":"traceutil/trace.go:171","msg":"trace[1956993565] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"118.762268ms","start":"2026-02-18T05:19:04.122233Z","end":"2026-02-18T05:19:04.240995Z","steps":["trace[1956993565] 'process raft request' (duration: 118.44343ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:07.462176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.985686ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:19:07.462351Z","caller":"traceutil/trace.go:171","msg":"trace[747580907] linearizableReadLoop","detail":"{readStateIndex:1223; appliedIndex:1221; }","duration":"156.33048ms","start":"2026-02-18T05:19:07.306005Z","end":"2026-02-18T05:19:07.462336Z","steps":["trace[747580907] 'read index received' (duration: 32.119418ms)","trace[747580907] 'applied index is now lower than readState.Index' (duration: 124.210012ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:19:07.462547Z","caller":"traceutil/trace.go:171","msg":"trace[607987648] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"170.058003ms","start":"2026-02-18T05:19:07.292477Z","end":"2026-02-18T05:19:07.462535Z","steps":["trace[607987648] 'process raft request' (duration: 45.635726ms)","trace[607987648] 'compare' (duration: 123.185645ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:19:07.464305Z","caller":"traceutil/trace.go:171","msg":"trace[167548723] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"170.652659ms","start":"2026-02-18T05:19:07.29361Z","end":"2026-02-18T05:19:07.464262Z","steps":["trace[167548723] 'process raft request' (duration: 168.659046ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:07.464527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.513207ms","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-18T05:19:07.464556Z","caller":"traceutil/trace.go:171","msg":"trace[1265181149] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1178; }","duration":"158.545378ms","start":"2026-02-18T05:19:07.306001Z","end":"2026-02-18T05:19:07.464547Z","steps":["trace[1265181149] 'agreement among raft nodes before linearized reading' (duration: 158.434965ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:07.464734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.019736ms","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-18T05:19:07.464759Z","caller":"traceutil/trace.go:171","msg":"trace[1232238859] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1178; }","duration":"152.061227ms","start":"2026-02-18T05:19:07.312691Z","end":"2026-02-18T05:19:07.464752Z","steps":["trace[1232238859] 'agreement among raft nodes before linearized reading' (duration: 151.986675ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:07.469825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.314399ms","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-18T05:19:07.469871Z","caller":"traceutil/trace.go:171","msg":"trace[704474367] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1178; }","duration":"145.390832ms","start":"2026-02-18T05:19:07.32447Z","end":"2026-02-18T05:19:07.46986Z","steps":["trace[704474367] 'agreement among raft nodes before linearized reading' (duration: 145.276349ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:07.686538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.936988ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:19:07.686732Z","caller":"traceutil/trace.go:171","msg":"trace[815474784] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"188.556072ms","start":"2026-02-18T05:19:07.498159Z","end":"2026-02-18T05:19:07.686715Z","steps":["trace[815474784] 'process raft request' (duration: 188.496291ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:19:07.686803Z","caller":"traceutil/trace.go:171","msg":"trace[1509541383] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"207.614326ms","start":"2026-02-18T05:19:07.479156Z","end":"2026-02-18T05:19:07.68677Z","steps":["trace[1509541383] 'process raft request' (duration: 84.365129ms)","trace[1509541383] 'compare' (duration: 122.818345ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:19:44.919821Z","caller":"traceutil/trace.go:171","msg":"trace[2037831013] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"108.629825ms","start":"2026-02-18T05:19:44.811171Z","end":"2026-02-18T05:19:44.919801Z","steps":["trace[2037831013] 'process raft request' (duration: 108.487051ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:01.978529Z","caller":"traceutil/trace.go:171","msg":"trace[2028766243] transaction","detail":"{read_only:false; response_revision:1399; number_of_response:1; }","duration":"108.634754ms","start":"2026-02-18T05:20:01.869871Z","end":"2026-02-18T05:20:01.978506Z","steps":["trace[2028766243] 'process raft request' (duration: 108.527572ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:05.987634Z","caller":"traceutil/trace.go:171","msg":"trace[801307969] transaction","detail":"{read_only:false; response_revision:1415; number_of_response:1; }","duration":"260.967601ms","start":"2026-02-18T05:20:05.726642Z","end":"2026-02-18T05:20:05.98761Z","steps":["trace[801307969] 'process raft request' (duration: 260.729404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:06.596627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.231002ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-18T05:20:06.59674Z","caller":"traceutil/trace.go:171","msg":"trace[652761082] linearizableReadLoop","detail":"{readStateIndex:1472; appliedIndex:1471; }","duration":"605.376593ms","start":"2026-02-18T05:20:05.991341Z","end":"2026-02-18T05:20:06.596717Z","steps":["trace[652761082] 'read index received' (duration: 99.570206ms)","trace[652761082] 'applied index is now lower than readState.Index' (duration: 505.804307ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:20:06.597066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.708682ms","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-18T05:20:06.597106Z","caller":"traceutil/trace.go:171","msg":"trace[1933200384] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1415; }","duration":"605.787784ms","start":"2026-02-18T05:20:05.991308Z","end":"2026-02-18T05:20:06.597096Z","steps":["trace[1933200384] 'agreement among raft nodes before linearized reading' (duration: 605.530967ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:06.597142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:05.991293Z","time spent":"605.841875ms","remote":"127.0.0.1:55292","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-18T05:20:06.597721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.254402ms","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-18T05:20:06.59859Z","caller":"traceutil/trace.go:171","msg":"trace[8326201] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1415; }","duration":"410.137195ms","start":"2026-02-18T05:20:06.188395Z","end":"2026-02-18T05:20:06.598533Z","steps":["trace[8326201] 'agreement among raft nodes before linearized reading' (duration: 408.640605ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:06.598797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:06.188381Z","time spent":"410.399851ms","remote":"127.0.0.1:55292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T05:20:06.599394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.003491ms","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-18T05:20:06.602503Z","caller":"traceutil/trace.go:171","msg":"trace[1800108402] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1415; }","duration":"278.154924ms","start":"2026-02-18T05:20:06.324335Z","end":"2026-02-18T05:20:06.60249Z","steps":["trace[1800108402] 'agreement among raft nodes before linearized reading' (duration: 274.952ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:07.842954Z","caller":"traceutil/trace.go:171","msg":"trace[1964494628] transaction","detail":"{read_only:false; response_revision:1422; number_of_response:1; }","duration":"162.439694ms","start":"2026-02-18T05:20:07.680495Z","end":"2026-02-18T05:20:07.842934Z","steps":["trace[1964494628] 'process raft request' (duration: 161.997062ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:37.15015Z","caller":"traceutil/trace.go:171","msg":"trace[1106963418] transaction","detail":"{read_only:false; response_revision:1542; number_of_response:1; }","duration":"124.468792ms","start":"2026-02-18T05:20:37.025653Z","end":"2026-02-18T05:20:37.150122Z","steps":["trace[1106963418] 'process raft request' (duration: 68.819805ms)","trace[1106963418] 'compare' (duration: 55.523434ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:20:37.74957Z","caller":"traceutil/trace.go:171","msg":"trace[969601225] transaction","detail":"{read_only:false; response_revision:1544; number_of_response:1; }","duration":"113.637606ms","start":"2026-02-18T05:20:37.635904Z","end":"2026-02-18T05:20:37.749542Z","steps":["trace[969601225] 'process raft request' (duration: 113.501712ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:22:08.420424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.87628ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:22:08.42655Z","caller":"traceutil/trace.go:171","msg":"trace[579329362] linearizableReadLoop","detail":"{readStateIndex:2026; appliedIndex:2025; }","duration":"202.598239ms","start":"2026-02-18T05:22:08.223939Z","end":"2026-02-18T05:22:08.426537Z","steps":["trace[579329362] 'read index received' (duration: 42.511725ms)","trace[579329362] 'applied index is now lower than readState.Index' (duration: 160.085424ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:22:08.426593Z","caller":"traceutil/trace.go:171","msg":"trace[338632161] transaction","detail":"{read_only:false; response_revision:1944; number_of_response:1; }","duration":"314.284683ms","start":"2026-02-18T05:22:08.112275Z","end":"2026-02-18T05:22:08.42656Z","steps":["trace[338632161] 'process raft request' (duration: 154.216569ms)","trace[338632161] 'compare' (duration: 153.799598ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:22:08.426681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.737792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-02-18T05:22:08.426714Z","caller":"traceutil/trace.go:171","msg":"trace[89174672] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1944; }","duration":"202.792024ms","start":"2026-02-18T05:22:08.223902Z","end":"2026-02-18T05:22:08.426694Z","steps":["trace[89174672] 'agreement among raft nodes before linearized reading' (duration: 202.695982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:22:08.426766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:22:08.112248Z","time spent":"314.411555ms","remote":"127.0.0.1:55292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:22:08.426894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.507214ms","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-18T05:22:08.426912Z","caller":"traceutil/trace.go:171","msg":"trace[826473782] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1944; }","duration":"135.581566ms","start":"2026-02-18T05:22:08.291325Z","end":"2026-02-18T05:22:08.426907Z","steps":["trace[826473782] 'agreement among raft nodes before linearized reading' (duration: 135.534215ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:22:08.605505Z","caller":"traceutil/trace.go:171","msg":"trace[1533427683] transaction","detail":"{read_only:false; response_revision:1946; number_of_response:1; }","duration":"174.229558ms","start":"2026-02-18T05:22:08.431256Z","end":"2026-02-18T05:22:08.605485Z","steps":["trace[1533427683] 'process raft request' (duration: 174.169367ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:22:08.605584Z","caller":"traceutil/trace.go:171","msg":"trace[1972354619] transaction","detail":"{read_only:false; response_revision:1945; number_of_response:1; }","duration":"174.64785ms","start":"2026-02-18T05:22:08.4309Z","end":"2026-02-18T05:22:08.605547Z","steps":["trace[1972354619] 'process raft request' (duration: 110.307378ms)","trace[1972354619] 'compare' (duration: 64.091705ms)"],"step_count":2}