{"level":"warn","ts":"2026-02-10T18:48:12.89317Z","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-10T18:48:12.894052Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.21: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.21:2380","--initial-cluster=instance=https://199.19.213.21:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.21:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.21: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-10T18:48:12.894118Z","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-10T18:48:12.894127Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.21:2380"]} {"level":"info","ts":"2026-02-10T18:48:12.894143Z","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-10T18:48:12.894755Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.21:2379"]} {"level":"info","ts":"2026-02-10T18:48:12.89482Z","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.21:2380"],"listen-peer-urls":["https://199.19.213.21:2380"],"advertise-client-urls":["https://199.19.213.21:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.21:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.21: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-10T18:48:12.900902Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.912361ms"} {"level":"info","ts":"2026-02-10T18:48:12.923087Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c39f03fd9ad57fdc","cluster-id":"3c95a04d00bbaaa4"} {"level":"info","ts":"2026-02-10T18:48:12.923179Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc switched to configuration voters=()"} {"level":"info","ts":"2026-02-10T18:48:12.923225Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc became follower at term 0"} {"level":"info","ts":"2026-02-10T18:48:12.923254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c39f03fd9ad57fdc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-10T18:48:12.923274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc became follower at term 1"} {"level":"info","ts":"2026-02-10T18:48:12.92333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc switched to configuration voters=(14095989746452234204)"} {"level":"warn","ts":"2026-02-10T18:48:12.934051Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-10T18:48:12.935262Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-10T18:48:12.936181Z","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-10T18:48:12.93769Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c39f03fd9ad57fdc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-10T18:48:12.938119Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c39f03fd9ad57fdc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-10T18:48:12.938265Z","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-10T18:48:12.938305Z","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-10T18:48:12.938347Z","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-10T18:48:12.93872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc switched to configuration voters=(14095989746452234204)"} {"level":"info","ts":"2026-02-10T18:48:12.938805Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3c95a04d00bbaaa4","local-member-id":"c39f03fd9ad57fdc","added-peer-id":"c39f03fd9ad57fdc","added-peer-peer-urls":["https://199.19.213.21:2380"]} {"level":"info","ts":"2026-02-10T18:48:12.943623Z","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-10T18:48:12.943889Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c39f03fd9ad57fdc","initial-advertise-peer-urls":["https://199.19.213.21:2380"],"listen-peer-urls":["https://199.19.213.21:2380"],"advertise-client-urls":["https://199.19.213.21:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.21:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-10T18:48:12.944017Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-10T18:48:12.944446Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.21:2380"} {"level":"info","ts":"2026-02-10T18:48:12.944881Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.21:2380"} {"level":"info","ts":"2026-02-10T18:48:13.22587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc is starting a new election at term 1"} {"level":"info","ts":"2026-02-10T18:48:13.225992Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc became pre-candidate at term 1"} {"level":"info","ts":"2026-02-10T18:48:13.226033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc received MsgPreVoteResp from c39f03fd9ad57fdc at term 1"} {"level":"info","ts":"2026-02-10T18:48:13.226074Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc became candidate at term 2"} {"level":"info","ts":"2026-02-10T18:48:13.226094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc received MsgVoteResp from c39f03fd9ad57fdc at term 2"} {"level":"info","ts":"2026-02-10T18:48:13.226112Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc became leader at term 2"} {"level":"info","ts":"2026-02-10T18:48:13.226153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c39f03fd9ad57fdc elected leader c39f03fd9ad57fdc at term 2"} {"level":"info","ts":"2026-02-10T18:48:13.226953Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T18:48:13.229157Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c39f03fd9ad57fdc","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.21:2379]}","request-path":"/0/members/c39f03fd9ad57fdc/attributes","cluster-id":"3c95a04d00bbaaa4","publish-timeout":"7s"} {"level":"info","ts":"2026-02-10T18:48:13.229344Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-10T18:48:13.229584Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3c95a04d00bbaaa4","local-member-id":"c39f03fd9ad57fdc","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T18:48:13.229662Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T18:48:13.229709Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T18:48:13.229748Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-10T18:48:13.229781Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-10T18:48:13.229803Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-10T18:48:13.235546Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-10T18:48:13.258203Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.21:2379"} {"level":"info","ts":"2026-02-10T18:48:16.341561Z","caller":"traceutil/trace.go:171","msg":"trace[584164086] transaction","detail":"{read_only:false; response_revision:88; number_of_response:1; }","duration":"174.815735ms","start":"2026-02-10T18:48:16.166717Z","end":"2026-02-10T18:48:16.341533Z","steps":["trace[584164086] 'process raft request' (duration: 103.20424ms)","trace[584164086] 'compare' (duration: 71.398404ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:48:17.116704Z","caller":"traceutil/trace.go:171","msg":"trace[265619352] transaction","detail":"{read_only:false; response_revision:114; number_of_response:1; }","duration":"108.004564ms","start":"2026-02-10T18:48:17.008674Z","end":"2026-02-10T18:48:17.116678Z","steps":["trace[265619352] 'process raft request' (duration: 47.113316ms)","trace[265619352] 'compare' (duration: 60.340015ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:48:18.620733Z","caller":"traceutil/trace.go:171","msg":"trace[951878844] transaction","detail":"{read_only:false; response_revision:194; number_of_response:1; }","duration":"147.026545ms","start":"2026-02-10T18:48:18.473649Z","end":"2026-02-10T18:48:18.620675Z","steps":["trace[951878844] 'process raft request' (duration: 111.39458ms)","trace[951878844] 'compare' (duration: 35.492384ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:48:19.717712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.241292ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:48:19.718211Z","caller":"traceutil/trace.go:171","msg":"trace[861265145] transaction","detail":"{read_only:false; response_revision:214; number_of_response:1; }","duration":"135.34326ms","start":"2026-02-10T18:48:19.582854Z","end":"2026-02-10T18:48:19.718197Z","steps":["trace[861265145] 'process raft request' (duration: 12.288944ms)","trace[861265145] 'compare' (duration: 122.070671ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:48:20.799218Z","caller":"traceutil/trace.go:171","msg":"trace[1182235674] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"136.506549ms","start":"2026-02-10T18:48:20.662684Z","end":"2026-02-10T18:48:20.799191Z","steps":["trace[1182235674] 'process raft request' (duration: 97.349999ms)","trace[1182235674] 'compare' (duration: 39.007168ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:48:21.34275Z","caller":"traceutil/trace.go:171","msg":"trace[141471460] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"108.49147ms","start":"2026-02-10T18:48:21.23424Z","end":"2026-02-10T18:48:21.342732Z","steps":["trace[141471460] 'process raft request' (duration: 105.796351ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:48:22.37837Z","caller":"traceutil/trace.go:171","msg":"trace[1592829982] linearizableReadLoop","detail":"{readStateIndex:312; appliedIndex:311; }","duration":"145.738792ms","start":"2026-02-10T18:48:22.232614Z","end":"2026-02-10T18:48:22.378353Z","steps":["trace[1592829982] 'read index received' (duration: 96.121062ms)","trace[1592829982] 'applied index is now lower than readState.Index' (duration: 49.61706ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:48:22.378475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.828616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:48:22.378493Z","caller":"traceutil/trace.go:171","msg":"trace[608337337] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:0; response_revision:299; }","duration":"145.883339ms","start":"2026-02-10T18:48:22.232603Z","end":"2026-02-10T18:48:22.378486Z","steps":["trace[608337337] 'agreement among raft nodes before linearized reading' (duration: 145.823126ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:48:22.378676Z","caller":"traceutil/trace.go:171","msg":"trace[884217329] transaction","detail":"{read_only:false; response_revision:299; number_of_response:1; }","duration":"146.12284ms","start":"2026-02-10T18:48:22.232519Z","end":"2026-02-10T18:48:22.378642Z","steps":["trace[884217329] 'process raft request' (duration: 96.26988ms)","trace[884217329] 'compare' (duration: 49.43032ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:48:22.59565Z","caller":"traceutil/trace.go:171","msg":"trace[1031639831] linearizableReadLoop","detail":"{readStateIndex:314; appliedIndex:313; }","duration":"134.271991ms","start":"2026-02-10T18:48:22.461359Z","end":"2026-02-10T18:48:22.595631Z","steps":["trace[1031639831] 'read index received' (duration: 120.700832ms)","trace[1031639831] 'applied index is now lower than readState.Index' (duration: 13.570119ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:48:22.59576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.387626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-02-10T18:48:22.595779Z","caller":"traceutil/trace.go:171","msg":"trace[1380149636] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:301; }","duration":"134.45507ms","start":"2026-02-10T18:48:22.461318Z","end":"2026-02-10T18:48:22.595773Z","steps":["trace[1380149636] 'agreement among raft nodes before linearized reading' (duration: 134.385177ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:48:22.596032Z","caller":"traceutil/trace.go:171","msg":"trace[1547010697] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"208.502031ms","start":"2026-02-10T18:48:22.387524Z","end":"2026-02-10T18:48:22.596026Z","steps":["trace[1547010697] 'process raft request' (duration: 194.553163ms)","trace[1547010697] 'compare' (duration: 13.470455ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:48:31.682611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.527673ms","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-10T18:48:31.682687Z","caller":"traceutil/trace.go:171","msg":"trace[542851632] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:403; }","duration":"154.657929ms","start":"2026-02-10T18:48:31.528015Z","end":"2026-02-10T18:48:31.682673Z","steps":["trace[542851632] 'range keys from in-memory index tree' (duration: 154.45157ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:48:32.894108Z","caller":"traceutil/trace.go:171","msg":"trace[1560712081] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"124.464982ms","start":"2026-02-10T18:48:32.769626Z","end":"2026-02-10T18:48:32.894091Z","steps":["trace[1560712081] 'process raft request' (duration: 84.501178ms)","trace[1560712081] 'compare' (duration: 39.62616ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:48:32.894275Z","caller":"traceutil/trace.go:171","msg":"trace[1435643816] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"123.68554ms","start":"2026-02-10T18:48:32.770567Z","end":"2026-02-10T18:48:32.894252Z","steps":["trace[1435643816] 'process raft request' (duration: 123.304744ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:48:33.105803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.865246ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:48:33.105965Z","caller":"traceutil/trace.go:171","msg":"trace[1462932273] linearizableReadLoop","detail":"{readStateIndex:446; appliedIndex:442; }","duration":"131.912233ms","start":"2026-02-10T18:48:32.974043Z","end":"2026-02-10T18:48:33.105955Z","steps":["trace[1462932273] 'read index received' (duration: 20.772055ms)","trace[1462932273] 'applied index is now lower than readState.Index' (duration: 111.139658ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:48:33.106019Z","caller":"traceutil/trace.go:171","msg":"trace[1320112612] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"167.6471ms","start":"2026-02-10T18:48:32.938367Z","end":"2026-02-10T18:48:33.106014Z","steps":["trace[1320112612] 'process raft request' (duration: 167.549826ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:48:33.106025Z","caller":"traceutil/trace.go:171","msg":"trace[1385872286] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"167.951903ms","start":"2026-02-10T18:48:32.938051Z","end":"2026-02-10T18:48:33.106003Z","steps":["trace[1385872286] 'process raft request' (duration: 167.831908ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:48:33.106165Z","caller":"traceutil/trace.go:171","msg":"trace[31834080] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"137.225773ms","start":"2026-02-10T18:48:32.968935Z","end":"2026-02-10T18:48:33.106161Z","steps":["trace[31834080] 'process raft request' (duration: 137.006094ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:48:33.106185Z","caller":"traceutil/trace.go:171","msg":"trace[323641965] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"168.861551ms","start":"2026-02-10T18:48:32.937315Z","end":"2026-02-10T18:48:33.106177Z","steps":["trace[323641965] 'process raft request' (duration: 57.491854ms)","trace[323641965] 'compare' (duration: 110.771152ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:48:33.106291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.249106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-5dd5756b68\" ","response":"range_response_count:1 size:3797"} {"level":"info","ts":"2026-02-10T18:48:33.106309Z","caller":"traceutil/trace.go:171","msg":"trace[1987375720] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-5dd5756b68; range_end:; response_count:1; response_revision:430; }","duration":"132.301008ms","start":"2026-02-10T18:48:32.974001Z","end":"2026-02-10T18:48:33.106302Z","steps":["trace[1987375720] 'agreement among raft nodes before linearized reading' (duration: 132.216585ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:48:33.106392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.125568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4173"} {"level":"info","ts":"2026-02-10T18:48:33.106404Z","caller":"traceutil/trace.go:171","msg":"trace[1052771468] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:430; }","duration":"130.147018ms","start":"2026-02-10T18:48:32.976254Z","end":"2026-02-10T18:48:33.106401Z","steps":["trace[1052771468] 'agreement among raft nodes before linearized reading' (duration: 130.122747ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:48:33.307232Z","caller":"traceutil/trace.go:171","msg":"trace[493873067] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"191.287048ms","start":"2026-02-10T18:48:33.115921Z","end":"2026-02-10T18:48:33.307208Z","steps":["trace[493873067] 'process raft request' (duration: 122.324851ms)","trace[493873067] 'compare' (duration: 68.852972ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:48:33.309792Z","caller":"traceutil/trace.go:171","msg":"trace[276590845] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"133.508641ms","start":"2026-02-10T18:48:33.176262Z","end":"2026-02-10T18:48:33.309771Z","steps":["trace[276590845] 'process raft request' (duration: 133.415467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:48:39.219071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:48:38.71857Z","time spent":"500.497923ms","remote":"127.0.0.1:51578","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-10T18:48:39.628783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.347568ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:48:39.628879Z","caller":"traceutil/trace.go:171","msg":"trace[1636882925] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"408.935927ms","start":"2026-02-10T18:48:39.21993Z","end":"2026-02-10T18:48:39.628866Z","steps":["trace[1636882925] 'process raft request' (duration: 296.446634ms)","trace[1636882925] 'compare' (duration: 112.13628ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:48:39.628929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:48:39.219913Z","time spent":"408.983798ms","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:48:42.86069Z","caller":"traceutil/trace.go:171","msg":"trace[614074239] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"834.387373ms","start":"2026-02-10T18:48:42.02628Z","end":"2026-02-10T18:48:42.860667Z","steps":["trace[614074239] 'process raft request' (duration: 834.242558ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:48:42.860834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:48:42.026261Z","time spent":"834.499416ms","remote":"127.0.0.1:51798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:48:42.861291Z","caller":"traceutil/trace.go:171","msg":"trace[983925544] linearizableReadLoop","detail":"{readStateIndex:476; appliedIndex:476; }","duration":"361.467599ms","start":"2026-02-10T18:48:42.499811Z","end":"2026-02-10T18:48:42.861279Z","steps":["trace[983925544] 'read index received' (duration: 361.462508ms)","trace[983925544] 'applied index is now lower than readState.Index' (duration: 4.01µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:48:42.861414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.577812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:48:42.861445Z","caller":"traceutil/trace.go:171","msg":"trace[898235220] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:458; }","duration":"361.635624ms","start":"2026-02-10T18:48:42.499798Z","end":"2026-02-10T18:48:42.861434Z","steps":["trace[898235220] 'agreement among raft nodes before linearized reading' (duration: 361.559351ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:48:42.861487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:48:42.499741Z","time spent":"361.722257ms","remote":"127.0.0.1:51532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T18:48:43.558843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.586795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12673"} {"level":"info","ts":"2026-02-10T18:48:43.558925Z","caller":"traceutil/trace.go:171","msg":"trace[1458652337] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:458; }","duration":"572.687939ms","start":"2026-02-10T18:48:42.986216Z","end":"2026-02-10T18:48:43.558904Z","steps":["trace[1458652337] 'range keys from in-memory index tree' (duration: 572.330376ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:48:43.55897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:48:42.985991Z","time spent":"572.96871ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":12696,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"info","ts":"2026-02-10T18:48:44.754971Z","caller":"traceutil/trace.go:171","msg":"trace[309480071] linearizableReadLoop","detail":"{readStateIndex:481; appliedIndex:480; }","duration":"255.8895ms","start":"2026-02-10T18:48:44.499058Z","end":"2026-02-10T18:48:44.754947Z","steps":["trace[309480071] 'read index received' (duration: 255.012929ms)","trace[309480071] 'applied index is now lower than readState.Index' (duration: 875.651µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:48:44.755289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.208102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:48:44.755421Z","caller":"traceutil/trace.go:171","msg":"trace[896510418] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:462; }","duration":"256.411209ms","start":"2026-02-10T18:48:44.498995Z","end":"2026-02-10T18:48:44.755407Z","steps":["trace[896510418] 'agreement among raft nodes before linearized reading' (duration: 256.128539ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:48:44.756687Z","caller":"traceutil/trace.go:171","msg":"trace[956672500] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"309.248516ms","start":"2026-02-10T18:48:44.447423Z","end":"2026-02-10T18:48:44.756671Z","steps":["trace[956672500] 'process raft request' (duration: 307.214792ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:48:44.757156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:48:44.447403Z","time spent":"309.706252ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317280,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T18:48:47.051722Z","caller":"traceutil/trace.go:171","msg":"trace[80109864] linearizableReadLoop","detail":"{readStateIndex:520; appliedIndex:519; }","duration":"133.907221ms","start":"2026-02-10T18:48:46.917795Z","end":"2026-02-10T18:48:47.051702Z","steps":["trace[80109864] 'read index received' (duration: 133.735465ms)","trace[80109864] 'applied index is now lower than readState.Index' (duration: 171.116µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:48:47.051836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.062926ms","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-10T18:48:47.051861Z","caller":"traceutil/trace.go:171","msg":"trace[948861976] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:501; }","duration":"134.136959ms","start":"2026-02-10T18:48:46.917714Z","end":"2026-02-10T18:48:47.051851Z","steps":["trace[948861976] 'agreement among raft nodes before linearized reading' (duration: 134.081967ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:48:47.051951Z","caller":"traceutil/trace.go:171","msg":"trace[83126015] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"327.6995ms","start":"2026-02-10T18:48:46.724225Z","end":"2026-02-10T18:48:47.051924Z","steps":["trace[83126015] 'process raft request' (duration: 327.325087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:48:47.052008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:48:46.72421Z","time spent":"327.760811ms","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4210,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:48:48.352364Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9213410774535470934,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T18:48:48.85252Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9213410774535470934,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T18:48:49.35331Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9213410774535470934,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T18:48:49.85386Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9213410774535470934,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T18:48:50.309585Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.459639811s","expected-duration":"1s"} {"level":"info","ts":"2026-02-10T18:48:50.310414Z","caller":"traceutil/trace.go:171","msg":"trace[1926602366] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"2.460575653s","start":"2026-02-10T18:48:47.849817Z","end":"2026-02-10T18:48:50.310393Z","steps":["trace[1926602366] 'process raft request' (duration: 2.460287603s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:48:50.310566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:48:47.849805Z","time spent":"2.460670576s","remote":"127.0.0.1:51798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:48:50.312689Z","caller":"traceutil/trace.go:171","msg":"trace[1562526291] linearizableReadLoop","detail":"{readStateIndex:523; appliedIndex:521; }","duration":"2.460518078s","start":"2026-02-10T18:48:47.852158Z","end":"2026-02-10T18:48:50.312676Z","steps":["trace[1562526291] 'read index received' (duration: 2.457928381s)","trace[1562526291] 'applied index is now lower than readState.Index' (duration: 2.588977ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:48:50.312866Z","caller":"traceutil/trace.go:171","msg":"trace[429332328] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"2.462847739s","start":"2026-02-10T18:48:47.850007Z","end":"2026-02-10T18:48:50.312855Z","steps":["trace[429332328] 'process raft request' (duration: 2.462612491s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:48:50.312954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:48:47.849992Z","time spent":"2.462910171s","remote":"127.0.0.1:51798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:48:50.313117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.460956982s","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-10T18:48:50.313154Z","caller":"traceutil/trace.go:171","msg":"trace[1612355438] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:504; }","duration":"2.460994794s","start":"2026-02-10T18:48:47.85215Z","end":"2026-02-10T18:48:50.313144Z","steps":["trace[1612355438] 'agreement among raft nodes before linearized reading' (duration: 2.460930201s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:48:50.313184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:48:47.852115Z","time spent":"2.461062257s","remote":"127.0.0.1:51798","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-10T18:48:50.313329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.81337766s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:48:50.313364Z","caller":"traceutil/trace.go:171","msg":"trace[1436924963] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:504; }","duration":"1.813440882s","start":"2026-02-10T18:48:48.499912Z","end":"2026-02-10T18:48:50.313353Z","steps":["trace[1436924963] 'agreement among raft nodes before linearized reading' (duration: 1.81339486s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:48:50.313388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:48:48.499892Z","time spent":"1.813489564s","remote":"127.0.0.1:51532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T18:48:50.313576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.066341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:48:50.313613Z","caller":"traceutil/trace.go:171","msg":"trace[2071099615] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:504; }","duration":"575.125882ms","start":"2026-02-10T18:48:49.738476Z","end":"2026-02-10T18:48:50.313602Z","steps":["trace[2071099615] 'agreement among raft nodes before linearized reading' (duration: 575.07231ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:48:50.313639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:48:49.738463Z","time spent":"575.169134ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"warn","ts":"2026-02-10T18:48:50.313784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.249514173s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-10T18:48:50.314554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.597431092s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.21\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-10T18:48:50.314596Z","caller":"traceutil/trace.go:171","msg":"trace[1875442650] range","detail":"{range_begin:/registry/masterleases/199.19.213.21; range_end:; response_count:1; response_revision:504; }","duration":"1.597471013s","start":"2026-02-10T18:48:48.717112Z","end":"2026-02-10T18:48:50.314583Z","steps":["trace[1875442650] 'agreement among raft nodes before linearized reading' (duration: 1.597405351s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:48:50.314633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:48:48.717094Z","time spent":"1.597531575s","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.21\" "} {"level":"info","ts":"2026-02-10T18:48:50.313818Z","caller":"traceutil/trace.go:171","msg":"trace[2039111690] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:504; }","duration":"1.249569975s","start":"2026-02-10T18:48:49.06424Z","end":"2026-02-10T18:48:50.31381Z","steps":["trace[2039111690] 'agreement among raft nodes before linearized reading' (duration: 1.249515613s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:48:50.315172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:48:49.064226Z","time spent":"1.250935011s","remote":"127.0.0.1:51798","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-10T18:48:58.000529Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9213410774535471025,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-10T18:48:58.052245Z","caller":"traceutil/trace.go:171","msg":"trace[714608808] linearizableReadLoop","detail":"{readStateIndex:568; appliedIndex:567; }","duration":"553.717335ms","start":"2026-02-10T18:48:57.498512Z","end":"2026-02-10T18:48:58.052229Z","steps":["trace[714608808] 'read index received' (duration: 553.580161ms)","trace[714608808] 'applied index is now lower than readState.Index' (duration: 136.544µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:48:58.052341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.811178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:48:58.052359Z","caller":"traceutil/trace.go:171","msg":"trace[1144021000] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:547; }","duration":"553.87173ms","start":"2026-02-10T18:48:57.498481Z","end":"2026-02-10T18:48:58.052352Z","steps":["trace[1144021000] 'agreement among raft nodes before linearized reading' (duration: 553.807578ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:48:58.052377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:48:57.498465Z","time spent":"553.909072ms","remote":"127.0.0.1:51532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-10T18:48:58.052542Z","caller":"traceutil/trace.go:171","msg":"trace[1201847436] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"813.251021ms","start":"2026-02-10T18:48:57.239285Z","end":"2026-02-10T18:48:58.052536Z","steps":["trace[1201847436] 'process raft request' (duration: 812.855328ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:48:58.052593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:48:57.23926Z","time spent":"813.296392ms","remote":"127.0.0.1:51738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:49:00.15429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.437264623s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.21\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-10T18:49:00.154357Z","caller":"traceutil/trace.go:171","msg":"trace[1980274169] range","detail":"{range_begin:/registry/masterleases/199.19.213.21; range_end:; response_count:1; response_revision:547; }","duration":"1.437377756s","start":"2026-02-10T18:48:58.716963Z","end":"2026-02-10T18:49:00.154341Z","steps":["trace[1980274169] 'range keys from in-memory index tree' (duration: 1.437154689s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:49:00.154405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:48:58.716947Z","time spent":"1.437448248s","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.21\" "} {"level":"warn","ts":"2026-02-10T18:49:00.154656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.766134764s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-10T18:49:00.154681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.757042854s","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-10T18:49:00.154707Z","caller":"traceutil/trace.go:171","msg":"trace[579775904] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:547; }","duration":"1.757109296s","start":"2026-02-10T18:48:58.39759Z","end":"2026-02-10T18:49:00.1547Z","steps":["trace[579775904] 'range keys from in-memory index tree' (duration: 1.756915241s)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:49:00.154723Z","caller":"traceutil/trace.go:171","msg":"trace[534833003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:547; }","duration":"1.766233527s","start":"2026-02-10T18:48:58.388465Z","end":"2026-02-10T18:49:00.154699Z","steps":["trace[534833003] 'range keys from in-memory index tree' (duration: 1.76599102s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:49:00.154727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:48:58.397569Z","time spent":"1.757153438s","remote":"127.0.0.1:51798","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-10T18:49:00.154746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:48:58.388432Z","time spent":"1.766309139s","remote":"127.0.0.1:51798","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-10T18:49:00.154888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.683186761s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-10T18:49:00.154912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.778182084s","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-10T18:49:00.154915Z","caller":"traceutil/trace.go:171","msg":"trace[1280796261] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:547; }","duration":"1.683247133s","start":"2026-02-10T18:48:58.471659Z","end":"2026-02-10T18:49:00.154906Z","steps":["trace[1280796261] 'range keys from in-memory index tree' (duration: 1.683092498s)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:49:00.154929Z","caller":"traceutil/trace.go:171","msg":"trace[301179529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:547; }","duration":"1.778238747s","start":"2026-02-10T18:48:58.376684Z","end":"2026-02-10T18:49:00.154922Z","steps":["trace[301179529] 'range keys from in-memory index tree' (duration: 1.778064251s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:49:00.154938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:48:58.471638Z","time spent":"1.683294734s","remote":"127.0.0.1:51798","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-10T18:49:00.154989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.656336785s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:49:00.155004Z","caller":"traceutil/trace.go:171","msg":"trace[940075667] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:547; }","duration":"1.656350445s","start":"2026-02-10T18:48:58.498649Z","end":"2026-02-10T18:49:00.154999Z","steps":["trace[940075667] 'range keys from in-memory index tree' (duration: 1.656107668s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:49:00.155015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:48:58.498599Z","time spent":"1.656413677s","remote":"127.0.0.1:51532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T18:49:00.154942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:48:58.376661Z","time spent":"1.778278057s","remote":"127.0.0.1:51798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-10T18:49:01.885446Z","caller":"traceutil/trace.go:171","msg":"trace[164894700] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"291.030289ms","start":"2026-02-10T18:49:01.594402Z","end":"2026-02-10T18:49:01.885432Z","steps":["trace[164894700] 'process raft request' (duration: 290.936126ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:49:20.497665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.570787ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:49:20.497736Z","caller":"traceutil/trace.go:171","msg":"trace[1345062346] linearizableReadLoop","detail":"{readStateIndex:785; appliedIndex:784; }","duration":"120.056561ms","start":"2026-02-10T18:49:20.377668Z","end":"2026-02-10T18:49:20.497725Z","steps":["trace[1345062346] 'read index received' (duration: 34.531µs)","trace[1345062346] 'applied index is now lower than readState.Index' (duration: 120.02131ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:49:20.497906Z","caller":"traceutil/trace.go:171","msg":"trace[487771891] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"149.162614ms","start":"2026-02-10T18:49:20.348734Z","end":"2026-02-10T18:49:20.497897Z","steps":["trace[487771891] 'process raft request' (duration: 16.30854ms)","trace[487771891] 'compare' (duration: 132.401353ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:49:20.498065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.394249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-54kd8\" ","response":"range_response_count:1 size:5011"} {"level":"info","ts":"2026-02-10T18:49:20.49811Z","caller":"traceutil/trace.go:171","msg":"trace[2055478710] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-54kd8; range_end:; response_count:1; response_revision:757; }","duration":"120.44118ms","start":"2026-02-10T18:49:20.377636Z","end":"2026-02-10T18:49:20.498077Z","steps":["trace[2055478710] 'agreement among raft nodes before linearized reading' (duration: 120.367718ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:49:20.618121Z","caller":"traceutil/trace.go:171","msg":"trace[1586341777] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"115.649842ms","start":"2026-02-10T18:49:20.502453Z","end":"2026-02-10T18:49:20.618103Z","steps":["trace[1586341777] 'process raft request' (duration: 113.121901ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:49:20.623841Z","caller":"traceutil/trace.go:171","msg":"trace[1045676358] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"119.394335ms","start":"2026-02-10T18:49:20.504428Z","end":"2026-02-10T18:49:20.623822Z","steps":["trace[1045676358] 'process raft request' (duration: 119.062916ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:49:30.816094Z","caller":"traceutil/trace.go:171","msg":"trace[1797003299] linearizableReadLoop","detail":"{readStateIndex:819; appliedIndex:818; }","duration":"108.998348ms","start":"2026-02-10T18:49:30.707078Z","end":"2026-02-10T18:49:30.816076Z","steps":["trace[1797003299] 'read index received' (duration: 108.874266ms)","trace[1797003299] 'applied index is now lower than readState.Index' (duration: 123.502µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:49:30.816233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.141642ms","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-10T18:49:30.816251Z","caller":"traceutil/trace.go:171","msg":"trace[381955223] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:789; }","duration":"109.196843ms","start":"2026-02-10T18:49:30.707049Z","end":"2026-02-10T18:49:30.816246Z","steps":["trace[381955223] 'agreement among raft nodes before linearized reading' (duration: 109.096921ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:49:30.816299Z","caller":"traceutil/trace.go:171","msg":"trace[575871943] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"142.507975ms","start":"2026-02-10T18:49:30.673777Z","end":"2026-02-10T18:49:30.816285Z","steps":["trace[575871943] 'process raft request' (duration: 142.195248ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:50:21.373906Z","caller":"traceutil/trace.go:171","msg":"trace[1095233451] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"128.879479ms","start":"2026-02-10T18:50:21.245008Z","end":"2026-02-10T18:50:21.373888Z","steps":["trace[1095233451] 'process raft request' (duration: 128.759027ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:22.069226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.149644ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:50:22.069301Z","caller":"traceutil/trace.go:171","msg":"trace[124792076] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:1002; }","duration":"569.835902ms","start":"2026-02-10T18:50:21.499452Z","end":"2026-02-10T18:50:22.069288Z","steps":["trace[124792076] 'read index received' (duration: 343.500675ms)","trace[124792076] 'applied index is now lower than readState.Index' (duration: 226.334267ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:50:22.06965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.173187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:50:22.06988Z","caller":"traceutil/trace.go:171","msg":"trace[1480084471] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:961; }","duration":"570.39461ms","start":"2026-02-10T18:50:21.499422Z","end":"2026-02-10T18:50:22.069816Z","steps":["trace[1480084471] 'agreement among raft nodes before linearized reading' (duration: 570.104746ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:22.070059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:50:21.499407Z","time spent":"570.600664ms","remote":"127.0.0.1:51532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-10T18:50:22.070366Z","caller":"traceutil/trace.go:171","msg":"trace[1634345231] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"689.928188ms","start":"2026-02-10T18:50:21.380422Z","end":"2026-02-10T18:50:22.070351Z","steps":["trace[1634345231] 'process raft request' (duration: 462.597606ms)","trace[1634345231] 'compare' (duration: 225.975422ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:50:22.070597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:50:21.380357Z","time spent":"690.139521ms","remote":"127.0.0.1:51798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:50:22.070954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.504779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-10T18:50:22.071834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.87554ms","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-10T18:50:22.072132Z","caller":"traceutil/trace.go:171","msg":"trace[181700386] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:961; }","duration":"144.204005ms","start":"2026-02-10T18:50:21.927909Z","end":"2026-02-10T18:50:22.072113Z","steps":["trace[181700386] 'agreement among raft nodes before linearized reading' (duration: 143.88735ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:50:22.071001Z","caller":"traceutil/trace.go:171","msg":"trace[1101099720] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:961; }","duration":"265.573161ms","start":"2026-02-10T18:50:21.805408Z","end":"2026-02-10T18:50:22.070981Z","steps":["trace[1101099720] 'agreement among raft nodes before linearized reading' (duration: 265.469179ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:50:23.152883Z","caller":"traceutil/trace.go:171","msg":"trace[1916630966] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"176.476429ms","start":"2026-02-10T18:50:22.976391Z","end":"2026-02-10T18:50:23.152867Z","steps":["trace[1916630966] 'process raft request' (duration: 176.383838ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:28.974484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.98325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.21\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-10T18:50:28.974586Z","caller":"traceutil/trace.go:171","msg":"trace[1784640077] range","detail":"{range_begin:/registry/masterleases/199.19.213.21; range_end:; response_count:1; response_revision:986; }","duration":"228.148832ms","start":"2026-02-10T18:50:28.74641Z","end":"2026-02-10T18:50:28.974559Z","steps":["trace[1784640077] 'range keys from in-memory index tree' (duration: 227.575554ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:29.441442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.599435ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:50:29.441528Z","caller":"traceutil/trace.go:171","msg":"trace[101361821] linearizableReadLoop","detail":"{readStateIndex:1031; appliedIndex:1030; }","duration":"260.890116ms","start":"2026-02-10T18:50:29.180623Z","end":"2026-02-10T18:50:29.441513Z","steps":["trace[101361821] 'read index received' (duration: 24.091898ms)","trace[101361821] 'applied index is now lower than readState.Index' (duration: 236.797118ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:50:29.441625Z","caller":"traceutil/trace.go:171","msg":"trace[1389313811] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"336.989125ms","start":"2026-02-10T18:50:29.104625Z","end":"2026-02-10T18:50:29.441614Z","steps":["trace[1389313811] 'process raft request' (duration: 100.140476ms)","trace[1389313811] 'compare' (duration: 236.357822ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:50:29.441678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:50:29.10461Z","time spent":"337.038475ms","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:50:29.441979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.354533ms","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-10T18:50:29.442008Z","caller":"traceutil/trace.go:171","msg":"trace[323595911] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:987; }","duration":"261.408153ms","start":"2026-02-10T18:50:29.180591Z","end":"2026-02-10T18:50:29.441999Z","steps":["trace[323595911] 'agreement among raft nodes before linearized reading' (duration: 261.282341ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:29.442147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.808149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T18:50:29.442173Z","caller":"traceutil/trace.go:171","msg":"trace[2114764043] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:987; }","duration":"243.888591ms","start":"2026-02-10T18:50:29.198276Z","end":"2026-02-10T18:50:29.442165Z","steps":["trace[2114764043] 'agreement among raft nodes before linearized reading' (duration: 243.84548ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:29.442372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.575001ms","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-10T18:50:29.442399Z","caller":"traceutil/trace.go:171","msg":"trace[2142038103] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:987; }","duration":"217.639602ms","start":"2026-02-10T18:50:29.224752Z","end":"2026-02-10T18:50:29.442392Z","steps":["trace[2142038103] 'agreement among raft nodes before linearized reading' (duration: 217.565041ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:30.66341Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9213410774535472097,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T18:50:31.165705Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9213410774535472097,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T18:50:31.666675Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9213410774535472097,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T18:50:31.755702Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.620567117s","expected-duration":"1s"} {"level":"info","ts":"2026-02-10T18:50:31.755954Z","caller":"traceutil/trace.go:171","msg":"trace[989236909] linearizableReadLoop","detail":"{readStateIndex:1035; appliedIndex:1034; }","duration":"1.593262524s","start":"2026-02-10T18:50:30.162674Z","end":"2026-02-10T18:50:31.755937Z","steps":["trace[989236909] 'read index received' (duration: 1.593123702s)","trace[989236909] 'applied index is now lower than readState.Index' (duration: 138.222µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:50:31.756043Z","caller":"traceutil/trace.go:171","msg":"trace[351726407] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"1.621070363s","start":"2026-02-10T18:50:30.134945Z","end":"2026-02-10T18:50:31.756016Z","steps":["trace[351726407] 'process raft request' (duration: 1.620888281s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:31.756114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.593422466s","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-10T18:50:31.756137Z","caller":"traceutil/trace.go:171","msg":"trace[500545654] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:991; }","duration":"1.593503968s","start":"2026-02-10T18:50:30.162627Z","end":"2026-02-10T18:50:31.756131Z","steps":["trace[500545654] 'agreement among raft nodes before linearized reading' (duration: 1.593387926s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:31.756162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:50:30.162606Z","time spent":"1.593550378s","remote":"127.0.0.1:51798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-10T18:50:31.75619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:50:30.13492Z","time spent":"1.621191666s","remote":"127.0.0.1:51798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:50:31.756379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.74591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:50:31.756408Z","caller":"traceutil/trace.go:171","msg":"trace[377098332] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:991; }","duration":"257.792351ms","start":"2026-02-10T18:50:31.498601Z","end":"2026-02-10T18:50:31.756393Z","steps":["trace[377098332] 'agreement among raft nodes before linearized reading' (duration: 257.76167ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:31.756536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.044742ms","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-10T18:50:31.756553Z","caller":"traceutil/trace.go:171","msg":"trace[1013753262] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:991; }","duration":"213.087813ms","start":"2026-02-10T18:50:31.54346Z","end":"2026-02-10T18:50:31.756548Z","steps":["trace[1013753262] 'agreement among raft nodes before linearized reading' (duration: 213.036812ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:31.756559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.570811ms","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-10T18:50:31.756591Z","caller":"traceutil/trace.go:171","msg":"trace[1176502697] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:991; }","duration":"148.628011ms","start":"2026-02-10T18:50:31.607952Z","end":"2026-02-10T18:50:31.75658Z","steps":["trace[1176502697] 'agreement among raft nodes before linearized reading' (duration: 148.52628ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:31.75666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.042158ms","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-10T18:50:31.756679Z","caller":"traceutil/trace.go:171","msg":"trace[1475851014] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:991; }","duration":"247.082988ms","start":"2026-02-10T18:50:31.50959Z","end":"2026-02-10T18:50:31.756673Z","steps":["trace[1475851014] 'agreement among raft nodes before linearized reading' (duration: 247.032967ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:32.593264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.234604ms","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-10T18:50:32.593335Z","caller":"traceutil/trace.go:171","msg":"trace[1164946103] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:995; }","duration":"733.379245ms","start":"2026-02-10T18:50:31.859942Z","end":"2026-02-10T18:50:32.593321Z","steps":["trace[1164946103] 'count revisions from in-memory index tree' (duration: 733.157982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:32.593364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:50:31.859924Z","time spent":"733.432936ms","remote":"127.0.0.1:51814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":2,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"info","ts":"2026-02-10T18:50:33.836747Z","caller":"traceutil/trace.go:171","msg":"trace[1937508056] transaction","detail":"{read_only:false; number_of_response:1; response_revision:999; }","duration":"117.673313ms","start":"2026-02-10T18:50:33.71905Z","end":"2026-02-10T18:50:33.836723Z","steps":["trace[1937508056] 'process raft request' (duration: 87.618168ms)","trace[1937508056] 'compare' (duration: 29.555167ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:51:04.482747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.234907ms","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-10T18:51:04.482851Z","caller":"traceutil/trace.go:171","msg":"trace[1452643407] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1085; }","duration":"280.345328ms","start":"2026-02-10T18:51:04.202488Z","end":"2026-02-10T18:51:04.482833Z","steps":["trace[1452643407] 'range keys from in-memory index tree' (duration: 280.213257ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:04.483217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.777697ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:51:04.483418Z","caller":"traceutil/trace.go:171","msg":"trace[527658223] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"324.221726ms","start":"2026-02-10T18:51:04.15918Z","end":"2026-02-10T18:51:04.483402Z","steps":["trace[527658223] 'process raft request' (duration: 170.170426ms)","trace[527658223] 'compare' (duration: 153.658325ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:51:04.484685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:04.159159Z","time spent":"325.455261ms","remote":"127.0.0.1:51798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:51:04.500796Z","caller":"traceutil/trace.go:171","msg":"trace[1524738612] linearizableReadLoop","detail":"{readStateIndex:1137; appliedIndex:1135; }","duration":"307.170977ms","start":"2026-02-10T18:51:04.193602Z","end":"2026-02-10T18:51:04.500773Z","steps":["trace[1524738612] 'read index received' (duration: 135.760325ms)","trace[1524738612] 'applied index is now lower than readState.Index' (duration: 171.409762ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:51:04.500988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.37347ms","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-10T18:51:04.501012Z","caller":"traceutil/trace.go:171","msg":"trace[1068683423] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1086; }","duration":"307.42566ms","start":"2026-02-10T18:51:04.193578Z","end":"2026-02-10T18:51:04.501003Z","steps":["trace[1068683423] 'agreement among raft nodes before linearized reading' (duration: 307.306949ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:04.501033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:04.193566Z","time spent":"307.46265ms","remote":"127.0.0.1:51798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-10T18:51:04.501398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.015589ms","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-10T18:51:04.501428Z","caller":"traceutil/trace.go:171","msg":"trace[1772459121] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1086; }","duration":"282.065449ms","start":"2026-02-10T18:51:04.219355Z","end":"2026-02-10T18:51:04.501421Z","steps":["trace[1772459121] 'agreement among raft nodes before linearized reading' (duration: 281.998778ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:04.501587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.959004ms","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-10T18:51:04.501622Z","caller":"traceutil/trace.go:171","msg":"trace[1287032311] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1086; }","duration":"285.005225ms","start":"2026-02-10T18:51:04.216601Z","end":"2026-02-10T18:51:04.501606Z","steps":["trace[1287032311] 'agreement among raft nodes before linearized reading' (duration: 284.709932ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:04.683616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.486823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T18:51:04.68373Z","caller":"traceutil/trace.go:171","msg":"trace[892891906] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1089; }","duration":"100.659635ms","start":"2026-02-10T18:51:04.583048Z","end":"2026-02-10T18:51:04.683707Z","steps":["trace[892891906] 'count revisions from in-memory index tree' (duration: 100.375112ms)"],"step_count":1}