{"level":"warn","ts":"2026-02-14T04:56:21.274525Z","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-14T04:56:21.274632Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.52: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.52:2380","--initial-cluster=instance=https://162.253.55.52:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.52:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.52: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-14T04:56:21.274714Z","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-14T04:56:21.274726Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.52:2380"]} {"level":"info","ts":"2026-02-14T04:56:21.274742Z","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-14T04:56:21.275963Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"]} {"level":"info","ts":"2026-02-14T04:56:21.276172Z","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.52:2380"],"listen-peer-urls":["https://162.253.55.52:2380"],"advertise-client-urls":["https://162.253.55.52:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.52: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-14T04:56:21.288907Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"12.43488ms"} {"level":"info","ts":"2026-02-14T04:56:21.308771Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b11e7fc5714be64b","cluster-id":"9349e4f32a52a90a"} {"level":"info","ts":"2026-02-14T04:56:21.308825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=()"} {"level":"info","ts":"2026-02-14T04:56:21.308854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became follower at term 0"} {"level":"info","ts":"2026-02-14T04:56:21.308866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b11e7fc5714be64b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-14T04:56:21.308875Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became follower at term 1"} {"level":"info","ts":"2026-02-14T04:56:21.308922Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=(12762778880000648779)"} {"level":"warn","ts":"2026-02-14T04:56:21.318979Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-14T04:56:21.32088Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-14T04:56:21.324703Z","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-14T04:56:21.332806Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b11e7fc5714be64b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-14T04:56:21.333597Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b11e7fc5714be64b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-14T04:56:21.33379Z","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-14T04:56:21.333827Z","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-14T04:56:21.333836Z","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-14T04:56:21.334778Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=(12762778880000648779)"} {"level":"info","ts":"2026-02-14T04:56:21.334907Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9349e4f32a52a90a","local-member-id":"b11e7fc5714be64b","added-peer-id":"b11e7fc5714be64b","added-peer-peer-urls":["https://162.253.55.52:2380"]} {"level":"info","ts":"2026-02-14T04:56:21.343398Z","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-14T04:56:21.343597Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.52:2380"} {"level":"info","ts":"2026-02-14T04:56:21.343612Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.52:2380"} {"level":"info","ts":"2026-02-14T04:56:21.344483Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b11e7fc5714be64b","initial-advertise-peer-urls":["https://162.253.55.52:2380"],"listen-peer-urls":["https://162.253.55.52:2380"],"advertise-client-urls":["https://162.253.55.52:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-14T04:56:21.34459Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-14T04:56:21.910139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b is starting a new election at term 1"} {"level":"info","ts":"2026-02-14T04:56:21.910219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became pre-candidate at term 1"} {"level":"info","ts":"2026-02-14T04:56:21.910242Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b received MsgPreVoteResp from b11e7fc5714be64b at term 1"} {"level":"info","ts":"2026-02-14T04:56:21.91026Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became candidate at term 2"} {"level":"info","ts":"2026-02-14T04:56:21.910266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b received MsgVoteResp from b11e7fc5714be64b at term 2"} {"level":"info","ts":"2026-02-14T04:56:21.910274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became leader at term 2"} {"level":"info","ts":"2026-02-14T04:56:21.910281Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b11e7fc5714be64b elected leader b11e7fc5714be64b at term 2"} {"level":"info","ts":"2026-02-14T04:56:21.912765Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-14T04:56:21.913956Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-14T04:56:21.916358Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.52:2379"} {"level":"info","ts":"2026-02-14T04:56:21.916589Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9349e4f32a52a90a","local-member-id":"b11e7fc5714be64b","cluster-version":"3.5"} {"level":"info","ts":"2026-02-14T04:56:21.916642Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-14T04:56:21.916676Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-14T04:56:21.913939Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b11e7fc5714be64b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.52:2379]}","request-path":"/0/members/b11e7fc5714be64b/attributes","cluster-id":"9349e4f32a52a90a","publish-timeout":"7s"} {"level":"info","ts":"2026-02-14T04:56:21.916839Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-14T04:56:21.922754Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-14T04:56:21.930381Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-14T04:56:21.930398Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-14T04:56:38.906601Z","caller":"traceutil/trace.go:171","msg":"trace[1802829540] transaction","detail":"{read_only:false; number_of_response:1; response_revision:430; }","duration":"300.648182ms","start":"2026-02-14T04:56:38.605935Z","end":"2026-02-14T04:56:38.906584Z","steps":["trace[1802829540] 'process raft request' (duration: 300.613041ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:38.907028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:38.605925Z","time spent":"300.742626ms","remote":"127.0.0.1:34218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":56,"response count":0,"response size":3868,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:56:38.90723Z","caller":"traceutil/trace.go:171","msg":"trace[973640560] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"301.358022ms","start":"2026-02-14T04:56:38.605864Z","end":"2026-02-14T04:56:38.907222Z","steps":["trace[973640560] 'process raft request' (duration: 255.043817ms)","trace[973640560] 'compare' (duration: 44.971458ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:56:38.907275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:38.605855Z","time spent":"301.398514ms","remote":"127.0.0.1:34218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3818,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:56:38.907685Z","caller":"traceutil/trace.go:171","msg":"trace[1206326042] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"287.616706ms","start":"2026-02-14T04:56:38.620061Z","end":"2026-02-14T04:56:38.907678Z","steps":["trace[1206326042] 'process raft request' (duration: 287.597706ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:56:38.907842Z","caller":"traceutil/trace.go:171","msg":"trace[516356843] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"297.218527ms","start":"2026-02-14T04:56:38.610617Z","end":"2026-02-14T04:56:38.907835Z","steps":["trace[516356843] 'process raft request' (duration: 296.955859ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:56:38.907918Z","caller":"traceutil/trace.go:171","msg":"trace[1863532693] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"297.111804ms","start":"2026-02-14T04:56:38.610799Z","end":"2026-02-14T04:56:38.907911Z","steps":["trace[1863532693] 'process raft request' (duration: 296.831086ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:56:39.43469Z","caller":"traceutil/trace.go:171","msg":"trace[1480723497] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"175.575907ms","start":"2026-02-14T04:56:39.259094Z","end":"2026-02-14T04:56:39.434669Z","steps":["trace[1480723497] 'process raft request' (duration: 175.465204ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:40.711103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.37948ms","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-14T04:56:40.711191Z","caller":"traceutil/trace.go:171","msg":"trace[800461591] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:447; }","duration":"407.503504ms","start":"2026-02-14T04:56:40.303667Z","end":"2026-02-14T04:56:40.711171Z","steps":["trace[800461591] 'range keys from in-memory index tree' (duration: 407.293798ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:40.711256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:40.303654Z","time spent":"407.591887ms","remote":"127.0.0.1:33988","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-14T04:56:40.711456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.244244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:56:40.711509Z","caller":"traceutil/trace.go:171","msg":"trace[575098913] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:447; }","duration":"530.327396ms","start":"2026-02-14T04:56:40.181168Z","end":"2026-02-14T04:56:40.711495Z","steps":["trace[575098913] 'range keys from in-memory index tree' (duration: 530.194702ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:40.711545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:40.18115Z","time spent":"530.386717ms","remote":"127.0.0.1:33778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-14T04:56:40.711729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.861376ms","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-14T04:56:40.711765Z","caller":"traceutil/trace.go:171","msg":"trace[1683314286] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:447; }","duration":"417.930028ms","start":"2026-02-14T04:56:40.293826Z","end":"2026-02-14T04:56:40.711756Z","steps":["trace[1683314286] 'range keys from in-memory index tree' (duration: 417.794614ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:40.711792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:40.293807Z","time spent":"417.978909ms","remote":"127.0.0.1:33988","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-14T04:56:40.712423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.520179ms","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-14T04:56:40.712465Z","caller":"traceutil/trace.go:171","msg":"trace[591476366] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:447; }","duration":"311.602141ms","start":"2026-02-14T04:56:40.400854Z","end":"2026-02-14T04:56:40.712456Z","steps":["trace[591476366] 'range keys from in-memory index tree' (duration: 311.446427ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:40.712499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:40.400832Z","time spent":"311.660253ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-14T04:56:41.414674Z","caller":"traceutil/trace.go:171","msg":"trace[1455199821] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"694.640226ms","start":"2026-02-14T04:56:40.720013Z","end":"2026-02-14T04:56:41.414654Z","steps":["trace[1455199821] 'process raft request' (duration: 694.521443ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:41.414815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:40.719997Z","time spent":"694.757349ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:56:41.465718Z","caller":"traceutil/trace.go:171","msg":"trace[1234765750] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"745.583092ms","start":"2026-02-14T04:56:40.720119Z","end":"2026-02-14T04:56:41.465702Z","steps":["trace[1234765750] 'process raft request' (duration: 745.303564ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:41.46582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:40.720114Z","time spent":"745.653264ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:56:41.465797Z","caller":"traceutil/trace.go:171","msg":"trace[274776961] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"745.578752ms","start":"2026-02-14T04:56:40.72019Z","end":"2026-02-14T04:56:41.465769Z","steps":["trace[274776961] 'process raft request' (duration: 745.326035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:41.465951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:40.720185Z","time spent":"745.684245ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:56:57.856291Z","caller":"traceutil/trace.go:171","msg":"trace[363177200] linearizableReadLoop","detail":"{readStateIndex:540; appliedIndex:539; }","duration":"308.974178ms","start":"2026-02-14T04:56:57.547296Z","end":"2026-02-14T04:56:57.85627Z","steps":["trace[363177200] 'read index received' (duration: 308.743571ms)","trace[363177200] 'applied index is now lower than readState.Index' (duration: 229.657µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:56:57.856394Z","caller":"traceutil/trace.go:171","msg":"trace[1401215568] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"331.634316ms","start":"2026-02-14T04:56:57.52475Z","end":"2026-02-14T04:56:57.856384Z","steps":["trace[1401215568] 'process raft request' (duration: 331.360788ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:57.856491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:57.524741Z","time spent":"331.680168ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:56:57.856618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.311337ms","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-14T04:56:57.856717Z","caller":"traceutil/trace.go:171","msg":"trace[1778389975] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:521; }","duration":"309.437381ms","start":"2026-02-14T04:56:57.547259Z","end":"2026-02-14T04:56:57.856697Z","steps":["trace[1778389975] 'agreement among raft nodes before linearized reading' (duration: 309.300067ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:57.856756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:57.547248Z","time spent":"309.498622ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-14T04:56:57.862508Z","caller":"traceutil/trace.go:171","msg":"trace[1151291090] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"124.507588ms","start":"2026-02-14T04:56:57.737991Z","end":"2026-02-14T04:56:57.862499Z","steps":["trace[1151291090] 'process raft request' (duration: 124.443126ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:57.8628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.802433ms","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-14T04:56:57.862834Z","caller":"traceutil/trace.go:171","msg":"trace[1626924720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:522; }","duration":"307.826414ms","start":"2026-02-14T04:56:57.554989Z","end":"2026-02-14T04:56:57.862815Z","steps":["trace[1626924720] 'agreement among raft nodes before linearized reading' (duration: 307.789063ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:57.862853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:57.554951Z","time spent":"307.896776ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-14T04:56:57.862935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.103033ms","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-14T04:56:57.862951Z","caller":"traceutil/trace.go:171","msg":"trace[1638947669] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:522; }","duration":"307.138784ms","start":"2026-02-14T04:56:57.555807Z","end":"2026-02-14T04:56:57.862946Z","steps":["trace[1638947669] 'agreement among raft nodes before linearized reading' (duration: 307.110234ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:57.862966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:57.555796Z","time spent":"307.167435ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-14T04:57:00.126317Z","caller":"traceutil/trace.go:171","msg":"trace[850418530] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:544; }","duration":"229.489398ms","start":"2026-02-14T04:56:59.896806Z","end":"2026-02-14T04:57:00.126295Z","steps":["trace[850418530] 'read index received' (duration: 229.338663ms)","trace[850418530] 'applied index is now lower than readState.Index' (duration: 149.795µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:57:00.126563Z","caller":"traceutil/trace.go:171","msg":"trace[619000269] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"260.306866ms","start":"2026-02-14T04:56:59.866144Z","end":"2026-02-14T04:57:00.126451Z","steps":["trace[619000269] 'process raft request' (duration: 260.017288ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:00.126636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.833468ms","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-14T04:57:00.126685Z","caller":"traceutil/trace.go:171","msg":"trace[201238223] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:526; }","duration":"229.928189ms","start":"2026-02-14T04:56:59.896745Z","end":"2026-02-14T04:57:00.126673Z","steps":["trace[201238223] 'agreement among raft nodes before linearized reading' (duration: 229.750994ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:00.126949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.837655ms","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-14T04:57:00.127032Z","caller":"traceutil/trace.go:171","msg":"trace[1066818729] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:526; }","duration":"225.942758ms","start":"2026-02-14T04:56:59.901077Z","end":"2026-02-14T04:57:00.12702Z","steps":["trace[1066818729] 'agreement among raft nodes before linearized reading' (duration: 225.786254ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:00.127117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.933239ms","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-14T04:57:00.127159Z","caller":"traceutil/trace.go:171","msg":"trace[1952853701] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:526; }","duration":"224.994801ms","start":"2026-02-14T04:56:59.902153Z","end":"2026-02-14T04:57:00.127148Z","steps":["trace[1952853701] 'agreement among raft nodes before linearized reading' (duration: 224.891488ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:04.601968Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.155256727s","expected-duration":"1s"} {"level":"info","ts":"2026-02-14T04:57:04.602361Z","caller":"traceutil/trace.go:171","msg":"trace[872762639] linearizableReadLoop","detail":"{readStateIndex:557; appliedIndex:556; }","duration":"463.013858ms","start":"2026-02-14T04:57:04.139304Z","end":"2026-02-14T04:57:04.602317Z","steps":["trace[872762639] 'read index received' (duration: 462.795672ms)","trace[872762639] 'applied index is now lower than readState.Index' (duration: 216.946µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:57:04.602494Z","caller":"traceutil/trace.go:171","msg":"trace[779396329] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"1.155888005s","start":"2026-02-14T04:57:03.446594Z","end":"2026-02-14T04:57:04.602482Z","steps":["trace[779396329] 'process raft request' (duration: 1.155524765s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:04.6026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:03.446579Z","time spent":"1.155941227s","remote":"127.0.0.1:33930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:57:04.602793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.487062ms","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-14T04:57:04.602824Z","caller":"traceutil/trace.go:171","msg":"trace[134728747] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:537; }","duration":"463.548403ms","start":"2026-02-14T04:57:04.139265Z","end":"2026-02-14T04:57:04.602813Z","steps":["trace[134728747] 'agreement among raft nodes before linearized reading' (duration: 463.44505ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:04.602851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:04.139247Z","time spent":"463.597685ms","remote":"127.0.0.1:33988","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-14T04:57:04.602917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.311047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:57:04.602966Z","caller":"traceutil/trace.go:171","msg":"trace[2019817641] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:537; }","duration":"419.389829ms","start":"2026-02-14T04:57:04.183558Z","end":"2026-02-14T04:57:04.602948Z","steps":["trace[2019817641] 'agreement among raft nodes before linearized reading' (duration: 419.311757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:04.603087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:04.183542Z","time spent":"419.534133ms","remote":"127.0.0.1:33778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-14T04:57:04.603178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.464475ms","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-14T04:57:04.60321Z","caller":"traceutil/trace.go:171","msg":"trace[490194119] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:537; }","duration":"356.516677ms","start":"2026-02-14T04:57:04.246685Z","end":"2026-02-14T04:57:04.603201Z","steps":["trace[490194119] 'agreement among raft nodes before linearized reading' (duration: 356.430565ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:04.603252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:04.246676Z","time spent":"356.563448ms","remote":"127.0.0.1:33988","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-14T04:57:04.603335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.626829ms","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-14T04:57:04.603368Z","caller":"traceutil/trace.go:171","msg":"trace[1305211597] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:537; }","duration":"357.688031ms","start":"2026-02-14T04:57:04.245669Z","end":"2026-02-14T04:57:04.603357Z","steps":["trace[1305211597] 'agreement among raft nodes before linearized reading' (duration: 357.582328ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:04.603394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:04.245654Z","time spent":"357.734452ms","remote":"127.0.0.1:33988","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-14T04:57:04.60352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.726155ms","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-14T04:57:04.603554Z","caller":"traceutil/trace.go:171","msg":"trace[1187058584] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:537; }","duration":"355.781336ms","start":"2026-02-14T04:57:04.247763Z","end":"2026-02-14T04:57:04.603544Z","steps":["trace[1187058584] 'agreement among raft nodes before linearized reading' (duration: 355.686043ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:04.603583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:04.247751Z","time spent":"355.824908ms","remote":"127.0.0.1:33988","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-14T04:57:05.561569Z","caller":"traceutil/trace.go:171","msg":"trace[216472822] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"100.323449ms","start":"2026-02-14T04:57:05.46122Z","end":"2026-02-14T04:57:05.561544Z","steps":["trace[216472822] 'process raft request' (duration: 100.197415ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:05.565811Z","caller":"traceutil/trace.go:171","msg":"trace[495940271] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"100.624334ms","start":"2026-02-14T04:57:05.465177Z","end":"2026-02-14T04:57:05.565801Z","steps":["trace[495940271] 'process raft request' (duration: 100.341307ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:07.367152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.934133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/cilium-operator-7fcd8d6ffd-8jwf4.1894041173dc907e\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-02-14T04:57:07.367216Z","caller":"traceutil/trace.go:171","msg":"trace[2084133624] range","detail":"{range_begin:/registry/events/kube-system/cilium-operator-7fcd8d6ffd-8jwf4.1894041173dc907e; range_end:; response_count:1; response_revision:556; }","duration":"744.035625ms","start":"2026-02-14T04:57:06.623163Z","end":"2026-02-14T04:57:07.367198Z","steps":["trace[2084133624] 'range keys from in-memory index tree' (duration: 743.762818ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:07.36725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:06.623139Z","time spent":"744.101117ms","remote":"127.0.0.1:33836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":885,"request content":"key:\"/registry/events/kube-system/cilium-operator-7fcd8d6ffd-8jwf4.1894041173dc907e\" "} {"level":"warn","ts":"2026-02-14T04:57:07.367228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.445845ms","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-14T04:57:07.367299Z","caller":"traceutil/trace.go:171","msg":"trace[1767932441] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:556; }","duration":"714.533287ms","start":"2026-02-14T04:57:06.652746Z","end":"2026-02-14T04:57:07.367279Z","steps":["trace[1767932441] 'range keys from in-memory index tree' (duration: 714.25968ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:07.36734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:06.652636Z","time spent":"714.694961ms","remote":"127.0.0.1:33988","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-14T04:57:07.36754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.981852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-14T04:57:07.367565Z","caller":"traceutil/trace.go:171","msg":"trace[1596177996] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:556; }","duration":"328.032553ms","start":"2026-02-14T04:57:07.039523Z","end":"2026-02-14T04:57:07.367555Z","steps":["trace[1596177996] 'range keys from in-memory index tree' (duration: 327.920891ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:07.367582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:07.039507Z","time spent":"328.071334ms","remote":"127.0.0.1:33878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":375,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-14T04:57:07.367542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.910576ms","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-14T04:57:07.367612Z","caller":"traceutil/trace.go:171","msg":"trace[1861222451] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:556; }","duration":"688.983878ms","start":"2026-02-14T04:57:06.678616Z","end":"2026-02-14T04:57:07.3676Z","steps":["trace[1861222451] 'range keys from in-memory index tree' (duration: 688.901836ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:07.367746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.835581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:57:07.367775Z","caller":"traceutil/trace.go:171","msg":"trace[2035283280] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:556; }","duration":"184.888363ms","start":"2026-02-14T04:57:07.182877Z","end":"2026-02-14T04:57:07.367766Z","steps":["trace[2035283280] 'range keys from in-memory index tree' (duration: 184.78823ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:07.367842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.46533ms","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-14T04:57:07.367876Z","caller":"traceutil/trace.go:171","msg":"trace[1500411003] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:556; }","duration":"655.520801ms","start":"2026-02-14T04:57:06.712346Z","end":"2026-02-14T04:57:07.367867Z","steps":["trace[1500411003] 'range keys from in-memory index tree' (duration: 655.379928ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:07.367907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:06.712333Z","time spent":"655.567692ms","remote":"127.0.0.1:33988","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-14T04:57:07.368086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.195438ms","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-14T04:57:07.368115Z","caller":"traceutil/trace.go:171","msg":"trace[140655470] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:556; }","duration":"653.234958ms","start":"2026-02-14T04:57:06.714872Z","end":"2026-02-14T04:57:07.368107Z","steps":["trace[140655470] 'range keys from in-memory index tree' (duration: 653.127775ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:07.368141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:06.714865Z","time spent":"653.269619ms","remote":"127.0.0.1:33988","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-14T04:57:07.368234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.822628ms","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-14T04:57:07.368266Z","caller":"traceutil/trace.go:171","msg":"trace[1027151191] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:556; }","duration":"652.863519ms","start":"2026-02-14T04:57:06.715393Z","end":"2026-02-14T04:57:07.368257Z","steps":["trace[1027151191] 'range keys from in-memory index tree' (duration: 652.760625ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:07.368296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:06.715389Z","time spent":"652.900559ms","remote":"127.0.0.1:33988","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-14T04:57:09.620441Z","caller":"traceutil/trace.go:171","msg":"trace[1673443332] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"130.273388ms","start":"2026-02-14T04:57:09.490156Z","end":"2026-02-14T04:57:09.62043Z","steps":["trace[1673443332] 'process raft request' (duration: 130.210926ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:09.731134Z","caller":"traceutil/trace.go:171","msg":"trace[1106049997] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:623; }","duration":"122.531026ms","start":"2026-02-14T04:57:09.608576Z","end":"2026-02-14T04:57:09.731107Z","steps":["trace[1106049997] 'read index received' (duration: 11.954597ms)","trace[1106049997] 'applied index is now lower than readState.Index' (duration: 110.575269ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:57:09.731343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.746642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-14T04:57:09.731377Z","caller":"traceutil/trace.go:171","msg":"trace[504368806] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:602; }","duration":"122.821254ms","start":"2026-02-14T04:57:09.608546Z","end":"2026-02-14T04:57:09.731367Z","steps":["trace[504368806] 'agreement among raft nodes before linearized reading' (duration: 122.65859ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:09.731438Z","caller":"traceutil/trace.go:171","msg":"trace[799140475] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"158.619455ms","start":"2026-02-14T04:57:09.572804Z","end":"2026-02-14T04:57:09.731423Z","steps":["trace[799140475] 'process raft request' (duration: 158.129621ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:10.314897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.425982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:57:10.315106Z","caller":"traceutil/trace.go:171","msg":"trace[1328873297] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:607; }","duration":"130.841854ms","start":"2026-02-14T04:57:10.184156Z","end":"2026-02-14T04:57:10.314998Z","steps":["trace[1328873297] 'range keys from in-memory index tree' (duration: 130.3648ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:11.983543Z","caller":"traceutil/trace.go:171","msg":"trace[1142954774] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"121.96954ms","start":"2026-02-14T04:57:11.861031Z","end":"2026-02-14T04:57:11.983001Z","steps":["trace[1142954774] 'process raft request' (duration: 121.756175ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:15.564542Z","caller":"traceutil/trace.go:171","msg":"trace[1048070616] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"121.742894ms","start":"2026-02-14T04:57:15.442772Z","end":"2026-02-14T04:57:15.564515Z","steps":["trace[1048070616] 'process raft request' (duration: 121.646271ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:15.866921Z","caller":"traceutil/trace.go:171","msg":"trace[955677340] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:665; }","duration":"372.84702ms","start":"2026-02-14T04:57:15.494055Z","end":"2026-02-14T04:57:15.866902Z","steps":["trace[955677340] 'read index received' (duration: 70.691926ms)","trace[955677340] 'applied index is now lower than readState.Index' (duration: 302.154383ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:57:15.867104Z","caller":"traceutil/trace.go:171","msg":"trace[1195191218] transaction","detail":"{read_only:false; number_of_response:0; response_revision:642; }","duration":"423.393413ms","start":"2026-02-14T04:57:15.443686Z","end":"2026-02-14T04:57:15.867079Z","steps":["trace[1195191218] 'process raft request' (duration: 346.684093ms)","trace[1195191218] 'compare' (duration: 76.493374ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:57:15.86725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.179659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.52\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-14T04:57:15.867278Z","caller":"traceutil/trace.go:171","msg":"trace[713246730] range","detail":"{range_begin:/registry/masterleases/162.253.55.52; range_end:; response_count:1; response_revision:642; }","duration":"373.239581ms","start":"2026-02-14T04:57:15.49403Z","end":"2026-02-14T04:57:15.86727Z","steps":["trace[713246730] 'agreement among raft nodes before linearized reading' (duration: 373.116477ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:15.86729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:15.443679Z","time spent":"423.535358ms","remote":"127.0.0.1:46974","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-14T04:57:15.867301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:15.494008Z","time spent":"373.288671ms","remote":"127.0.0.1:33806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.52\" "} {"level":"info","ts":"2026-02-14T04:57:16.110708Z","caller":"traceutil/trace.go:171","msg":"trace[2053292832] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"538.910718ms","start":"2026-02-14T04:57:15.571781Z","end":"2026-02-14T04:57:16.110691Z","steps":["trace[2053292832] 'process raft request' (duration: 538.810535ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:16.11083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:15.571771Z","time spent":"538.99162ms","remote":"127.0.0.1:46974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1873,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:57:16.111238Z","caller":"traceutil/trace.go:171","msg":"trace[296213508] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:667; }","duration":"244.2556ms","start":"2026-02-14T04:57:15.866975Z","end":"2026-02-14T04:57:16.11123Z","steps":["trace[296213508] 'read index received' (duration: 244.252029ms)","trace[296213508] 'applied index is now lower than readState.Index' (duration: 2.971µs)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:57:16.111344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.604758ms","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-14T04:57:16.111366Z","caller":"traceutil/trace.go:171","msg":"trace[1895913885] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:643; }","duration":"332.644839ms","start":"2026-02-14T04:57:15.778714Z","end":"2026-02-14T04:57:16.111359Z","steps":["trace[1895913885] 'agreement among raft nodes before linearized reading' (duration: 332.559587ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:16.111388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:15.778708Z","time spent":"332.674861ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-14T04:57:16.111516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.546014ms","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-14T04:57:16.111535Z","caller":"traceutil/trace.go:171","msg":"trace[235200497] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:643; }","duration":"333.563555ms","start":"2026-02-14T04:57:15.777965Z","end":"2026-02-14T04:57:16.111528Z","steps":["trace[235200497] 'agreement among raft nodes before linearized reading' (duration: 333.510403ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:16.111551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:15.777933Z","time spent":"333.614336ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-14T04:57:16.111654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.7218ms","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-14T04:57:16.11167Z","caller":"traceutil/trace.go:171","msg":"trace[710541528] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:643; }","duration":"359.751511ms","start":"2026-02-14T04:57:15.751914Z","end":"2026-02-14T04:57:16.111665Z","steps":["trace[710541528] 'agreement among raft nodes before linearized reading' (duration: 359.70314ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:16.111686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:15.751907Z","time spent":"359.774912ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-14T04:57:16.111804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.626332ms","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-14T04:57:16.111826Z","caller":"traceutil/trace.go:171","msg":"trace[518120345] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:643; }","duration":"365.656403ms","start":"2026-02-14T04:57:15.746163Z","end":"2026-02-14T04:57:16.111819Z","steps":["trace[518120345] 'agreement among raft nodes before linearized reading' (duration: 365.598751ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:16.111845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:15.746158Z","time spent":"365.681403ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-14T04:57:16.111965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.297261ms","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-14T04:57:16.111983Z","caller":"traceutil/trace.go:171","msg":"trace[280870492] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:643; }","duration":"366.339471ms","start":"2026-02-14T04:57:15.745638Z","end":"2026-02-14T04:57:16.111978Z","steps":["trace[280870492] 'agreement among raft nodes before linearized reading' (duration: 366.28443ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:16.112061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:15.745625Z","time spent":"366.430744ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-14T04:57:16.112165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.438248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:1 size:1888"} {"level":"info","ts":"2026-02-14T04:57:16.112192Z","caller":"traceutil/trace.go:171","msg":"trace[804008341] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:1; response_revision:643; }","duration":"544.48256ms","start":"2026-02-14T04:57:15.567704Z","end":"2026-02-14T04:57:16.112187Z","steps":["trace[804008341] 'agreement among raft nodes before linearized reading' (duration: 544.442359ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:16.112215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:15.567695Z","time spent":"544.51656ms","remote":"127.0.0.1:46974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":1912,"request content":"key:\"/registry/cilium.io/ciliumnodes/instance\" "} {"level":"warn","ts":"2026-02-14T04:57:16.501633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:15.868173Z","time spent":"633.457638ms","remote":"127.0.0.1:33806","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-14T04:57:16.501592Z","caller":"traceutil/trace.go:171","msg":"trace[1853810501] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:667; }","duration":"390.310449ms","start":"2026-02-14T04:57:16.111262Z","end":"2026-02-14T04:57:16.501573Z","steps":["trace[1853810501] 'read index received' (duration: 306.966297ms)","trace[1853810501] 'applied index is now lower than readState.Index' (duration: 83.342832ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:57:16.501951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.583891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:1 size:1888"} {"level":"info","ts":"2026-02-14T04:57:16.501976Z","caller":"traceutil/trace.go:171","msg":"trace[2071449484] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:1; response_revision:643; }","duration":"633.629142ms","start":"2026-02-14T04:57:15.868339Z","end":"2026-02-14T04:57:16.501968Z","steps":["trace[2071449484] 'agreement among raft nodes before linearized reading' (duration: 633.54858ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:16.502009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:15.868333Z","time spent":"633.671373ms","remote":"127.0.0.1:46974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":1912,"request content":"key:\"/registry/cilium.io/ciliumnodes/instance\" "} {"level":"info","ts":"2026-02-14T04:57:16.697761Z","caller":"traceutil/trace.go:171","msg":"trace[366161697] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:668; }","duration":"195.880074ms","start":"2026-02-14T04:57:16.501867Z","end":"2026-02-14T04:57:16.697747Z","steps":["trace[366161697] 'read index received' (duration: 190.668152ms)","trace[366161697] 'applied index is now lower than readState.Index' (duration: 5.211462ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:57:16.697847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.745484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:57:16.697864Z","caller":"traceutil/trace.go:171","msg":"trace[1407136858] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:649; }","duration":"553.832396ms","start":"2026-02-14T04:57:16.144027Z","end":"2026-02-14T04:57:16.697859Z","steps":["trace[1407136858] 'agreement among raft nodes before linearized reading' (duration: 553.774145ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:16.697882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:16.144015Z","time spent":"553.861458ms","remote":"127.0.0.1:47002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 "} {"level":"info","ts":"2026-02-14T04:57:16.698035Z","caller":"traceutil/trace.go:171","msg":"trace[1301343227] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"581.651888ms","start":"2026-02-14T04:57:16.116378Z","end":"2026-02-14T04:57:16.69803Z","steps":["trace[1301343227] 'process raft request' (duration: 576.147277ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:16.698079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:16.116365Z","time spent":"581.682059ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:57:16.698147Z","caller":"traceutil/trace.go:171","msg":"trace[694422485] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"581.147714ms","start":"2026-02-14T04:57:16.116995Z","end":"2026-02-14T04:57:16.698143Z","steps":["trace[694422485] 'process raft request' (duration: 580.64264ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:16.698174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:16.116988Z","time spent":"581.171645ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:57:16.69897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.834004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:57:16.699Z","caller":"traceutil/trace.go:171","msg":"trace[1747551197] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:649; }","duration":"554.868155ms","start":"2026-02-14T04:57:16.144125Z","end":"2026-02-14T04:57:16.698993Z","steps":["trace[1747551197] 'agreement among raft nodes before linearized reading' (duration: 554.813364ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:16.699019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:16.144112Z","time spent":"554.901606ms","remote":"127.0.0.1:47002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 "} {"level":"info","ts":"2026-02-14T04:57:16.69916Z","caller":"traceutil/trace.go:171","msg":"trace[31152510] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"579.289764ms","start":"2026-02-14T04:57:16.119864Z","end":"2026-02-14T04:57:16.699154Z","steps":["trace[31152510] 'process raft request' (duration: 577.823234ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:16.699193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:16.119859Z","time spent":"579.315214ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:57:16.699474Z","caller":"traceutil/trace.go:171","msg":"trace[867233244] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"575.324305ms","start":"2026-02-14T04:57:16.124142Z","end":"2026-02-14T04:57:16.699467Z","steps":["trace[867233244] 'process raft request' (duration: 573.558877ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:16.699507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:16.124124Z","time spent":"575.364456ms","remote":"127.0.0.1:46974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2236,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:57:16.69961Z","caller":"traceutil/trace.go:171","msg":"trace[115633153] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"575.276204ms","start":"2026-02-14T04:57:16.124328Z","end":"2026-02-14T04:57:16.699604Z","steps":["trace[115633153] 'process raft request' (duration: 573.395483ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:16.699641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:16.124322Z","time spent":"575.300785ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:57:16.69975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.856004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:57:16.699765Z","caller":"traceutil/trace.go:171","msg":"trace[1387657833] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:649; }","duration":"516.907225ms","start":"2026-02-14T04:57:16.182853Z","end":"2026-02-14T04:57:16.699761Z","steps":["trace[1387657833] 'agreement among raft nodes before linearized reading' (duration: 516.876975ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:16.699778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:16.182846Z","time spent":"516.928656ms","remote":"127.0.0.1:33778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-14T04:57:16.701235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.100356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:57:16.701288Z","caller":"traceutil/trace.go:171","msg":"trace[1647496654] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:649; }","duration":"557.176048ms","start":"2026-02-14T04:57:16.1441Z","end":"2026-02-14T04:57:16.701276Z","steps":["trace[1647496654] 'agreement among raft nodes before linearized reading' (duration: 557.071765ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:16.701321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:16.144096Z","time spent":"557.217909ms","remote":"127.0.0.1:47002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"info","ts":"2026-02-14T04:57:16.7018Z","caller":"traceutil/trace.go:171","msg":"trace[716512410] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"581.972307ms","start":"2026-02-14T04:57:16.119816Z","end":"2026-02-14T04:57:16.701788Z","steps":["trace[716512410] 'process raft request' (duration: 577.854804ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:16.701871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:16.119808Z","time spent":"582.015989ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:57:16.936722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.68329ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T04:57:16.936836Z","caller":"traceutil/trace.go:171","msg":"trace[1563759353] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:674; }","duration":"231.382556ms","start":"2026-02-14T04:57:16.705437Z","end":"2026-02-14T04:57:16.936819Z","steps":["trace[1563759353] 'read index received' (duration: 93.356106ms)","trace[1563759353] 'applied index is now lower than readState.Index' (duration: 138.02526ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:57:16.937456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.007943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:57:16.937525Z","caller":"traceutil/trace.go:171","msg":"trace[1137352529] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:650; }","duration":"232.083366ms","start":"2026-02-14T04:57:16.705413Z","end":"2026-02-14T04:57:16.937497Z","steps":["trace[1137352529] 'agreement among raft nodes before linearized reading' (duration: 231.449338ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:16.938199Z","caller":"traceutil/trace.go:171","msg":"trace[1995176376] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"433.987215ms","start":"2026-02-14T04:57:16.504133Z","end":"2026-02-14T04:57:16.938121Z","steps":["trace[1995176376] 'process raft request' (duration: 294.641299ms)","trace[1995176376] 'compare' (duration: 137.582188ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:57:16.938455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:16.50412Z","time spent":"434.17487ms","remote":"127.0.0.1:33806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:57:20.900993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.084093ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-14T04:57:20.9011Z","caller":"traceutil/trace.go:171","msg":"trace[1044547241] linearizableReadLoop","detail":"{readStateIndex:703; appliedIndex:702; }","duration":"129.562939ms","start":"2026-02-14T04:57:20.771524Z","end":"2026-02-14T04:57:20.901087Z","steps":["trace[1044547241] 'read index received' (duration: 7.507716ms)","trace[1044547241] 'applied index is now lower than readState.Index' (duration: 122.054093ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:57:20.901266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.852031ms","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-14T04:57:20.901414Z","caller":"traceutil/trace.go:171","msg":"trace[656414043] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:677; }","duration":"174.014175ms","start":"2026-02-14T04:57:20.727393Z","end":"2026-02-14T04:57:20.901407Z","steps":["trace[656414043] 'agreement among raft nodes before linearized reading' (duration: 173.82076ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:20.901329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.81683ms","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-14T04:57:20.901529Z","caller":"traceutil/trace.go:171","msg":"trace[1778177085] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:677; }","duration":"174.034437ms","start":"2026-02-14T04:57:20.727488Z","end":"2026-02-14T04:57:20.901523Z","steps":["trace[1778177085] 'agreement among raft nodes before linearized reading' (duration: 173.80856ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:20.901379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.774735ms","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-14T04:57:20.901729Z","caller":"traceutil/trace.go:171","msg":"trace[968844776] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:677; }","duration":"168.132514ms","start":"2026-02-14T04:57:20.73359Z","end":"2026-02-14T04:57:20.901722Z","steps":["trace[968844776] 'agreement among raft nodes before linearized reading' (duration: 167.547338ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:20.911505Z","caller":"traceutil/trace.go:171","msg":"trace[241706179] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"136.95206ms","start":"2026-02-14T04:57:20.774528Z","end":"2026-02-14T04:57:20.91148Z","steps":["trace[241706179] 'process raft request' (duration: 136.842947ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:22.695309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.289237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:57:22.701796Z","caller":"traceutil/trace.go:171","msg":"trace[1177601570] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:688; }","duration":"513.41242ms","start":"2026-02-14T04:57:22.181962Z","end":"2026-02-14T04:57:22.695375Z","steps":["trace[1177601570] 'range keys from in-memory index tree' (duration: 513.222775ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:22.701841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:22.181945Z","time spent":"519.882627ms","remote":"127.0.0.1:33778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-14T04:57:22.827688Z","caller":"traceutil/trace.go:171","msg":"trace[1083506618] linearizableReadLoop","detail":"{readStateIndex:715; appliedIndex:714; }","duration":"112.352357ms","start":"2026-02-14T04:57:22.715321Z","end":"2026-02-14T04:57:22.827674Z","steps":["trace[1083506618] 'read index received' (duration: 112.219523ms)","trace[1083506618] 'applied index is now lower than readState.Index' (duration: 132.223µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:57:22.82773Z","caller":"traceutil/trace.go:171","msg":"trace[908422043] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"116.057139ms","start":"2026-02-14T04:57:22.711654Z","end":"2026-02-14T04:57:22.827711Z","steps":["trace[908422043] 'process raft request' (duration: 115.936035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:22.827966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.583303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-fwkvn\" ","response":"range_response_count:1 size:4696"} {"level":"info","ts":"2026-02-14T04:57:22.827992Z","caller":"traceutil/trace.go:171","msg":"trace[97787330] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-fwkvn; range_end:; response_count:1; response_revision:689; }","duration":"112.666895ms","start":"2026-02-14T04:57:22.715318Z","end":"2026-02-14T04:57:22.827985Z","steps":["trace[97787330] 'agreement among raft nodes before linearized reading' (duration: 112.432029ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:23.076642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.039783ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T04:57:23.076725Z","caller":"traceutil/trace.go:171","msg":"trace[1855192314] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"245.278237ms","start":"2026-02-14T04:57:22.831434Z","end":"2026-02-14T04:57:23.076713Z","steps":["trace[1855192314] 'process raft request' (duration: 108.10028ms)","trace[1855192314] 'compare' (duration: 136.91917ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:57:23.078315Z","caller":"traceutil/trace.go:171","msg":"trace[363368793] linearizableReadLoop","detail":"{readStateIndex:718; appliedIndex:715; }","duration":"163.938898ms","start":"2026-02-14T04:57:22.914366Z","end":"2026-02-14T04:57:23.078305Z","steps":["trace[363368793] 'read index received' (duration: 25.23687ms)","trace[363368793] 'applied index is now lower than readState.Index' (duration: 138.701428ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:57:23.078419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.061842ms","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-14T04:57:23.078441Z","caller":"traceutil/trace.go:171","msg":"trace[854610877] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:692; }","duration":"164.122764ms","start":"2026-02-14T04:57:22.914312Z","end":"2026-02-14T04:57:23.078435Z","steps":["trace[854610877] 'agreement among raft nodes before linearized reading' (duration: 164.035521ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:23.078604Z","caller":"traceutil/trace.go:171","msg":"trace[1453261839] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"242.841229ms","start":"2026-02-14T04:57:22.835755Z","end":"2026-02-14T04:57:23.078596Z","steps":["trace[1453261839] 'process raft request' (duration: 242.466499ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:23.078831Z","caller":"traceutil/trace.go:171","msg":"trace[1793121251] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"242.618683ms","start":"2026-02-14T04:57:22.836204Z","end":"2026-02-14T04:57:23.078823Z","steps":["trace[1793121251] 'process raft request' (duration: 242.074959ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:23.094918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.781291ms","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-14T04:57:23.094963Z","caller":"traceutil/trace.go:171","msg":"trace[2068040729] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:695; }","duration":"134.841742ms","start":"2026-02-14T04:57:22.960112Z","end":"2026-02-14T04:57:23.094954Z","steps":["trace[2068040729] 'agreement among raft nodes before linearized reading' (duration: 134.743019ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:23.09505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.99381ms","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-14T04:57:23.095063Z","caller":"traceutil/trace.go:171","msg":"trace[831709249] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:695; }","duration":"134.02037ms","start":"2026-02-14T04:57:22.961038Z","end":"2026-02-14T04:57:23.095059Z","steps":["trace[831709249] 'agreement among raft nodes before linearized reading' (duration: 133.982749ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:23.095241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.143941ms","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-14T04:57:23.095256Z","caller":"traceutil/trace.go:171","msg":"trace[1256556478] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:695; }","duration":"135.180261ms","start":"2026-02-14T04:57:22.960071Z","end":"2026-02-14T04:57:23.095252Z","steps":["trace[1256556478] 'agreement among raft nodes before linearized reading' (duration: 135.13872ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:31.656642Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529164515230500,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-14T04:57:31.741147Z","caller":"traceutil/trace.go:171","msg":"trace[1824640833] linearizableReadLoop","detail":"{readStateIndex:775; appliedIndex:774; }","duration":"585.881915ms","start":"2026-02-14T04:57:31.155245Z","end":"2026-02-14T04:57:31.741127Z","steps":["trace[1824640833] 'read index received' (duration: 585.742981ms)","trace[1824640833] 'applied index is now lower than readState.Index' (duration: 137.944µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:57:31.741939Z","caller":"traceutil/trace.go:171","msg":"trace[812667116] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"640.556722ms","start":"2026-02-14T04:57:31.101372Z","end":"2026-02-14T04:57:31.741929Z","steps":["trace[812667116] 'process raft request' (duration: 639.665508ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:31.74208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:31.10136Z","time spent":"640.653504ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:57:31.742293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.056047ms","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-14T04:57:31.742315Z","caller":"traceutil/trace.go:171","msg":"trace[681738318] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:747; }","duration":"587.115198ms","start":"2026-02-14T04:57:31.155192Z","end":"2026-02-14T04:57:31.742308Z","steps":["trace[681738318] 'agreement among raft nodes before linearized reading' (duration: 587.030536ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:31.742332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:31.155171Z","time spent":"587.15788ms","remote":"127.0.0.1:33988","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-14T04:57:31.742439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.474697ms","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-14T04:57:31.742456Z","caller":"traceutil/trace.go:171","msg":"trace[1033019288] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:747; }","duration":"580.509817ms","start":"2026-02-14T04:57:31.16194Z","end":"2026-02-14T04:57:31.74245Z","steps":["trace[1033019288] 'agreement among raft nodes before linearized reading' (duration: 580.463956ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:31.742471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:31.161931Z","time spent":"580.536789ms","remote":"127.0.0.1:33988","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-14T04:57:31.742588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.564913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:57:31.74261Z","caller":"traceutil/trace.go:171","msg":"trace[1064799683] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:747; }","duration":"219.587194ms","start":"2026-02-14T04:57:31.523016Z","end":"2026-02-14T04:57:31.742603Z","steps":["trace[1064799683] 'agreement among raft nodes before linearized reading' (duration: 219.550383ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:31.74274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.731837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:57:31.742759Z","caller":"traceutil/trace.go:171","msg":"trace[75114195] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:747; }","duration":"219.759968ms","start":"2026-02-14T04:57:31.522994Z","end":"2026-02-14T04:57:31.742754Z","steps":["trace[75114195] 'agreement among raft nodes before linearized reading' (duration: 219.728317ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:31.743189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.20083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:57:31.743212Z","caller":"traceutil/trace.go:171","msg":"trace[1663931124] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:747; }","duration":"220.250892ms","start":"2026-02-14T04:57:31.522954Z","end":"2026-02-14T04:57:31.743205Z","steps":["trace[1663931124] 'agreement among raft nodes before linearized reading' (duration: 220.19522ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:31.7434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.521109ms","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-14T04:57:31.743435Z","caller":"traceutil/trace.go:171","msg":"trace[1838559079] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:747; }","duration":"568.576631ms","start":"2026-02-14T04:57:31.174853Z","end":"2026-02-14T04:57:31.74343Z","steps":["trace[1838559079] 'agreement among raft nodes before linearized reading' (duration: 568.503189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:31.743453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:31.174824Z","time spent":"568.625243ms","remote":"127.0.0.1:33988","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-14T04:57:31.743661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.610598ms","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-14T04:57:31.743685Z","caller":"traceutil/trace.go:171","msg":"trace[43007190] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:747; }","duration":"577.67094ms","start":"2026-02-14T04:57:31.166008Z","end":"2026-02-14T04:57:31.743679Z","steps":["trace[43007190] 'agreement among raft nodes before linearized reading' (duration: 577.579127ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:31.743704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:31.165984Z","time spent":"577.715021ms","remote":"127.0.0.1:33988","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-14T04:57:32.292642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.362292ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T04:57:32.292735Z","caller":"traceutil/trace.go:171","msg":"trace[546739896] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"544.381768ms","start":"2026-02-14T04:57:31.748341Z","end":"2026-02-14T04:57:32.292723Z","steps":["trace[546739896] 'process raft request' (duration: 299.886382ms)","trace[546739896] 'compare' (duration: 244.206478ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:57:32.292772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:31.748331Z","time spent":"544.423939ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:57:32.311626Z","caller":"traceutil/trace.go:171","msg":"trace[1096633306] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"559.503072ms","start":"2026-02-14T04:57:31.752109Z","end":"2026-02-14T04:57:32.311612Z","steps":["trace[1096633306] 'process raft request' (duration: 559.265465ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:32.311703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:31.752095Z","time spent":"559.564183ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:57:32.311889Z","caller":"traceutil/trace.go:171","msg":"trace[255906390] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"553.673422ms","start":"2026-02-14T04:57:31.758207Z","end":"2026-02-14T04:57:32.31188Z","steps":["trace[255906390] 'process raft request' (duration: 553.348323ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:32.311929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:31.758189Z","time spent":"553.715784ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:57:32.569966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.365866ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-14T04:57:32.570377Z","caller":"traceutil/trace.go:171","msg":"trace[1435409024] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"798.402224ms","start":"2026-02-14T04:57:31.771961Z","end":"2026-02-14T04:57:32.570364Z","steps":["trace[1435409024] 'process raft request' (duration: 798.094806ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:32.570459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:31.771934Z","time spent":"798.474937ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:57:32.599328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.165618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:57:32.599364Z","caller":"traceutil/trace.go:171","msg":"trace[1311072025] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:751; }","duration":"515.23926ms","start":"2026-02-14T04:57:32.084111Z","end":"2026-02-14T04:57:32.59935Z","steps":["trace[1311072025] 'agreement among raft nodes before linearized reading' (duration: 515.183759ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:32.599384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:32.084092Z","time spent":"515.286872ms","remote":"127.0.0.1:58550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 "} {"level":"info","ts":"2026-02-14T04:57:32.599844Z","caller":"traceutil/trace.go:171","msg":"trace[1585508555] linearizableReadLoop","detail":"{readStateIndex:780; appliedIndex:776; }","duration":"486.091432ms","start":"2026-02-14T04:57:32.084155Z","end":"2026-02-14T04:57:32.570247Z","steps":["trace[1585508555] 'read index received' (duration: 227.164621ms)","trace[1585508555] 'applied index is now lower than readState.Index' (duration: 258.926031ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:57:32.599923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.524999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:57:32.599939Z","caller":"traceutil/trace.go:171","msg":"trace[1630300470] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:751; }","duration":"515.556429ms","start":"2026-02-14T04:57:32.084378Z","end":"2026-02-14T04:57:32.599934Z","steps":["trace[1630300470] 'agreement among raft nodes before linearized reading' (duration: 515.516748ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:32.599953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:32.084372Z","time spent":"515.57725ms","remote":"127.0.0.1:58550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-02-14T04:57:32.600086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.735207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:57:32.600104Z","caller":"traceutil/trace.go:171","msg":"trace[1714901125] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:751; }","duration":"487.760358ms","start":"2026-02-14T04:57:32.112338Z","end":"2026-02-14T04:57:32.600098Z","steps":["trace[1714901125] 'agreement among raft nodes before linearized reading' (duration: 487.726817ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:32.600122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:32.112335Z","time spent":"487.780138ms","remote":"127.0.0.1:58564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 "} {"level":"warn","ts":"2026-02-14T04:57:32.600239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.482042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-14T04:57:32.600241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.903781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:57:32.600266Z","caller":"traceutil/trace.go:171","msg":"trace[796454553] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:751; }","duration":"487.938182ms","start":"2026-02-14T04:57:32.112322Z","end":"2026-02-14T04:57:32.60026Z","steps":["trace[796454553] 'agreement among raft nodes before linearized reading' (duration: 487.903231ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:32.600274Z","caller":"traceutil/trace.go:171","msg":"trace[1067735010] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:751; }","duration":"479.660816ms","start":"2026-02-14T04:57:32.120598Z","end":"2026-02-14T04:57:32.600259Z","steps":["trace[1067735010] 'agreement among raft nodes before linearized reading' (duration: 479.45202ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:32.600279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:32.11232Z","time spent":"487.956143ms","remote":"127.0.0.1:58564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-02-14T04:57:32.600293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:32.120589Z","time spent":"479.698087ms","remote":"127.0.0.1:58574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-02-14T04:57:32.600354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.046476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:57:32.60038Z","caller":"traceutil/trace.go:171","msg":"trace[1857701685] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:751; }","duration":"488.071906ms","start":"2026-02-14T04:57:32.112303Z","end":"2026-02-14T04:57:32.600375Z","steps":["trace[1857701685] 'agreement among raft nodes before linearized reading' (duration: 488.039365ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:32.600393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:32.112272Z","time spent":"488.117028ms","remote":"127.0.0.1:58564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 "} {"level":"warn","ts":"2026-02-14T04:57:32.600468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.017795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-14T04:57:32.600476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.22552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:57:32.600482Z","caller":"traceutil/trace.go:171","msg":"trace[27077241] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:751; }","duration":"507.032626ms","start":"2026-02-14T04:57:32.093445Z","end":"2026-02-14T04:57:32.600478Z","steps":["trace[27077241] 'agreement among raft nodes before linearized reading' (duration: 507.010696ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:32.600495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:32.093389Z","time spent":"507.101547ms","remote":"127.0.0.1:58550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 "} {"level":"warn","ts":"2026-02-14T04:57:32.600529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.881156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:57:32.600563Z","caller":"traceutil/trace.go:171","msg":"trace[649230921] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:751; }","duration":"462.977819ms","start":"2026-02-14T04:57:32.137576Z","end":"2026-02-14T04:57:32.600554Z","steps":["trace[649230921] 'agreement among raft nodes before linearized reading' (duration: 462.927018ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:32.600577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:32.137565Z","time spent":"463.00871ms","remote":"127.0.0.1:58574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 "} {"level":"info","ts":"2026-02-14T04:57:32.600494Z","caller":"traceutil/trace.go:171","msg":"trace[2113909803] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:751; }","duration":"412.26492ms","start":"2026-02-14T04:57:32.188223Z","end":"2026-02-14T04:57:32.600488Z","steps":["trace[2113909803] 'agreement among raft nodes before linearized reading' (duration: 412.23573ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:32.600618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:32.188208Z","time spent":"412.405495ms","remote":"127.0.0.1:33778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-14T04:57:32.600733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.099838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:57:32.60075Z","caller":"traceutil/trace.go:171","msg":"trace[1490053351] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:751; }","duration":"480.151029ms","start":"2026-02-14T04:57:32.120595Z","end":"2026-02-14T04:57:32.600746Z","steps":["trace[1490053351] 'agreement among raft nodes before linearized reading' (duration: 480.121549ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:32.600765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:32.12058Z","time spent":"480.18081ms","remote":"127.0.0.1:58574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-14T04:57:34.32263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.417448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:57:34.322696Z","caller":"traceutil/trace.go:171","msg":"trace[684055371] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:752; }","duration":"139.51346ms","start":"2026-02-14T04:57:34.183171Z","end":"2026-02-14T04:57:34.322685Z","steps":["trace[684055371] 'range keys from in-memory index tree' (duration: 139.371977ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:34.479962Z","caller":"traceutil/trace.go:171","msg":"trace[1727111139] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"151.636373ms","start":"2026-02-14T04:57:34.328307Z","end":"2026-02-14T04:57:34.479944Z","steps":["trace[1727111139] 'process raft request' (duration: 151.51983ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:34.825122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.208252ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T04:57:34.825216Z","caller":"traceutil/trace.go:171","msg":"trace[1166950188] linearizableReadLoop","detail":"{readStateIndex:784; appliedIndex:782; }","duration":"220.795657ms","start":"2026-02-14T04:57:34.604411Z","end":"2026-02-14T04:57:34.825207Z","steps":["trace[1166950188] 'read index received' (duration: 29.951µs)","trace[1166950188] 'applied index is now lower than readState.Index' (duration: 220.765225ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:57:34.825287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.875679ms","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-14T04:57:34.825299Z","caller":"traceutil/trace.go:171","msg":"trace[1203365406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:755; }","duration":"220.91328ms","start":"2026-02-14T04:57:34.604381Z","end":"2026-02-14T04:57:34.825294Z","steps":["trace[1203365406] 'agreement among raft nodes before linearized reading' (duration: 220.850428ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:34.82532Z","caller":"traceutil/trace.go:171","msg":"trace[1847251310] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"496.899607ms","start":"2026-02-14T04:57:34.328401Z","end":"2026-02-14T04:57:34.825301Z","steps":["trace[1847251310] 'process raft request' (duration: 223.461509ms)","trace[1847251310] 'compare' (duration: 272.979926ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:57:34.825427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:34.328386Z","time spent":"496.97895ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:57:34.825436Z","caller":"traceutil/trace.go:171","msg":"trace[1728654664] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"495.680774ms","start":"2026-02-14T04:57:34.329732Z","end":"2026-02-14T04:57:34.825413Z","steps":["trace[1728654664] 'process raft request' (duration: 495.441658ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:34.825469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:34.329691Z","time spent":"495.759686ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:57:35.963567Z","caller":"traceutil/trace.go:171","msg":"trace[2053454533] linearizableReadLoop","detail":"{readStateIndex:788; appliedIndex:786; }","duration":"139.331015ms","start":"2026-02-14T04:57:35.824216Z","end":"2026-02-14T04:57:35.963547Z","steps":["trace[2053454533] 'read index received' (duration: 133.162766ms)","trace[2053454533] 'applied index is now lower than readState.Index' (duration: 6.167499ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:57:35.963744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.513411ms","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-14T04:57:35.963789Z","caller":"traceutil/trace.go:171","msg":"trace[1060290417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:758; }","duration":"139.603073ms","start":"2026-02-14T04:57:35.824177Z","end":"2026-02-14T04:57:35.96378Z","steps":["trace[1060290417] 'agreement among raft nodes before linearized reading' (duration: 139.445498ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:35.964041Z","caller":"traceutil/trace.go:171","msg":"trace[125467359] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"356.06444ms","start":"2026-02-14T04:57:35.607966Z","end":"2026-02-14T04:57:35.96403Z","steps":["trace[125467359] 'process raft request' (duration: 349.403758ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:35.964113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:35.607941Z","time spent":"356.124312ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:57:35.964238Z","caller":"traceutil/trace.go:171","msg":"trace[556187793] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"304.249612ms","start":"2026-02-14T04:57:35.65998Z","end":"2026-02-14T04:57:35.96423Z","steps":["trace[556187793] 'process raft request' (duration: 303.498471ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:35.964285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:35.659957Z","time spent":"304.301994ms","remote":"127.0.0.1:33806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:57:36.138695Z","caller":"traceutil/trace.go:171","msg":"trace[518052332] linearizableReadLoop","detail":"{readStateIndex:789; appliedIndex:788; }","duration":"165.123522ms","start":"2026-02-14T04:57:35.973554Z","end":"2026-02-14T04:57:36.138677Z","steps":["trace[518052332] 'read index received' (duration: 156.813484ms)","trace[518052332] 'applied index is now lower than readState.Index' (duration: 8.309238ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:57:36.138789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.221055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-14T04:57:36.138808Z","caller":"traceutil/trace.go:171","msg":"trace[1881139134] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:759; }","duration":"165.260566ms","start":"2026-02-14T04:57:35.973541Z","end":"2026-02-14T04:57:36.138802Z","steps":["trace[1881139134] 'agreement among raft nodes before linearized reading' (duration: 165.191994ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:36.138951Z","caller":"traceutil/trace.go:171","msg":"trace[631653119] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"166.253353ms","start":"2026-02-14T04:57:35.97269Z","end":"2026-02-14T04:57:36.138943Z","steps":["trace[631653119] 'process raft request' (duration: 157.698158ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:44.708213Z","caller":"traceutil/trace.go:171","msg":"trace[1719063652] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"199.870273ms","start":"2026-02-14T04:57:44.508326Z","end":"2026-02-14T04:57:44.708196Z","steps":["trace[1719063652] 'process raft request' (duration: 199.312958ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:45.081893Z","caller":"traceutil/trace.go:171","msg":"trace[1392816954] linearizableReadLoop","detail":"{readStateIndex:818; appliedIndex:817; }","duration":"214.531475ms","start":"2026-02-14T04:57:44.867342Z","end":"2026-02-14T04:57:45.081874Z","steps":["trace[1392816954] 'read index received' (duration: 214.400912ms)","trace[1392816954] 'applied index is now lower than readState.Index' (duration: 129.713µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:57:45.081987Z","caller":"traceutil/trace.go:171","msg":"trace[1431146645] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"220.324473ms","start":"2026-02-14T04:57:44.861654Z","end":"2026-02-14T04:57:45.081979Z","steps":["trace[1431146645] 'process raft request' (duration: 220.109977ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:45.082148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.792263ms","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-14T04:57:45.082228Z","caller":"traceutil/trace.go:171","msg":"trace[749735714] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:787; }","duration":"214.893185ms","start":"2026-02-14T04:57:44.867328Z","end":"2026-02-14T04:57:45.082221Z","steps":["trace[749735714] 'agreement among raft nodes before linearized reading' (duration: 214.749291ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:45.082356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.15805ms","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-14T04:57:45.082395Z","caller":"traceutil/trace.go:171","msg":"trace[1825114815] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:787; }","duration":"143.230512ms","start":"2026-02-14T04:57:44.939153Z","end":"2026-02-14T04:57:45.082384Z","steps":["trace[1825114815] 'agreement among raft nodes before linearized reading' (duration: 143.12992ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:45.651592Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529164515230613,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-14T04:57:45.763436Z","caller":"traceutil/trace.go:171","msg":"trace[190370497] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"676.24759ms","start":"2026-02-14T04:57:45.087166Z","end":"2026-02-14T04:57:45.763414Z","steps":["trace[190370497] 'process raft request' (duration: 585.118014ms)","trace[190370497] 'compare' (duration: 90.853219ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:57:45.76355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:45.087148Z","time spent":"676.341112ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:57:45.77293Z","caller":"traceutil/trace.go:171","msg":"trace[499559009] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:818; }","duration":"621.558332ms","start":"2026-02-14T04:57:45.151357Z","end":"2026-02-14T04:57:45.772915Z","steps":["trace[499559009] 'read index received' (duration: 520.937097ms)","trace[499559009] 'applied index is now lower than readState.Index' (duration: 100.620425ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:57:45.77312Z","caller":"traceutil/trace.go:171","msg":"trace[82886466] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"684.526246ms","start":"2026-02-14T04:57:45.088585Z","end":"2026-02-14T04:57:45.773111Z","steps":["trace[82886466] 'process raft request' (duration: 684.266999ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:45.773219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:45.088576Z","time spent":"684.568177ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:57:45.773387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.027565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:57:45.773414Z","caller":"traceutil/trace.go:171","msg":"trace[198043253] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:789; }","duration":"622.078417ms","start":"2026-02-14T04:57:45.151327Z","end":"2026-02-14T04:57:45.773406Z","steps":["trace[198043253] 'agreement among raft nodes before linearized reading' (duration: 622.012195ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:45.773435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:45.151314Z","time spent":"622.116237ms","remote":"127.0.0.1:33778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-14T04:57:45.77356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.562941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:57:45.773584Z","caller":"traceutil/trace.go:171","msg":"trace[211875631] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:789; }","duration":"591.613132ms","start":"2026-02-14T04:57:45.181964Z","end":"2026-02-14T04:57:45.773577Z","steps":["trace[211875631] 'agreement among raft nodes before linearized reading' (duration: 591.575201ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:45.773604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:45.18195Z","time spent":"591.650283ms","remote":"127.0.0.1:33790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-14T04:57:45.775789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.156385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.52\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-14T04:57:45.775888Z","caller":"traceutil/trace.go:171","msg":"trace[1156694696] range","detail":"{range_begin:/registry/masterleases/162.253.55.52; range_end:; response_count:1; response_revision:789; }","duration":"279.292538ms","start":"2026-02-14T04:57:45.496584Z","end":"2026-02-14T04:57:45.775877Z","steps":["trace[1156694696] 'agreement among raft nodes before linearized reading' (duration: 277.18351ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:46.669248Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529164515230617,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-14T04:57:46.969461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.960789ms","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-14T04:57:46.969536Z","caller":"traceutil/trace.go:171","msg":"trace[1100116495] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:789; }","duration":"290.044571ms","start":"2026-02-14T04:57:46.67948Z","end":"2026-02-14T04:57:46.969524Z","steps":["trace[1100116495] 'range keys from in-memory index tree' (duration: 289.951158ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:46.969709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.693124ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T04:57:46.969752Z","caller":"traceutil/trace.go:171","msg":"trace[81394771] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"1.119730561s","start":"2026-02-14T04:57:45.850015Z","end":"2026-02-14T04:57:46.969746Z","steps":["trace[81394771] 'process raft request' (duration: 1.017954995s)","trace[81394771] 'compare' (duration: 101.19494ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:57:46.969787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:45.849984Z","time spent":"1.119786423s","remote":"127.0.0.1:33806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:57:47.771316Z","caller":"traceutil/trace.go:171","msg":"trace[771084832] linearizableReadLoop","detail":"{readStateIndex:823; appliedIndex:821; }","duration":"1.602850668s","start":"2026-02-14T04:57:46.168449Z","end":"2026-02-14T04:57:47.7713Z","steps":["trace[771084832] 'read index received' (duration: 699.529725ms)","trace[771084832] 'applied index is now lower than readState.Index' (duration: 903.320273ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:57:47.771462Z","caller":"traceutil/trace.go:171","msg":"trace[1869542771] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"1.503837438s","start":"2026-02-14T04:57:46.267618Z","end":"2026-02-14T04:57:47.771456Z","steps":["trace[1869542771] 'process raft request' (duration: 1.494094411s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:47.771554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:46.267596Z","time spent":"1.503883248s","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:57:47.771806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.588577848s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:57:47.771832Z","caller":"traceutil/trace.go:171","msg":"trace[655303450] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:791; }","duration":"1.5886197s","start":"2026-02-14T04:57:46.183205Z","end":"2026-02-14T04:57:47.771824Z","steps":["trace[655303450] 'agreement among raft nodes before linearized reading' (duration: 1.588579769s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:47.77185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:46.183196Z","time spent":"1.58865046s","remote":"127.0.0.1:33778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-14T04:57:47.772034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.494352ms","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-14T04:57:47.772053Z","caller":"traceutil/trace.go:171","msg":"trace[439162325] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:791; }","duration":"619.552484ms","start":"2026-02-14T04:57:47.152494Z","end":"2026-02-14T04:57:47.772046Z","steps":["trace[439162325] 'agreement among raft nodes before linearized reading' (duration: 619.522293ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:47.772071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:47.152462Z","time spent":"619.604015ms","remote":"127.0.0.1:34086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":31,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-14T04:57:47.772981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.120626ms","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-14T04:57:47.773008Z","caller":"traceutil/trace.go:171","msg":"trace[943494869] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:791; }","duration":"686.183558ms","start":"2026-02-14T04:57:47.086818Z","end":"2026-02-14T04:57:47.773001Z","steps":["trace[943494869] 'agreement among raft nodes before linearized reading' (duration: 686.115776ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:47.773103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:47.0868Z","time spent":"686.29598ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-14T04:57:47.773344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.625769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-02-14T04:57:47.773369Z","caller":"traceutil/trace.go:171","msg":"trace[1760688339] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:791; }","duration":"801.690191ms","start":"2026-02-14T04:57:46.971673Z","end":"2026-02-14T04:57:47.773363Z","steps":["trace[1760688339] 'agreement among raft nodes before linearized reading' (duration: 801.625849ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:47.77346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:46.971657Z","time spent":"801.728351ms","remote":"127.0.0.1:33920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-02-14T04:57:47.77368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.057143014s","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-14T04:57:47.773705Z","caller":"traceutil/trace.go:171","msg":"trace[1455464877] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:791; }","duration":"1.057204616s","start":"2026-02-14T04:57:46.716494Z","end":"2026-02-14T04:57:47.773699Z","steps":["trace[1455464877] 'agreement among raft nodes before linearized reading' (duration: 1.057138804s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:47.773723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:46.716476Z","time spent":"1.057243557s","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-14T04:57:47.775022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.32466223s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:57:47.775053Z","caller":"traceutil/trace.go:171","msg":"trace[422753157] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:791; }","duration":"1.324716922s","start":"2026-02-14T04:57:46.450327Z","end":"2026-02-14T04:57:47.775044Z","steps":["trace[422753157] 'agreement among raft nodes before linearized reading' (duration: 1.32464547s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:47.775095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:46.450279Z","time spent":"1.324788995s","remote":"127.0.0.1:33910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-02-14T04:57:47.776683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.608209476s","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-14T04:57:47.776716Z","caller":"traceutil/trace.go:171","msg":"trace[1187544981] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:791; }","duration":"1.608312948s","start":"2026-02-14T04:57:46.168395Z","end":"2026-02-14T04:57:47.776708Z","steps":["trace[1187544981] 'agreement among raft nodes before linearized reading' (duration: 1.60325664s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:47.776736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:46.168373Z","time spent":"1.608357159s","remote":"127.0.0.1:33988","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-14T04:57:47.952783Z","caller":"traceutil/trace.go:171","msg":"trace[619211617] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"162.224763ms","start":"2026-02-14T04:57:47.790542Z","end":"2026-02-14T04:57:47.952767Z","steps":["trace[619211617] 'process raft request' (duration: 162.202512ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:47.952945Z","caller":"traceutil/trace.go:171","msg":"trace[682359763] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:823; }","duration":"171.062564ms","start":"2026-02-14T04:57:47.781875Z","end":"2026-02-14T04:57:47.952938Z","steps":["trace[682359763] 'read index received' (duration: 103.556986ms)","trace[682359763] 'applied index is now lower than readState.Index' (duration: 67.504888ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:57:47.952924Z","caller":"traceutil/trace.go:171","msg":"trace[1664802548] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"172.991027ms","start":"2026-02-14T04:57:47.779902Z","end":"2026-02-14T04:57:47.952893Z","steps":["trace[1664802548] 'process raft request' (duration: 105.49768ms)","trace[1664802548] 'compare' (duration: 67.165118ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:57:47.953006Z","caller":"traceutil/trace.go:171","msg":"trace[1400275722] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"167.150917ms","start":"2026-02-14T04:57:47.785848Z","end":"2026-02-14T04:57:47.952999Z","steps":["trace[1400275722] 'process raft request' (duration: 166.851209ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:47.953117Z","caller":"traceutil/trace.go:171","msg":"trace[1848129544] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"162.577162ms","start":"2026-02-14T04:57:47.790535Z","end":"2026-02-14T04:57:47.953112Z","steps":["trace[1848129544] 'process raft request' (duration: 162.192652ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:47.953305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.331872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-14T04:57:47.953333Z","caller":"traceutil/trace.go:171","msg":"trace[471352655] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:795; }","duration":"171.461715ms","start":"2026-02-14T04:57:47.781861Z","end":"2026-02-14T04:57:47.953323Z","steps":["trace[471352655] 'agreement among raft nodes before linearized reading' (duration: 171.322321ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:47.953444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.421647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:57:47.953461Z","caller":"traceutil/trace.go:171","msg":"trace[1055062782] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:795; }","duration":"166.457709ms","start":"2026-02-14T04:57:47.786998Z","end":"2026-02-14T04:57:47.953456Z","steps":["trace[1055062782] 'agreement among raft nodes before linearized reading' (duration: 166.430188ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:47.953606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.911747ms","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-14T04:57:47.953623Z","caller":"traceutil/trace.go:171","msg":"trace[92629875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:795; }","duration":"137.950467ms","start":"2026-02-14T04:57:47.815667Z","end":"2026-02-14T04:57:47.953618Z","steps":["trace[92629875] 'agreement among raft nodes before linearized reading' (duration: 137.887545ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:48.1603Z","caller":"traceutil/trace.go:171","msg":"trace[342399246] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"196.357466ms","start":"2026-02-14T04:57:47.963927Z","end":"2026-02-14T04:57:48.160284Z","steps":["trace[342399246] 'process raft request' (duration: 196.030047ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:58:00.27588Z","caller":"traceutil/trace.go:171","msg":"trace[419541832] linearizableReadLoop","detail":"{readStateIndex:912; appliedIndex:911; }","duration":"205.076146ms","start":"2026-02-14T04:58:00.070788Z","end":"2026-02-14T04:58:00.275864Z","steps":["trace[419541832] 'read index received' (duration: 204.963913ms)","trace[419541832] 'applied index is now lower than readState.Index' (duration: 111.673µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:58:00.27607Z","caller":"traceutil/trace.go:171","msg":"trace[585137905] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"212.713525ms","start":"2026-02-14T04:58:00.06335Z","end":"2026-02-14T04:58:00.276064Z","steps":["trace[585137905] 'process raft request' (duration: 212.440127ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:00.27623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.427876ms","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-14T04:58:00.276248Z","caller":"traceutil/trace.go:171","msg":"trace[618927407] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:877; }","duration":"205.466537ms","start":"2026-02-14T04:58:00.070776Z","end":"2026-02-14T04:58:00.276242Z","steps":["trace[618927407] 'agreement among raft nodes before linearized reading' (duration: 205.392635ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:00.276354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.249285ms","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-14T04:58:00.276367Z","caller":"traceutil/trace.go:171","msg":"trace[52659544] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:877; }","duration":"188.273236ms","start":"2026-02-14T04:58:00.088089Z","end":"2026-02-14T04:58:00.276362Z","steps":["trace[52659544] 'agreement among raft nodes before linearized reading' (duration: 188.238565ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:00.276544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.126651ms","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-14T04:58:00.276557Z","caller":"traceutil/trace.go:171","msg":"trace[537922558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:877; }","duration":"177.150711ms","start":"2026-02-14T04:58:00.099402Z","end":"2026-02-14T04:58:00.276552Z","steps":["trace[537922558] 'agreement among raft nodes before linearized reading' (duration: 177.11853ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:03.356135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.389172082s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-14T04:58:03.356228Z","caller":"traceutil/trace.go:171","msg":"trace[2020572025] linearizableReadLoop","detail":"{readStateIndex:917; appliedIndex:916; }","duration":"1.176325653s","start":"2026-02-14T04:58:02.179888Z","end":"2026-02-14T04:58:03.356214Z","steps":["trace[2020572025] 'read index received' (duration: 24.2µs)","trace[2020572025] 'applied index is now lower than readState.Index' (duration: 1.176300382s)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:58:03.356878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.176988591s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:58:03.356901Z","caller":"traceutil/trace.go:171","msg":"trace[642452246] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:881; }","duration":"1.177038802s","start":"2026-02-14T04:58:02.179854Z","end":"2026-02-14T04:58:03.356893Z","steps":["trace[642452246] 'agreement among raft nodes before linearized reading' (duration: 1.176989641s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:03.35692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:58:02.179842Z","time spent":"1.177073143s","remote":"127.0.0.1:33778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-14T04:58:03.357103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.076985442s","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-14T04:58:03.357125Z","caller":"traceutil/trace.go:171","msg":"trace[1922272377] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:881; }","duration":"1.077038694s","start":"2026-02-14T04:58:02.280081Z","end":"2026-02-14T04:58:03.35712Z","steps":["trace[1922272377] 'agreement among raft nodes before linearized reading' (duration: 1.076949372s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:03.35714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:58:02.280069Z","time spent":"1.077067515s","remote":"127.0.0.1:33988","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-14T04:58:03.357304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.064740088s","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-14T04:58:03.357325Z","caller":"traceutil/trace.go:171","msg":"trace[58503548] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:881; }","duration":"1.064772099s","start":"2026-02-14T04:58:02.292548Z","end":"2026-02-14T04:58:03.35732Z","steps":["trace[58503548] 'agreement among raft nodes before linearized reading' (duration: 1.064715847s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:03.35734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:58:02.292543Z","time spent":"1.064793239s","remote":"127.0.0.1:33988","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-14T04:58:03.357447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.068186082s","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-14T04:58:03.357469Z","caller":"traceutil/trace.go:171","msg":"trace[573354426] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:881; }","duration":"1.068216564s","start":"2026-02-14T04:58:02.289246Z","end":"2026-02-14T04:58:03.357463Z","steps":["trace[573354426] 'agreement among raft nodes before linearized reading' (duration: 1.068163742s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:03.357483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:58:02.289242Z","time spent":"1.068237594s","remote":"127.0.0.1:33988","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-14T04:58:03.357581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.069094197s","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-14T04:58:03.357602Z","caller":"traceutil/trace.go:171","msg":"trace[1593382083] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:881; }","duration":"1.069122307s","start":"2026-02-14T04:58:02.288474Z","end":"2026-02-14T04:58:03.357596Z","steps":["trace[1593382083] 'agreement among raft nodes before linearized reading' (duration: 1.069075746s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:03.357616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:58:02.288469Z","time spent":"1.069143478s","remote":"127.0.0.1:33988","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-14T04:58:03.357716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.069872408s","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-14T04:58:03.357737Z","caller":"traceutil/trace.go:171","msg":"trace[338599390] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:881; }","duration":"1.069905579s","start":"2026-02-14T04:58:02.287827Z","end":"2026-02-14T04:58:03.357732Z","steps":["trace[338599390] 'agreement among raft nodes before linearized reading' (duration: 1.069855058s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:03.35775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:58:02.287821Z","time spent":"1.06992639s","remote":"127.0.0.1:33988","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-14T04:58:05.756951Z","caller":"traceutil/trace.go:171","msg":"trace[614928529] linearizableReadLoop","detail":"{readStateIndex:923; appliedIndex:922; }","duration":"346.15074ms","start":"2026-02-14T04:58:05.410783Z","end":"2026-02-14T04:58:05.756934Z","steps":["trace[614928529] 'read index received' (duration: 346.065197ms)","trace[614928529] 'applied index is now lower than readState.Index' (duration: 84.793µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:58:05.757031Z","caller":"traceutil/trace.go:171","msg":"trace[1361421838] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"357.573042ms","start":"2026-02-14T04:58:05.399453Z","end":"2026-02-14T04:58:05.757026Z","steps":["trace[1361421838] 'process raft request' (duration: 357.375847ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:05.7571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:58:05.399443Z","time spent":"357.603473ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:58:05.757328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.255013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-14T04:58:05.757387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.506024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.52\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-14T04:58:05.757409Z","caller":"traceutil/trace.go:171","msg":"trace[2102576720] range","detail":"{range_begin:/registry/masterleases/162.253.55.52; range_end:; response_count:1; response_revision:887; }","duration":"260.545174ms","start":"2026-02-14T04:58:05.496858Z","end":"2026-02-14T04:58:05.757404Z","steps":["trace[2102576720] 'agreement among raft nodes before linearized reading' (duration: 260.466382ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:58:05.757397Z","caller":"traceutil/trace.go:171","msg":"trace[1238498232] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:887; }","duration":"346.339995ms","start":"2026-02-14T04:58:05.411037Z","end":"2026-02-14T04:58:05.757377Z","steps":["trace[1238498232] 'agreement among raft nodes before linearized reading' (duration: 346.144109ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:05.757453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:58:05.411033Z","time spent":"346.408757ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-14T04:58:05.757526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.741256ms","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-14T04:58:05.75754Z","caller":"traceutil/trace.go:171","msg":"trace[761338816] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:887; }","duration":"346.767766ms","start":"2026-02-14T04:58:05.410769Z","end":"2026-02-14T04:58:05.757537Z","steps":["trace[761338816] 'agreement among raft nodes before linearized reading' (duration: 346.728565ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:05.757555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:58:05.410764Z","time spent":"346.788317ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-14T04:58:05.757717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.721801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-14T04:58:05.757732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.958416ms","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-14T04:58:05.75775Z","caller":"traceutil/trace.go:171","msg":"trace[1232696988] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:887; }","duration":"344.984658ms","start":"2026-02-14T04:58:05.41276Z","end":"2026-02-14T04:58:05.757745Z","steps":["trace[1232696988] 'agreement among raft nodes before linearized reading' (duration: 344.938537ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:58:05.757749Z","caller":"traceutil/trace.go:171","msg":"trace[982790933] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:887; }","duration":"344.759822ms","start":"2026-02-14T04:58:05.412979Z","end":"2026-02-14T04:58:05.757739Z","steps":["trace[982790933] 'agreement among raft nodes before linearized reading' (duration: 344.674729ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:05.757765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:58:05.412756Z","time spent":"345.003248ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-14T04:58:05.75778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:58:05.412976Z","time spent":"344.798483ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-14T04:58:05.889009Z","caller":"traceutil/trace.go:171","msg":"trace[677586990] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"125.833215ms","start":"2026-02-14T04:58:05.763163Z","end":"2026-02-14T04:58:05.888996Z","steps":["trace[677586990] 'process raft request' (duration: 125.65311ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:58:05.889037Z","caller":"traceutil/trace.go:171","msg":"trace[451008215] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"123.231654ms","start":"2026-02-14T04:58:05.765792Z","end":"2026-02-14T04:58:05.889024Z","steps":["trace[451008215] 'process raft request' (duration: 123.048899ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:58:05.889108Z","caller":"traceutil/trace.go:171","msg":"trace[777684964] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"122.217506ms","start":"2026-02-14T04:58:05.766884Z","end":"2026-02-14T04:58:05.889102Z","steps":["trace[777684964] 'process raft request' (duration: 121.976579ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:58:05.8892Z","caller":"traceutil/trace.go:171","msg":"trace[1392920804] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"127.188082ms","start":"2026-02-14T04:58:05.762004Z","end":"2026-02-14T04:58:05.889193Z","steps":["trace[1392920804] 'process raft request' (duration: 126.737249ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:58:11.91325Z","caller":"traceutil/trace.go:171","msg":"trace[464795590] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"121.429843ms","start":"2026-02-14T04:58:11.791804Z","end":"2026-02-14T04:58:11.913233Z","steps":["trace[464795590] 'process raft request' (duration: 121.120075ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:58:14.321233Z","caller":"traceutil/trace.go:171","msg":"trace[1129151803] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"306.16319ms","start":"2026-02-14T04:58:14.015055Z","end":"2026-02-14T04:58:14.321218Z","steps":["trace[1129151803] 'process raft request' (duration: 306.069127ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:14.321327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:58:14.015042Z","time spent":"306.234412ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:58:14.420349Z","caller":"traceutil/trace.go:171","msg":"trace[1499074125] linearizableReadLoop","detail":"{readStateIndex:952; appliedIndex:948; }","duration":"240.218408ms","start":"2026-02-14T04:58:14.180117Z","end":"2026-02-14T04:58:14.420335Z","steps":["trace[1499074125] 'read index received' (duration: 141.017472ms)","trace[1499074125] 'applied index is now lower than readState.Index' (duration: 99.200436ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:58:14.420438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.31455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:58:14.420455Z","caller":"traceutil/trace.go:171","msg":"trace[1734094836] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:914; }","duration":"240.365572ms","start":"2026-02-14T04:58:14.180084Z","end":"2026-02-14T04:58:14.42045Z","steps":["trace[1734094836] 'agreement among raft nodes before linearized reading' (duration: 240.30583ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:58:14.420632Z","caller":"traceutil/trace.go:171","msg":"trace[825933393] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"405.436938ms","start":"2026-02-14T04:58:14.015189Z","end":"2026-02-14T04:58:14.420626Z","steps":["trace[825933393] 'process raft request' (duration: 360.157222ms)","trace[825933393] 'compare' (duration: 44.876406ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:58:14.42072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:58:14.015184Z","time spent":"405.460669ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:58:14.42079Z","caller":"traceutil/trace.go:171","msg":"trace[14379188] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"405.061228ms","start":"2026-02-14T04:58:14.015724Z","end":"2026-02-14T04:58:14.420785Z","steps":["trace[14379188] 'process raft request' (duration: 404.570396ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:14.420813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:58:14.015719Z","time spent":"405.080979ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:58:14.42087Z","caller":"traceutil/trace.go:171","msg":"trace[580912825] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"402.964893ms","start":"2026-02-14T04:58:14.017901Z","end":"2026-02-14T04:58:14.420866Z","steps":["trace[580912825] 'process raft request' (duration: 402.415709ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:14.420892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:58:14.017895Z","time spent":"402.985474ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:58:15.81496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:58:15.498447Z","time spent":"316.510288ms","remote":"127.0.0.1:33806","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-14T04:58:16.585587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.106962ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T04:58:16.585719Z","caller":"traceutil/trace.go:171","msg":"trace[1717311631] linearizableReadLoop","detail":"{readStateIndex:954; appliedIndex:953; }","duration":"564.24046ms","start":"2026-02-14T04:58:16.021463Z","end":"2026-02-14T04:58:16.585703Z","steps":["trace[1717311631] 'read index received' (duration: 206.85401ms)","trace[1717311631] 'applied index is now lower than readState.Index' (duration: 357.38526ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:58:16.585819Z","caller":"traceutil/trace.go:171","msg":"trace[1277458187] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"770.033604ms","start":"2026-02-14T04:58:15.815756Z","end":"2026-02-14T04:58:16.585789Z","steps":["trace[1277458187] 'process raft request' (duration: 412.621814ms)","trace[1277458187] 'compare' (duration: 356.921298ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:58:16.585897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:58:15.815743Z","time spent":"770.110046ms","remote":"127.0.0.1:33806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:58:16.586273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.820376ms","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-14T04:58:16.586313Z","caller":"traceutil/trace.go:171","msg":"trace[648404748] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:915; }","duration":"564.896568ms","start":"2026-02-14T04:58:16.021406Z","end":"2026-02-14T04:58:16.586303Z","steps":["trace[648404748] 'agreement among raft nodes before linearized reading' (duration: 564.719013ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:16.586345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:58:16.021387Z","time spent":"564.950879ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-14T04:58:16.586345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.247594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:58:16.586411Z","caller":"traceutil/trace.go:171","msg":"trace[1789035303] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:915; }","duration":"402.360687ms","start":"2026-02-14T04:58:16.184032Z","end":"2026-02-14T04:58:16.586392Z","steps":["trace[1789035303] 'agreement among raft nodes before linearized reading' (duration: 402.233944ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:16.586449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:58:16.184014Z","time spent":"402.427349ms","remote":"127.0.0.1:33778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-14T04:58:16.586758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.962881ms","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-14T04:58:16.58679Z","caller":"traceutil/trace.go:171","msg":"trace[1195007039] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:915; }","duration":"126.023783ms","start":"2026-02-14T04:58:16.460756Z","end":"2026-02-14T04:58:16.58678Z","steps":["trace[1195007039] 'agreement among raft nodes before linearized reading' (duration: 125.859509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:16.586873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.741572ms","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-14T04:58:16.586918Z","caller":"traceutil/trace.go:171","msg":"trace[1084188310] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:915; }","duration":"158.817024ms","start":"2026-02-14T04:58:16.428089Z","end":"2026-02-14T04:58:16.586906Z","steps":["trace[1084188310] 'agreement among raft nodes before linearized reading' (duration: 158.687631ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:16.586992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.993142ms","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-14T04:58:16.587027Z","caller":"traceutil/trace.go:171","msg":"trace[418378185] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:915; }","duration":"262.065754ms","start":"2026-02-14T04:58:16.324953Z","end":"2026-02-14T04:58:16.587018Z","steps":["trace[418378185] 'agreement among raft nodes before linearized reading' (duration: 261.979102ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:16.587186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.023499ms","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-14T04:58:16.58722Z","caller":"traceutil/trace.go:171","msg":"trace[998373144] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:915; }","duration":"159.06902ms","start":"2026-02-14T04:58:16.428142Z","end":"2026-02-14T04:58:16.587211Z","steps":["trace[998373144] 'agreement among raft nodes before linearized reading' (duration: 158.924766ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:17.311279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.995891ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T04:58:17.311396Z","caller":"traceutil/trace.go:171","msg":"trace[1458249818] linearizableReadLoop","detail":"{readStateIndex:955; appliedIndex:954; }","duration":"716.969736ms","start":"2026-02-14T04:58:16.594417Z","end":"2026-02-14T04:58:17.311387Z","steps":["trace[1458249818] 'read index received' (duration: 285.909072ms)","trace[1458249818] 'applied index is now lower than readState.Index' (duration: 431.060144ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:58:17.311441Z","caller":"traceutil/trace.go:171","msg":"trace[1393849027] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"717.967742ms","start":"2026-02-14T04:58:16.593467Z","end":"2026-02-14T04:58:17.311434Z","steps":["trace[1393849027] 'process raft request' (duration: 286.765405ms)","trace[1393849027] 'compare' (duration: 430.633322ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:58:17.311469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:58:16.593455Z","time spent":"717.999002ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:58:17.311578Z","caller":"traceutil/trace.go:171","msg":"trace[1930635067] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"716.994067ms","start":"2026-02-14T04:58:16.594564Z","end":"2026-02-14T04:58:17.311558Z","steps":["trace[1930635067] 'process raft request' (duration: 716.783312ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:17.31168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.261484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-14T04:58:17.311697Z","caller":"traceutil/trace.go:171","msg":"trace[444232818] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:916; }","duration":"717.278395ms","start":"2026-02-14T04:58:16.594413Z","end":"2026-02-14T04:58:17.311692Z","steps":["trace[444232818] 'agreement among raft nodes before linearized reading' (duration: 716.998487ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:17.311712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:58:16.594391Z","time spent":"717.318015ms","remote":"127.0.0.1:33806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-02-14T04:58:17.311699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:58:16.594555Z","time spent":"717.078419ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:58:17.311846Z","caller":"traceutil/trace.go:171","msg":"trace[1956243956] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"714.034669ms","start":"2026-02-14T04:58:16.597806Z","end":"2026-02-14T04:58:17.311841Z","steps":["trace[1956243956] 'process raft request' (duration: 714.016749ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:17.311873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:58:16.597796Z","time spent":"714.062161ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:58:17.31194Z","caller":"traceutil/trace.go:171","msg":"trace[2128705972] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"717.294445ms","start":"2026-02-14T04:58:16.594634Z","end":"2026-02-14T04:58:17.311929Z","steps":["trace[2128705972] 'process raft request' (duration: 716.890334ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:58:17.311967Z","caller":"traceutil/trace.go:171","msg":"trace[648835907] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"715.242922ms","start":"2026-02-14T04:58:16.596719Z","end":"2026-02-14T04:58:17.311962Z","steps":["trace[648835907] 'process raft request' (duration: 715.079887ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:17.311995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:58:16.59671Z","time spent":"715.271132ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:58:17.311995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:58:16.594627Z","time spent":"717.338516ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:58:18.525079Z","caller":"traceutil/trace.go:171","msg":"trace[171994978] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"155.756935ms","start":"2026-02-14T04:58:18.369307Z","end":"2026-02-14T04:58:18.525064Z","steps":["trace[171994978] 'process raft request' (duration: 155.658142ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:58:20.0494Z","caller":"traceutil/trace.go:171","msg":"trace[1366233597] linearizableReadLoop","detail":"{readStateIndex:962; appliedIndex:960; }","duration":"713.261531ms","start":"2026-02-14T04:58:19.336117Z","end":"2026-02-14T04:58:20.049379Z","steps":["trace[1366233597] 'read index received' (duration: 39.622669ms)","trace[1366233597] 'applied index is now lower than readState.Index' (duration: 673.637952ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:58:20.049678Z","caller":"traceutil/trace.go:171","msg":"trace[1332118276] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"714.503653ms","start":"2026-02-14T04:58:19.335161Z","end":"2026-02-14T04:58:20.049665Z","steps":["trace[1332118276] 'process raft request' (duration: 672.40947ms)","trace[1332118276] 'compare' (duration: 41.510088ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:58:20.049786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:58:19.335155Z","time spent":"714.567485ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:58:20.057093Z","caller":"traceutil/trace.go:171","msg":"trace[209398690] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"717.05825ms","start":"2026-02-14T04:58:19.340015Z","end":"2026-02-14T04:58:20.057073Z","steps":["trace[209398690] 'process raft request' (duration: 709.318599ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:20.057176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:58:19.339999Z","time spent":"717.122871ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:58:20.057312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.193047ms","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-14T04:58:20.05733Z","caller":"traceutil/trace.go:171","msg":"trace[424546392] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:924; }","duration":"721.223777ms","start":"2026-02-14T04:58:19.3361Z","end":"2026-02-14T04:58:20.057323Z","steps":["trace[424546392] 'agreement among raft nodes before linearized reading' (duration: 721.137375ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:20.057344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:58:19.336092Z","time spent":"721.248507ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-14T04:58:20.057435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.286709ms","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-14T04:58:20.057447Z","caller":"traceutil/trace.go:171","msg":"trace[819499494] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:924; }","duration":"721.307039ms","start":"2026-02-14T04:58:19.336136Z","end":"2026-02-14T04:58:20.057443Z","steps":["trace[819499494] 'agreement among raft nodes before linearized reading' (duration: 721.271778ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:20.057461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:58:19.336133Z","time spent":"721.32568ms","remote":"127.0.0.1:33988","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-14T04:58:20.443013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.954476ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T04:58:20.443162Z","caller":"traceutil/trace.go:171","msg":"trace[1270989076] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"161.259778ms","start":"2026-02-14T04:58:20.281888Z","end":"2026-02-14T04:58:20.443148Z","steps":["trace[1270989076] 'process raft request' (duration: 161.207536ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:58:20.443439Z","caller":"traceutil/trace.go:171","msg":"trace[1805871774] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"381.486585ms","start":"2026-02-14T04:58:20.061944Z","end":"2026-02-14T04:58:20.443431Z","steps":["trace[1805871774] 'process raft request' (duration: 161.997536ms)","trace[1805871774] 'compare' (duration: 218.788391ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:58:20.443478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:58:20.061938Z","time spent":"381.518135ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:58:28.899286Z","caller":"traceutil/trace.go:171","msg":"trace[1146142835] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"164.956973ms","start":"2026-02-14T04:58:28.7343Z","end":"2026-02-14T04:58:28.899257Z","steps":["trace[1146142835] 'process raft request' (duration: 164.83564ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:58:38.881466Z","caller":"traceutil/trace.go:171","msg":"trace[455637757] linearizableReadLoop","detail":"{readStateIndex:1083; appliedIndex:1082; }","duration":"110.384587ms","start":"2026-02-14T04:58:38.771066Z","end":"2026-02-14T04:58:38.881451Z","steps":["trace[455637757] 'read index received' (duration: 48.386227ms)","trace[455637757] 'applied index is now lower than readState.Index' (duration: 61.99756ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:58:38.881553Z","caller":"traceutil/trace.go:171","msg":"trace[1739386601] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"314.469865ms","start":"2026-02-14T04:58:38.567075Z","end":"2026-02-14T04:58:38.881545Z","steps":["trace[1739386601] 'process raft request' (duration: 314.27821ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:38.881627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:58:38.567062Z","time spent":"314.507577ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:58:38.881764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.692704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-02-14T04:58:38.881786Z","caller":"traceutil/trace.go:171","msg":"trace[990693746] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1039; }","duration":"110.760926ms","start":"2026-02-14T04:58:38.771017Z","end":"2026-02-14T04:58:38.881777Z","steps":["trace[990693746] 'agreement among raft nodes before linearized reading' (duration: 110.674384ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:39.68346Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529164515231176,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-14T04:58:40.053106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.134019826s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T04:58:40.053538Z","caller":"traceutil/trace.go:171","msg":"trace[259042574] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"1.165493094s","start":"2026-02-14T04:58:38.888027Z","end":"2026-02-14T04:58:40.05352Z","steps":["trace[259042574] 'process raft request' (duration: 1.165424482s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:40.053737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:58:38.888019Z","time spent":"1.165650628s","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:58:40.054299Z","caller":"traceutil/trace.go:171","msg":"trace[23211137] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"1.167216408s","start":"2026-02-14T04:58:38.887065Z","end":"2026-02-14T04:58:40.054281Z","steps":["trace[23211137] 'process raft request' (duration: 31.889228ms)","trace[23211137] 'compare' (duration: 1.133358619s)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:58:40.054553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:58:38.887049Z","time spent":"1.167465465s","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:58:40.10479Z","caller":"traceutil/trace.go:171","msg":"trace[147563543] linearizableReadLoop","detail":"{readStateIndex:1086; appliedIndex:1083; }","duration":"921.508888ms","start":"2026-02-14T04:58:39.18326Z","end":"2026-02-14T04:58:40.104769Z","steps":["trace[147563543] 'read index received' (duration: 545.837053ms)","trace[147563543] 'applied index is now lower than readState.Index' (duration: 375.670954ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:58:40.106849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"923.575402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:58:40.106922Z","caller":"traceutil/trace.go:171","msg":"trace[1254143966] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1042; }","duration":"923.688634ms","start":"2026-02-14T04:58:39.183221Z","end":"2026-02-14T04:58:40.10691Z","steps":["trace[1254143966] 'agreement among raft nodes before linearized reading' (duration: 923.169941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:40.106983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:58:39.183205Z","time spent":"923.767577ms","remote":"127.0.0.1:33778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-14T04:58:40.107339Z","caller":"traceutil/trace.go:171","msg":"trace[1353973081] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"1.052019367s","start":"2026-02-14T04:58:39.055306Z","end":"2026-02-14T04:58:40.107326Z","steps":["trace[1353973081] 'process raft request' (duration: 1.049337857s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:40.107507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:58:39.055281Z","time spent":"1.05211593s","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:58:40.110172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.563928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-14T04:58:40.110266Z","caller":"traceutil/trace.go:171","msg":"trace[1986828920] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1042; }","duration":"361.635711ms","start":"2026-02-14T04:58:39.74857Z","end":"2026-02-14T04:58:40.110205Z","steps":["trace[1986828920] 'agreement among raft nodes before linearized reading' (duration: 360.323297ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:40.110348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:58:39.748556Z","time spent":"361.781414ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":498,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-14T04:58:40.112038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.361949ms","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-14T04:58:40.112113Z","caller":"traceutil/trace.go:171","msg":"trace[1184521267] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1042; }","duration":"616.243561ms","start":"2026-02-14T04:58:39.495848Z","end":"2026-02-14T04:58:40.112092Z","steps":["trace[1184521267] 'agreement among raft nodes before linearized reading' (duration: 610.278507ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:40.112144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:58:39.495833Z","time spent":"616.304473ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-14T04:58:40.368594Z","caller":"traceutil/trace.go:171","msg":"trace[353809405] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"252.169908ms","start":"2026-02-14T04:58:40.116398Z","end":"2026-02-14T04:58:40.368568Z","steps":["trace[353809405] 'process raft request' (duration: 239.019947ms)","trace[353809405] 'compare' (duration: 12.595557ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:58:40.371713Z","caller":"traceutil/trace.go:171","msg":"trace[540929007] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"254.94941ms","start":"2026-02-14T04:58:40.116746Z","end":"2026-02-14T04:58:40.371696Z","steps":["trace[540929007] 'process raft request' (duration: 254.615642ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:58:40.373098Z","caller":"traceutil/trace.go:171","msg":"trace[1477345852] linearizableReadLoop","detail":"{readStateIndex:1088; appliedIndex:1086; }","duration":"206.548763ms","start":"2026-02-14T04:58:40.166529Z","end":"2026-02-14T04:58:40.373078Z","steps":["trace[1477345852] 'read index received' (duration: 188.895995ms)","trace[1477345852] 'applied index is now lower than readState.Index' (duration: 17.650258ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:58:40.373255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.711717ms","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-14T04:58:40.373298Z","caller":"traceutil/trace.go:171","msg":"trace[1820335476] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1044; }","duration":"206.79918ms","start":"2026-02-14T04:58:40.16649Z","end":"2026-02-14T04:58:40.373289Z","steps":["trace[1820335476] 'agreement among raft nodes before linearized reading' (duration: 206.667436ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:40.373568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.306773ms","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-14T04:58:40.375213Z","caller":"traceutil/trace.go:171","msg":"trace[81147282] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1044; }","duration":"203.977286ms","start":"2026-02-14T04:58:40.171218Z","end":"2026-02-14T04:58:40.375195Z","steps":["trace[81147282] 'agreement among raft nodes before linearized reading' (duration: 202.233241ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:40.37511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.369063ms","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-14T04:58:40.37561Z","caller":"traceutil/trace.go:171","msg":"trace[1520827550] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1044; }","duration":"133.888386ms","start":"2026-02-14T04:58:40.241686Z","end":"2026-02-14T04:58:40.375574Z","steps":["trace[1520827550] 'agreement among raft nodes before linearized reading' (duration: 133.223419ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:59:25.672716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.625465ms","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-14T04:59:25.672752Z","caller":"traceutil/trace.go:171","msg":"trace[1586500214] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1229; }","duration":"120.718537ms","start":"2026-02-14T04:59:25.552026Z","end":"2026-02-14T04:59:25.672745Z","steps":["trace[1586500214] 'agreement among raft nodes before linearized reading' (duration: 120.557533ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:59:25.672158Z","caller":"traceutil/trace.go:171","msg":"trace[955358410] linearizableReadLoop","detail":"{readStateIndex:1283; appliedIndex:1282; }","duration":"120.06226ms","start":"2026-02-14T04:59:25.552069Z","end":"2026-02-14T04:59:25.672131Z","steps":["trace[955358410] 'read index received' (duration: 105.166592ms)","trace[955358410] 'applied index is now lower than readState.Index' (duration: 14.893988ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:59:41.965072Z","caller":"traceutil/trace.go:171","msg":"trace[889629075] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"105.466119ms","start":"2026-02-14T04:59:41.859577Z","end":"2026-02-14T04:59:41.965043Z","steps":["trace[889629075] 'process raft request' (duration: 105.301245ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:30.736526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.304195ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-14T05:01:00.659405Z","caller":"traceutil/trace.go:171","msg":"trace[78288583] transaction","detail":"{read_only:false; response_revision:1614; number_of_response:1; }","duration":"161.456335ms","start":"2026-02-14T05:01:00.497926Z","end":"2026-02-14T05:01:00.659382Z","steps":["trace[78288583] 'process raft request' (duration: 161.342402ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:00.871836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.337274ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-14T05:01:00.872583Z","caller":"traceutil/trace.go:171","msg":"trace[269790370] linearizableReadLoop","detail":"{readStateIndex:1687; appliedIndex:1686; }","duration":"212.279682ms","start":"2026-02-14T05:01:00.660284Z","end":"2026-02-14T05:01:00.872564Z","steps":["trace[269790370] 'read index received' (duration: 68.196528ms)","trace[269790370] 'applied index is now lower than readState.Index' (duration: 144.081154ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T05:01:00.872861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.981907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-02-14T05:01:00.872942Z","caller":"traceutil/trace.go:171","msg":"trace[948949803] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1614; }","duration":"304.097239ms","start":"2026-02-14T05:01:00.568825Z","end":"2026-02-14T05:01:00.872922Z","steps":["trace[948949803] 'agreement among raft nodes before linearized reading' (duration: 303.854233ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:00.87299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:00.568818Z","time spent":"304.162352ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":560,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-14T05:01:00.872896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.327126ms","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-14T05:01:00.873972Z","caller":"traceutil/trace.go:171","msg":"trace[1776492769] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1614; }","duration":"188.434835ms","start":"2026-02-14T05:01:00.685521Z","end":"2026-02-14T05:01:00.873956Z","steps":["trace[1776492769] 'agreement among raft nodes before linearized reading' (duration: 187.317225ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:00.873375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.709068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4197"} {"level":"info","ts":"2026-02-14T05:01:00.874693Z","caller":"traceutil/trace.go:171","msg":"trace[261277875] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:1614; }","duration":"171.025444ms","start":"2026-02-14T05:01:00.703645Z","end":"2026-02-14T05:01:00.874671Z","steps":["trace[261277875] 'agreement among raft nodes before linearized reading' (duration: 169.653997ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:01:01.041283Z","caller":"traceutil/trace.go:171","msg":"trace[1624690013] transaction","detail":"{read_only:false; response_revision:1616; number_of_response:1; }","duration":"136.813927ms","start":"2026-02-14T05:01:00.904452Z","end":"2026-02-14T05:01:01.041266Z","steps":["trace[1624690013] 'process raft request' (duration: 133.773335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:01.312479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.821736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T05:01:01.313907Z","caller":"traceutil/trace.go:171","msg":"trace[380507580] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1617; }","duration":"160.914519ms","start":"2026-02-14T05:01:01.151601Z","end":"2026-02-14T05:01:01.312516Z","steps":["trace[380507580] 'count revisions from in-memory index tree' (duration: 160.753905ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:01:18.081532Z","caller":"traceutil/trace.go:171","msg":"trace[1657880909] linearizableReadLoop","detail":"{readStateIndex:1764; appliedIndex:1763; }","duration":"102.997581ms","start":"2026-02-14T05:01:17.97851Z","end":"2026-02-14T05:01:18.081507Z","steps":["trace[1657880909] 'read index received' (duration: 102.902738ms)","trace[1657880909] 'applied index is now lower than readState.Index' (duration: 93.552µs)"],"step_count":2} {"level":"warn","ts":"2026-02-14T05:01:18.081753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.227637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-14T05:01:18.081787Z","caller":"traceutil/trace.go:171","msg":"trace[1137588117] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1688; }","duration":"103.31002ms","start":"2026-02-14T05:01:17.978465Z","end":"2026-02-14T05:01:18.081775Z","steps":["trace[1137588117] 'agreement among raft nodes before linearized reading' (duration: 103.159586ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:01:18.081952Z","caller":"traceutil/trace.go:171","msg":"trace[930734010] transaction","detail":"{read_only:false; response_revision:1688; number_of_response:1; }","duration":"131.322108ms","start":"2026-02-14T05:01:17.95062Z","end":"2026-02-14T05:01:18.081942Z","steps":["trace[930734010] 'process raft request' (duration: 130.717922ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:41.248283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.199089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-02-14T05:01:41.248361Z","caller":"traceutil/trace.go:171","msg":"trace[311412027] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1784; }","duration":"103.310562ms","start":"2026-02-14T05:01:41.145033Z","end":"2026-02-14T05:01:41.248344Z","steps":["trace[311412027] 'range keys from in-memory index tree' (duration: 103.011194ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:01:46.043395Z","caller":"traceutil/trace.go:171","msg":"trace[1549045010] transaction","detail":"{read_only:false; response_revision:1814; number_of_response:1; }","duration":"167.563427ms","start":"2026-02-14T05:01:45.875809Z","end":"2026-02-14T05:01:46.043372Z","steps":["trace[1549045010] 'process raft request' (duration: 106.833618ms)","trace[1549045010] 'compare' (duration: 60.503833ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T05:01:46.275276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.703116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-df5c360d-217e-4f10-9cb7-d1892dee7e36\" ","response":"range_response_count:1 size:2174"} {"level":"info","ts":"2026-02-14T05:01:46.275344Z","caller":"traceutil/trace.go:171","msg":"trace[1250314486] range","detail":"{range_begin:/registry/persistentvolumes/pvc-df5c360d-217e-4f10-9cb7-d1892dee7e36; range_end:; response_count:1; response_revision:1818; }","duration":"128.857839ms","start":"2026-02-14T05:01:46.14647Z","end":"2026-02-14T05:01:46.275328Z","steps":["trace[1250314486] 'range keys from in-memory index tree' (duration: 128.542031ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:47.737376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.195736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-02-14T05:01:47.737626Z","caller":"traceutil/trace.go:171","msg":"trace[898336857] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1827; }","duration":"130.491244ms","start":"2026-02-14T05:01:47.607116Z","end":"2026-02-14T05:01:47.737607Z","steps":["trace[898336857] 'range keys from in-memory index tree' (duration: 130.015071ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:47.737878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.027876ms","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:533"} {"level":"info","ts":"2026-02-14T05:01:47.737959Z","caller":"traceutil/trace.go:171","msg":"trace[986204635] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1827; }","duration":"162.140609ms","start":"2026-02-14T05:01:47.575804Z","end":"2026-02-14T05:01:47.737945Z","steps":["trace[986204635] 'range keys from in-memory index tree' (duration: 161.553492ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:58.966601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.642569ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T05:01:58.966707Z","caller":"traceutil/trace.go:171","msg":"trace[1926326492] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"397.539278ms","start":"2026-02-14T05:01:58.569143Z","end":"2026-02-14T05:01:58.966683Z","steps":["trace[1926326492] 'process raft request' (duration: 58.708393ms)","trace[1926326492] 'compare' (duration: 338.359902ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T05:01:58.966763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:58.569123Z","time spent":"397.61302ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T05:02:05.719866Z","caller":"traceutil/trace.go:171","msg":"trace[334884860] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"161.037807ms","start":"2026-02-14T05:02:05.558797Z","end":"2026-02-14T05:02:05.719835Z","steps":["trace[334884860] 'process raft request' (duration: 111.357001ms)","trace[334884860] 'compare' (duration: 49.497441ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T05:02:07.451772Z","caller":"traceutil/trace.go:171","msg":"trace[479523621] transaction","detail":"{read_only:false; response_revision:1917; number_of_response:1; }","duration":"142.809011ms","start":"2026-02-14T05:02:07.308707Z","end":"2026-02-14T05:02:07.451516Z","steps":["trace[479523621] 'process raft request' (duration: 142.379239ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:02:08.213864Z","caller":"traceutil/trace.go:171","msg":"trace[266085576] linearizableReadLoop","detail":"{readStateIndex:2007; appliedIndex:2006; }","duration":"290.177257ms","start":"2026-02-14T05:02:07.923674Z","end":"2026-02-14T05:02:08.213852Z","steps":["trace[266085576] 'read index received' (duration: 290.044364ms)","trace[266085576] 'applied index is now lower than readState.Index' (duration: 132.393µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T05:02:08.21402Z","caller":"traceutil/trace.go:171","msg":"trace[949913647] transaction","detail":"{read_only:false; response_revision:1920; number_of_response:1; }","duration":"338.242931ms","start":"2026-02-14T05:02:07.875771Z","end":"2026-02-14T05:02:08.214013Z","steps":["trace[949913647] 'process raft request' (duration: 337.986004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:02:08.214086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:02:07.875758Z","time spent":"338.276821ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":495,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T05:02:08.214188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.517517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T05:02:08.214206Z","caller":"traceutil/trace.go:171","msg":"trace[1635999482] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1920; }","duration":"290.534698ms","start":"2026-02-14T05:02:07.923664Z","end":"2026-02-14T05:02:08.214199Z","steps":["trace[1635999482] 'agreement among raft nodes before linearized reading' (duration: 290.506187ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:02:08.217844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.535045ms","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:533"} {"level":"info","ts":"2026-02-14T05:02:08.217869Z","caller":"traceutil/trace.go:171","msg":"trace[370084784] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1920; }","duration":"209.617098ms","start":"2026-02-14T05:02:08.008246Z","end":"2026-02-14T05:02:08.217863Z","steps":["trace[370084784] 'agreement among raft nodes before linearized reading' (duration: 206.04066ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:02:08.550261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.56414ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T05:02:08.550368Z","caller":"traceutil/trace.go:171","msg":"trace[1409327793] linearizableReadLoop","detail":"{readStateIndex:2008; appliedIndex:2007; }","duration":"295.852149ms","start":"2026-02-14T05:02:08.2545Z","end":"2026-02-14T05:02:08.550352Z","steps":["trace[1409327793] 'read index received' (duration: 174.180186ms)","trace[1409327793] 'applied index is now lower than readState.Index' (duration: 121.671043ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T05:02:08.550559Z","caller":"traceutil/trace.go:171","msg":"trace[165764263] transaction","detail":"{read_only:false; response_revision:1921; number_of_response:1; }","duration":"305.520843ms","start":"2026-02-14T05:02:08.245028Z","end":"2026-02-14T05:02:08.550549Z","steps":["trace[165764263] 'process raft request' (duration: 183.566142ms)","trace[165764263] 'compare' (duration: 121.268612ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T05:02:08.55061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:02:08.245002Z","time spent":"305.580615ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T05:02:08.559724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.218664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T05:02:08.559783Z","caller":"traceutil/trace.go:171","msg":"trace[303533792] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1921; }","duration":"305.319688ms","start":"2026-02-14T05:02:08.254454Z","end":"2026-02-14T05:02:08.559773Z","steps":["trace[303533792] 'agreement among raft nodes before linearized reading' (duration: 304.795054ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:02:08.559807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:02:08.254431Z","time spent":"305.370359ms","remote":"127.0.0.1:33778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-14T05:02:08.562698Z","caller":"traceutil/trace.go:171","msg":"trace[339619094] transaction","detail":"{read_only:false; response_revision:1922; number_of_response:1; }","duration":"308.114263ms","start":"2026-02-14T05:02:08.254572Z","end":"2026-02-14T05:02:08.562686Z","steps":["trace[339619094] 'process raft request' (duration: 307.909868ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:02:08.563371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:02:08.254561Z","time spent":"308.775582ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T05:02:08.563185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.963062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-14T05:02:08.56673Z","caller":"traceutil/trace.go:171","msg":"trace[1065607900] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1922; }","duration":"236.5314ms","start":"2026-02-14T05:02:08.330189Z","end":"2026-02-14T05:02:08.566721Z","steps":["trace[1065607900] 'agreement among raft nodes before linearized reading' (duration: 232.927641ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:02:49.476881Z","caller":"traceutil/trace.go:171","msg":"trace[751445188] linearizableReadLoop","detail":"{readStateIndex:2191; appliedIndex:2190; }","duration":"101.49867ms","start":"2026-02-14T05:02:49.375361Z","end":"2026-02-14T05:02:49.47686Z","steps":["trace[751445188] 'read index received' (duration: 41.896808ms)","trace[751445188] 'applied index is now lower than readState.Index' (duration: 59.601162ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T05:02:49.477001Z","caller":"traceutil/trace.go:171","msg":"trace[384302334] transaction","detail":"{read_only:false; response_revision:2096; number_of_response:1; }","duration":"117.319222ms","start":"2026-02-14T05:02:49.359672Z","end":"2026-02-14T05:02:49.476991Z","steps":["trace[384302334] 'process raft request' (duration: 57.656579ms)","trace[384302334] 'compare' (duration: 59.428267ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T05:02:49.4772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.819819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-14T05:02:49.477235Z","caller":"traceutil/trace.go:171","msg":"trace[977140074] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2096; }","duration":"101.89093ms","start":"2026-02-14T05:02:49.375336Z","end":"2026-02-14T05:02:49.477227Z","steps":["trace[977140074] 'agreement among raft nodes before linearized reading' (duration: 101.766376ms)"],"step_count":1}