{"level":"warn","ts":"2026-02-19T19:01:05.026772Z","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-19T19:01:05.026864Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.162: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://199.204.45.162:2380","--initial-cluster=instance=https://199.204.45.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.162: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-19T19:01:05.026933Z","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-19T19:01:05.026947Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-02-19T19:01:05.026972Z","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-19T19:01:05.027623Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"]} {"level":"info","ts":"2026-02-19T19:01:05.02775Z","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":8,"max-cpu-available":8,"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://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.162: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-19T19:01:05.031949Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.012016ms"} {"level":"info","ts":"2026-02-19T19:01:05.039683Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2d372260bb4c37a","cluster-id":"52bb6ec15e8722ff"} {"level":"info","ts":"2026-02-19T19:01:05.039787Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=()"} {"level":"info","ts":"2026-02-19T19:01:05.039825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 0"} {"level":"info","ts":"2026-02-19T19:01:05.03985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2d372260bb4c37a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-19T19:01:05.039864Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 1"} {"level":"info","ts":"2026-02-19T19:01:05.039926Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"warn","ts":"2026-02-19T19:01:05.045919Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-19T19:01:05.047549Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-19T19:01:05.048454Z","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-19T19:01:05.05054Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2d372260bb4c37a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-19T19:01:05.05062Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2d372260bb4c37a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-19T19:01:05.050676Z","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-19T19:01:05.051178Z","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-19T19:01:05.051247Z","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-19T19:01:05.051872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"info","ts":"2026-02-19T19:01:05.052488Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","added-peer-id":"2d372260bb4c37a","added-peer-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-02-19T19:01:05.054399Z","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-19T19:01:05.054452Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-02-19T19:01:05.054481Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-02-19T19:01:05.054682Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2d372260bb4c37a","initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-19T19:01:05.054728Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-19T19:01:05.14016Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a is starting a new election at term 1"} {"level":"info","ts":"2026-02-19T19:01:05.140232Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became pre-candidate at term 1"} {"level":"info","ts":"2026-02-19T19:01:05.14026Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgPreVoteResp from 2d372260bb4c37a at term 1"} {"level":"info","ts":"2026-02-19T19:01:05.140279Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became candidate at term 2"} {"level":"info","ts":"2026-02-19T19:01:05.140291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgVoteResp from 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-02-19T19:01:05.140306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became leader at term 2"} {"level":"info","ts":"2026-02-19T19:01:05.14032Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2d372260bb4c37a elected leader 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-02-19T19:01:05.146158Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2d372260bb4c37a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.162:2379]}","request-path":"/0/members/2d372260bb4c37a/attributes","cluster-id":"52bb6ec15e8722ff","publish-timeout":"7s"} {"level":"info","ts":"2026-02-19T19:01:05.146469Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T19:01:05.147272Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-19T19:01:05.14766Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-19T19:01:05.14771Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T19:01:05.147832Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T19:01:05.147872Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T19:01:05.147934Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-19T19:01:05.147958Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-19T19:01:05.149327Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.162:2379"} {"level":"info","ts":"2026-02-19T19:01:05.155475Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-19T19:01:31.688637Z","caller":"traceutil/trace.go:171","msg":"trace[894894571] linearizableReadLoop","detail":"{readStateIndex:451; appliedIndex:450; }","duration":"272.152318ms","start":"2026-02-19T19:01:31.416461Z","end":"2026-02-19T19:01:31.688613Z","steps":["trace[894894571] 'read index received' (duration: 271.658765ms)","trace[894894571] 'applied index is now lower than readState.Index' (duration: 492.563µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T19:01:31.688729Z","caller":"traceutil/trace.go:171","msg":"trace[2050810485] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"318.197048ms","start":"2026-02-19T19:01:31.370487Z","end":"2026-02-19T19:01:31.688684Z","steps":["trace[2050810485] 'process raft request' (duration: 317.840509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:01:31.688857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.381344ms","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-19T19:01:31.68894Z","caller":"traceutil/trace.go:171","msg":"trace[1705331333] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:435; }","duration":"272.506007ms","start":"2026-02-19T19:01:31.41641Z","end":"2026-02-19T19:01:31.688916Z","steps":["trace[1705331333] 'agreement among raft nodes before linearized reading' (duration: 272.335473ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:01:31.689239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:01:31.370454Z","time spent":"318.361643ms","remote":"127.0.0.1:36436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317276,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-19T19:01:34.026346Z","caller":"traceutil/trace.go:171","msg":"trace[280850190] linearizableReadLoop","detail":"{readStateIndex:491; appliedIndex:490; }","duration":"286.985991ms","start":"2026-02-19T19:01:33.739322Z","end":"2026-02-19T19:01:34.026308Z","steps":["trace[280850190] 'read index received' (duration: 286.616511ms)","trace[280850190] 'applied index is now lower than readState.Index' (duration: 368.15µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T19:01:34.02644Z","caller":"traceutil/trace.go:171","msg":"trace[81480645] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"314.785848ms","start":"2026-02-19T19:01:33.711621Z","end":"2026-02-19T19:01:34.026407Z","steps":["trace[81480645] 'process raft request' (duration: 314.464769ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:01:34.02654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.189317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4021"} {"level":"info","ts":"2026-02-19T19:01:34.026577Z","caller":"traceutil/trace.go:171","msg":"trace[189431193] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:474; }","duration":"287.3114ms","start":"2026-02-19T19:01:33.739253Z","end":"2026-02-19T19:01:34.026564Z","steps":["trace[189431193] 'agreement among raft nodes before linearized reading' (duration: 287.199337ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:01:34.02677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:01:33.711602Z","time spent":"314.905901ms","remote":"127.0.0.1:36590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T19:01:34.574761Z","caller":"traceutil/trace.go:171","msg":"trace[1510433382] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"537.725552ms","start":"2026-02-19T19:01:34.036992Z","end":"2026-02-19T19:01:34.574717Z","steps":["trace[1510433382] 'process raft request' (duration: 537.432384ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:01:34.575019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:01:34.036963Z","time spent":"537.916587ms","remote":"127.0.0.1:36518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4213,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T19:01:34.575157Z","caller":"traceutil/trace.go:171","msg":"trace[1778511784] linearizableReadLoop","detail":"{readStateIndex:492; appliedIndex:491; }","duration":"148.841202ms","start":"2026-02-19T19:01:34.426284Z","end":"2026-02-19T19:01:34.575125Z","steps":["trace[1778511784] 'read index received' (duration: 147.982369ms)","trace[1778511784] 'applied index is now lower than readState.Index' (duration: 856.453µs)"],"step_count":2} {"level":"warn","ts":"2026-02-19T19:01:34.575407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.110199ms","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-19T19:01:34.575462Z","caller":"traceutil/trace.go:171","msg":"trace[875424270] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:475; }","duration":"149.245702ms","start":"2026-02-19T19:01:34.426203Z","end":"2026-02-19T19:01:34.575449Z","steps":["trace[875424270] 'agreement among raft nodes before linearized reading' (duration: 149.035867ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T19:01:40.814553Z","caller":"traceutil/trace.go:171","msg":"trace[455281435] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"201.957569ms","start":"2026-02-19T19:01:40.612572Z","end":"2026-02-19T19:01:40.81453Z","steps":["trace[455281435] 'process raft request' (duration: 201.807035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:01:41.370105Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085742820660188233,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-19T19:01:41.44064Z","caller":"traceutil/trace.go:171","msg":"trace[1716128] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"572.799782ms","start":"2026-02-19T19:01:40.867811Z","end":"2026-02-19T19:01:41.440611Z","steps":["trace[1716128] 'process raft request' (duration: 572.329549ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:01:41.440968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.718842ms","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-19T19:01:41.441044Z","caller":"traceutil/trace.go:171","msg":"trace[1255595011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:495; }","duration":"571.847396ms","start":"2026-02-19T19:01:40.869175Z","end":"2026-02-19T19:01:41.441023Z","steps":["trace[1255595011] 'agreement among raft nodes before linearized reading' (duration: 571.677521ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T19:01:41.44105Z","caller":"traceutil/trace.go:171","msg":"trace[855062276] linearizableReadLoop","detail":"{readStateIndex:513; appliedIndex:512; }","duration":"571.309342ms","start":"2026-02-19T19:01:40.869235Z","end":"2026-02-19T19:01:41.440544Z","steps":["trace[855062276] 'read index received' (duration: 570.804148ms)","trace[855062276] 'applied index is now lower than readState.Index' (duration: 503.494µs)"],"step_count":2} {"level":"warn","ts":"2026-02-19T19:01:41.441086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:01:40.869144Z","time spent":"571.934258ms","remote":"127.0.0.1:36590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-19T19:01:41.440991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:01:40.867784Z","time spent":"573.014777ms","remote":"127.0.0.1:36524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T19:01:41.44148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.630891ms","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-19T19:01:41.441621Z","caller":"traceutil/trace.go:171","msg":"trace[95446028] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:495; }","duration":"329.797556ms","start":"2026-02-19T19:01:41.111764Z","end":"2026-02-19T19:01:41.441561Z","steps":["trace[95446028] 'agreement among raft nodes before linearized reading' (duration: 329.619621ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:01:41.441707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:01:41.111741Z","time spent":"329.950669ms","remote":"127.0.0.1:36590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-19T19:01:42.700456Z","caller":"traceutil/trace.go:171","msg":"trace[2117338729] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"636.617952ms","start":"2026-02-19T19:01:42.063808Z","end":"2026-02-19T19:01:42.700426Z","steps":["trace[2117338729] 'process raft request' (duration: 636.378255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:01:42.700608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:01:42.06378Z","time spent":"636.747125ms","remote":"127.0.0.1:36590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T19:01:43.413048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.475785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-19T19:01:43.413162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.735319ms","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-19T19:01:43.413298Z","caller":"traceutil/trace.go:171","msg":"trace[56827862] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:498; }","duration":"194.896953ms","start":"2026-02-19T19:01:43.218382Z","end":"2026-02-19T19:01:43.413279Z","steps":["trace[56827862] 'range keys from in-memory index tree' (duration: 194.712148ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T19:01:43.413212Z","caller":"traceutil/trace.go:171","msg":"trace[820317911] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:498; }","duration":"594.68908ms","start":"2026-02-19T19:01:42.818497Z","end":"2026-02-19T19:01:43.413186Z","steps":["trace[820317911] 'range keys from in-memory index tree' (duration: 594.373322ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:01:43.414284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:01:42.818475Z","time spent":"595.754248ms","remote":"127.0.0.1:36590","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-19T19:01:45.486572Z","caller":"traceutil/trace.go:171","msg":"trace[59461495] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"489.909498ms","start":"2026-02-19T19:01:44.996641Z","end":"2026-02-19T19:01:45.48655Z","steps":["trace[59461495] 'process raft request' (duration: 489.785474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:01:45.486763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:01:44.996628Z","time spent":"490.020551ms","remote":"127.0.0.1:36378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4068,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T19:01:45.494302Z","caller":"traceutil/trace.go:171","msg":"trace[1188014875] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"127.848005ms","start":"2026-02-19T19:01:45.36644Z","end":"2026-02-19T19:01:45.494288Z","steps":["trace[1188014875] 'process raft request' (duration: 127.764713ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T19:01:45.494279Z","caller":"traceutil/trace.go:171","msg":"trace[566934791] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"497.138179ms","start":"2026-02-19T19:01:44.997113Z","end":"2026-02-19T19:01:45.494251Z","steps":["trace[566934791] 'process raft request' (duration: 496.981005ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T19:01:45.494392Z","caller":"traceutil/trace.go:171","msg":"trace[2140491423] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"329.143959ms","start":"2026-02-19T19:01:45.165239Z","end":"2026-02-19T19:01:45.494383Z","steps":["trace[2140491423] 'process raft request' (duration: 328.930593ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:01:45.494445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:01:45.165225Z","time spent":"329.18619ms","remote":"127.0.0.1:36378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-19T19:01:45.494438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:01:44.997107Z","time spent":"497.244782ms","remote":"127.0.0.1:36378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5562,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T19:02:03.052804Z","caller":"traceutil/trace.go:171","msg":"trace[1145599675] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"225.597955ms","start":"2026-02-19T19:02:02.827184Z","end":"2026-02-19T19:02:03.052782Z","steps":["trace[1145599675] 'process raft request' (duration: 225.479952ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:02:04.085382Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085742820660188595,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T19:02:04.137569Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.062995285s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-19T19:02:04.249393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.030382001s","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-19T19:02:04.249561Z","caller":"traceutil/trace.go:171","msg":"trace[2136759199] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:686; }","duration":"1.030561696s","start":"2026-02-19T19:02:03.21898Z","end":"2026-02-19T19:02:04.249542Z","steps":["trace[2136759199] 'range keys from in-memory index tree' (duration: 1.030366981s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:02:04.249645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.696498ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T19:02:04.249878Z","caller":"traceutil/trace.go:171","msg":"trace[1087859714] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"1.19380688s","start":"2026-02-19T19:02:03.056042Z","end":"2026-02-19T19:02:04.249849Z","steps":["trace[1087859714] 'process raft request' (duration: 1.08168971s)","trace[1087859714] 'compare' (duration: 111.395751ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T19:02:04.250002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:02:03.056022Z","time spent":"1.193908542s","remote":"127.0.0.1:36590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T19:02:04.303849Z","caller":"traceutil/trace.go:171","msg":"trace[585201858] linearizableReadLoop","detail":"{readStateIndex:711; appliedIndex:709; }","duration":"718.757398ms","start":"2026-02-19T19:02:03.585066Z","end":"2026-02-19T19:02:04.303823Z","steps":["trace[585201858] 'read index received' (duration: 552.674239ms)","trace[585201858] 'applied index is now lower than readState.Index' (duration: 166.081609ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T19:02:04.304117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.753303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-19T19:02:04.304104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.205703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T19:02:04.304161Z","caller":"traceutil/trace.go:171","msg":"trace[2059179109] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:687; }","duration":"708.844495ms","start":"2026-02-19T19:02:03.595306Z","end":"2026-02-19T19:02:04.30415Z","steps":["trace[2059179109] 'agreement among raft nodes before linearized reading' (duration: 708.675511ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T19:02:04.304183Z","caller":"traceutil/trace.go:171","msg":"trace[55546603] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:687; }","duration":"698.299046ms","start":"2026-02-19T19:02:03.605865Z","end":"2026-02-19T19:02:04.304164Z","steps":["trace[55546603] 'agreement among raft nodes before linearized reading' (duration: 698.147852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:02:04.304176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.081956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-19T19:02:04.304204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:02:03.595288Z","time spent":"708.907516ms","remote":"127.0.0.1:36590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-19T19:02:04.304228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:02:03.605826Z","time spent":"698.394228ms","remote":"127.0.0.1:36338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-19T19:02:04.304263Z","caller":"traceutil/trace.go:171","msg":"trace[357615259] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:687; }","duration":"719.208079ms","start":"2026-02-19T19:02:03.585035Z","end":"2026-02-19T19:02:04.304243Z","steps":["trace[357615259] 'agreement among raft nodes before linearized reading' (duration: 718.948642ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:02:04.304194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.01837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-19T19:02:04.304316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:02:03.58502Z","time spent":"719.286251ms","remote":"127.0.0.1:36590","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-19T19:02:04.304352Z","caller":"traceutil/trace.go:171","msg":"trace[1395398005] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:687; }","duration":"706.213665ms","start":"2026-02-19T19:02:03.59812Z","end":"2026-02-19T19:02:04.304334Z","steps":["trace[1395398005] 'agreement among raft nodes before linearized reading' (duration: 705.921897ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:02:04.304401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:02:03.598101Z","time spent":"706.290567ms","remote":"127.0.0.1:36590","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-19T19:02:04.477452Z","caller":"traceutil/trace.go:171","msg":"trace[1582763186] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"168.322938ms","start":"2026-02-19T19:02:04.309114Z","end":"2026-02-19T19:02:04.477437Z","steps":["trace[1582763186] 'process raft request' (duration: 168.296477ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T19:02:04.477498Z","caller":"traceutil/trace.go:171","msg":"trace[2091197000] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"168.546354ms","start":"2026-02-19T19:02:04.308925Z","end":"2026-02-19T19:02:04.477471Z","steps":["trace[2091197000] 'process raft request' (duration: 168.39752ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:02:05.257554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.50437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T19:02:05.257652Z","caller":"traceutil/trace.go:171","msg":"trace[720108446] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:690; }","duration":"650.656064ms","start":"2026-02-19T19:02:04.606975Z","end":"2026-02-19T19:02:05.257632Z","steps":["trace[720108446] 'range keys from in-memory index tree' (duration: 650.388267ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:02:05.257698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:02:04.606951Z","time spent":"650.737817ms","remote":"127.0.0.1:36338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T19:02:05.257778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.648521ms","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-19T19:02:05.25786Z","caller":"traceutil/trace.go:171","msg":"trace[1598842707] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:690; }","duration":"201.798015ms","start":"2026-02-19T19:02:05.056043Z","end":"2026-02-19T19:02:05.257841Z","steps":["trace[1598842707] 'range keys from in-memory index tree' (duration: 201.452366ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T19:02:06.592749Z","caller":"traceutil/trace.go:171","msg":"trace[470177195] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"109.909631ms","start":"2026-02-19T19:02:06.482814Z","end":"2026-02-19T19:02:06.592724Z","steps":["trace[470177195] 'process raft request' (duration: 15.047899ms)","trace[470177195] 'compare' (duration: 94.76288ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T19:02:06.599955Z","caller":"traceutil/trace.go:171","msg":"trace[629946690] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"116.453765ms","start":"2026-02-19T19:02:06.483297Z","end":"2026-02-19T19:02:06.59975Z","steps":["trace[629946690] 'process raft request' (duration: 116.323891ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T19:02:24.810605Z","caller":"traceutil/trace.go:171","msg":"trace[1228402775] linearizableReadLoop","detail":"{readStateIndex:781; appliedIndex:780; }","duration":"101.768631ms","start":"2026-02-19T19:02:24.708811Z","end":"2026-02-19T19:02:24.81058Z","steps":["trace[1228402775] 'read index received' (duration: 59.217442ms)","trace[1228402775] 'applied index is now lower than readState.Index' (duration: 42.550199ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T19:02:24.810764Z","caller":"traceutil/trace.go:171","msg":"trace[2017215216] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"127.716219ms","start":"2026-02-19T19:02:24.683031Z","end":"2026-02-19T19:02:24.810748Z","steps":["trace[2017215216] 'process raft request' (duration: 85.002286ms)","trace[2017215216] 'compare' (duration: 42.444096ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T19:02:24.811068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.238143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-19T19:02:24.811114Z","caller":"traceutil/trace.go:171","msg":"trace[1346757497] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:752; }","duration":"102.324326ms","start":"2026-02-19T19:02:24.708776Z","end":"2026-02-19T19:02:24.811101Z","steps":["trace[1346757497] 'agreement among raft nodes before linearized reading' (duration: 101.911135ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:02:26.461703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"850.494811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T19:02:26.461784Z","caller":"traceutil/trace.go:171","msg":"trace[2084322434] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:753; }","duration":"850.627075ms","start":"2026-02-19T19:02:25.611139Z","end":"2026-02-19T19:02:26.461766Z","steps":["trace[2084322434] 'range keys from in-memory index tree' (duration: 850.42482ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:02:26.461832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:02:25.611121Z","time spent":"850.700147ms","remote":"127.0.0.1:36338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T19:02:26.461968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.741989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-19T19:02:26.462048Z","caller":"traceutil/trace.go:171","msg":"trace[1947018541] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:753; }","duration":"483.853462ms","start":"2026-02-19T19:02:25.978176Z","end":"2026-02-19T19:02:26.46203Z","steps":["trace[1947018541] 'count revisions from in-memory index tree' (duration: 483.662176ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:02:26.4621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:02:25.978154Z","time spent":"483.927163ms","remote":"127.0.0.1:36512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":2,"response size":29,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-19T19:02:26.462174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"832.381491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-19T19:02:26.462243Z","caller":"traceutil/trace.go:171","msg":"trace[673979458] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:753; }","duration":"832.477963ms","start":"2026-02-19T19:02:25.629748Z","end":"2026-02-19T19:02:26.462226Z","steps":["trace[673979458] 'count revisions from in-memory index tree' (duration: 832.312959ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:02:26.462299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:02:25.629735Z","time spent":"832.553656ms","remote":"127.0.0.1:36378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":11,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-02-19T19:02:28.108114Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085742820660188807,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-19T19:02:28.447444Z","caller":"traceutil/trace.go:171","msg":"trace[465227009] linearizableReadLoop","detail":"{readStateIndex:787; appliedIndex:786; }","duration":"839.743927ms","start":"2026-02-19T19:02:27.607674Z","end":"2026-02-19T19:02:28.447418Z","steps":["trace[465227009] 'read index received' (duration: 839.455699ms)","trace[465227009] 'applied index is now lower than readState.Index' (duration: 287.038µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T19:02:28.447585Z","caller":"traceutil/trace.go:171","msg":"trace[469513062] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"915.487707ms","start":"2026-02-19T19:02:27.532061Z","end":"2026-02-19T19:02:28.447548Z","steps":["trace[469513062] 'process raft request' (duration: 915.166379ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:02:28.447598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.910201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T19:02:28.44778Z","caller":"traceutil/trace.go:171","msg":"trace[182912743] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:758; }","duration":"840.173338ms","start":"2026-02-19T19:02:27.607592Z","end":"2026-02-19T19:02:28.447766Z","steps":["trace[182912743] 'agreement among raft nodes before linearized reading' (duration: 839.958482ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:02:28.447797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:02:27.532035Z","time spent":"915.672702ms","remote":"127.0.0.1:36590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T19:02:28.447827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:02:27.607576Z","time spent":"840.23946ms","remote":"127.0.0.1:36338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T19:02:28.447868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.129602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-19T19:02:28.447948Z","caller":"traceutil/trace.go:171","msg":"trace[1997908129] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:758; }","duration":"697.244675ms","start":"2026-02-19T19:02:27.750684Z","end":"2026-02-19T19:02:28.447929Z","steps":["trace[1997908129] 'agreement among raft nodes before linearized reading' (duration: 697.097662ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:02:28.447996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:02:27.750668Z","time spent":"697.315137ms","remote":"127.0.0.1:36756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-02-19T19:02:28.447979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.1709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-19T19:02:28.448054Z","caller":"traceutil/trace.go:171","msg":"trace[1654421010] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:1; response_revision:758; }","duration":"241.288883ms","start":"2026-02-19T19:02:28.206746Z","end":"2026-02-19T19:02:28.448035Z","steps":["trace[1654421010] 'agreement among raft nodes before linearized reading' (duration: 241.063887ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:02:28.713205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.312212ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-02-19T19:02:28.713391Z","caller":"traceutil/trace.go:171","msg":"trace[1901040718] linearizableReadLoop","detail":"{readStateIndex:788; appliedIndex:787; }","duration":"237.975956ms","start":"2026-02-19T19:02:28.475397Z","end":"2026-02-19T19:02:28.713373Z","steps":["trace[1901040718] 'read index received' (duration: 27.431508ms)","trace[1901040718] 'applied index is now lower than readState.Index' (duration: 210.542957ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T19:02:28.713634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.194112ms","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-19T19:02:28.713674Z","caller":"traceutil/trace.go:171","msg":"trace[1622500502] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:758; }","duration":"238.322615ms","start":"2026-02-19T19:02:28.475341Z","end":"2026-02-19T19:02:28.713663Z","steps":["trace[1622500502] 'agreement among raft nodes before linearized reading' (duration: 238.097349ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:02:28.747455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.539883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T19:02:28.747511Z","caller":"traceutil/trace.go:171","msg":"trace[1614345074] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:759; }","duration":"139.626836ms","start":"2026-02-19T19:02:28.607868Z","end":"2026-02-19T19:02:28.747495Z","steps":["trace[1614345074] 'agreement among raft nodes before linearized reading' (duration: 139.540474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:02:28.747754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.128114ms","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-19T19:02:28.747837Z","caller":"traceutil/trace.go:171","msg":"trace[1382777709] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:759; }","duration":"202.251787ms","start":"2026-02-19T19:02:28.545564Z","end":"2026-02-19T19:02:28.747816Z","steps":["trace[1382777709] 'agreement among raft nodes before linearized reading' (duration: 202.034602ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:02:29.641086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.029298ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T19:02:29.641276Z","caller":"traceutil/trace.go:171","msg":"trace[1850269095] linearizableReadLoop","detail":"{readStateIndex:792; appliedIndex:791; }","duration":"848.502979ms","start":"2026-02-19T19:02:28.792752Z","end":"2026-02-19T19:02:29.641255Z","steps":["trace[1850269095] 'read index received' (duration: 38.159813ms)","trace[1850269095] 'applied index is now lower than readState.Index' (duration: 810.341676ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T19:02:29.641345Z","caller":"traceutil/trace.go:171","msg":"trace[280407397] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"888.614684ms","start":"2026-02-19T19:02:28.752695Z","end":"2026-02-19T19:02:29.64131Z","steps":["trace[280407397] 'process raft request' (duration: 78.225856ms)","trace[280407397] 'compare' (duration: 809.928525ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T19:02:29.641427Z","caller":"traceutil/trace.go:171","msg":"trace[1977299917] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"846.367782ms","start":"2026-02-19T19:02:28.795041Z","end":"2026-02-19T19:02:29.641408Z","steps":["trace[1977299917] 'process raft request' (duration: 846.159627ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:02:29.641529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:02:28.752678Z","time spent":"888.747197ms","remote":"127.0.0.1:36590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T19:02:29.641532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:02:28.795026Z","time spent":"846.457064ms","remote":"127.0.0.1:36590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T19:02:29.641701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"848.875629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"warn","ts":"2026-02-19T19:02:29.641734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"800.363401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-19T19:02:29.641758Z","caller":"traceutil/trace.go:171","msg":"trace[626566311] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:763; }","duration":"849.024563ms","start":"2026-02-19T19:02:28.792719Z","end":"2026-02-19T19:02:29.641743Z","steps":["trace[626566311] 'agreement among raft nodes before linearized reading' (duration: 848.732515ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:02:29.641772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.362511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-19T19:02:29.641788Z","caller":"traceutil/trace.go:171","msg":"trace[944012835] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:763; }","duration":"800.431353ms","start":"2026-02-19T19:02:28.841335Z","end":"2026-02-19T19:02:29.641766Z","steps":["trace[944012835] 'agreement among raft nodes before linearized reading' (duration: 800.263268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:02:29.64181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:02:28.792706Z","time spent":"849.091955ms","remote":"127.0.0.1:36512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-02-19T19:02:29.641816Z","caller":"traceutil/trace.go:171","msg":"trace[622466617] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:763; }","duration":"638.443964ms","start":"2026-02-19T19:02:29.003358Z","end":"2026-02-19T19:02:29.641802Z","steps":["trace[622466617] 'agreement among raft nodes before linearized reading' (duration: 638.346791ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:02:29.642727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:02:29.003323Z","time spent":"639.356788ms","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":40,"response size":29,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-02-19T19:02:29.641828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:02:28.841322Z","time spent":"800.498375ms","remote":"127.0.0.1:36590","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-19T19:02:30.014651Z","caller":"traceutil/trace.go:171","msg":"trace[1616786494] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"367.76609ms","start":"2026-02-19T19:02:29.646859Z","end":"2026-02-19T19:02:30.014625Z","steps":["trace[1616786494] 'process raft request' (duration: 367.635837ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:02:30.014796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:02:29.646841Z","time spent":"367.884734ms","remote":"127.0.0.1:36590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T19:02:33.77395Z","caller":"traceutil/trace.go:171","msg":"trace[401069822] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"112.903037ms","start":"2026-02-19T19:02:33.661026Z","end":"2026-02-19T19:02:33.773929Z","steps":["trace[401069822] 'process raft request' (duration: 112.768193ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T19:02:38.837519Z","caller":"traceutil/trace.go:171","msg":"trace[1355107393] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"113.276956ms","start":"2026-02-19T19:02:38.724206Z","end":"2026-02-19T19:02:38.837483Z","steps":["trace[1355107393] 'process raft request' (duration: 57.590198ms)","trace[1355107393] 'compare' (duration: 55.439632ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T19:02:39.132887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.04979ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T19:02:39.133104Z","caller":"traceutil/trace.go:171","msg":"trace[1368443698] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"293.011696ms","start":"2026-02-19T19:02:38.840061Z","end":"2026-02-19T19:02:39.133072Z","steps":["trace[1368443698] 'process raft request' (duration: 98.696039ms)","trace[1368443698] 'compare' (duration: 193.868895ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T19:02:39.133225Z","caller":"traceutil/trace.go:171","msg":"trace[1819499582] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"291.956789ms","start":"2026-02-19T19:02:38.841218Z","end":"2026-02-19T19:02:39.133175Z","steps":["trace[1819499582] 'process raft request' (duration: 291.767874ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T19:02:39.133345Z","caller":"traceutil/trace.go:171","msg":"trace[1186255268] linearizableReadLoop","detail":"{readStateIndex:841; appliedIndex:836; }","duration":"102.451349ms","start":"2026-02-19T19:02:39.030878Z","end":"2026-02-19T19:02:39.133329Z","steps":["trace[1186255268] 'read index received' (duration: 14.152795ms)","trace[1186255268] 'applied index is now lower than readState.Index' (duration: 88.297634ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T19:02:39.133415Z","caller":"traceutil/trace.go:171","msg":"trace[414186859] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"237.961976ms","start":"2026-02-19T19:02:38.895405Z","end":"2026-02-19T19:02:39.133367Z","steps":["trace[414186859] 'process raft request' (duration: 237.874443ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:02:39.133458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.577282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-19T19:02:39.133464Z","caller":"traceutil/trace.go:171","msg":"trace[1313560047] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"285.761654ms","start":"2026-02-19T19:02:38.847684Z","end":"2026-02-19T19:02:39.133445Z","steps":["trace[1313560047] 'process raft request' (duration: 285.404674ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T19:02:39.133549Z","caller":"traceutil/trace.go:171","msg":"trace[1045124638] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:808; }","duration":"102.646634ms","start":"2026-02-19T19:02:39.030846Z","end":"2026-02-19T19:02:39.133493Z","steps":["trace[1045124638] 'agreement among raft nodes before linearized reading' (duration: 102.525991ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T19:02:39.133304Z","caller":"traceutil/trace.go:171","msg":"trace[1814935389] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"286.824882ms","start":"2026-02-19T19:02:38.846452Z","end":"2026-02-19T19:02:39.133277Z","steps":["trace[1814935389] 'process raft request' (duration: 286.598916ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T19:02:42.291041Z","caller":"traceutil/trace.go:171","msg":"trace[1532365463] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"166.777646ms","start":"2026-02-19T19:02:42.124237Z","end":"2026-02-19T19:02:42.291014Z","steps":["trace[1532365463] 'process raft request' (duration: 166.640652ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:02:42.912738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.021996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T19:02:42.91283Z","caller":"traceutil/trace.go:171","msg":"trace[1547171968] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:849; }","duration":"305.114478ms","start":"2026-02-19T19:02:42.607685Z","end":"2026-02-19T19:02:42.912799Z","steps":["trace[1547171968] 'range keys from in-memory index tree' (duration: 304.941293ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:02:42.912865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:02:42.607618Z","time spent":"305.241092ms","remote":"127.0.0.1:36338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T19:02:42.912805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.2506ms","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-19T19:02:42.912977Z","caller":"traceutil/trace.go:171","msg":"trace[199189722] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:849; }","duration":"401.459454ms","start":"2026-02-19T19:02:42.511501Z","end":"2026-02-19T19:02:42.91296Z","steps":["trace[199189722] 'range keys from in-memory index tree' (duration: 401.089165ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:02:42.913027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:02:42.511484Z","time spent":"401.527727ms","remote":"127.0.0.1:36590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-19T19:02:45.1095Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085742820660189024,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T19:02:45.609679Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085742820660189024,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T19:02:45.915493Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.617529049s","expected-duration":"1s"} {"level":"info","ts":"2026-02-19T19:02:45.915898Z","caller":"traceutil/trace.go:171","msg":"trace[22265224] linearizableReadLoop","detail":"{readStateIndex:889; appliedIndex:888; }","duration":"1.306835744s","start":"2026-02-19T19:02:44.609038Z","end":"2026-02-19T19:02:45.915873Z","steps":["trace[22265224] 'read index received' (duration: 1.306621898s)","trace[22265224] 'applied index is now lower than readState.Index' (duration: 212.676µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T19:02:45.915938Z","caller":"traceutil/trace.go:171","msg":"trace[1210795872] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"1.618015853s","start":"2026-02-19T19:02:44.29789Z","end":"2026-02-19T19:02:45.915906Z","steps":["trace[1210795872] 'process raft request' (duration: 1.617848648s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:02:45.91606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.306996938s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T19:02:45.916092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:02:44.297881Z","time spent":"1.618110785s","remote":"127.0.0.1:36590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T19:02:45.916125Z","caller":"traceutil/trace.go:171","msg":"trace[798523970] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:854; }","duration":"1.307102471s","start":"2026-02-19T19:02:44.609005Z","end":"2026-02-19T19:02:45.916108Z","steps":["trace[798523970] 'agreement among raft nodes before linearized reading' (duration: 1.306974718s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:02:45.916145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"995.268265ms","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-19T19:02:45.916163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:02:44.60899Z","time spent":"1.307164782s","remote":"127.0.0.1:36338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-19T19:02:45.916184Z","caller":"traceutil/trace.go:171","msg":"trace[2046704399] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:854; }","duration":"995.357498ms","start":"2026-02-19T19:02:44.920814Z","end":"2026-02-19T19:02:45.916171Z","steps":["trace[2046704399] 'agreement among raft nodes before linearized reading' (duration: 995.197733ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:02:45.916225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:02:44.920794Z","time spent":"995.422439ms","remote":"127.0.0.1:36590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-19T19:02:45.916417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.152825ms","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-19T19:02:45.916466Z","caller":"traceutil/trace.go:171","msg":"trace[870758254] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:854; }","duration":"760.232557ms","start":"2026-02-19T19:02:45.156221Z","end":"2026-02-19T19:02:45.916453Z","steps":["trace[870758254] 'agreement among raft nodes before linearized reading' (duration: 760.119284ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:02:45.9165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:02:45.156201Z","time spent":"760.291918ms","remote":"127.0.0.1:36590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-19T19:02:46.676843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.97924ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T19:02:46.677128Z","caller":"traceutil/trace.go:171","msg":"trace[480022079] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"757.114884ms","start":"2026-02-19T19:02:45.919983Z","end":"2026-02-19T19:02:46.677098Z","steps":["trace[480022079] 'process raft request' (duration: 424.781294ms)","trace[480022079] 'compare' (duration: 331.675292ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T19:02:46.677134Z","caller":"traceutil/trace.go:171","msg":"trace[952878732] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"757.021501ms","start":"2026-02-19T19:02:45.920096Z","end":"2026-02-19T19:02:46.677117Z","steps":["trace[952878732] 'process raft request' (duration: 756.868757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:02:46.67722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:02:45.919967Z","time spent":"757.205837ms","remote":"127.0.0.1:36590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T19:02:46.677229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:02:45.920072Z","time spent":"757.106324ms","remote":"127.0.0.1:36590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T19:02:46.677375Z","caller":"traceutil/trace.go:171","msg":"trace[1895451849] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"756.968751ms","start":"2026-02-19T19:02:45.920384Z","end":"2026-02-19T19:02:46.677353Z","steps":["trace[1895451849] 'process raft request' (duration: 756.688143ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T19:02:46.677434Z","caller":"traceutil/trace.go:171","msg":"trace[115491436] linearizableReadLoop","detail":"{readStateIndex:893; appliedIndex:889; }","duration":"752.873371ms","start":"2026-02-19T19:02:45.924543Z","end":"2026-02-19T19:02:46.677417Z","steps":["trace[115491436] 'read index received' (duration: 420.197142ms)","trace[115491436] 'applied index is now lower than readState.Index' (duration: 332.673439ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T19:02:46.677514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.968204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T19:02:46.677508Z","caller":"traceutil/trace.go:171","msg":"trace[2039835453] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"757.062292ms","start":"2026-02-19T19:02:45.920434Z","end":"2026-02-19T19:02:46.677497Z","steps":["trace[2039835453] 'process raft request' (duration: 756.848937ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:02:46.677515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:02:45.920364Z","time spent":"757.079603ms","remote":"127.0.0.1:36590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T19:02:46.677536Z","caller":"traceutil/trace.go:171","msg":"trace[2103320226] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:858; }","duration":"753.020645ms","start":"2026-02-19T19:02:45.924507Z","end":"2026-02-19T19:02:46.677528Z","steps":["trace[2103320226] 'agreement among raft nodes before linearized reading' (duration: 752.966604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:02:46.677593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:02:45.924492Z","time spent":"753.095698ms","remote":"127.0.0.1:36338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T19:02:46.677597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:02:45.920426Z","time spent":"757.111864ms","remote":"127.0.0.1:36590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T19:03:02.920045Z","caller":"traceutil/trace.go:171","msg":"trace[1478934802] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"154.407108ms","start":"2026-02-19T19:03:02.765611Z","end":"2026-02-19T19:03:02.920018Z","steps":["trace[1478934802] 'process raft request' (duration: 154.365117ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T19:03:02.920065Z","caller":"traceutil/trace.go:171","msg":"trace[1922313278] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"155.305802ms","start":"2026-02-19T19:03:02.76472Z","end":"2026-02-19T19:03:02.920026Z","steps":["trace[1922313278] 'process raft request' (duration: 139.80342ms)","trace[1922313278] 'compare' (duration: 15.288656ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T19:03:02.920077Z","caller":"traceutil/trace.go:171","msg":"trace[1790740530] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"154.558163ms","start":"2026-02-19T19:03:02.76549Z","end":"2026-02-19T19:03:02.920048Z","steps":["trace[1790740530] 'process raft request' (duration: 154.44881ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T19:03:06.530853Z","caller":"traceutil/trace.go:171","msg":"trace[763645984] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"506.244806ms","start":"2026-02-19T19:03:06.024583Z","end":"2026-02-19T19:03:06.530827Z","steps":["trace[763645984] 'process raft request' (duration: 506.01796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:03:06.531049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:03:06.024566Z","time spent":"506.38215ms","remote":"127.0.0.1:36590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T19:03:07.853475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.643399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T19:03:07.853567Z","caller":"traceutil/trace.go:171","msg":"trace[1507796806] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:942; }","duration":"245.782713ms","start":"2026-02-19T19:03:07.607766Z","end":"2026-02-19T19:03:07.853549Z","steps":["trace[1507796806] 'range keys from in-memory index tree' (duration: 245.587658ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T19:03:08.548189Z","caller":"traceutil/trace.go:171","msg":"trace[268080785] linearizableReadLoop","detail":"{readStateIndex:982; appliedIndex:981; }","duration":"339.574183ms","start":"2026-02-19T19:03:08.208593Z","end":"2026-02-19T19:03:08.548167Z","steps":["trace[268080785] 'read index received' (duration: 339.337077ms)","trace[268080785] 'applied index is now lower than readState.Index' (duration: 236.296µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T19:03:08.548311Z","caller":"traceutil/trace.go:171","msg":"trace[328754884] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"487.836768ms","start":"2026-02-19T19:03:08.060439Z","end":"2026-02-19T19:03:08.548275Z","steps":["trace[328754884] 'process raft request' (duration: 487.55955ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:03:08.548478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:03:08.060419Z","time spent":"487.972321ms","remote":"127.0.0.1:36590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T19:03:08.548503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.901201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-19T19:03:08.548567Z","caller":"traceutil/trace.go:171","msg":"trace[740869536] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:1; response_revision:943; }","duration":"340.016894ms","start":"2026-02-19T19:03:08.208538Z","end":"2026-02-19T19:03:08.548555Z","steps":["trace[740869536] 'agreement among raft nodes before linearized reading' (duration: 339.763667ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:03:08.548679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:03:08.208519Z","time spent":"340.150957ms","remote":"127.0.0.1:36356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.162\" "} {"level":"warn","ts":"2026-02-19T19:03:08.890974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.543199ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-19T19:03:08.89107Z","caller":"traceutil/trace.go:171","msg":"trace[545265268] linearizableReadLoop","detail":"{readStateIndex:983; appliedIndex:982; }","duration":"342.797688ms","start":"2026-02-19T19:03:08.548256Z","end":"2026-02-19T19:03:08.891054Z","steps":["trace[545265268] 'read index received' (duration: 112.093715ms)","trace[545265268] 'applied index is now lower than readState.Index' (duration: 230.702723ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T19:03:08.891148Z","caller":"traceutil/trace.go:171","msg":"trace[510821555] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"530.956762ms","start":"2026-02-19T19:03:08.360154Z","end":"2026-02-19T19:03:08.891111Z","steps":["trace[510821555] 'process raft request' (duration: 300.206618ms)","trace[510821555] 'compare' (duration: 230.430855ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T19:03:08.891248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.486255ms","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-19T19:03:08.891287Z","caller":"traceutil/trace.go:171","msg":"trace[571047787] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:944; }","duration":"355.568517ms","start":"2026-02-19T19:03:08.535707Z","end":"2026-02-19T19:03:08.891276Z","steps":["trace[571047787] 'agreement among raft nodes before linearized reading' (duration: 355.411313ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:03:08.891294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:03:08.360116Z","time spent":"531.110236ms","remote":"127.0.0.1:36416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":782,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-19T19:03:08.891319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:03:08.535682Z","time spent":"355.628748ms","remote":"127.0.0.1:36590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-19T19:03:08.91811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:03:08.549544Z","time spent":"368.562271ms","remote":"127.0.0.1:36356","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-19T19:03:08.918189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.935315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T19:03:08.918198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.807397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T19:03:08.918255Z","caller":"traceutil/trace.go:171","msg":"trace[9971168] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:944; }","duration":"310.034507ms","start":"2026-02-19T19:03:08.608203Z","end":"2026-02-19T19:03:08.918238Z","steps":["trace[9971168] 'agreement among raft nodes before linearized reading' (duration: 309.924835ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T19:03:08.918271Z","caller":"traceutil/trace.go:171","msg":"trace[399242720] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:944; }","duration":"189.914399ms","start":"2026-02-19T19:03:08.72834Z","end":"2026-02-19T19:03:08.918254Z","steps":["trace[399242720] 'agreement among raft nodes before linearized reading' (duration: 189.790656ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:03:08.918295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:03:08.608191Z","time spent":"310.096889ms","remote":"127.0.0.1:36338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T19:03:09.16883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.178103ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T19:03:09.169051Z","caller":"traceutil/trace.go:171","msg":"trace[1832512191] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"205.69964ms","start":"2026-02-19T19:03:08.963334Z","end":"2026-02-19T19:03:09.169033Z","steps":["trace[1832512191] 'process raft request' (duration: 205.617988ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T19:03:09.169048Z","caller":"traceutil/trace.go:171","msg":"trace[1726877290] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"248.957597ms","start":"2026-02-19T19:03:08.920059Z","end":"2026-02-19T19:03:09.169016Z","steps":["trace[1726877290] 'process raft request' (duration: 74.495267ms)","trace[1726877290] 'compare' (duration: 174.027719ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T19:03:09.16917Z","caller":"traceutil/trace.go:171","msg":"trace[715820702] linearizableReadLoop","detail":"{readStateIndex:986; appliedIndex:985; }","duration":"208.844933ms","start":"2026-02-19T19:03:08.960234Z","end":"2026-02-19T19:03:09.169079Z","steps":["trace[715820702] 'read index received' (duration: 34.324381ms)","trace[715820702] 'applied index is now lower than readState.Index' (duration: 174.489141ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T19:03:09.169396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.853645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T19:03:09.169501Z","caller":"traceutil/trace.go:171","msg":"trace[1396148018] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:947; }","duration":"246.01611ms","start":"2026-02-19T19:03:08.923463Z","end":"2026-02-19T19:03:09.169479Z","steps":["trace[1396148018] 'agreement among raft nodes before linearized reading' (duration: 245.800004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:03:09.169563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.557087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-19T19:03:09.169437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.093028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T19:03:09.169607Z","caller":"traceutil/trace.go:171","msg":"trace[2019678024] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:947; }","duration":"115.64787ms","start":"2026-02-19T19:03:09.053948Z","end":"2026-02-19T19:03:09.169596Z","steps":["trace[2019678024] 'agreement among raft nodes before linearized reading' (duration: 115.487495ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T19:03:09.170539Z","caller":"traceutil/trace.go:171","msg":"trace[547366427] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:947; }","duration":"250.337674ms","start":"2026-02-19T19:03:08.919298Z","end":"2026-02-19T19:03:09.169636Z","steps":["trace[547366427] 'agreement among raft nodes before linearized reading' (duration: 250.101537ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:03:09.787303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.843189ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T19:03:09.787569Z","caller":"traceutil/trace.go:171","msg":"trace[1603470257] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"612.091604ms","start":"2026-02-19T19:03:09.17546Z","end":"2026-02-19T19:03:09.787551Z","steps":["trace[1603470257] 'process raft request' (duration: 611.93929ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T19:03:09.787569Z","caller":"traceutil/trace.go:171","msg":"trace[886613759] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"613.378979ms","start":"2026-02-19T19:03:09.174156Z","end":"2026-02-19T19:03:09.787535Z","steps":["trace[886613759] 'process raft request' (duration: 296.200341ms)","trace[886613759] 'compare' (duration: 316.669554ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T19:03:09.787683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:03:09.175452Z","time spent":"612.151777ms","remote":"127.0.0.1:36590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T19:03:09.787853Z","caller":"traceutil/trace.go:171","msg":"trace[483042555] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"612.130956ms","start":"2026-02-19T19:03:09.175696Z","end":"2026-02-19T19:03:09.787827Z","steps":["trace[483042555] 'process raft request' (duration: 611.817707ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:03:09.788027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:03:09.175689Z","time spent":"612.230718ms","remote":"127.0.0.1:36590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T19:03:09.78817Z","caller":"traceutil/trace.go:171","msg":"trace[1671415110] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"554.751563ms","start":"2026-02-19T19:03:09.233406Z","end":"2026-02-19T19:03:09.788158Z","steps":["trace[1671415110] 'process raft request' (duration: 554.701362ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:03:09.788238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:03:09.233391Z","time spent":"554.818475ms","remote":"127.0.0.1:36416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":871,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-19T19:03:09.788394Z","caller":"traceutil/trace.go:171","msg":"trace[24573606] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"565.629411ms","start":"2026-02-19T19:03:09.222756Z","end":"2026-02-19T19:03:09.788385Z","steps":["trace[24573606] 'process raft request' (duration: 564.989614ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:03:09.788446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:03:09.222723Z","time spent":"565.700333ms","remote":"127.0.0.1:36416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":782,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-19T19:03:09.788473Z","caller":"traceutil/trace.go:171","msg":"trace[745364852] linearizableReadLoop","detail":"{readStateIndex:992; appliedIndex:988; }","duration":"557.504516ms","start":"2026-02-19T19:03:09.230923Z","end":"2026-02-19T19:03:09.788427Z","steps":["trace[745364852] 'read index received' (duration: 239.385913ms)","trace[745364852] 'applied index is now lower than readState.Index' (duration: 318.115422ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T19:03:09.788745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.848859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-19T19:03:09.78879Z","caller":"traceutil/trace.go:171","msg":"trace[1025220413] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:953; }","duration":"111.940241ms","start":"2026-02-19T19:03:09.676837Z","end":"2026-02-19T19:03:09.788777Z","steps":["trace[1025220413] 'agreement among raft nodes before linearized reading' (duration: 111.856689ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:03:09.788802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.868956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-02-19T19:03:09.788818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.311704ms","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-19T19:03:09.788858Z","caller":"traceutil/trace.go:171","msg":"trace[1646567887] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:953; }","duration":"557.979609ms","start":"2026-02-19T19:03:09.230863Z","end":"2026-02-19T19:03:09.788843Z","steps":["trace[1646567887] 'agreement among raft nodes before linearized reading' (duration: 557.694271ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:03:09.788904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:03:09.230846Z","time spent":"558.047291ms","remote":"127.0.0.1:36356","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":"info","ts":"2026-02-19T19:03:09.788902Z","caller":"traceutil/trace.go:171","msg":"trace[120329774] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:953; }","duration":"556.405427ms","start":"2026-02-19T19:03:09.232465Z","end":"2026-02-19T19:03:09.78887Z","steps":["trace[120329774] 'agreement among raft nodes before linearized reading' (duration: 556.13228ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:03:09.788951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:03:09.232458Z","time spent":"556.483799ms","remote":"127.0.0.1:36590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-19T19:03:09.789047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:03:09.174148Z","time spent":"613.577784ms","remote":"127.0.0.1:36590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T19:03:10.939537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.63334ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-19T19:03:10.939823Z","caller":"traceutil/trace.go:171","msg":"trace[121487863] linearizableReadLoop","detail":"{readStateIndex:996; appliedIndex:993; }","duration":"331.471418ms","start":"2026-02-19T19:03:10.608335Z","end":"2026-02-19T19:03:10.939806Z","steps":["trace[121487863] 'read index received' (duration: 10.493769ms)","trace[121487863] 'applied index is now lower than readState.Index' (duration: 320.976289ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T19:03:10.939921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.57818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T19:03:10.939948Z","caller":"traceutil/trace.go:171","msg":"trace[1414249672] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:956; }","duration":"331.644972ms","start":"2026-02-19T19:03:10.608293Z","end":"2026-02-19T19:03:10.939938Z","steps":["trace[1414249672] 'agreement among raft nodes before linearized reading' (duration: 331.56301ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:03:10.939979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:03:10.608274Z","time spent":"331.698243ms","remote":"127.0.0.1:36338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-19T19:03:10.93998Z","caller":"traceutil/trace.go:171","msg":"trace[1091990489] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"1.145604925s","start":"2026-02-19T19:03:09.794354Z","end":"2026-02-19T19:03:10.939959Z","steps":["trace[1091990489] 'process raft request' (duration: 1.145316007s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:03:10.940099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:03:09.794333Z","time spent":"1.145694477s","remote":"127.0.0.1:36590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T19:03:10.940182Z","caller":"traceutil/trace.go:171","msg":"trace[1912660095] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"844.962636ms","start":"2026-02-19T19:03:10.095203Z","end":"2026-02-19T19:03:10.940166Z","steps":["trace[1912660095] 'process raft request' (duration: 844.565786ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T19:03:10.940168Z","caller":"traceutil/trace.go:171","msg":"trace[1056179004] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"1.146412326s","start":"2026-02-19T19:03:09.793726Z","end":"2026-02-19T19:03:10.940138Z","steps":["trace[1056179004] 'process raft request' (duration: 825.091458ms)","trace[1056179004] 'compare' (duration: 320.393204ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T19:03:10.940267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:03:10.095177Z","time spent":"845.047648ms","remote":"127.0.0.1:36590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T19:03:10.940295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:03:09.793699Z","time spent":"1.14653333s","remote":"127.0.0.1:36416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":823,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-19T19:03:12.544288Z","caller":"traceutil/trace.go:171","msg":"trace[1665979454] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"746.81738ms","start":"2026-02-19T19:03:11.797451Z","end":"2026-02-19T19:03:12.544269Z","steps":["trace[1665979454] 'process raft request' (duration: 746.608034ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:03:12.544417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:03:11.797432Z","time spent":"746.910243ms","remote":"127.0.0.1:36590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T19:03:13.100746Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085742820660189267,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-19T19:03:13.154742Z","caller":"traceutil/trace.go:171","msg":"trace[789753245] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"1.35606835s","start":"2026-02-19T19:03:11.798621Z","end":"2026-02-19T19:03:13.154689Z","steps":["trace[789753245] 'process raft request' (duration: 1.355821843s)"],"step_count":1} {"level":"info","ts":"2026-02-19T19:03:13.154746Z","caller":"traceutil/trace.go:171","msg":"trace[414784439] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"1.355956506s","start":"2026-02-19T19:03:11.798762Z","end":"2026-02-19T19:03:13.154718Z","steps":["trace[414784439] 'process raft request' (duration: 1.355855634s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:03:13.154951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:03:11.798602Z","time spent":"1.356238335s","remote":"127.0.0.1:36590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T19:03:13.155057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:03:11.798747Z","time spent":"1.356175823s","remote":"127.0.0.1:36590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T19:03:13.155307Z","caller":"traceutil/trace.go:171","msg":"trace[1983355448] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"709.758767ms","start":"2026-02-19T19:03:12.445529Z","end":"2026-02-19T19:03:13.155287Z","steps":["trace[1983355448] 'process raft request' (duration: 709.12864ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:03:13.155419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:03:12.445496Z","time spent":"709.88675ms","remote":"127.0.0.1:36518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5552,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T19:03:13.155402Z","caller":"traceutil/trace.go:171","msg":"trace[1605028534] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"670.690499ms","start":"2026-02-19T19:03:12.484668Z","end":"2026-02-19T19:03:13.155358Z","steps":["trace[1605028534] 'process raft request' (duration: 670.427392ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T19:03:13.155475Z","caller":"traceutil/trace.go:171","msg":"trace[1414140896] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:999; }","duration":"554.876287ms","start":"2026-02-19T19:03:12.600398Z","end":"2026-02-19T19:03:13.155275Z","steps":["trace[1414140896] 'read index received' (duration: 553.940162ms)","trace[1414140896] 'applied index is now lower than readState.Index' (duration: 935.155µs)"],"step_count":2} {"level":"warn","ts":"2026-02-19T19:03:13.155669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:03:12.48465Z","time spent":"670.866144ms","remote":"127.0.0.1:36590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T19:03:13.155692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.595603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T19:03:13.155811Z","caller":"traceutil/trace.go:171","msg":"trace[1402180879] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:963; }","duration":"547.712566ms","start":"2026-02-19T19:03:12.608085Z","end":"2026-02-19T19:03:13.155798Z","steps":["trace[1402180879] 'agreement among raft nodes before linearized reading' (duration: 547.513431ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:03:13.155765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.320459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T19:03:13.155892Z","caller":"traceutil/trace.go:171","msg":"trace[1567124440] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:963; }","duration":"555.525504ms","start":"2026-02-19T19:03:12.600354Z","end":"2026-02-19T19:03:13.15588Z","steps":["trace[1567124440] 'agreement among raft nodes before linearized reading' (duration: 555.224796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:03:13.15598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:03:12.608066Z","time spent":"547.801579ms","remote":"127.0.0.1:36340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T19:03:13.156121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.144147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T19:03:13.156168Z","caller":"traceutil/trace.go:171","msg":"trace[420141036] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:963; }","duration":"522.203319ms","start":"2026-02-19T19:03:12.633947Z","end":"2026-02-19T19:03:13.156151Z","steps":["trace[420141036] 'agreement among raft nodes before linearized reading' (duration: 522.140168ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:03:13.156202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:03:12.633936Z","time spent":"522.258441ms","remote":"127.0.0.1:36876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":27,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-19T19:03:13.156008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:03:12.600341Z","time spent":"555.651447ms","remote":"127.0.0.1:36596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":27,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-19T19:03:13.156509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.961053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-19T19:03:13.15667Z","caller":"traceutil/trace.go:171","msg":"trace[253196282] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:963; }","duration":"129.123757ms","start":"2026-02-19T19:03:13.027532Z","end":"2026-02-19T19:03:13.156655Z","steps":["trace[253196282] 'agreement among raft nodes before linearized reading' (duration: 128.693005ms)"],"step_count":1}