{"level":"warn","ts":"2026-02-12T16:57:30.482378Z","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-12T16:57:30.4826Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.47: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.19.213.47:2380","--initial-cluster=instance=https://199.19.213.47:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.47:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.47: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-12T16:57:30.482742Z","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-12T16:57:30.482836Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.47:2380"]} {"level":"info","ts":"2026-02-12T16:57:30.482922Z","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-12T16:57:30.483655Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.47:2379"]} {"level":"info","ts":"2026-02-12T16:57:30.483809Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.47:2380"],"listen-peer-urls":["https://199.19.213.47:2380"],"advertise-client-urls":["https://199.19.213.47:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.47:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.47: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-12T16:57:30.503278Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"19.120074ms"} {"level":"info","ts":"2026-02-12T16:57:30.526892Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"944e7e1987ec6eaf","cluster-id":"cb5c24893c5cbfa7"} {"level":"info","ts":"2026-02-12T16:57:30.527067Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf switched to configuration voters=()"} {"level":"info","ts":"2026-02-12T16:57:30.527149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf became follower at term 0"} {"level":"info","ts":"2026-02-12T16:57:30.527191Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 944e7e1987ec6eaf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-12T16:57:30.527249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf became follower at term 1"} {"level":"info","ts":"2026-02-12T16:57:30.527334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf switched to configuration voters=(10686617613916466863)"} {"level":"warn","ts":"2026-02-12T16:57:30.545747Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-12T16:57:30.565784Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-12T16:57:30.6612Z","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-12T16:57:30.674673Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"944e7e1987ec6eaf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-12T16:57:30.680577Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"944e7e1987ec6eaf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-12T16:57:30.680824Z","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-12T16:57:30.680886Z","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-12T16:57:30.680919Z","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-12T16:57:30.694759Z","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-12T16:57:30.69525Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"944e7e1987ec6eaf","initial-advertise-peer-urls":["https://199.19.213.47:2380"],"listen-peer-urls":["https://199.19.213.47:2380"],"advertise-client-urls":["https://199.19.213.47:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.47:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-12T16:57:30.695274Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-12T16:57:30.694983Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.47:2380"} {"level":"info","ts":"2026-02-12T16:57:30.695305Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.47:2380"} {"level":"info","ts":"2026-02-12T16:57:30.705891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf switched to configuration voters=(10686617613916466863)"} {"level":"info","ts":"2026-02-12T16:57:30.705976Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cb5c24893c5cbfa7","local-member-id":"944e7e1987ec6eaf","added-peer-id":"944e7e1987ec6eaf","added-peer-peer-urls":["https://199.19.213.47:2380"]} {"level":"info","ts":"2026-02-12T16:57:30.727785Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf is starting a new election at term 1"} {"level":"info","ts":"2026-02-12T16:57:30.727829Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf became pre-candidate at term 1"} {"level":"info","ts":"2026-02-12T16:57:30.727852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf received MsgPreVoteResp from 944e7e1987ec6eaf at term 1"} {"level":"info","ts":"2026-02-12T16:57:30.727864Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf became candidate at term 2"} {"level":"info","ts":"2026-02-12T16:57:30.727869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf received MsgVoteResp from 944e7e1987ec6eaf at term 2"} {"level":"info","ts":"2026-02-12T16:57:30.727881Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf became leader at term 2"} {"level":"info","ts":"2026-02-12T16:57:30.727888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 944e7e1987ec6eaf elected leader 944e7e1987ec6eaf at term 2"} {"level":"info","ts":"2026-02-12T16:57:30.742672Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T16:57:30.751625Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"944e7e1987ec6eaf","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.47:2379]}","request-path":"/0/members/944e7e1987ec6eaf/attributes","cluster-id":"cb5c24893c5cbfa7","publish-timeout":"7s"} {"level":"info","ts":"2026-02-12T16:57:30.751778Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-12T16:57:30.752127Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-12T16:57:30.753923Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.47:2379"} {"level":"info","ts":"2026-02-12T16:57:30.754074Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-12T16:57:30.754167Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-12T16:57:30.754428Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cb5c24893c5cbfa7","local-member-id":"944e7e1987ec6eaf","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T16:57:30.75449Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T16:57:30.754504Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T16:57:30.761347Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-12T16:57:36.550821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.24384ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-12T16:57:36.550913Z","caller":"traceutil/trace.go:171","msg":"trace[920743178] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"127.358594ms","start":"2026-02-12T16:57:36.423541Z","end":"2026-02-12T16:57:36.550899Z","steps":["trace[920743178] 'process raft request' (duration: 23.678071ms)","trace[920743178] 'compare' (duration: 103.152827ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:57:36.551463Z","caller":"traceutil/trace.go:171","msg":"trace[836285878] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"112.844118ms","start":"2026-02-12T16:57:36.438608Z","end":"2026-02-12T16:57:36.551452Z","steps":["trace[836285878] 'process raft request' (duration: 112.806717ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:57:36.551609Z","caller":"traceutil/trace.go:171","msg":"trace[1976152062] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"118.155427ms","start":"2026-02-12T16:57:36.433448Z","end":"2026-02-12T16:57:36.551603Z","steps":["trace[1976152062] 'process raft request' (duration: 117.899419ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:57:36.611626Z","caller":"traceutil/trace.go:171","msg":"trace[1247469484] transaction","detail":"{read_only:false; number_of_response:0; response_revision:274; }","duration":"135.752626ms","start":"2026-02-12T16:57:36.475852Z","end":"2026-02-12T16:57:36.611605Z","steps":["trace[1247469484] 'process raft request' (duration: 135.641123ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:57:36.753149Z","caller":"traceutil/trace.go:171","msg":"trace[178041302] linearizableReadLoop","detail":"{readStateIndex:283; appliedIndex:281; }","duration":"130.167518ms","start":"2026-02-12T16:57:36.622963Z","end":"2026-02-12T16:57:36.753131Z","steps":["trace[178041302] 'read index received' (duration: 93.822197ms)","trace[178041302] 'applied index is now lower than readState.Index' (duration: 36.344691ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:57:36.753279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.290842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3704"} {"level":"info","ts":"2026-02-12T16:57:36.753305Z","caller":"traceutil/trace.go:171","msg":"trace[1237478531] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:279; }","duration":"130.357634ms","start":"2026-02-12T16:57:36.62294Z","end":"2026-02-12T16:57:36.753297Z","steps":["trace[1237478531] 'agreement among raft nodes before linearized reading' (duration: 130.273632ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:57:36.753507Z","caller":"traceutil/trace.go:171","msg":"trace[31910900] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"130.671783ms","start":"2026-02-12T16:57:36.622826Z","end":"2026-02-12T16:57:36.753498Z","steps":["trace[31910900] 'process raft request' (duration: 130.072905ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:57:36.964918Z","caller":"traceutil/trace.go:171","msg":"trace[1920412006] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"168.940173ms","start":"2026-02-12T16:57:36.795937Z","end":"2026-02-12T16:57:36.964877Z","steps":["trace[1920412006] 'process raft request' (duration: 77.456456ms)","trace[1920412006] 'compare' (duration: 91.323202ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:57:38.16361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.752277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-12T16:57:38.163707Z","caller":"traceutil/trace.go:171","msg":"trace[907437881] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:316; }","duration":"182.891482ms","start":"2026-02-12T16:57:37.980795Z","end":"2026-02-12T16:57:38.163686Z","steps":["trace[907437881] 'range keys from in-memory index tree' (duration: 182.643684ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:57:39.858041Z","caller":"traceutil/trace.go:171","msg":"trace[255270085] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"527.478808ms","start":"2026-02-12T16:57:39.330527Z","end":"2026-02-12T16:57:39.858005Z","steps":["trace[255270085] 'process raft request' (duration: 527.406386ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:57:39.858265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:57:39.330492Z","time spent":"527.661814ms","remote":"127.0.0.1:55006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":178,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-12T16:57:39.859154Z","caller":"traceutil/trace.go:171","msg":"trace[783636437] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"556.885241ms","start":"2026-02-12T16:57:39.302229Z","end":"2026-02-12T16:57:39.859114Z","steps":["trace[783636437] 'process raft request' (duration: 544.393686ms)","trace[783636437] 'compare' (duration: 11.119854ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:57:39.859716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:57:39.302214Z","time spent":"557.19604ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:57:41.865976Z","caller":"traceutil/trace.go:171","msg":"trace[1878597022] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"123.561419ms","start":"2026-02-12T16:57:41.742395Z","end":"2026-02-12T16:57:41.865957Z","steps":["trace[1878597022] 'process raft request' (duration: 53.361682ms)","trace[1878597022] 'compare' (duration: 69.990451ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:57:42.220103Z","caller":"traceutil/trace.go:171","msg":"trace[256291254] linearizableReadLoop","detail":"{readStateIndex:367; appliedIndex:364; }","duration":"289.144032ms","start":"2026-02-12T16:57:41.930902Z","end":"2026-02-12T16:57:42.220046Z","steps":["trace[256291254] 'read index received' (duration: 6.355791ms)","trace[256291254] 'applied index is now lower than readState.Index' (duration: 282.787411ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:57:42.220215Z","caller":"traceutil/trace.go:171","msg":"trace[1830396052] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"326.275757ms","start":"2026-02-12T16:57:41.893932Z","end":"2026-02-12T16:57:42.220208Z","steps":["trace[1830396052] 'process raft request' (duration: 248.707388ms)","trace[1830396052] 'compare' (duration: 77.063134ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:57:42.220299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:57:41.893915Z","time spent":"326.322818ms","remote":"127.0.0.1:54886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":654,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-12T16:57:42.220432Z","caller":"traceutil/trace.go:171","msg":"trace[780501949] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"321.833774ms","start":"2026-02-12T16:57:41.898594Z","end":"2026-02-12T16:57:42.220427Z","steps":["trace[780501949] 'process raft request' (duration: 321.414091ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:57:42.220458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:57:41.898571Z","time spent":"321.875334ms","remote":"127.0.0.1:54886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":754,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-12T16:57:42.220593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.683718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-12T16:57:42.220617Z","caller":"traceutil/trace.go:171","msg":"trace[320623721] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:356; }","duration":"289.75416ms","start":"2026-02-12T16:57:41.930857Z","end":"2026-02-12T16:57:42.220611Z","steps":["trace[320623721] 'agreement among raft nodes before linearized reading' (duration: 289.669278ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:57:42.220729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.713937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-02-12T16:57:42.220746Z","caller":"traceutil/trace.go:171","msg":"trace[794000585] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:356; }","duration":"238.734178ms","start":"2026-02-12T16:57:41.982008Z","end":"2026-02-12T16:57:42.220742Z","steps":["trace[794000585] 'agreement among raft nodes before linearized reading' (duration: 238.701167ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:57:42.22096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.873589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-12T16:57:42.220978Z","caller":"traceutil/trace.go:171","msg":"trace[1517090702] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:356; }","duration":"140.917271ms","start":"2026-02-12T16:57:42.080056Z","end":"2026-02-12T16:57:42.220974Z","steps":["trace[1517090702] 'agreement among raft nodes before linearized reading' (duration: 140.88491ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:57:42.22104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.945602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-02-12T16:57:42.221056Z","caller":"traceutil/trace.go:171","msg":"trace[353848379] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:1; response_revision:356; }","duration":"141.989643ms","start":"2026-02-12T16:57:42.079062Z","end":"2026-02-12T16:57:42.221052Z","steps":["trace[353848379] 'agreement among raft nodes before linearized reading' (duration: 141.961222ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:57:42.221145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.157448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3840"} {"level":"info","ts":"2026-02-12T16:57:42.221161Z","caller":"traceutil/trace.go:171","msg":"trace[257625381] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:356; }","duration":"142.187709ms","start":"2026-02-12T16:57:42.078969Z","end":"2026-02-12T16:57:42.221157Z","steps":["trace[257625381] 'agreement among raft nodes before linearized reading' (duration: 142.155278ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:57:42.221247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.505358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4256"} {"level":"info","ts":"2026-02-12T16:57:42.221263Z","caller":"traceutil/trace.go:171","msg":"trace[143709400] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:356; }","duration":"142.52957ms","start":"2026-02-12T16:57:42.078729Z","end":"2026-02-12T16:57:42.221259Z","steps":["trace[143709400] 'agreement among raft nodes before linearized reading' (duration: 142.498269ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:57:42.221358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.634722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5260"} {"level":"info","ts":"2026-02-12T16:57:42.221374Z","caller":"traceutil/trace.go:171","msg":"trace[1509742793] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:356; }","duration":"142.669004ms","start":"2026-02-12T16:57:42.078701Z","end":"2026-02-12T16:57:42.22137Z","steps":["trace[1509742793] 'agreement among raft nodes before linearized reading' (duration: 142.637583ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:57:42.2215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.801188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7228"} {"level":"info","ts":"2026-02-12T16:57:42.221569Z","caller":"traceutil/trace.go:171","msg":"trace[1303605120] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:356; }","duration":"142.87573ms","start":"2026-02-12T16:57:42.078682Z","end":"2026-02-12T16:57:42.221558Z","steps":["trace[1303605120] 'agreement among raft nodes before linearized reading' (duration: 142.753076ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:57:42.221809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.146639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-12T16:57:42.221839Z","caller":"traceutil/trace.go:171","msg":"trace[656451186] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:356; }","duration":"190.227502ms","start":"2026-02-12T16:57:42.031597Z","end":"2026-02-12T16:57:42.221825Z","steps":["trace[656451186] 'agreement among raft nodes before linearized reading' (duration: 190.193561ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:57:42.221909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.235981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6894"} {"level":"info","ts":"2026-02-12T16:57:42.221927Z","caller":"traceutil/trace.go:171","msg":"trace[138034517] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:356; }","duration":"143.254071ms","start":"2026-02-12T16:57:42.078669Z","end":"2026-02-12T16:57:42.221923Z","steps":["trace[138034517] 'agreement among raft nodes before linearized reading' (duration: 143.221491ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:57:42.221991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.376524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4725"} {"level":"info","ts":"2026-02-12T16:57:42.222006Z","caller":"traceutil/trace.go:171","msg":"trace[614984832] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:356; }","duration":"143.433427ms","start":"2026-02-12T16:57:42.078569Z","end":"2026-02-12T16:57:42.222003Z","steps":["trace[614984832] 'agreement among raft nodes before linearized reading' (duration: 143.407236ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:57:42.398448Z","caller":"traceutil/trace.go:171","msg":"trace[594066267] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"144.119357ms","start":"2026-02-12T16:57:42.254301Z","end":"2026-02-12T16:57:42.39842Z","steps":["trace[594066267] 'process raft request' (duration: 143.585381ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:57:42.398652Z","caller":"traceutil/trace.go:171","msg":"trace[481532716] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"144.337404ms","start":"2026-02-12T16:57:42.254308Z","end":"2026-02-12T16:57:42.398645Z","steps":["trace[481532716] 'process raft request' (duration: 144.007474ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:57:42.4229Z","caller":"traceutil/trace.go:171","msg":"trace[2066252918] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"164.598472ms","start":"2026-02-12T16:57:42.258258Z","end":"2026-02-12T16:57:42.422857Z","steps":["trace[2066252918] 'process raft request' (duration: 163.172429ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:57:42.423869Z","caller":"traceutil/trace.go:171","msg":"trace[1978077733] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"158.309693ms","start":"2026-02-12T16:57:42.265553Z","end":"2026-02-12T16:57:42.423863Z","steps":["trace[1978077733] 'process raft request' (duration: 156.186669ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:57:42.435629Z","caller":"traceutil/trace.go:171","msg":"trace[2049716504] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"169.702316ms","start":"2026-02-12T16:57:42.265878Z","end":"2026-02-12T16:57:42.43558Z","steps":["trace[2049716504] 'process raft request' (duration: 157.924772ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:57:42.442129Z","caller":"traceutil/trace.go:171","msg":"trace[1254342074] linearizableReadLoop","detail":"{readStateIndex:372; appliedIndex:367; }","duration":"160.408767ms","start":"2026-02-12T16:57:42.281701Z","end":"2026-02-12T16:57:42.44211Z","steps":["trace[1254342074] 'read index received' (duration: 116.116367ms)","trace[1254342074] 'applied index is now lower than readState.Index' (duration: 44.29054ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:57:42.442486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.719606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-02-12T16:57:42.442513Z","caller":"traceutil/trace.go:171","msg":"trace[1230257793] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:361; }","duration":"160.85778ms","start":"2026-02-12T16:57:42.281649Z","end":"2026-02-12T16:57:42.442507Z","steps":["trace[1230257793] 'agreement among raft nodes before linearized reading' (duration: 160.569871ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:57:42.565583Z","caller":"traceutil/trace.go:171","msg":"trace[1329389984] linearizableReadLoop","detail":"{readStateIndex:374; appliedIndex:372; }","duration":"123.352873ms","start":"2026-02-12T16:57:42.442205Z","end":"2026-02-12T16:57:42.565558Z","steps":["trace[1329389984] 'read index received' (duration: 18.736762ms)","trace[1329389984] 'applied index is now lower than readState.Index' (duration: 104.615411ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:57:42.565703Z","caller":"traceutil/trace.go:171","msg":"trace[677920854] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"123.652463ms","start":"2026-02-12T16:57:42.442043Z","end":"2026-02-12T16:57:42.565696Z","steps":["trace[677920854] 'process raft request' (duration: 65.861147ms)","trace[677920854] 'compare' (duration: 57.073304ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:57:42.565946Z","caller":"traceutil/trace.go:171","msg":"trace[329689218] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"120.031514ms","start":"2026-02-12T16:57:42.44591Z","end":"2026-02-12T16:57:42.565941Z","steps":["trace[329689218] 'process raft request' (duration: 119.433326ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:57:42.566078Z","caller":"traceutil/trace.go:171","msg":"trace[1190979980] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"119.987303ms","start":"2026-02-12T16:57:42.446086Z","end":"2026-02-12T16:57:42.566073Z","steps":["trace[1190979980] 'process raft request' (duration: 119.371324ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:57:42.566145Z","caller":"traceutil/trace.go:171","msg":"trace[883125675] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"115.129607ms","start":"2026-02-12T16:57:42.451011Z","end":"2026-02-12T16:57:42.566141Z","steps":["trace[883125675] 'process raft request' (duration: 114.476277ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:57:42.566262Z","caller":"traceutil/trace.go:171","msg":"trace[943703827] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"115.151357ms","start":"2026-02-12T16:57:42.451106Z","end":"2026-02-12T16:57:42.566258Z","steps":["trace[943703827] 'process raft request' (duration: 114.411685ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:57:42.566368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.267271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-proxy-6rvct.18938e3ad945cb23\" ","response":"range_response_count:1 size:769"} {"level":"info","ts":"2026-02-12T16:57:42.566393Z","caller":"traceutil/trace.go:171","msg":"trace[822673129] range","detail":"{range_begin:/registry/events/kube-system/kube-proxy-6rvct.18938e3ad945cb23; range_end:; response_count:1; response_revision:367; }","duration":"124.319492ms","start":"2026-02-12T16:57:42.442068Z","end":"2026-02-12T16:57:42.566387Z","steps":["trace[822673129] 'agreement among raft nodes before linearized reading' (duration: 124.25327ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:57:42.566465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.447267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-12T16:57:42.566483Z","caller":"traceutil/trace.go:171","msg":"trace[2097970122] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:367; }","duration":"124.488328ms","start":"2026-02-12T16:57:42.44199Z","end":"2026-02-12T16:57:42.566479Z","steps":["trace[2097970122] 'agreement among raft nodes before linearized reading' (duration: 124.456447ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:57:42.566567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.440749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-02-12T16:57:42.566585Z","caller":"traceutil/trace.go:171","msg":"trace[1672131705] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:367; }","duration":"183.525381ms","start":"2026-02-12T16:57:42.383055Z","end":"2026-02-12T16:57:42.56658Z","steps":["trace[1672131705] 'agreement among raft nodes before linearized reading' (duration: 183.49234ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:57:42.566651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.249593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-02-12T16:57:42.566668Z","caller":"traceutil/trace.go:171","msg":"trace[129247225] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:367; }","duration":"235.311956ms","start":"2026-02-12T16:57:42.331352Z","end":"2026-02-12T16:57:42.566664Z","steps":["trace[129247225] 'agreement among raft nodes before linearized reading' (duration: 235.281165ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:57:42.847804Z","caller":"traceutil/trace.go:171","msg":"trace[1107695971] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"258.242223ms","start":"2026-02-12T16:57:42.589485Z","end":"2026-02-12T16:57:42.847727Z","steps":["trace[1107695971] 'process raft request' (duration: 205.377466ms)","trace[1107695971] 'compare' (duration: 52.503836ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:57:42.848429Z","caller":"traceutil/trace.go:171","msg":"trace[121639683] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"250.357577ms","start":"2026-02-12T16:57:42.59806Z","end":"2026-02-12T16:57:42.848417Z","steps":["trace[121639683] 'process raft request' (duration: 249.46427ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:57:42.848724Z","caller":"traceutil/trace.go:171","msg":"trace[1922144558] linearizableReadLoop","detail":"{readStateIndex:380; appliedIndex:379; }","duration":"250.82342ms","start":"2026-02-12T16:57:42.597886Z","end":"2026-02-12T16:57:42.84871Z","steps":["trace[1922144558] 'read index received' (duration: 47.509157ms)","trace[1922144558] 'applied index is now lower than readState.Index' (duration: 203.313183ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:57:42.848939Z","caller":"traceutil/trace.go:171","msg":"trace[1753917348] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"248.666566ms","start":"2026-02-12T16:57:42.600258Z","end":"2026-02-12T16:57:42.848925Z","steps":["trace[1753917348] 'process raft request' (duration: 247.331106ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:57:42.849068Z","caller":"traceutil/trace.go:171","msg":"trace[1705505835] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"245.337606ms","start":"2026-02-12T16:57:42.603714Z","end":"2026-02-12T16:57:42.849052Z","steps":["trace[1705505835] 'process raft request' (duration: 244.15634ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:57:42.849174Z","caller":"traceutil/trace.go:171","msg":"trace[136917] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"242.422298ms","start":"2026-02-12T16:57:42.606742Z","end":"2026-02-12T16:57:42.849165Z","steps":["trace[136917] 'process raft request' (duration: 241.189181ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:57:42.849348Z","caller":"traceutil/trace.go:171","msg":"trace[1151167561] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"242.362556ms","start":"2026-02-12T16:57:42.606977Z","end":"2026-02-12T16:57:42.849339Z","steps":["trace[1151167561] 'process raft request' (duration: 241.299994ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:57:42.849934Z","caller":"traceutil/trace.go:171","msg":"trace[1231899280] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"242.775829ms","start":"2026-02-12T16:57:42.607146Z","end":"2026-02-12T16:57:42.849922Z","steps":["trace[1231899280] 'process raft request' (duration: 241.183921ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:57:42.850034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.118409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-12T16:57:42.854133Z","caller":"traceutil/trace.go:171","msg":"trace[1632543872] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:375; }","duration":"256.259854ms","start":"2026-02-12T16:57:42.597856Z","end":"2026-02-12T16:57:42.854116Z","steps":["trace[1632543872] 'agreement among raft nodes before linearized reading' (duration: 252.111149ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:57:42.856143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.513269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4256"} {"level":"info","ts":"2026-02-12T16:57:42.85618Z","caller":"traceutil/trace.go:171","msg":"trace[1168635482] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:375; }","duration":"115.607292ms","start":"2026-02-12T16:57:42.740563Z","end":"2026-02-12T16:57:42.856171Z","steps":["trace[1168635482] 'agreement among raft nodes before linearized reading' (duration: 115.481198ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:57:45.091036Z","caller":"traceutil/trace.go:171","msg":"trace[871429645] linearizableReadLoop","detail":"{readStateIndex:420; appliedIndex:419; }","duration":"501.719695ms","start":"2026-02-12T16:57:44.589278Z","end":"2026-02-12T16:57:45.090997Z","steps":["trace[871429645] 'read index received' (duration: 489.91158ms)","trace[871429645] 'applied index is now lower than readState.Index' (duration: 11.806385ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:57:45.091093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:57:44.587577Z","time spent":"503.508648ms","remote":"127.0.0.1:54844","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-12T16:57:45.093053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.715524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-6rvct\" ","response":"range_response_count:1 size:4417"} {"level":"info","ts":"2026-02-12T16:57:45.093102Z","caller":"traceutil/trace.go:171","msg":"trace[1301034016] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-6rvct; range_end:; response_count:1; response_revision:407; }","duration":"501.758615ms","start":"2026-02-12T16:57:44.591325Z","end":"2026-02-12T16:57:45.093083Z","steps":["trace[1301034016] 'agreement among raft nodes before linearized reading' (duration: 501.678953ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:57:45.09313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:57:44.591305Z","time spent":"501.819928ms","remote":"127.0.0.1:54994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":4441,"request content":"key:\"/registry/pods/kube-system/kube-proxy-6rvct\" "} {"level":"warn","ts":"2026-02-12T16:57:45.091209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.91055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:57:45.093328Z","caller":"traceutil/trace.go:171","msg":"trace[1237709843] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:407; }","duration":"504.051184ms","start":"2026-02-12T16:57:44.589259Z","end":"2026-02-12T16:57:45.09331Z","steps":["trace[1237709843] 'agreement among raft nodes before linearized reading' (duration: 501.892009ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:57:45.093399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:57:44.589252Z","time spent":"504.112256ms","remote":"127.0.0.1:54826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T16:57:55.706224Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7975765344475063030,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-12T16:57:56.102283Z","caller":"traceutil/trace.go:171","msg":"trace[173668894] linearizableReadLoop","detail":"{readStateIndex:483; appliedIndex:482; }","duration":"896.2277ms","start":"2026-02-12T16:57:55.206034Z","end":"2026-02-12T16:57:56.102262Z","steps":["trace[173668894] 'read index received' (duration: 895.983813ms)","trace[173668894] 'applied index is now lower than readState.Index' (duration: 242.977µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:57:56.10244Z","caller":"traceutil/trace.go:171","msg":"trace[225963519] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"938.84953ms","start":"2026-02-12T16:57:55.163577Z","end":"2026-02-12T16:57:56.102427Z","steps":["trace[225963519] 'process raft request' (duration: 938.554021ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:57:56.102542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:57:55.16356Z","time spent":"938.907001ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T16:57:56.102781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.725995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-12T16:57:56.102913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.845129ms","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-12T16:57:56.102953Z","caller":"traceutil/trace.go:171","msg":"trace[963130911] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:468; }","duration":"877.928071ms","start":"2026-02-12T16:57:55.225012Z","end":"2026-02-12T16:57:56.10294Z","steps":["trace[963130911] 'agreement among raft nodes before linearized reading' (duration: 877.859999ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:57:56.102939Z","caller":"traceutil/trace.go:171","msg":"trace[1733766645] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:468; }","duration":"896.9128ms","start":"2026-02-12T16:57:55.205985Z","end":"2026-02-12T16:57:56.102898Z","steps":["trace[1733766645] 'agreement among raft nodes before linearized reading' (duration: 896.700424ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:57:56.102986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:57:55.224981Z","time spent":"877.995643ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T16:57:56.103023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:57:55.205961Z","time spent":"897.051415ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-12T16:57:56.103236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.909603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:57:56.103264Z","caller":"traceutil/trace.go:171","msg":"trace[1369788636] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:468; }","duration":"612.967725ms","start":"2026-02-12T16:57:55.490287Z","end":"2026-02-12T16:57:56.103255Z","steps":["trace[1369788636] 'agreement among raft nodes before linearized reading' (duration: 612.927894ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:57:56.103292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:57:55.490271Z","time spent":"613.015386ms","remote":"127.0.0.1:54822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-12T16:57:56.45281Z","caller":"traceutil/trace.go:171","msg":"trace[1013130988] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"341.480853ms","start":"2026-02-12T16:57:56.11131Z","end":"2026-02-12T16:57:56.452791Z","steps":["trace[1013130988] 'process raft request' (duration: 340.745981ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:57:56.452915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:57:56.111288Z","time spent":"341.573006ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:57:56.454237Z","caller":"traceutil/trace.go:171","msg":"trace[581247777] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"341.021009ms","start":"2026-02-12T16:57:56.11319Z","end":"2026-02-12T16:57:56.454211Z","steps":["trace[581247777] 'process raft request' (duration: 339.938846ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:57:56.454316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:57:56.113179Z","time spent":"341.095602ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:57:56.454993Z","caller":"traceutil/trace.go:171","msg":"trace[1906075723] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"341.05084ms","start":"2026-02-12T16:57:56.113732Z","end":"2026-02-12T16:57:56.454783Z","steps":["trace[1906075723] 'process raft request' (duration: 340.988968ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:57:56.45506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:57:56.113717Z","time spent":"341.303928ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T16:57:57.718507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.281384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:57:57.718578Z","caller":"traceutil/trace.go:171","msg":"trace[1425533374] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:472; }","duration":"229.404218ms","start":"2026-02-12T16:57:57.489157Z","end":"2026-02-12T16:57:57.718561Z","steps":["trace[1425533374] 'range keys from in-memory index tree' (duration: 229.203193ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:07.293751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.124535236s","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-12T16:58:07.29381Z","caller":"traceutil/trace.go:171","msg":"trace[1926137182] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:526; }","duration":"1.124614088s","start":"2026-02-12T16:58:06.169182Z","end":"2026-02-12T16:58:07.293796Z","steps":["trace[1926137182] 'range keys from in-memory index tree' (duration: 1.124444213s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:07.293861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:06.164442Z","time spent":"1.129389672s","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-12T16:58:07.294124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.774134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:58:07.294146Z","caller":"traceutil/trace.go:171","msg":"trace[1297598505] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:526; }","duration":"801.818906ms","start":"2026-02-12T16:58:06.492321Z","end":"2026-02-12T16:58:07.294139Z","steps":["trace[1297598505] 'range keys from in-memory index tree' (duration: 801.731983ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:07.294163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:06.492307Z","time spent":"801.851977ms","remote":"127.0.0.1:54822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T16:58:07.294291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.518057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-12T16:58:07.294309Z","caller":"traceutil/trace.go:171","msg":"trace[199760395] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:526; }","duration":"801.574039ms","start":"2026-02-12T16:58:06.492729Z","end":"2026-02-12T16:58:07.294304Z","steps":["trace[199760395] 'range keys from in-memory index tree' (duration: 801.430344ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:07.294326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:06.492709Z","time spent":"801.61279ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-12T16:58:07.294408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.9996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-12T16:58:07.294425Z","caller":"traceutil/trace.go:171","msg":"trace[1055416615] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:526; }","duration":"777.043982ms","start":"2026-02-12T16:58:06.517375Z","end":"2026-02-12T16:58:07.294419Z","steps":["trace[1055416615] 'range keys from in-memory index tree' (duration: 776.921498ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:07.294443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:06.51735Z","time spent":"777.089183ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T16:58:07.294518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.196176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-12T16:58:07.294534Z","caller":"traceutil/trace.go:171","msg":"trace[643456178] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:526; }","duration":"776.221557ms","start":"2026-02-12T16:58:06.518308Z","end":"2026-02-12T16:58:07.294529Z","steps":["trace[643456178] 'range keys from in-memory index tree' (duration: 776.170655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:07.294551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:06.518303Z","time spent":"776.243707ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-12T16:58:07.29527Z","caller":"traceutil/trace.go:171","msg":"trace[937221809] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"199.505241ms","start":"2026-02-12T16:58:07.095755Z","end":"2026-02-12T16:58:07.29526Z","steps":["trace[937221809] 'process raft request' (duration: 199.388517ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:58:08.235812Z","caller":"traceutil/trace.go:171","msg":"trace[1321361975] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"932.121397ms","start":"2026-02-12T16:58:07.303667Z","end":"2026-02-12T16:58:08.235789Z","steps":["trace[1321361975] 'process raft request' (duration: 931.984703ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:08.235966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:07.30366Z","time spent":"932.22339ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:58:08.252997Z","caller":"traceutil/trace.go:171","msg":"trace[334343403] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"949.143578ms","start":"2026-02-12T16:58:07.303828Z","end":"2026-02-12T16:58:08.252971Z","steps":["trace[334343403] 'process raft request' (duration: 949.089307ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:08.253204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:07.303824Z","time spent":"949.248481ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:58:08.253669Z","caller":"traceutil/trace.go:171","msg":"trace[106313305] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"949.85471ms","start":"2026-02-12T16:58:07.303796Z","end":"2026-02-12T16:58:08.253651Z","steps":["trace[106313305] 'process raft request' (duration: 949.029025ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:58:08.253644Z","caller":"traceutil/trace.go:171","msg":"trace[1017307132] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"949.83583ms","start":"2026-02-12T16:58:07.303772Z","end":"2026-02-12T16:58:08.253607Z","steps":["trace[1017307132] 'process raft request' (duration: 940.713136ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:08.253728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:07.303793Z","time spent":"949.901081ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T16:58:08.253835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:07.303766Z","time spent":"949.956853ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T16:58:10.741739Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7975765344475063171,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T16:58:10.924284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.951953ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-12T16:58:10.924408Z","caller":"traceutil/trace.go:171","msg":"trace[1197344008] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:548; }","duration":"683.203194ms","start":"2026-02-12T16:58:10.241182Z","end":"2026-02-12T16:58:10.924385Z","steps":["trace[1197344008] 'read index received' (duration: 509.119877ms)","trace[1197344008] 'applied index is now lower than readState.Index' (duration: 174.081407ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:58:10.924777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.572215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-12T16:58:10.924832Z","caller":"traceutil/trace.go:171","msg":"trace[553350277] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:531; }","duration":"683.663758ms","start":"2026-02-12T16:58:10.241156Z","end":"2026-02-12T16:58:10.924819Z","steps":["trace[553350277] 'agreement among raft nodes before linearized reading' (duration: 683.465882ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:10.92483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.08797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-12T16:58:10.924863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:10.241142Z","time spent":"683.715079ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-12T16:58:10.924872Z","caller":"traceutil/trace.go:171","msg":"trace[1164462173] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:531; }","duration":"667.149222ms","start":"2026-02-12T16:58:10.257708Z","end":"2026-02-12T16:58:10.924857Z","steps":["trace[1164462173] 'agreement among raft nodes before linearized reading' (duration: 666.972917ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:10.924917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:10.257702Z","time spent":"667.202604ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-12T16:58:10.925166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.917075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-12T16:58:10.925184Z","caller":"traceutil/trace.go:171","msg":"trace[303437258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:531; }","duration":"666.946956ms","start":"2026-02-12T16:58:10.258232Z","end":"2026-02-12T16:58:10.925179Z","steps":["trace[303437258] 'agreement among raft nodes before linearized reading' (duration: 666.883384ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:10.925199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:10.258228Z","time spent":"666.968136ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-12T16:58:10.925371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.627376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-12T16:58:10.92539Z","caller":"traceutil/trace.go:171","msg":"trace[118242599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:531; }","duration":"667.653687ms","start":"2026-02-12T16:58:10.257731Z","end":"2026-02-12T16:58:10.925384Z","steps":["trace[118242599] 'agreement among raft nodes before linearized reading' (duration: 667.607876ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:10.925408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:10.257728Z","time spent":"667.673257ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-12T16:58:14.277856Z","caller":"traceutil/trace.go:171","msg":"trace[1758942896] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"141.026394ms","start":"2026-02-12T16:58:14.136814Z","end":"2026-02-12T16:58:14.27784Z","steps":["trace[1758942896] 'process raft request' (duration: 140.929351ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:14.952861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:14.582746Z","time spent":"370.111126ms","remote":"127.0.0.1:54844","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-12T16:58:15.613193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.338115ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T16:58:15.613258Z","caller":"traceutil/trace.go:171","msg":"trace[720180586] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"659.337961ms","start":"2026-02-12T16:58:14.953909Z","end":"2026-02-12T16:58:15.613247Z","steps":["trace[720180586] 'process raft request' (duration: 394.893052ms)","trace[720180586] 'compare' (duration: 263.816469ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:58:15.613289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:14.953899Z","time spent":"659.374783ms","remote":"127.0.0.1:54844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:58:15.789945Z","caller":"traceutil/trace.go:171","msg":"trace[939010653] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"833.635338ms","start":"2026-02-12T16:58:14.956291Z","end":"2026-02-12T16:58:15.789927Z","steps":["trace[939010653] 'process raft request' (duration: 774.423415ms)","trace[939010653] 'compare' (duration: 59.076609ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:58:15.790046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:14.956283Z","time spent":"833.70926ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:58:15.790155Z","caller":"traceutil/trace.go:171","msg":"trace[1930641836] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"833.834994ms","start":"2026-02-12T16:58:14.956307Z","end":"2026-02-12T16:58:15.790142Z","steps":["trace[1930641836] 'process raft request' (duration: 833.579346ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:58:15.790188Z","caller":"traceutil/trace.go:171","msg":"trace[834022189] linearizableReadLoop","detail":"{readStateIndex:562; appliedIndex:559; }","duration":"830.265962ms","start":"2026-02-12T16:58:14.959915Z","end":"2026-02-12T16:58:15.790181Z","steps":["trace[834022189] 'read index received' (duration: 388.892494ms)","trace[834022189] 'applied index is now lower than readState.Index' (duration: 441.372728ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:58:15.790216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:14.956301Z","time spent":"833.882795ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T16:58:15.79027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.351235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-12T16:58:15.790289Z","caller":"traceutil/trace.go:171","msg":"trace[1618505800] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:543; }","duration":"830.387206ms","start":"2026-02-12T16:58:14.959896Z","end":"2026-02-12T16:58:15.790283Z","steps":["trace[1618505800] 'agreement among raft nodes before linearized reading' (duration: 830.349585ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:15.79032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:14.95989Z","time spent":"830.424817ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T16:58:15.790416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.468108ms","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-12T16:58:15.790433Z","caller":"traceutil/trace.go:171","msg":"trace[1755700901] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:543; }","duration":"830.49273ms","start":"2026-02-12T16:58:14.959934Z","end":"2026-02-12T16:58:15.790427Z","steps":["trace[1755700901] 'agreement among raft nodes before linearized reading' (duration: 830.462909ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:15.790459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:14.959931Z","time spent":"830.524921ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-12T16:58:15.79053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.937519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-02-12T16:58:15.790561Z","caller":"traceutil/trace.go:171","msg":"trace[1320843308] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:543; }","duration":"175.9857ms","start":"2026-02-12T16:58:15.614566Z","end":"2026-02-12T16:58:15.790552Z","steps":["trace[1320843308] 'agreement among raft nodes before linearized reading' (duration: 175.931678ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:15.790541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.7075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:58:15.790725Z","caller":"traceutil/trace.go:171","msg":"trace[1297654120] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:543; }","duration":"297.946758ms","start":"2026-02-12T16:58:15.49277Z","end":"2026-02-12T16:58:15.790716Z","steps":["trace[1297654120] 'agreement among raft nodes before linearized reading' (duration: 297.757342ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:58:16.580883Z","caller":"traceutil/trace.go:171","msg":"trace[964815496] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"118.647025ms","start":"2026-02-12T16:58:16.462211Z","end":"2026-02-12T16:58:16.580858Z","steps":["trace[964815496] 'process raft request' (duration: 118.517551ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:19.495111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000205967s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-12T16:58:19.496702Z","caller":"traceutil/trace.go:171","msg":"trace[1850284291] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001845569s","start":"2026-02-12T16:58:17.494837Z","end":"2026-02-12T16:58:19.496682Z","steps":["trace[1850284291] 'agreement among raft nodes before linearized reading' (duration: 2.000235578s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:19.496774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:17.494821Z","time spent":"2.001937462s","remote":"127.0.0.1:54826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/12 16:58:19 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-12T16:58:19.877112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.355395446s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T16:58:19.877173Z","caller":"traceutil/trace.go:171","msg":"trace[1280119771] linearizableReadLoop","detail":"{readStateIndex:568; appliedIndex:567; }","duration":"2.382287748s","start":"2026-02-12T16:58:17.494875Z","end":"2026-02-12T16:58:19.877163Z","steps":["trace[1280119771] 'read index received' (duration: 26.740897ms)","trace[1280119771] 'applied index is now lower than readState.Index' (duration: 2.355546191s)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:58:19.877286Z","caller":"traceutil/trace.go:171","msg":"trace[1690180695] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"2.523352715s","start":"2026-02-12T16:58:17.353926Z","end":"2026-02-12T16:58:19.877278Z","steps":["trace[1690180695] 'process raft request' (duration: 167.738722ms)","trace[1690180695] 'compare' (duration: 2.354389975s)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:58:19.877324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:17.353899Z","time spent":"2.523405396s","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T16:58:20.377895Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7975765344475063218,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-12T16:58:20.538684Z","caller":"traceutil/trace.go:171","msg":"trace[1391403225] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"1.039088029s","start":"2026-02-12T16:58:19.499571Z","end":"2026-02-12T16:58:20.538659Z","steps":["trace[1391403225] 'process raft request' (duration: 1.038859502s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:20.539078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:19.499535Z","time spent":"1.039206163s","remote":"127.0.0.1:54886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-12T16:58:20.585107Z","caller":"traceutil/trace.go:171","msg":"trace[1433841238] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:569; }","duration":"707.900312ms","start":"2026-02-12T16:58:19.877189Z","end":"2026-02-12T16:58:20.585089Z","steps":["trace[1433841238] 'read index received' (duration: 661.783558ms)","trace[1433841238] 'applied index is now lower than readState.Index' (duration: 46.115734ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:58:20.585175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.737480828s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-12T16:58:20.585222Z","caller":"traceutil/trace.go:171","msg":"trace[139085000] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:550; }","duration":"2.737568261s","start":"2026-02-12T16:58:17.84764Z","end":"2026-02-12T16:58:20.585209Z","steps":["trace[139085000] 'agreement among raft nodes before linearized reading' (duration: 2.737457967s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:20.58525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:17.847627Z","time spent":"2.737617473s","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T16:58:20.585268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.00906122s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-67cpr\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-12T16:58:20.585292Z","caller":"traceutil/trace.go:171","msg":"trace[457993243] range","detail":"{range_begin:/registry/pods/kube-system/cilium-67cpr; range_end:; response_count:1; response_revision:550; }","duration":"3.009121782s","start":"2026-02-12T16:58:17.576163Z","end":"2026-02-12T16:58:20.585285Z","steps":["trace[457993243] 'agreement among raft nodes before linearized reading' (duration: 3.008993658s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:20.585317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:17.576151Z","time spent":"3.009160913s","remote":"127.0.0.1:54994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16676,"request content":"key:\"/registry/pods/kube-system/cilium-67cpr\" "} {"level":"warn","ts":"2026-02-12T16:58:20.585423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.082983534s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:58:20.585445Z","caller":"traceutil/trace.go:171","msg":"trace[1924833738] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:550; }","duration":"1.083036226s","start":"2026-02-12T16:58:19.502402Z","end":"2026-02-12T16:58:20.585439Z","steps":["trace[1924833738] 'agreement among raft nodes before linearized reading' (duration: 1.082989985s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:20.585462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:19.502393Z","time spent":"1.083064667s","remote":"127.0.0.1:54822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T16:58:20.585745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.785610955s","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-12T16:58:20.585771Z","caller":"traceutil/trace.go:171","msg":"trace[371633132] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:550; }","duration":"2.785662766s","start":"2026-02-12T16:58:17.800101Z","end":"2026-02-12T16:58:20.585764Z","steps":["trace[371633132] 'agreement among raft nodes before linearized reading' (duration: 2.785614335s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:20.585796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:17.800089Z","time spent":"2.785700488s","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-12T16:58:20.585911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.785730869s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-12T16:58:20.585932Z","caller":"traceutil/trace.go:171","msg":"trace[1746284761] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:550; }","duration":"2.78578132s","start":"2026-02-12T16:58:17.800145Z","end":"2026-02-12T16:58:20.585927Z","steps":["trace[1746284761] 'agreement among raft nodes before linearized reading' (duration: 2.785726879s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:20.58595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:17.80014Z","time spent":"2.785805231s","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-12T16:58:20.586169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.790378055s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-12T16:58:20.586189Z","caller":"traceutil/trace.go:171","msg":"trace[580045841] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:550; }","duration":"2.790431476s","start":"2026-02-12T16:58:17.795752Z","end":"2026-02-12T16:58:20.586183Z","steps":["trace[580045841] 'agreement among raft nodes before linearized reading' (duration: 2.790394515s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:20.586206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:17.795734Z","time spent":"2.790468207s","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-12T16:58:20.705847Z","caller":"traceutil/trace.go:171","msg":"trace[1984653359] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"115.851067ms","start":"2026-02-12T16:58:20.589974Z","end":"2026-02-12T16:58:20.705825Z","steps":["trace[1984653359] 'process raft request' (duration: 115.719123ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:58:20.731619Z","caller":"traceutil/trace.go:171","msg":"trace[775256499] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"139.761746ms","start":"2026-02-12T16:58:20.591837Z","end":"2026-02-12T16:58:20.731599Z","steps":["trace[775256499] 'process raft request' (duration: 139.693373ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:58:20.731852Z","caller":"traceutil/trace.go:171","msg":"trace[64030162] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"140.044644ms","start":"2026-02-12T16:58:20.591798Z","end":"2026-02-12T16:58:20.731843Z","steps":["trace[64030162] 'process raft request' (duration: 139.609551ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:58:20.732125Z","caller":"traceutil/trace.go:171","msg":"trace[1218700149] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"138.749603ms","start":"2026-02-12T16:58:20.593354Z","end":"2026-02-12T16:58:20.732104Z","steps":["trace[1218700149] 'process raft request' (duration: 138.209707ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:58:20.732177Z","caller":"traceutil/trace.go:171","msg":"trace[953449668] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:570; }","duration":"132.173658ms","start":"2026-02-12T16:58:20.599992Z","end":"2026-02-12T16:58:20.732165Z","steps":["trace[953449668] 'read index received' (duration: 105.773712ms)","trace[953449668] 'applied index is now lower than readState.Index' (duration: 26.399066ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:58:20.732266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.256191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:58:20.732294Z","caller":"traceutil/trace.go:171","msg":"trace[386241117] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:555; }","duration":"132.314722ms","start":"2026-02-12T16:58:20.59997Z","end":"2026-02-12T16:58:20.732285Z","steps":["trace[386241117] 'agreement among raft nodes before linearized reading' (duration: 132.23322ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:58:20.73247Z","caller":"traceutil/trace.go:171","msg":"trace[1777438623] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"130.380522ms","start":"2026-02-12T16:58:20.602079Z","end":"2026-02-12T16:58:20.73246Z","steps":["trace[1777438623] 'process raft request' (duration: 129.776903ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:58:21.718559Z","caller":"traceutil/trace.go:171","msg":"trace[2126091624] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"127.891043ms","start":"2026-02-12T16:58:21.590629Z","end":"2026-02-12T16:58:21.71852Z","steps":["trace[2126091624] 'process raft request' (duration: 127.667606ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:58:21.821972Z","caller":"traceutil/trace.go:171","msg":"trace[813464453] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"216.951852ms","start":"2026-02-12T16:58:21.604992Z","end":"2026-02-12T16:58:21.821944Z","steps":["trace[813464453] 'process raft request' (duration: 177.338132ms)","trace[813464453] 'compare' (duration: 39.464536ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:58:22.890091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.14759ms","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-12T16:58:22.890143Z","caller":"traceutil/trace.go:171","msg":"trace[661528131] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:561; }","duration":"180.208751ms","start":"2026-02-12T16:58:22.709922Z","end":"2026-02-12T16:58:22.890131Z","steps":["trace[661528131] 'range keys from in-memory index tree' (duration: 180.021376ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:22.890369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.818674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-12T16:58:22.890404Z","caller":"traceutil/trace.go:171","msg":"trace[601189385] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:561; }","duration":"152.862445ms","start":"2026-02-12T16:58:22.737521Z","end":"2026-02-12T16:58:22.890384Z","steps":["trace[601189385] 'range keys from in-memory index tree' (duration: 152.752702ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:22.890497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.333181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-12T16:58:22.890514Z","caller":"traceutil/trace.go:171","msg":"trace[1143068898] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:561; }","duration":"146.395643ms","start":"2026-02-12T16:58:22.744113Z","end":"2026-02-12T16:58:22.890509Z","steps":["trace[1143068898] 'range keys from in-memory index tree' (duration: 146.231698ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:22.890605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.124464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-12T16:58:22.890624Z","caller":"traceutil/trace.go:171","msg":"trace[1746619972] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:561; }","duration":"146.182497ms","start":"2026-02-12T16:58:22.744435Z","end":"2026-02-12T16:58:22.890618Z","steps":["trace[1746619972] 'range keys from in-memory index tree' (duration: 146.061383ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:58:25.892843Z","caller":"traceutil/trace.go:171","msg":"trace[590842647] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"142.595533ms","start":"2026-02-12T16:58:25.750231Z","end":"2026-02-12T16:58:25.892827Z","steps":["trace[590842647] 'process raft request' (duration: 142.506181ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:28.249814Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7975765344475063357,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-12T16:58:28.366239Z","caller":"traceutil/trace.go:171","msg":"trace[395966134] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"716.690737ms","start":"2026-02-12T16:58:27.64952Z","end":"2026-02-12T16:58:28.366211Z","steps":["trace[395966134] 'process raft request' (duration: 716.181831ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:28.366433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:27.649492Z","time spent":"716.795749ms","remote":"127.0.0.1:54994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18231,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:58:28.399276Z","caller":"traceutil/trace.go:171","msg":"trace[1781139593] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:653; }","duration":"650.222576ms","start":"2026-02-12T16:58:27.749014Z","end":"2026-02-12T16:58:28.399237Z","steps":["trace[1781139593] 'read index received' (duration: 616.946484ms)","trace[1781139593] 'applied index is now lower than readState.Index' (duration: 33.275542ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:58:28.399393Z","caller":"traceutil/trace.go:171","msg":"trace[48854627] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"230.661192ms","start":"2026-02-12T16:58:28.168724Z","end":"2026-02-12T16:58:28.399385Z","steps":["trace[48854627] 'process raft request' (duration: 230.464595ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:28.399769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.736862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-02-12T16:58:28.399812Z","caller":"traceutil/trace.go:171","msg":"trace[774440082] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:634; }","duration":"650.819425ms","start":"2026-02-12T16:58:27.748982Z","end":"2026-02-12T16:58:28.399801Z","steps":["trace[774440082] 'agreement among raft nodes before linearized reading' (duration: 650.66787ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:28.399835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:27.748966Z","time spent":"650.863085ms","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10401,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-12T16:58:28.399875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.792179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-02-12T16:58:28.399913Z","caller":"traceutil/trace.go:171","msg":"trace[43019207] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:634; }","duration":"248.870702ms","start":"2026-02-12T16:58:28.151032Z","end":"2026-02-12T16:58:28.399902Z","steps":["trace[43019207] 'agreement among raft nodes before linearized reading' (duration: 248.718727ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:58:28.399289Z","caller":"traceutil/trace.go:171","msg":"trace[855754467] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"417.453908ms","start":"2026-02-12T16:58:27.981799Z","end":"2026-02-12T16:58:28.399253Z","steps":["trace[855754467] 'process raft request' (duration: 417.094807ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:28.40009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:27.981778Z","time spent":"418.259464ms","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-12T16:58:34.522298Z","caller":"traceutil/trace.go:171","msg":"trace[14007749] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"332.937783ms","start":"2026-02-12T16:58:34.189344Z","end":"2026-02-12T16:58:34.522281Z","steps":["trace[14007749] 'process raft request' (duration: 332.589162ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:34.522408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:34.189325Z","time spent":"333.028095ms","remote":"127.0.0.1:54994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18231,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T16:58:35.083948Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7975765344475063475,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-12T16:58:35.123898Z","caller":"traceutil/trace.go:171","msg":"trace[1218917412] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"578.435579ms","start":"2026-02-12T16:58:34.545445Z","end":"2026-02-12T16:58:35.12388Z","steps":["trace[1218917412] 'process raft request' (duration: 578.263023ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:35.124014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:34.545424Z","time spent":"578.530371ms","remote":"127.0.0.1:54994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18043,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T16:58:35.896341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.407528ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T16:58:35.896405Z","caller":"traceutil/trace.go:171","msg":"trace[811961631] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:703; }","duration":"1.31284126s","start":"2026-02-12T16:58:34.583552Z","end":"2026-02-12T16:58:35.896394Z","steps":["trace[811961631] 'read index received' (duration: 540.228783ms)","trace[811961631] 'applied index is now lower than readState.Index' (duration: 772.610447ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:58:35.896529Z","caller":"traceutil/trace.go:171","msg":"trace[1876542075] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"1.141210647s","start":"2026-02-12T16:58:34.755311Z","end":"2026-02-12T16:58:35.896521Z","steps":["trace[1876542075] 'process raft request' (duration: 945.553361ms)","trace[1876542075] 'compare' (duration: 195.342116ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:58:35.896569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:34.755287Z","time spent":"1.141261018s","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T16:58:35.897148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.313587873s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.47\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-12T16:58:35.897197Z","caller":"traceutil/trace.go:171","msg":"trace[240676058] range","detail":"{range_begin:/registry/masterleases/199.19.213.47; range_end:; response_count:1; response_revision:681; }","duration":"1.313680996s","start":"2026-02-12T16:58:34.583502Z","end":"2026-02-12T16:58:35.897183Z","steps":["trace[240676058] 'agreement among raft nodes before linearized reading' (duration: 1.313524951s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:35.897232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:34.583484Z","time spent":"1.313739858s","remote":"127.0.0.1:54844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.47\" "} {"level":"info","ts":"2026-02-12T16:58:35.97517Z","caller":"traceutil/trace.go:171","msg":"trace[995404366] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"837.935162ms","start":"2026-02-12T16:58:35.137218Z","end":"2026-02-12T16:58:35.975153Z","steps":["trace[995404366] 'process raft request' (duration: 837.896371ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:35.975284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:35.137209Z","time spent":"838.014435ms","remote":"127.0.0.1:55244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14311,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:58:35.975637Z","caller":"traceutil/trace.go:171","msg":"trace[169494006] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"839.821711ms","start":"2026-02-12T16:58:35.135807Z","end":"2026-02-12T16:58:35.975629Z","steps":["trace[169494006] 'process raft request' (duration: 839.216562ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:35.975686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:35.135789Z","time spent":"839.875393ms","remote":"127.0.0.1:54990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4085,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T16:58:35.976143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.221147539s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-12T16:58:35.97617Z","caller":"traceutil/trace.go:171","msg":"trace[425155522] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:683; }","duration":"1.221212181s","start":"2026-02-12T16:58:34.75495Z","end":"2026-02-12T16:58:35.976162Z","steps":["trace[425155522] 'agreement among raft nodes before linearized reading' (duration: 1.22116635s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:35.97619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:34.754897Z","time spent":"1.221288544s","remote":"127.0.0.1:54904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":4,"response size":31,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-02-12T16:58:35.9763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.96787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:58:35.976316Z","caller":"traceutil/trace.go:171","msg":"trace[1887069766] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:683; }","duration":"483.012552ms","start":"2026-02-12T16:58:35.493299Z","end":"2026-02-12T16:58:35.976311Z","steps":["trace[1887069766] 'agreement among raft nodes before linearized reading' (duration: 482.985201ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:35.976332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:35.49328Z","time spent":"483.048852ms","remote":"127.0.0.1:54822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T16:58:35.976465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.458985ms","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-12T16:58:35.976482Z","caller":"traceutil/trace.go:171","msg":"trace[708167392] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:683; }","duration":"897.495427ms","start":"2026-02-12T16:58:35.078982Z","end":"2026-02-12T16:58:35.976477Z","steps":["trace[708167392] 'agreement among raft nodes before linearized reading' (duration: 897.423435ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:35.976498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:35.078968Z","time spent":"897.526667ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T16:58:35.97661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.084538ms","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-12T16:58:35.976626Z","caller":"traceutil/trace.go:171","msg":"trace[1093900195] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:683; }","duration":"915.107318ms","start":"2026-02-12T16:58:35.061514Z","end":"2026-02-12T16:58:35.976621Z","steps":["trace[1093900195] 'agreement among raft nodes before linearized reading' (duration: 915.057687ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:35.976642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:35.061511Z","time spent":"915.126379ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-12T16:58:35.976734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.229422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-12T16:58:35.976762Z","caller":"traceutil/trace.go:171","msg":"trace[574501499] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:683; }","duration":"915.282354ms","start":"2026-02-12T16:58:35.061475Z","end":"2026-02-12T16:58:35.976757Z","steps":["trace[574501499] 'agreement among raft nodes before linearized reading' (duration: 915.225602ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:35.976778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:35.061462Z","time spent":"915.311984ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-12T16:58:35.976876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"940.990808ms","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-12T16:58:35.978642Z","caller":"traceutil/trace.go:171","msg":"trace[1657976430] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:683; }","duration":"941.049911ms","start":"2026-02-12T16:58:35.035839Z","end":"2026-02-12T16:58:35.976889Z","steps":["trace[1657976430] 'agreement among raft nodes before linearized reading' (duration: 940.999489ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:35.978689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:35.035819Z","time spent":"942.860257ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-12T16:58:35.978839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.144564542s","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-12T16:58:35.97886Z","caller":"traceutil/trace.go:171","msg":"trace[1628902101] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:683; }","duration":"1.144622053s","start":"2026-02-12T16:58:34.834231Z","end":"2026-02-12T16:58:35.978853Z","steps":["trace[1628902101] 'agreement among raft nodes before linearized reading' (duration: 1.144544511s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:35.978876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:34.834213Z","time spent":"1.144659805s","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-12T16:58:40.171808Z","caller":"traceutil/trace.go:171","msg":"trace[873240997] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"138.768675ms","start":"2026-02-12T16:58:40.033018Z","end":"2026-02-12T16:58:40.171786Z","steps":["trace[873240997] 'process raft request' (duration: 119.549743ms)","trace[873240997] 'compare' (duration: 19.125439ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:58:40.200776Z","caller":"traceutil/trace.go:171","msg":"trace[620462654] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"163.693165ms","start":"2026-02-12T16:58:40.037067Z","end":"2026-02-12T16:58:40.20076Z","steps":["trace[620462654] 'process raft request' (duration: 163.660094ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:58:40.201058Z","caller":"traceutil/trace.go:171","msg":"trace[1421167600] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"166.438891ms","start":"2026-02-12T16:58:40.034611Z","end":"2026-02-12T16:58:40.20105Z","steps":["trace[1421167600] 'process raft request' (duration: 166.029158ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:46.043334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.551830472s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:58:46.043417Z","caller":"traceutil/trace.go:171","msg":"trace[1327381726] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:750; }","duration":"1.551947805s","start":"2026-02-12T16:58:44.491449Z","end":"2026-02-12T16:58:46.043397Z","steps":["trace[1327381726] 'range keys from in-memory index tree' (duration: 1.551771169s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:46.043458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:44.491435Z","time spent":"1.552013657s","remote":"127.0.0.1:54822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T16:58:46.043737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.45947145s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.47\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-12T16:58:46.043772Z","caller":"traceutil/trace.go:171","msg":"trace[2118205379] range","detail":"{range_begin:/registry/masterleases/199.19.213.47; range_end:; response_count:1; response_revision:750; }","duration":"1.459529882s","start":"2026-02-12T16:58:44.584229Z","end":"2026-02-12T16:58:46.043759Z","steps":["trace[2118205379] 'range keys from in-memory index tree' (duration: 1.459334756s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:46.043799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:44.584214Z","time spent":"1.459578803s","remote":"127.0.0.1:54844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.47\" "} {"level":"warn","ts":"2026-02-12T16:58:46.47822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.576773ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T16:58:46.478336Z","caller":"traceutil/trace.go:171","msg":"trace[728106416] linearizableReadLoop","detail":"{readStateIndex:784; appliedIndex:782; }","duration":"234.274855ms","start":"2026-02-12T16:58:46.24405Z","end":"2026-02-12T16:58:46.478325Z","steps":["trace[728106416] 'read index received' (duration: 99.544796ms)","trace[728106416] 'applied index is now lower than readState.Index' (duration: 134.729409ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:58:46.478477Z","caller":"traceutil/trace.go:171","msg":"trace[672879213] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"235.274346ms","start":"2026-02-12T16:58:46.243195Z","end":"2026-02-12T16:58:46.478469Z","steps":["trace[672879213] 'process raft request' (duration: 100.390973ms)","trace[672879213] 'compare' (duration: 134.500921ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:58:46.478581Z","caller":"traceutil/trace.go:171","msg":"trace[712969712] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"235.300146ms","start":"2026-02-12T16:58:46.243275Z","end":"2026-02-12T16:58:46.478575Z","steps":["trace[712969712] 'process raft request' (duration: 235.018928ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:46.4787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.644436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-12T16:58:46.478721Z","caller":"traceutil/trace.go:171","msg":"trace[528409400] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:756; }","duration":"234.677887ms","start":"2026-02-12T16:58:46.244035Z","end":"2026-02-12T16:58:46.478713Z","steps":["trace[528409400] 'agreement among raft nodes before linearized reading' (duration: 234.609435ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:46.478862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.499218ms","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-12T16:58:46.478881Z","caller":"traceutil/trace.go:171","msg":"trace[1733444549] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:756; }","duration":"193.55005ms","start":"2026-02-12T16:58:46.285325Z","end":"2026-02-12T16:58:46.478875Z","steps":["trace[1733444549] 'agreement among raft nodes before linearized reading' (duration: 193.500068ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:58:46.632047Z","caller":"traceutil/trace.go:171","msg":"trace[2018028278] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"138.747064ms","start":"2026-02-12T16:58:46.49328Z","end":"2026-02-12T16:58:46.632027Z","steps":["trace[2018028278] 'process raft request' (duration: 123.971452ms)","trace[2018028278] 'compare' (duration: 14.70306ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:58:58.528736Z","caller":"traceutil/trace.go:171","msg":"trace[1353854256] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"247.84975ms","start":"2026-02-12T16:58:58.28087Z","end":"2026-02-12T16:58:58.52872Z","steps":["trace[1353854256] 'process raft request' (duration: 247.725946ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:58:58.55038Z","caller":"traceutil/trace.go:171","msg":"trace[1955589963] linearizableReadLoop","detail":"{readStateIndex:822; appliedIndex:820; }","duration":"191.639749ms","start":"2026-02-12T16:58:58.3587Z","end":"2026-02-12T16:58:58.55034Z","steps":["trace[1955589963] 'read index received' (duration: 170.001312ms)","trace[1955589963] 'applied index is now lower than readState.Index' (duration: 21.637527ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:58:58.550606Z","caller":"traceutil/trace.go:171","msg":"trace[621694207] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"267.517294ms","start":"2026-02-12T16:58:58.283076Z","end":"2026-02-12T16:58:58.550594Z","steps":["trace[621694207] 'process raft request' (duration: 267.178774ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:58.55079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.069013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-12T16:58:58.550868Z","caller":"traceutil/trace.go:171","msg":"trace[1853911783] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:792; }","duration":"192.185216ms","start":"2026-02-12T16:58:58.358672Z","end":"2026-02-12T16:58:58.550858Z","steps":["trace[1853911783] 'agreement among raft nodes before linearized reading' (duration: 192.072053ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:59.320176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.042734ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T16:58:59.320264Z","caller":"traceutil/trace.go:171","msg":"trace[1044547946] linearizableReadLoop","detail":"{readStateIndex:825; appliedIndex:824; }","duration":"316.894591ms","start":"2026-02-12T16:58:59.003354Z","end":"2026-02-12T16:58:59.320249Z","steps":["trace[1044547946] 'read index received' (duration: 29.531µs)","trace[1044547946] 'applied index is now lower than readState.Index' (duration: 316.86394ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:58:59.320343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.982744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-12T16:58:59.320368Z","caller":"traceutil/trace.go:171","msg":"trace[192960106] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:795; }","duration":"317.050526ms","start":"2026-02-12T16:58:59.003309Z","end":"2026-02-12T16:58:59.320359Z","steps":["trace[192960106] 'agreement among raft nodes before linearized reading' (duration: 316.980943ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:58:59.320398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:59.003294Z","time spent":"317.096138ms","remote":"127.0.0.1:55060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":2,"response size":31,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"info","ts":"2026-02-12T16:58:59.321888Z","caller":"traceutil/trace.go:171","msg":"trace[1162240725] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"631.159499ms","start":"2026-02-12T16:58:58.690717Z","end":"2026-02-12T16:58:59.321877Z","steps":["trace[1162240725] 'process raft request' (duration: 221.339189ms)","trace[1162240725] 'compare' (duration: 406.842807ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:58:59.321954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:58.690692Z","time spent":"631.218441ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T16:59:00.110344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.444921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:59:00.110419Z","caller":"traceutil/trace.go:171","msg":"trace[1405704227] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:795; }","duration":"618.552984ms","start":"2026-02-12T16:58:59.491843Z","end":"2026-02-12T16:59:00.110396Z","steps":["trace[1405704227] 'range keys from in-memory index tree' (duration: 618.379969ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:59:00.110455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:58:59.491826Z","time spent":"618.619837ms","remote":"127.0.0.1:54822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T16:59:00.110899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.962042ms","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-12T16:59:00.110971Z","caller":"traceutil/trace.go:171","msg":"trace[1254342953] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:795; }","duration":"316.036064ms","start":"2026-02-12T16:58:59.794925Z","end":"2026-02-12T16:59:00.110961Z","steps":["trace[1254342953] 'range keys from in-memory index tree' (duration: 315.953322ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:59:13.757553Z","caller":"traceutil/trace.go:171","msg":"trace[85234618] linearizableReadLoop","detail":"{readStateIndex:920; appliedIndex:919; }","duration":"267.67602ms","start":"2026-02-12T16:59:13.489856Z","end":"2026-02-12T16:59:13.757532Z","steps":["trace[85234618] 'read index received' (duration: 267.321249ms)","trace[85234618] 'applied index is now lower than readState.Index' (duration: 353.811µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:59:13.757762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.889176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:59:13.757815Z","caller":"traceutil/trace.go:171","msg":"trace[415313374] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:886; }","duration":"267.960428ms","start":"2026-02-12T16:59:13.489845Z","end":"2026-02-12T16:59:13.757805Z","steps":["trace[415313374] 'agreement among raft nodes before linearized reading' (duration: 267.805204ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:59:13.758254Z","caller":"traceutil/trace.go:171","msg":"trace[665372713] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"330.690963ms","start":"2026-02-12T16:59:13.427551Z","end":"2026-02-12T16:59:13.758242Z","steps":["trace[665372713] 'process raft request' (duration: 329.739713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:59:13.75901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:59:13.42754Z","time spent":"330.740364ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:59:14.792292Z","caller":"traceutil/trace.go:171","msg":"trace[165279164] linearizableReadLoop","detail":"{readStateIndex:921; appliedIndex:920; }","duration":"162.975832ms","start":"2026-02-12T16:59:14.629299Z","end":"2026-02-12T16:59:14.792274Z","steps":["trace[165279164] 'read index received' (duration: 162.871708ms)","trace[165279164] 'applied index is now lower than readState.Index' (duration: 100.174µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:59:14.792461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.148048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-12T16:59:14.792479Z","caller":"traceutil/trace.go:171","msg":"trace[1287342913] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:886; }","duration":"163.204289ms","start":"2026-02-12T16:59:14.629269Z","end":"2026-02-12T16:59:14.792473Z","steps":["trace[1287342913] 'agreement among raft nodes before linearized reading' (duration: 163.099236ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:59:14.792681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.600306ms","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-12T16:59:14.792734Z","caller":"traceutil/trace.go:171","msg":"trace[1426538032] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:886; }","duration":"143.695979ms","start":"2026-02-12T16:59:14.649024Z","end":"2026-02-12T16:59:14.79272Z","steps":["trace[1426538032] 'agreement among raft nodes before linearized reading' (duration: 143.542444ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:59:14.792841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.412943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-12T16:59:14.792859Z","caller":"traceutil/trace.go:171","msg":"trace[446864920] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:886; }","duration":"100.453215ms","start":"2026-02-12T16:59:14.692401Z","end":"2026-02-12T16:59:14.792855Z","steps":["trace[446864920] 'agreement among raft nodes before linearized reading' (duration: 100.412444ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:59:15.039554Z","caller":"traceutil/trace.go:171","msg":"trace[634764286] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"244.671389ms","start":"2026-02-12T16:59:14.794864Z","end":"2026-02-12T16:59:15.039535Z","steps":["trace[634764286] 'process raft request' (duration: 224.496408ms)","trace[634764286] 'compare' (duration: 20.088469ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:59:15.042495Z","caller":"traceutil/trace.go:171","msg":"trace[347300978] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"246.647221ms","start":"2026-02-12T16:59:14.795831Z","end":"2026-02-12T16:59:15.042478Z","steps":["trace[347300978] 'process raft request' (duration: 246.349792ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:59:15.042571Z","caller":"traceutil/trace.go:171","msg":"trace[286877139] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"243.586835ms","start":"2026-02-12T16:59:14.798979Z","end":"2026-02-12T16:59:15.042565Z","steps":["trace[286877139] 'process raft request' (duration: 243.257325ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:59:15.04262Z","caller":"traceutil/trace.go:171","msg":"trace[1052247855] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"239.5699ms","start":"2026-02-12T16:59:14.803046Z","end":"2026-02-12T16:59:15.042616Z","steps":["trace[1052247855] 'process raft request' (duration: 239.207298ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:59:15.042667Z","caller":"traceutil/trace.go:171","msg":"trace[296278831] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"234.809571ms","start":"2026-02-12T16:59:14.807853Z","end":"2026-02-12T16:59:15.042662Z","steps":["trace[296278831] 'process raft request' (duration: 234.414099ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:59:23.208084Z","caller":"traceutil/trace.go:171","msg":"trace[1152044555] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"130.553754ms","start":"2026-02-12T16:59:23.077522Z","end":"2026-02-12T16:59:23.208076Z","steps":["trace[1152044555] 'process raft request' (duration: 130.273525ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:59:23.207923Z","caller":"traceutil/trace.go:171","msg":"trace[398319717] linearizableReadLoop","detail":"{readStateIndex:946; appliedIndex:945; }","duration":"111.464676ms","start":"2026-02-12T16:59:23.096442Z","end":"2026-02-12T16:59:23.207906Z","steps":["trace[398319717] 'read index received' (duration: 111.303411ms)","trace[398319717] 'applied index is now lower than readState.Index' (duration: 160.565µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:59:23.208441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.333029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-12T16:59:23.208465Z","caller":"traceutil/trace.go:171","msg":"trace[1879889337] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:910; }","duration":"110.389581ms","start":"2026-02-12T16:59:23.098067Z","end":"2026-02-12T16:59:23.208456Z","steps":["trace[1879889337] 'agreement among raft nodes before linearized reading' (duration: 110.300768ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:59:23.208583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.284008ms","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-12T16:59:23.208601Z","caller":"traceutil/trace.go:171","msg":"trace[2027202879] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:910; }","duration":"107.340131ms","start":"2026-02-12T16:59:23.101256Z","end":"2026-02-12T16:59:23.208596Z","steps":["trace[2027202879] 'agreement among raft nodes before linearized reading' (duration: 107.289109ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:59:23.211502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.066494ms","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-12T16:59:23.21164Z","caller":"traceutil/trace.go:171","msg":"trace[2014797932] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:910; }","duration":"115.214849ms","start":"2026-02-12T16:59:23.096395Z","end":"2026-02-12T16:59:23.211609Z","steps":["trace[2014797932] 'agreement among raft nodes before linearized reading' (duration: 111.919381ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:59:23.528071Z","caller":"traceutil/trace.go:171","msg":"trace[1476932781] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"312.372605ms","start":"2026-02-12T16:59:23.215665Z","end":"2026-02-12T16:59:23.528038Z","steps":["trace[1476932781] 'process raft request' (duration: 312.173468ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:59:23.528192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:59:23.215645Z","time spent":"312.483758ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T16:59:23.801539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.271661ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T16:59:23.801673Z","caller":"traceutil/trace.go:171","msg":"trace[1848239865] linearizableReadLoop","detail":"{readStateIndex:949; appliedIndex:947; }","duration":"309.095667ms","start":"2026-02-12T16:59:23.492566Z","end":"2026-02-12T16:59:23.801661Z","steps":["trace[1848239865] 'read index received' (duration: 35.87789ms)","trace[1848239865] 'applied index is now lower than readState.Index' (duration: 273.216937ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:59:23.801754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.18906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:59:23.801771Z","caller":"traceutil/trace.go:171","msg":"trace[1560227982] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:913; }","duration":"309.240621ms","start":"2026-02-12T16:59:23.492525Z","end":"2026-02-12T16:59:23.801765Z","steps":["trace[1560227982] 'agreement among raft nodes before linearized reading' (duration: 309.168679ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:59:23.801791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:59:23.492504Z","time spent":"309.282313ms","remote":"127.0.0.1:54822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-12T16:59:23.802674Z","caller":"traceutil/trace.go:171","msg":"trace[956951578] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"585.894271ms","start":"2026-02-12T16:59:23.216712Z","end":"2026-02-12T16:59:23.802606Z","steps":["trace[956951578] 'process raft request' (duration: 445.498973ms)","trace[956951578] 'compare' (duration: 138.953371ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:59:23.802723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:59:23.216704Z","time spent":"585.993444ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:59:23.802937Z","caller":"traceutil/trace.go:171","msg":"trace[956276874] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"584.754624ms","start":"2026-02-12T16:59:23.218173Z","end":"2026-02-12T16:59:23.802927Z","steps":["trace[956276874] 'process raft request' (duration: 583.446491ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:59:23.80298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:59:23.218153Z","time spent":"584.803185ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T16:59:25.118695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.60495ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T16:59:25.118926Z","caller":"traceutil/trace.go:171","msg":"trace[594835979] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"381.667904ms","start":"2026-02-12T16:59:24.737247Z","end":"2026-02-12T16:59:25.118915Z","steps":["trace[594835979] 'process raft request' (duration: 259.791815ms)","trace[594835979] 'compare' (duration: 121.533467ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:59:25.118958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:59:24.737229Z","time spent":"381.714246ms","remote":"127.0.0.1:54844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T16:59:25.56757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.157576ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T16:59:25.567791Z","caller":"traceutil/trace.go:171","msg":"trace[1848852858] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"352.899647ms","start":"2026-02-12T16:59:25.214873Z","end":"2026-02-12T16:59:25.567773Z","steps":["trace[1848852858] 'process raft request' (duration: 68.466082ms)","trace[1848852858] 'compare' (duration: 283.940259ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:59:25.567854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:59:25.21486Z","time spent":"352.962909ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:59:30.043404Z","caller":"traceutil/trace.go:171","msg":"trace[1895520955] linearizableReadLoop","detail":"{readStateIndex:964; appliedIndex:963; }","duration":"395.636253ms","start":"2026-02-12T16:59:29.647753Z","end":"2026-02-12T16:59:30.043389Z","steps":["trace[1895520955] 'read index received' (duration: 395.501858ms)","trace[1895520955] 'applied index is now lower than readState.Index' (duration: 133.895µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:59:30.04348Z","caller":"traceutil/trace.go:171","msg":"trace[1245637700] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"459.799533ms","start":"2026-02-12T16:59:29.583675Z","end":"2026-02-12T16:59:30.043475Z","steps":["trace[1245637700] 'process raft request' (duration: 459.625897ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:59:30.043547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:59:29.583666Z","time spent":"459.827794ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T16:59:30.043671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.917772ms","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-12T16:59:30.043686Z","caller":"traceutil/trace.go:171","msg":"trace[1942424593] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:927; }","duration":"395.931862ms","start":"2026-02-12T16:59:29.647749Z","end":"2026-02-12T16:59:30.043681Z","steps":["trace[1942424593] 'agreement among raft nodes before linearized reading' (duration: 395.86841ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:59:30.0437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:59:29.647711Z","time spent":"395.985934ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-12T16:59:30.043757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.462722ms","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-12T16:59:30.043768Z","caller":"traceutil/trace.go:171","msg":"trace[606155563] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:927; }","duration":"217.506013ms","start":"2026-02-12T16:59:29.826258Z","end":"2026-02-12T16:59:30.043765Z","steps":["trace[606155563] 'agreement among raft nodes before linearized reading' (duration: 217.473432ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:59:30.043823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.552274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-12T16:59:30.043834Z","caller":"traceutil/trace.go:171","msg":"trace[2062249719] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:927; }","duration":"144.631156ms","start":"2026-02-12T16:59:29.899199Z","end":"2026-02-12T16:59:30.04383Z","steps":["trace[2062249719] 'agreement among raft nodes before linearized reading' (duration: 144.600515ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:59:30.043891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.017797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-12T16:59:30.043901Z","caller":"traceutil/trace.go:171","msg":"trace[1546591315] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:927; }","duration":"217.104781ms","start":"2026-02-12T16:59:29.826793Z","end":"2026-02-12T16:59:30.043898Z","steps":["trace[1546591315] 'agreement among raft nodes before linearized reading' (duration: 217.07244ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:59:30.980589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.908372ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T16:59:30.980957Z","caller":"traceutil/trace.go:171","msg":"trace[1854433118] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"929.250801ms","start":"2026-02-12T16:59:30.051685Z","end":"2026-02-12T16:59:30.980936Z","steps":["trace[1854433118] 'process raft request' (duration: 286.900286ms)","trace[1854433118] 'compare' (duration: 641.738675ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:59:30.981086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:59:30.051653Z","time spent":"929.397026ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T16:59:31.736379Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.397856286s","expected-duration":"1s"} {"level":"info","ts":"2026-02-12T16:59:31.784269Z","caller":"traceutil/trace.go:171","msg":"trace[1158712555] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"1.727399515s","start":"2026-02-12T16:59:30.056843Z","end":"2026-02-12T16:59:31.784242Z","steps":["trace[1158712555] 'process raft request' (duration: 1.726711752s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:59:31.784411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:59:30.056838Z","time spent":"1.727495278s","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:59:31.784618Z","caller":"traceutil/trace.go:171","msg":"trace[679092963] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"1.727821548s","start":"2026-02-12T16:59:30.056783Z","end":"2026-02-12T16:59:31.784605Z","steps":["trace[679092963] 'process raft request' (duration: 1.68168055s)","trace[679092963] 'compare' (duration: 44.182164ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:59:31.784674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:59:30.056766Z","time spent":"1.72787737s","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:59:31.785038Z","caller":"traceutil/trace.go:171","msg":"trace[1834241880] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"1.723368562s","start":"2026-02-12T16:59:30.061621Z","end":"2026-02-12T16:59:31.784989Z","steps":["trace[1834241880] 'process raft request' (duration: 1.722029798s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:59:31.785189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:59:30.061608Z","time spent":"1.723492226s","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T16:59:32.415033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.326325ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-12T16:59:32.415196Z","caller":"traceutil/trace.go:171","msg":"trace[181869835] linearizableReadLoop","detail":"{readStateIndex:968; appliedIndex:964; }","duration":"2.248300179s","start":"2026-02-12T16:59:30.166884Z","end":"2026-02-12T16:59:32.415184Z","steps":["trace[181869835] 'read index received' (duration: 171.688087ms)","trace[181869835] 'applied index is now lower than readState.Index' (duration: 2.076611482s)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:59:32.415775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.248884968s","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-12T16:59:32.415807Z","caller":"traceutil/trace.go:171","msg":"trace[1816876212] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:931; }","duration":"2.24895114s","start":"2026-02-12T16:59:30.166849Z","end":"2026-02-12T16:59:32.4158Z","steps":["trace[1816876212] 'agreement among raft nodes before linearized reading' (duration: 2.248893229s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:59:32.415829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:59:30.166829Z","time spent":"2.248993802s","remote":"127.0.0.1:54976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":31,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"info","ts":"2026-02-12T16:59:32.423216Z","caller":"traceutil/trace.go:171","msg":"trace[773259082] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"884.509663ms","start":"2026-02-12T16:59:31.538646Z","end":"2026-02-12T16:59:32.423156Z","steps":["trace[773259082] 'process raft request' (duration: 876.461889ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:59:32.424088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:59:31.538623Z","time spent":"885.070682ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T16:59:32.42903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.552827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-12T16:59:32.42927Z","caller":"traceutil/trace.go:171","msg":"trace[1151535565] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:932; }","duration":"380.827116ms","start":"2026-02-12T16:59:32.048431Z","end":"2026-02-12T16:59:32.429258Z","steps":["trace[1151535565] 'agreement among raft nodes before linearized reading' (duration: 376.347799ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:59:32.429333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:59:32.048413Z","time spent":"380.910518ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-12T16:59:32.429525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.939606625s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:59:32.434849Z","caller":"traceutil/trace.go:171","msg":"trace[2119796989] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:932; }","duration":"1.94490786s","start":"2026-02-12T16:59:30.489875Z","end":"2026-02-12T16:59:32.434783Z","steps":["trace[2119796989] 'agreement among raft nodes before linearized reading' (duration: 1.933196475s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:59:32.434935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:59:30.489854Z","time spent":"1.945057345s","remote":"127.0.0.1:54822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T16:59:34.751213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.736928ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-12T16:59:34.751299Z","caller":"traceutil/trace.go:171","msg":"trace[139909866] linearizableReadLoop","detail":"{readStateIndex:983; appliedIndex:982; }","duration":"304.225786ms","start":"2026-02-12T16:59:34.447057Z","end":"2026-02-12T16:59:34.751283Z","steps":["trace[139909866] 'read index received' (duration: 17.51µs)","trace[139909866] 'applied index is now lower than readState.Index' (duration: 304.207006ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:59:34.751393Z","caller":"traceutil/trace.go:171","msg":"trace[2004642421] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"400.890737ms","start":"2026-02-12T16:59:34.350492Z","end":"2026-02-12T16:59:34.751382Z","steps":["trace[2004642421] 'process raft request' (duration: 21.918852ms)","trace[2004642421] 'compare' (duration: 378.272942ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:59:34.751462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:59:34.350469Z","time spent":"400.968498ms","remote":"127.0.0.1:54886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":823,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-12T16:59:34.752437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.369195ms","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-12T16:59:34.752483Z","caller":"traceutil/trace.go:171","msg":"trace[1893644811] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:945; }","duration":"305.459237ms","start":"2026-02-12T16:59:34.447003Z","end":"2026-02-12T16:59:34.752462Z","steps":["trace[1893644811] 'agreement among raft nodes before linearized reading' (duration: 305.338473ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:59:34.752478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.6952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.47\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-02-12T16:59:34.752517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:59:34.446991Z","time spent":"305.51693ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-12T16:59:34.752555Z","caller":"traceutil/trace.go:171","msg":"trace[377087094] range","detail":"{range_begin:/registry/masterleases/199.19.213.47; range_end:; response_count:1; response_revision:945; }","duration":"165.808724ms","start":"2026-02-12T16:59:34.586732Z","end":"2026-02-12T16:59:34.75254Z","steps":["trace[377087094] 'agreement among raft nodes before linearized reading' (duration: 165.38836ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:59:35.041007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.102429ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-02-12T16:59:35.041455Z","caller":"traceutil/trace.go:171","msg":"trace[143113603] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"282.4275ms","start":"2026-02-12T16:59:34.759015Z","end":"2026-02-12T16:59:35.041443Z","steps":["trace[143113603] 'process raft request' (duration: 282.058708ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:59:37.250957Z","caller":"traceutil/trace.go:171","msg":"trace[204156536] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"122.209238ms","start":"2026-02-12T16:59:37.12872Z","end":"2026-02-12T16:59:37.250929Z","steps":["trace[204156536] 'process raft request' (duration: 65.71579ms)","trace[204156536] 'compare' (duration: 56.133706ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:59:41.285584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.904659ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-12T16:59:41.285669Z","caller":"traceutil/trace.go:171","msg":"trace[769438407] linearizableReadLoop","detail":"{readStateIndex:1021; appliedIndex:1020; }","duration":"148.285007ms","start":"2026-02-12T16:59:41.137369Z","end":"2026-02-12T16:59:41.285654Z","steps":["trace[769438407] 'read index received' (duration: 9.237334ms)","trace[769438407] 'applied index is now lower than readState.Index' (duration: 139.046573ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:59:41.285815Z","caller":"traceutil/trace.go:171","msg":"trace[360257528] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"327.423009ms","start":"2026-02-12T16:59:40.958381Z","end":"2026-02-12T16:59:41.285804Z","steps":["trace[360257528] 'process raft request' (duration: 188.234071ms)","trace[360257528] 'compare' (duration: 138.676851ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:59:41.285862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:59:40.958363Z","time spent":"327.476241ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-12T16:59:41.291334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.580961ms","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-12T16:59:41.291505Z","caller":"traceutil/trace.go:171","msg":"trace[1665591839] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:980; }","duration":"154.126349ms","start":"2026-02-12T16:59:41.137332Z","end":"2026-02-12T16:59:41.291458Z","steps":["trace[1665591839] 'agreement among raft nodes before linearized reading' (duration: 148.793474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:59:45.591521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.318968ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T16:59:45.591999Z","caller":"traceutil/trace.go:171","msg":"trace[915525105] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"236.358845ms","start":"2026-02-12T16:59:45.355619Z","end":"2026-02-12T16:59:45.591977Z","steps":["trace[915525105] 'process raft request' (duration: 56.119696ms)","trace[915525105] 'compare' (duration: 155.209185ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:59:52.105935Z","caller":"traceutil/trace.go:171","msg":"trace[504665124] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"130.775622ms","start":"2026-02-12T16:59:51.975129Z","end":"2026-02-12T16:59:52.105904Z","steps":["trace[504665124] 'process raft request' (duration: 130.593276ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:59:52.107613Z","caller":"traceutil/trace.go:171","msg":"trace[1725864453] linearizableReadLoop","detail":"{readStateIndex:1073; appliedIndex:1073; }","duration":"110.741932ms","start":"2026-02-12T16:59:51.996854Z","end":"2026-02-12T16:59:52.107596Z","steps":["trace[1725864453] 'read index received' (duration: 110.734382ms)","trace[1725864453] 'applied index is now lower than readState.Index' (duration: 5.93µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:59:52.107893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.010341ms","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-12T16:59:52.109699Z","caller":"traceutil/trace.go:171","msg":"trace[689512775] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1030; }","duration":"112.856402ms","start":"2026-02-12T16:59:51.996827Z","end":"2026-02-12T16:59:52.109683Z","steps":["trace[689512775] 'agreement among raft nodes before linearized reading' (duration: 110.916668ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:59:52.304154Z","caller":"traceutil/trace.go:171","msg":"trace[1991333739] linearizableReadLoop","detail":"{readStateIndex:1074; appliedIndex:1073; }","duration":"163.636332ms","start":"2026-02-12T16:59:52.140492Z","end":"2026-02-12T16:59:52.304129Z","steps":["trace[1991333739] 'read index received' (duration: 163.436646ms)","trace[1991333739] 'applied index is now lower than readState.Index' (duration: 198.856µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:59:52.304377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.859949ms","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-12T16:59:52.30441Z","caller":"traceutil/trace.go:171","msg":"trace[2033508143] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1031; }","duration":"163.951182ms","start":"2026-02-12T16:59:52.140448Z","end":"2026-02-12T16:59:52.304399Z","steps":["trace[2033508143] 'agreement among raft nodes before linearized reading' (duration: 163.764356ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:59:52.304672Z","caller":"traceutil/trace.go:171","msg":"trace[1438177512] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"190.839657ms","start":"2026-02-12T16:59:52.113821Z","end":"2026-02-12T16:59:52.304661Z","steps":["trace[1438177512] 'process raft request' (duration: 190.177785ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:00:03.776389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.279885ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T17:00:03.776951Z","caller":"traceutil/trace.go:171","msg":"trace[299735936] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"190.615469ms","start":"2026-02-12T17:00:03.586291Z","end":"2026-02-12T17:00:03.776906Z","steps":["trace[299735936] 'process raft request' (duration: 76.737244ms)","trace[299735936] 'compare' (duration: 113.164322ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T17:00:04.48922Z","caller":"traceutil/trace.go:171","msg":"trace[1780147175] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"119.957636ms","start":"2026-02-12T17:00:04.369237Z","end":"2026-02-12T17:00:04.489195Z","steps":["trace[1780147175] 'process raft request' (duration: 82.157052ms)","trace[1780147175] 'compare' (duration: 37.68414ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T17:00:05.011927Z","caller":"traceutil/trace.go:171","msg":"trace[857433563] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"103.615728ms","start":"2026-02-12T17:00:04.908271Z","end":"2026-02-12T17:00:05.011887Z","steps":["trace[857433563] 'process raft request' (duration: 103.349409ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:29.394392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.990138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-12T17:02:29.394653Z","caller":"traceutil/trace.go:171","msg":"trace[809993348] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1690; }","duration":"116.345139ms","start":"2026-02-12T17:02:29.278287Z","end":"2026-02-12T17:02:29.394632Z","steps":["trace[809993348] 'range keys from in-memory index tree' (duration: 115.539344ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:30.016412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.084742ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-12T17:02:30.016511Z","caller":"traceutil/trace.go:171","msg":"trace[360595558] linearizableReadLoop","detail":"{readStateIndex:1766; appliedIndex:1765; }","duration":"251.108652ms","start":"2026-02-12T17:02:29.765384Z","end":"2026-02-12T17:02:30.016493Z","steps":["trace[360595558] 'read index received' (duration: 88.882325ms)","trace[360595558] 'applied index is now lower than readState.Index' (duration: 162.224847ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T17:02:30.016662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.271986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-12T17:02:30.016702Z","caller":"traceutil/trace.go:171","msg":"trace[1852013273] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1691; }","duration":"251.386879ms","start":"2026-02-12T17:02:29.765293Z","end":"2026-02-12T17:02:30.01668Z","steps":["trace[1852013273] 'agreement among raft nodes before linearized reading' (duration: 251.247835ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:02:30.228592Z","caller":"traceutil/trace.go:171","msg":"trace[702001689] transaction","detail":"{read_only:false; response_revision:1692; number_of_response:1; }","duration":"207.286258ms","start":"2026-02-12T17:02:30.021276Z","end":"2026-02-12T17:02:30.228562Z","steps":["trace[702001689] 'process raft request' (duration: 206.795533ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:30.748819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.329099ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T17:02:30.748927Z","caller":"traceutil/trace.go:171","msg":"trace[454431185] transaction","detail":"{read_only:false; response_revision:1695; number_of_response:1; }","duration":"164.013392ms","start":"2026-02-12T17:02:30.584893Z","end":"2026-02-12T17:02:30.748906Z","steps":["trace[454431185] 'process raft request' (duration: 29.517608ms)","trace[454431185] 'compare' (duration: 134.233956ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T17:02:31.35101Z","caller":"traceutil/trace.go:171","msg":"trace[1664344873] transaction","detail":"{read_only:false; response_revision:1698; number_of_response:1; }","duration":"133.7548ms","start":"2026-02-12T17:02:31.217231Z","end":"2026-02-12T17:02:31.350985Z","steps":["trace[1664344873] 'process raft request' (duration: 133.609296ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:02:51.671925Z","caller":"traceutil/trace.go:171","msg":"trace[955359707] transaction","detail":"{read_only:false; response_revision:1786; number_of_response:1; }","duration":"167.994777ms","start":"2026-02-12T17:02:51.503885Z","end":"2026-02-12T17:02:51.67188Z","steps":["trace[955359707] 'process raft request' (duration: 82.739739ms)","trace[955359707] 'compare' (duration: 84.797534ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T17:02:51.68379Z","caller":"traceutil/trace.go:171","msg":"trace[311566484] linearizableReadLoop","detail":"{readStateIndex:1867; appliedIndex:1865; }","duration":"115.905974ms","start":"2026-02-12T17:02:51.567869Z","end":"2026-02-12T17:02:51.683775Z","steps":["trace[311566484] 'read index received' (duration: 18.719364ms)","trace[311566484] 'applied index is now lower than readState.Index' (duration: 97.18563ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T17:02:51.683866Z","caller":"traceutil/trace.go:171","msg":"trace[1853142397] transaction","detail":"{read_only:false; response_revision:1787; number_of_response:1; }","duration":"167.309395ms","start":"2026-02-12T17:02:51.516547Z","end":"2026-02-12T17:02:51.683857Z","steps":["trace[1853142397] 'process raft request' (duration: 167.14865ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:51.684135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.247134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T17:02:51.68417Z","caller":"traceutil/trace.go:171","msg":"trace[1724319326] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1787; }","duration":"116.333197ms","start":"2026-02-12T17:02:51.567827Z","end":"2026-02-12T17:02:51.68416Z","steps":["trace[1724319326] 'agreement among raft nodes before linearized reading' (duration: 116.237664ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:51.995256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.900665ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T17:02:51.995959Z","caller":"traceutil/trace.go:171","msg":"trace[1003773192] transaction","detail":"{read_only:false; response_revision:1789; number_of_response:1; }","duration":"225.859672ms","start":"2026-02-12T17:02:51.77008Z","end":"2026-02-12T17:02:51.995939Z","steps":["trace[1003773192] 'process raft request' (duration: 91.0687ms)","trace[1003773192] 'compare' (duration: 133.754989ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T17:02:52.326349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.933981ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T17:02:52.326529Z","caller":"traceutil/trace.go:171","msg":"trace[1238206253] transaction","detail":"{read_only:false; response_revision:1790; number_of_response:1; }","duration":"300.120426ms","start":"2026-02-12T17:02:52.026385Z","end":"2026-02-12T17:02:52.326505Z","steps":["trace[1238206253] 'process raft request' (duration: 109.920597ms)","trace[1238206253] 'compare' (duration: 189.817067ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T17:02:52.326618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:52.026358Z","time spent":"300.212849ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T17:02:52.805847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.149709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-12T17:02:52.805921Z","caller":"traceutil/trace.go:171","msg":"trace[436307177] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1790; }","duration":"302.276143ms","start":"2026-02-12T17:02:52.503623Z","end":"2026-02-12T17:02:52.805899Z","steps":["trace[436307177] 'range keys from in-memory index tree' (duration: 301.87878ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:52.805959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:52.503599Z","time spent":"302.350466ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-12T17:02:52.806292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.232703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T17:02:52.806332Z","caller":"traceutil/trace.go:171","msg":"trace[972939393] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1790; }","duration":"312.381178ms","start":"2026-02-12T17:02:52.493941Z","end":"2026-02-12T17:02:52.806322Z","steps":["trace[972939393] 'range keys from in-memory index tree' (duration: 312.150491ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:52.806429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.606573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-12T17:02:52.806716Z","caller":"traceutil/trace.go:171","msg":"trace[1355030299] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1790; }","duration":"303.028297ms","start":"2026-02-12T17:02:52.503655Z","end":"2026-02-12T17:02:52.806683Z","steps":["trace[1355030299] 'range keys from in-memory index tree' (duration: 302.048086ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:52.807779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:52.503647Z","time spent":"304.11215ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-12T17:02:52.806507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:52.493919Z","time spent":"312.576394ms","remote":"127.0.0.1:54822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-12T17:02:53.002991Z","caller":"traceutil/trace.go:171","msg":"trace[564173103] transaction","detail":"{read_only:false; response_revision:1791; number_of_response:1; }","duration":"189.795357ms","start":"2026-02-12T17:02:52.813169Z","end":"2026-02-12T17:02:53.002964Z","steps":["trace[564173103] 'process raft request' (duration: 189.670763ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:02:53.003597Z","caller":"traceutil/trace.go:171","msg":"trace[74252289] linearizableReadLoop","detail":"{readStateIndex:1871; appliedIndex:1871; }","duration":"190.178719ms","start":"2026-02-12T17:02:52.81336Z","end":"2026-02-12T17:02:53.003539Z","steps":["trace[74252289] 'read index received' (duration: 190.171889ms)","trace[74252289] 'applied index is now lower than readState.Index' (duration: 5.51µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T17:02:53.003944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.557631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T17:02:53.004088Z","caller":"traceutil/trace.go:171","msg":"trace[730463991] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1791; }","duration":"190.753096ms","start":"2026-02-12T17:02:52.813315Z","end":"2026-02-12T17:02:53.004068Z","steps":["trace[730463991] 'agreement among raft nodes before linearized reading' (duration: 190.516949ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:53.134666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.354735ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T17:02:53.13486Z","caller":"traceutil/trace.go:171","msg":"trace[1388729906] linearizableReadLoop","detail":"{readStateIndex:1873; appliedIndex:1871; }","duration":"130.828538ms","start":"2026-02-12T17:02:53.004016Z","end":"2026-02-12T17:02:53.134844Z","steps":["trace[1388729906] 'read index received' (duration: 25.083602ms)","trace[1388729906] 'applied index is now lower than readState.Index' (duration: 105.743756ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T17:02:53.134954Z","caller":"traceutil/trace.go:171","msg":"trace[972105775] transaction","detail":"{read_only:false; response_revision:1793; number_of_response:1; }","duration":"320.558203ms","start":"2026-02-12T17:02:52.814384Z","end":"2026-02-12T17:02:53.134942Z","steps":["trace[972105775] 'process raft request' (duration: 320.367257ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:53.135016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:52.814372Z","time spent":"320.610574ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T17:02:53.134988Z","caller":"traceutil/trace.go:171","msg":"trace[1711392842] transaction","detail":"{read_only:false; response_revision:1792; number_of_response:1; }","duration":"321.448531ms","start":"2026-02-12T17:02:52.813415Z","end":"2026-02-12T17:02:53.134863Z","steps":["trace[1711392842] 'process raft request' (duration: 215.822618ms)","trace[1711392842] 'compare' (duration: 105.23107ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T17:02:53.135155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:52.813408Z","time spent":"321.659197ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T17:02:53.135263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.536752ms","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-12T17:02:53.135302Z","caller":"traceutil/trace.go:171","msg":"trace[2139739118] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1793; }","duration":"234.610044ms","start":"2026-02-12T17:02:52.900682Z","end":"2026-02-12T17:02:53.135292Z","steps":["trace[2139739118] 'agreement among raft nodes before linearized reading' (duration: 234.499981ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:53.135787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.054719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-02-12T17:02:53.135833Z","caller":"traceutil/trace.go:171","msg":"trace[625933717] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1793; }","duration":"100.136242ms","start":"2026-02-12T17:02:53.035685Z","end":"2026-02-12T17:02:53.135821Z","steps":["trace[625933717] 'agreement among raft nodes before linearized reading' (duration: 99.75951ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:53.399924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.114089ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T17:02:53.400282Z","caller":"traceutil/trace.go:171","msg":"trace[440950783] transaction","detail":"{read_only:false; response_revision:1795; number_of_response:1; }","duration":"168.844964ms","start":"2026-02-12T17:02:53.231366Z","end":"2026-02-12T17:02:53.400211Z","steps":["trace[440950783] 'process raft request' (duration: 10.358643ms)","trace[440950783] 'compare' (duration: 157.915053ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T17:03:03.175592Z","caller":"traceutil/trace.go:171","msg":"trace[220656836] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"119.3309ms","start":"2026-02-12T17:03:03.056234Z","end":"2026-02-12T17:03:03.175565Z","steps":["trace[220656836] 'process raft request' (duration: 119.124063ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:03:12.936985Z","caller":"traceutil/trace.go:171","msg":"trace[13346659] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"197.751775ms","start":"2026-02-12T17:03:12.739213Z","end":"2026-02-12T17:03:12.936965Z","steps":["trace[13346659] 'process raft request' (duration: 197.626631ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:03:13.188765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.623287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T17:03:13.188835Z","caller":"traceutil/trace.go:171","msg":"trace[1843743479] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1895; }","duration":"134.72446ms","start":"2026-02-12T17:03:13.054095Z","end":"2026-02-12T17:03:13.188819Z","steps":["trace[1843743479] 'range keys from in-memory index tree' (duration: 134.560035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:03:13.189107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.628133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-12T17:03:13.193247Z","caller":"traceutil/trace.go:171","msg":"trace[1576962906] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1895; }","duration":"131.682775ms","start":"2026-02-12T17:03:13.057448Z","end":"2026-02-12T17:03:13.189131Z","steps":["trace[1576962906] 'count revisions from in-memory index tree' (duration: 131.557211ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:03:13.470139Z","caller":"traceutil/trace.go:171","msg":"trace[1346295411] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"106.776769ms","start":"2026-02-12T17:03:13.363335Z","end":"2026-02-12T17:03:13.470111Z","steps":["trace[1346295411] 'process raft request' (duration: 101.882956ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:03:13.891171Z","caller":"traceutil/trace.go:171","msg":"trace[1242956657] linearizableReadLoop","detail":"{readStateIndex:1985; appliedIndex:1984; }","duration":"183.251923ms","start":"2026-02-12T17:03:13.707901Z","end":"2026-02-12T17:03:13.891153Z","steps":["trace[1242956657] 'read index received' (duration: 183.066007ms)","trace[1242956657] 'applied index is now lower than readState.Index' (duration: 185.136µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T17:03:13.891261Z","caller":"traceutil/trace.go:171","msg":"trace[643798921] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1901; }","duration":"224.050645ms","start":"2026-02-12T17:03:13.667201Z","end":"2026-02-12T17:03:13.891252Z","steps":["trace[643798921] 'process raft request' (duration: 223.810637ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:03:13.891478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.310219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2833"} {"level":"info","ts":"2026-02-12T17:03:13.891542Z","caller":"traceutil/trace.go:171","msg":"trace[1228728728] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1901; }","duration":"178.402951ms","start":"2026-02-12T17:03:13.713122Z","end":"2026-02-12T17:03:13.891525Z","steps":["trace[1228728728] 'agreement among raft nodes before linearized reading' (duration: 178.234016ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:03:13.891604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.687776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-12T17:03:13.891632Z","caller":"traceutil/trace.go:171","msg":"trace[1036663130] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1901; }","duration":"183.754279ms","start":"2026-02-12T17:03:13.70787Z","end":"2026-02-12T17:03:13.891624Z","steps":["trace[1036663130] 'agreement among raft nodes before linearized reading' (duration: 183.645535ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:03:16.113039Z","caller":"traceutil/trace.go:171","msg":"trace[1752886691] transaction","detail":"{read_only:false; response_revision:1915; number_of_response:1; }","duration":"101.446612ms","start":"2026-02-12T17:03:16.011573Z","end":"2026-02-12T17:03:16.11302Z","steps":["trace[1752886691] 'process raft request' (duration: 101.338939ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:03:17.449549Z","caller":"traceutil/trace.go:171","msg":"trace[1725848227] transaction","detail":"{read_only:false; response_revision:1917; number_of_response:1; }","duration":"107.180071ms","start":"2026-02-12T17:03:17.342342Z","end":"2026-02-12T17:03:17.449522Z","steps":["trace[1725848227] 'process raft request' (duration: 106.579912ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:03:34.314111Z","caller":"traceutil/trace.go:171","msg":"trace[1588946736] linearizableReadLoop","detail":"{readStateIndex:2079; appliedIndex:2078; }","duration":"113.210839ms","start":"2026-02-12T17:03:34.200886Z","end":"2026-02-12T17:03:34.314097Z","steps":["trace[1588946736] 'read index received' (duration: 113.059834ms)","trace[1588946736] 'applied index is now lower than readState.Index' (duration: 150.375µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T17:03:34.314209Z","caller":"traceutil/trace.go:171","msg":"trace[407100380] transaction","detail":"{read_only:false; response_revision:1991; number_of_response:1; }","duration":"118.746001ms","start":"2026-02-12T17:03:34.195455Z","end":"2026-02-12T17:03:34.314201Z","steps":["trace[407100380] 'process raft request' (duration: 118.541205ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:03:34.314481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.57981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-node-lease/kube-root-ca.crt\" ","response":"range_response_count:1 size:1754"} {"level":"info","ts":"2026-02-12T17:03:34.314536Z","caller":"traceutil/trace.go:171","msg":"trace[1007225742] range","detail":"{range_begin:/registry/configmaps/kube-node-lease/kube-root-ca.crt; range_end:; response_count:1; response_revision:1991; }","duration":"113.660533ms","start":"2026-02-12T17:03:34.20086Z","end":"2026-02-12T17:03:34.31452Z","steps":["trace[1007225742] 'agreement among raft nodes before linearized reading' (duration: 113.583021ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:03:37.295476Z","caller":"traceutil/trace.go:171","msg":"trace[1009737078] transaction","detail":"{read_only:false; response_revision:2001; number_of_response:1; }","duration":"140.547929ms","start":"2026-02-12T17:03:37.154906Z","end":"2026-02-12T17:03:37.295454Z","steps":["trace[1009737078] 'process raft request' (duration: 140.358903ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:03:37.296305Z","caller":"traceutil/trace.go:171","msg":"trace[1923178018] linearizableReadLoop","detail":"{readStateIndex:2090; appliedIndex:2089; }","duration":"124.487995ms","start":"2026-02-12T17:03:37.171803Z","end":"2026-02-12T17:03:37.296291Z","steps":["trace[1923178018] 'read index received' (duration: 123.292908ms)","trace[1923178018] 'applied index is now lower than readState.Index' (duration: 1.191317ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T17:03:37.296659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.737324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-public/\" range_end:\"/registry/deployments/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T17:03:37.296885Z","caller":"traceutil/trace.go:171","msg":"trace[783427176] range","detail":"{range_begin:/registry/deployments/kube-public/; range_end:/registry/deployments/kube-public0; response_count:0; response_revision:2001; }","duration":"125.097544ms","start":"2026-02-12T17:03:37.171773Z","end":"2026-02-12T17:03:37.29687Z","steps":["trace[783427176] 'agreement among raft nodes before linearized reading' (duration: 124.735083ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:03:37.297672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.127044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/coredns\" ","response":"range_response_count:1 size:612"} {"level":"info","ts":"2026-02-12T17:03:37.310567Z","caller":"traceutil/trace.go:171","msg":"trace[344673812] range","detail":"{range_begin:/registry/configmaps/kube-system/coredns; range_end:; response_count:1; response_revision:2001; }","duration":"130.03251ms","start":"2026-02-12T17:03:37.180515Z","end":"2026-02-12T17:03:37.310547Z","steps":["trace[344673812] 'agreement among raft nodes before linearized reading' (duration: 117.038892ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:03:37.472361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.042667ms","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-12T17:03:37.472423Z","caller":"traceutil/trace.go:171","msg":"trace[1430335021] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:2001; }","duration":"114.13633ms","start":"2026-02-12T17:03:37.358271Z","end":"2026-02-12T17:03:37.472407Z","steps":["trace[1430335021] 'count revisions from in-memory index tree' (duration: 113.989356ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:03:37.938786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.850262ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T17:03:37.939035Z","caller":"traceutil/trace.go:171","msg":"trace[1517765865] transaction","detail":"{read_only:false; response_revision:2005; number_of_response:1; }","duration":"169.420725ms","start":"2026-02-12T17:03:37.769588Z","end":"2026-02-12T17:03:37.939009Z","steps":["trace[1517765865] 'compare' (duration: 164.642035ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:03:37.939067Z","caller":"traceutil/trace.go:171","msg":"trace[95726159] transaction","detail":"{read_only:false; response_revision:2006; number_of_response:1; }","duration":"135.596134ms","start":"2026-02-12T17:03:37.803453Z","end":"2026-02-12T17:03:37.939049Z","steps":["trace[95726159] 'process raft request' (duration: 135.498361ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:03:37.93922Z","caller":"traceutil/trace.go:171","msg":"trace[1973795472] linearizableReadLoop","detail":"{readStateIndex:2094; appliedIndex:2093; }","duration":"165.900655ms","start":"2026-02-12T17:03:37.77331Z","end":"2026-02-12T17:03:37.93921Z","steps":["trace[1973795472] 'read index received' (duration: 507.096µs)","trace[1973795472] 'applied index is now lower than readState.Index' (duration: 165.392729ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T17:03:37.939399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.08204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kube-apiserver-legacy-service-account-token-tracking\" ","response":"range_response_count:1 size:370"} {"level":"info","ts":"2026-02-12T17:03:37.939432Z","caller":"traceutil/trace.go:171","msg":"trace[884532831] range","detail":"{range_begin:/registry/configmaps/kube-system/kube-apiserver-legacy-service-account-token-tracking; range_end:; response_count:1; response_revision:2006; }","duration":"166.136372ms","start":"2026-02-12T17:03:37.773283Z","end":"2026-02-12T17:03:37.939419Z","steps":["trace[884532831] 'agreement among raft nodes before linearized reading' (duration: 166.0797ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:03:37.939531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.641434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:1 size:3017"} {"level":"info","ts":"2026-02-12T17:03:37.939587Z","caller":"traceutil/trace.go:171","msg":"trace[102521567] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:1; response_revision:2006; }","duration":"119.736247ms","start":"2026-02-12T17:03:37.819834Z","end":"2026-02-12T17:03:37.93957Z","steps":["trace[102521567] 'agreement among raft nodes before linearized reading' (duration: 119.624713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:03:37.939722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.509085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/kube-public/\" range_end:\"/registry/jobs/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T17:03:37.939755Z","caller":"traceutil/trace.go:171","msg":"trace[390969349] range","detail":"{range_begin:/registry/jobs/kube-public/; range_end:/registry/jobs/kube-public0; response_count:0; response_revision:2006; }","duration":"116.569286ms","start":"2026-02-12T17:03:37.823177Z","end":"2026-02-12T17:03:37.939746Z","steps":["trace[390969349] 'agreement among raft nodes before linearized reading' (duration: 116.519035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:03:38.188071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.387115ms","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-12T17:03:38.188127Z","caller":"traceutil/trace.go:171","msg":"trace[1689219972] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2006; }","duration":"148.477757ms","start":"2026-02-12T17:03:38.039636Z","end":"2026-02-12T17:03:38.188114Z","steps":["trace[1689219972] 'range keys from in-memory index tree' (duration: 148.271211ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:03:38.188276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.40254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-public/\" range_end:\"/registry/pods/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T17:03:38.188296Z","caller":"traceutil/trace.go:171","msg":"trace[540978897] range","detail":"{range_begin:/registry/pods/kube-public/; range_end:/registry/pods/kube-public0; response_count:0; response_revision:2006; }","duration":"115.448292ms","start":"2026-02-12T17:03:38.072842Z","end":"2026-02-12T17:03:38.18829Z","steps":["trace[540978897] 'range keys from in-memory index tree' (duration: 115.338038ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:03:38.338189Z","caller":"traceutil/trace.go:171","msg":"trace[1755757069] transaction","detail":"{read_only:false; response_revision:2007; number_of_response:1; }","duration":"139.551228ms","start":"2026-02-12T17:03:38.198621Z","end":"2026-02-12T17:03:38.338173Z","steps":["trace[1755757069] 'process raft request' (duration: 139.363252ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:03:41.106545Z","caller":"traceutil/trace.go:171","msg":"trace[1934391180] linearizableReadLoop","detail":"{readStateIndex:2107; appliedIndex:2106; }","duration":"161.458355ms","start":"2026-02-12T17:03:40.945061Z","end":"2026-02-12T17:03:41.10652Z","steps":["trace[1934391180] 'read index received' (duration: 161.235928ms)","trace[1934391180] 'applied index is now lower than readState.Index' (duration: 221.607µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T17:03:41.108198Z","caller":"traceutil/trace.go:171","msg":"trace[164786837] transaction","detail":"{read_only:false; response_revision:2017; number_of_response:1; }","duration":"167.078112ms","start":"2026-02-12T17:03:40.941103Z","end":"2026-02-12T17:03:41.108181Z","steps":["trace[164786837] 'process raft request' (duration: 165.277065ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:03:41.108476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.397006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7036"} {"level":"info","ts":"2026-02-12T17:03:41.108519Z","caller":"traceutil/trace.go:171","msg":"trace[1864928942] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:2017; }","duration":"163.451248ms","start":"2026-02-12T17:03:40.945054Z","end":"2026-02-12T17:03:41.108505Z","steps":["trace[1864928942] 'agreement among raft nodes before linearized reading' (duration: 163.363275ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:03:41.108794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.134047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5088"} {"level":"info","ts":"2026-02-12T17:03:41.108829Z","caller":"traceutil/trace.go:171","msg":"trace[545298386] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:2017; }","duration":"155.173278ms","start":"2026-02-12T17:03:40.953646Z","end":"2026-02-12T17:03:41.108819Z","steps":["trace[545298386] 'agreement among raft nodes before linearized reading' (duration: 155.098005ms)"],"step_count":1}