{"level":"warn","ts":"2026-02-10T18:48:59.855677Z","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:59.855841Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.169:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.169:2380","--initial-cluster=instance=https://199.204.45.169:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.169:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.169: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:59.855996Z","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:59.858232Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-02-10T18:48:59.858264Z","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:59.859111Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"]} {"level":"info","ts":"2026-02-10T18:48:59.864295Z","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.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.169: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:59.868669Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.086362ms"} {"level":"info","ts":"2026-02-10T18:48:59.881649Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1c917701ce165bac","cluster-id":"bfb5247022b87cb1"} {"level":"info","ts":"2026-02-10T18:48:59.881814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=()"} {"level":"info","ts":"2026-02-10T18:48:59.881837Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 0"} {"level":"info","ts":"2026-02-10T18:48:59.881885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1c917701ce165bac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-10T18:48:59.881897Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 1"} {"level":"info","ts":"2026-02-10T18:48:59.881962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"warn","ts":"2026-02-10T18:48:59.902145Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-10T18:48:59.909533Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-10T18:48:59.910937Z","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:59.913005Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1c917701ce165bac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-10T18:48:59.913733Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1c917701ce165bac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-10T18:48:59.913907Z","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:59.913931Z","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:59.913938Z","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:59.914604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"info","ts":"2026-02-10T18:48:59.914719Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","added-peer-id":"1c917701ce165bac","added-peer-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-02-10T18:48:59.916247Z","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:59.916445Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-02-10T18:48:59.916457Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-02-10T18:48:59.917436Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1c917701ce165bac","initial-advertise-peer-urls":["https://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-10T18:48:59.917459Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-10T18:49:00.496251Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac is starting a new election at term 1"} {"level":"info","ts":"2026-02-10T18:49:00.496292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became pre-candidate at term 1"} {"level":"info","ts":"2026-02-10T18:49:00.496311Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgPreVoteResp from 1c917701ce165bac at term 1"} {"level":"info","ts":"2026-02-10T18:49:00.496323Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became candidate at term 2"} {"level":"info","ts":"2026-02-10T18:49:00.496328Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgVoteResp from 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-02-10T18:49:00.496343Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became leader at term 2"} {"level":"info","ts":"2026-02-10T18:49:00.49636Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1c917701ce165bac elected leader 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-02-10T18:49:00.507576Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T18:49:00.516364Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1c917701ce165bac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.169:2379]}","request-path":"/0/members/1c917701ce165bac/attributes","cluster-id":"bfb5247022b87cb1","publish-timeout":"7s"} {"level":"info","ts":"2026-02-10T18:49:00.516392Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-10T18:49:00.516731Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-10T18:49:00.518073Z","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:49:00.519378Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.169:2379"} {"level":"info","ts":"2026-02-10T18:49:00.528808Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T18:49:00.528862Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T18:49:00.528878Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T18:49:00.539131Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-10T18:49:00.539168Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-10T18:49:05.571865Z","caller":"traceutil/trace.go:171","msg":"trace[589570960] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"127.168342ms","start":"2026-02-10T18:49:05.444668Z","end":"2026-02-10T18:49:05.571836Z","steps":["trace[589570960] 'process raft request' (duration: 46.643925ms)","trace[589570960] 'compare' (duration: 80.080182ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:49:06.004591Z","caller":"traceutil/trace.go:171","msg":"trace[313454188] linearizableReadLoop","detail":"{readStateIndex:299; appliedIndex:298; }","duration":"119.273351ms","start":"2026-02-10T18:49:05.885298Z","end":"2026-02-10T18:49:06.004572Z","steps":["trace[313454188] 'read index received' (duration: 118.471657ms)","trace[313454188] 'applied index is now lower than readState.Index' (duration: 800.975µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:49:06.004666Z","caller":"traceutil/trace.go:171","msg":"trace[1631657319] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"120.219951ms","start":"2026-02-10T18:49:05.88444Z","end":"2026-02-10T18:49:06.00466Z","steps":["trace[1631657319] 'process raft request' (duration: 119.258911ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:49:06.005027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.687668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:49:06.005079Z","caller":"traceutil/trace.go:171","msg":"trace[104927485] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:291; }","duration":"119.807264ms","start":"2026-02-10T18:49:05.88526Z","end":"2026-02-10T18:49:06.005068Z","steps":["trace[104927485] 'agreement among raft nodes before linearized reading' (duration: 119.675339ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:49:06.006103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.501996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/attachdetach-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:49:06.006141Z","caller":"traceutil/trace.go:171","msg":"trace[1098075741] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/attachdetach-controller; range_end:; response_count:0; response_revision:292; }","duration":"111.560813ms","start":"2026-02-10T18:49:05.89457Z","end":"2026-02-10T18:49:06.00613Z","steps":["trace[1098075741] 'agreement among raft nodes before linearized reading' (duration: 111.487976ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:49:06.006318Z","caller":"traceutil/trace.go:171","msg":"trace[1947665662] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"116.563059ms","start":"2026-02-10T18:49:05.889748Z","end":"2026-02-10T18:49:06.006311Z","steps":["trace[1947665662] 'process raft request' (duration: 116.24796ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:49:06.327403Z","caller":"traceutil/trace.go:171","msg":"trace[1716316541] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"244.991816ms","start":"2026-02-10T18:49:06.082393Z","end":"2026-02-10T18:49:06.327385Z","steps":["trace[1716316541] 'process raft request' (duration: 244.965267ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:49:06.32764Z","caller":"traceutil/trace.go:171","msg":"trace[1068624252] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"265.736801ms","start":"2026-02-10T18:49:06.061892Z","end":"2026-02-10T18:49:06.327629Z","steps":["trace[1068624252] 'process raft request' (duration: 170.014977ms)","trace[1068624252] 'compare' (duration: 94.774435ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:49:06.327715Z","caller":"traceutil/trace.go:171","msg":"trace[176244648] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"265.778429ms","start":"2026-02-10T18:49:06.061931Z","end":"2026-02-10T18:49:06.32771Z","steps":["trace[176244648] 'process raft request' (duration: 265.382882ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:49:06.327806Z","caller":"traceutil/trace.go:171","msg":"trace[1926936977] linearizableReadLoop","detail":"{readStateIndex:304; appliedIndex:302; }","duration":"250.962692ms","start":"2026-02-10T18:49:06.076836Z","end":"2026-02-10T18:49:06.327799Z","steps":["trace[1926936977] 'read index received' (duration: 155.049044ms)","trace[1926936977] 'applied index is now lower than readState.Index' (duration: 95.912968ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:49:06.327941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.944913ms","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:06.327962Z","caller":"traceutil/trace.go:171","msg":"trace[488809320] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:297; }","duration":"265.994442ms","start":"2026-02-10T18:49:06.061961Z","end":"2026-02-10T18:49:06.327956Z","steps":["trace[488809320] 'agreement among raft nodes before linearized reading' (duration: 265.938374ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:49:06.32805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.962901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:49:06.328068Z","caller":"traceutil/trace.go:171","msg":"trace[1570670124] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:0; response_revision:297; }","duration":"247.00245ms","start":"2026-02-10T18:49:06.08106Z","end":"2026-02-10T18:49:06.328063Z","steps":["trace[1570670124] 'agreement among raft nodes before linearized reading' (duration: 246.973241ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:49:06.662522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.750156ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:49:06.662608Z","caller":"traceutil/trace.go:171","msg":"trace[1804488171] linearizableReadLoop","detail":"{readStateIndex:310; appliedIndex:309; }","duration":"260.85301ms","start":"2026-02-10T18:49:06.401741Z","end":"2026-02-10T18:49:06.662594Z","steps":["trace[1804488171] 'read index received' (duration: 91.473173ms)","trace[1804488171] 'applied index is now lower than readState.Index' (duration: 169.378267ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:49:06.662679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.931817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replication-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:49:06.662703Z","caller":"traceutil/trace.go:171","msg":"trace[599077882] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replication-controller; range_end:; response_count:0; response_revision:302; }","duration":"261.003125ms","start":"2026-02-10T18:49:06.401692Z","end":"2026-02-10T18:49:06.662695Z","steps":["trace[599077882] 'agreement among raft nodes before linearized reading' (duration: 260.942307ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:49:06.662936Z","caller":"traceutil/trace.go:171","msg":"trace[720557377] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"273.650703ms","start":"2026-02-10T18:49:06.389274Z","end":"2026-02-10T18:49:06.662924Z","steps":["trace[720557377] 'process raft request' (duration: 104.018494ms)","trace[720557377] 'compare' (duration: 168.443697ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:49:08.449932Z","caller":"traceutil/trace.go:171","msg":"trace[452149309] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"113.030201ms","start":"2026-02-10T18:49:08.336878Z","end":"2026-02-10T18:49:08.449908Z","steps":["trace[452149309] 'process raft request' (duration: 109.382448ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:49:08.450563Z","caller":"traceutil/trace.go:171","msg":"trace[735360773] linearizableReadLoop","detail":"{readStateIndex:337; appliedIndex:335; }","duration":"108.74312ms","start":"2026-02-10T18:49:08.341798Z","end":"2026-02-10T18:49:08.450541Z","steps":["trace[735360773] 'read index received' (duration: 104.435708ms)","trace[735360773] 'applied index is now lower than readState.Index' (duration: 4.306502ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:49:08.450805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.931784ms","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-10T18:49:08.451203Z","caller":"traceutil/trace.go:171","msg":"trace[832979912] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:330; }","duration":"109.418298ms","start":"2026-02-10T18:49:08.341768Z","end":"2026-02-10T18:49:08.451186Z","steps":["trace[832979912] 'agreement among raft nodes before linearized reading' (duration: 108.908124ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:49:08.450607Z","caller":"traceutil/trace.go:171","msg":"trace[1450575561] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"112.835117ms","start":"2026-02-10T18:49:08.337757Z","end":"2026-02-10T18:49:08.450592Z","steps":["trace[1450575561] 'process raft request' (duration: 112.655663ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:49:08.597123Z","caller":"traceutil/trace.go:171","msg":"trace[739039524] linearizableReadLoop","detail":"{readStateIndex:339; appliedIndex:338; }","duration":"127.419946ms","start":"2026-02-10T18:49:08.469677Z","end":"2026-02-10T18:49:08.597097Z","steps":["trace[739039524] 'read index received' (duration: 115.870189ms)","trace[739039524] 'applied index is now lower than readState.Index' (duration: 11.548837ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:49:08.597427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.700887ms","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-10T18:49:08.597476Z","caller":"traceutil/trace.go:171","msg":"trace[2125458860] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:331; }","duration":"127.826753ms","start":"2026-02-10T18:49:08.469634Z","end":"2026-02-10T18:49:08.597461Z","steps":["trace[2125458860] 'agreement among raft nodes before linearized reading' (duration: 127.554212ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:49:08.59776Z","caller":"traceutil/trace.go:171","msg":"trace[51957779] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"138.491989ms","start":"2026-02-10T18:49:08.459245Z","end":"2026-02-10T18:49:08.597737Z","steps":["trace[51957779] 'process raft request' (duration: 126.374481ms)","trace[51957779] 'compare' (duration: 11.376992ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:49:09.351599Z","caller":"traceutil/trace.go:171","msg":"trace[2129076837] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"178.229783ms","start":"2026-02-10T18:49:09.173167Z","end":"2026-02-10T18:49:09.351397Z","steps":["trace[2129076837] 'process raft request' (duration: 130.145453ms)","trace[2129076837] 'compare' (duration: 47.942747ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:49:11.502585Z","caller":"traceutil/trace.go:171","msg":"trace[1030985550] linearizableReadLoop","detail":"{readStateIndex:375; appliedIndex:374; }","duration":"106.09989ms","start":"2026-02-10T18:49:11.396464Z","end":"2026-02-10T18:49:11.502564Z","steps":["trace[1030985550] 'read index received' (duration: 82.316019ms)","trace[1030985550] 'applied index is now lower than readState.Index' (duration: 23.782961ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:49:11.502734Z","caller":"traceutil/trace.go:171","msg":"trace[556756214] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"135.221741ms","start":"2026-02-10T18:49:11.367489Z","end":"2026-02-10T18:49:11.50271Z","steps":["trace[556756214] 'process raft request' (duration: 111.285237ms)","trace[556756214] 'compare' (duration: 23.542714ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:49:11.502841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.541093ms","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-10T18:49:11.504252Z","caller":"traceutil/trace.go:171","msg":"trace[682302686] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:367; }","duration":"134.969893ms","start":"2026-02-10T18:49:11.369274Z","end":"2026-02-10T18:49:11.504244Z","steps":["trace[682302686] 'agreement among raft nodes before linearized reading' (duration: 133.498707ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:49:11.504035Z","caller":"traceutil/trace.go:171","msg":"trace[1254597018] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"101.321996ms","start":"2026-02-10T18:49:11.402706Z","end":"2026-02-10T18:49:11.504028Z","steps":["trace[1254597018] 'process raft request' (duration: 101.112824ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:49:11.862305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.688107ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:49:11.862411Z","caller":"traceutil/trace.go:171","msg":"trace[900604514] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"347.219172ms","start":"2026-02-10T18:49:11.515165Z","end":"2026-02-10T18:49:11.862384Z","steps":["trace[900604514] 'process raft request' (duration: 213.391734ms)","trace[900604514] 'compare' (duration: 133.434407ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:49:11.862469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:49:11.515152Z","time spent":"347.295553ms","remote":"127.0.0.1:47480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1739,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T18:49:11.910024Z","caller":"traceutil/trace.go:171","msg":"trace[557639942] linearizableReadLoop","detail":"{readStateIndex:380; appliedIndex:377; }","duration":"386.731629ms","start":"2026-02-10T18:49:11.523281Z","end":"2026-02-10T18:49:11.910013Z","steps":["trace[557639942] 'read index received' (duration: 205.367802ms)","trace[557639942] 'applied index is now lower than readState.Index' (duration: 181.363247ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:49:11.910236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.914406ms","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-10T18:49:11.91028Z","caller":"traceutil/trace.go:171","msg":"trace[904663777] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:374; }","duration":"387.013292ms","start":"2026-02-10T18:49:11.523261Z","end":"2026-02-10T18:49:11.910274Z","steps":["trace[904663777] 'agreement among raft nodes before linearized reading' (duration: 386.842446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:49:11.910327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:49:11.523254Z","time spent":"387.06611ms","remote":"127.0.0.1:47560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"info","ts":"2026-02-10T18:49:11.910477Z","caller":"traceutil/trace.go:171","msg":"trace[1132370260] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"391.242211ms","start":"2026-02-10T18:49:11.519228Z","end":"2026-02-10T18:49:11.91047Z","steps":["trace[1132370260] 'process raft request' (duration: 390.645689ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:49:11.910579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:49:11.519221Z","time spent":"391.331645ms","remote":"127.0.0.1:47450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":669,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T18:49:11.910765Z","caller":"traceutil/trace.go:171","msg":"trace[541760590] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"389.470039ms","start":"2026-02-10T18:49:11.52129Z","end":"2026-02-10T18:49:11.91076Z","steps":["trace[541760590] 'process raft request' (duration: 388.645623ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:49:11.911174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:49:11.521266Z","time spent":"389.888213ms","remote":"127.0.0.1:47538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3401,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:49:11.910865Z","caller":"traceutil/trace.go:171","msg":"trace[1591188343] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"386.386705ms","start":"2026-02-10T18:49:11.524472Z","end":"2026-02-10T18:49:11.910858Z","steps":["trace[1591188343] 'process raft request' (duration: 385.512971ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:49:11.911415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:49:11.524459Z","time spent":"386.93648ms","remote":"127.0.0.1:47808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2864,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:49:11.910971Z","caller":"traceutil/trace.go:171","msg":"trace[1895080392] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"387.644068ms","start":"2026-02-10T18:49:11.523322Z","end":"2026-02-10T18:49:11.910966Z","steps":["trace[1895080392] 'process raft request' (duration: 386.639544ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:49:11.911622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:49:11.523318Z","time spent":"388.289847ms","remote":"127.0.0.1:47560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":156,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T18:49:13.773416Z","caller":"traceutil/trace.go:171","msg":"trace[1063453827] linearizableReadLoop","detail":"{readStateIndex:415; appliedIndex:414; }","duration":"183.478996ms","start":"2026-02-10T18:49:13.58992Z","end":"2026-02-10T18:49:13.773399Z","steps":["trace[1063453827] 'read index received' (duration: 183.36944ms)","trace[1063453827] 'applied index is now lower than readState.Index' (duration: 108.776µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:49:13.773525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.592683ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:49:13.773545Z","caller":"traceutil/trace.go:171","msg":"trace[986098832] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:405; }","duration":"183.626118ms","start":"2026-02-10T18:49:13.589912Z","end":"2026-02-10T18:49:13.773539Z","steps":["trace[986098832] 'agreement among raft nodes before linearized reading' (duration: 183.549067ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:49:18.316134Z","caller":"traceutil/trace.go:171","msg":"trace[1291710096] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"182.087729ms","start":"2026-02-10T18:49:18.134026Z","end":"2026-02-10T18:49:18.316114Z","steps":["trace[1291710096] 'process raft request' (duration: 159.250107ms)","trace[1291710096] 'compare' (duration: 22.744769ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:49:18.317521Z","caller":"traceutil/trace.go:171","msg":"trace[150300736] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"175.239613ms","start":"2026-02-10T18:49:18.142273Z","end":"2026-02-10T18:49:18.317512Z","steps":["trace[150300736] 'process raft request' (duration: 175.209439ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:49:18.317793Z","caller":"traceutil/trace.go:171","msg":"trace[1997620594] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"177.581593ms","start":"2026-02-10T18:49:18.14011Z","end":"2026-02-10T18:49:18.317691Z","steps":["trace[1997620594] 'process raft request' (duration: 177.306085ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:49:18.31808Z","caller":"traceutil/trace.go:171","msg":"trace[758354580] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"183.964356ms","start":"2026-02-10T18:49:18.134107Z","end":"2026-02-10T18:49:18.318071Z","steps":["trace[758354580] 'process raft request' (duration: 183.240597ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:49:18.318557Z","caller":"traceutil/trace.go:171","msg":"trace[224854825] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"176.037602ms","start":"2026-02-10T18:49:18.14214Z","end":"2026-02-10T18:49:18.318178Z","steps":["trace[224854825] 'process raft request' (duration: 175.304312ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:49:18.521734Z","caller":"traceutil/trace.go:171","msg":"trace[1353701361] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"155.262819ms","start":"2026-02-10T18:49:18.366455Z","end":"2026-02-10T18:49:18.521718Z","steps":["trace[1353701361] 'process raft request' (duration: 155.221973ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:49:18.521965Z","caller":"traceutil/trace.go:171","msg":"trace[40521427] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"160.247803ms","start":"2026-02-10T18:49:18.36171Z","end":"2026-02-10T18:49:18.521958Z","steps":["trace[40521427] 'process raft request' (duration: 94.225564ms)","trace[40521427] 'compare' (duration: 65.634115ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:49:18.522009Z","caller":"traceutil/trace.go:171","msg":"trace[271094792] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"160.248192ms","start":"2026-02-10T18:49:18.361757Z","end":"2026-02-10T18:49:18.522005Z","steps":["trace[271094792] 'process raft request' (duration: 159.892682ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:49:18.522146Z","caller":"traceutil/trace.go:171","msg":"trace[361451908] linearizableReadLoop","detail":"{readStateIndex:457; appliedIndex:453; }","duration":"127.764751ms","start":"2026-02-10T18:49:18.394355Z","end":"2026-02-10T18:49:18.52212Z","steps":["trace[361451908] 'read index received' (duration: 61.520192ms)","trace[361451908] 'applied index is now lower than readState.Index' (duration: 66.243178ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:49:18.522338Z","caller":"traceutil/trace.go:171","msg":"trace[377036175] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"155.752067ms","start":"2026-02-10T18:49:18.366571Z","end":"2026-02-10T18:49:18.522323Z","steps":["trace[377036175] 'process raft request' (duration: 155.129922ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:49:18.522566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.169914ms","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:18.522599Z","caller":"traceutil/trace.go:171","msg":"trace[1497562025] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:447; }","duration":"156.220161ms","start":"2026-02-10T18:49:18.366362Z","end":"2026-02-10T18:49:18.522582Z","steps":["trace[1497562025] 'agreement among raft nodes before linearized reading' (duration: 156.167133ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:49:18.522927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.142544ms","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:49:18.522954Z","caller":"traceutil/trace.go:171","msg":"trace[1876809164] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-5dd5756b68; range_end:; response_count:1; response_revision:447; }","duration":"121.196271ms","start":"2026-02-10T18:49:18.401753Z","end":"2026-02-10T18:49:18.522949Z","steps":["trace[1876809164] 'agreement among raft nodes before linearized reading' (duration: 121.153195ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:49:18.523045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.082463ms","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:49:18.523065Z","caller":"traceutil/trace.go:171","msg":"trace[449500182] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:447; }","duration":"119.115568ms","start":"2026-02-10T18:49:18.403944Z","end":"2026-02-10T18:49:18.52306Z","steps":["trace[449500182] 'agreement among raft nodes before linearized reading' (duration: 119.073022ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:49:19.944874Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.012559975s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-10T18:49:33.712999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.66199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:49:33.713055Z","caller":"traceutil/trace.go:171","msg":"trace[13608249] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:550; }","duration":"103.829118ms","start":"2026-02-10T18:49:33.609211Z","end":"2026-02-10T18:49:33.71304Z","steps":["trace[13608249] 'agreement among raft nodes before linearized reading' (duration: 103.701984ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:49:33.712796Z","caller":"traceutil/trace.go:171","msg":"trace[957517444] linearizableReadLoop","detail":"{readStateIndex:566; appliedIndex:566; }","duration":"103.47884ms","start":"2026-02-10T18:49:33.609294Z","end":"2026-02-10T18:49:33.712773Z","steps":["trace[957517444] 'read index received' (duration: 103.461919ms)","trace[957517444] 'applied index is now lower than readState.Index' (duration: 12.151µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:49:37.07309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.106377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-02-10T18:49:37.073175Z","caller":"traceutil/trace.go:171","msg":"trace[1074718261] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:599; }","duration":"264.242261ms","start":"2026-02-10T18:49:36.808913Z","end":"2026-02-10T18:49:37.073155Z","steps":["trace[1074718261] 'range keys from in-memory index tree' (duration: 263.913948ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:49:37.073415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.246849ms","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:37.073506Z","caller":"traceutil/trace.go:171","msg":"trace[1348807539] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:599; }","duration":"271.392004ms","start":"2026-02-10T18:49:36.802081Z","end":"2026-02-10T18:49:37.073473Z","steps":["trace[1348807539] 'range keys from in-memory index tree' (duration: 271.018627ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:49:40.824969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.219278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:49:40.825048Z","caller":"traceutil/trace.go:171","msg":"trace[2031963230] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:607; }","duration":"296.35819ms","start":"2026-02-10T18:49:40.528671Z","end":"2026-02-10T18:49:40.82503Z","steps":["trace[2031963230] 'range keys from in-memory index tree' (duration: 296.14146ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:49:40.825156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.318907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:49:40.825169Z","caller":"traceutil/trace.go:171","msg":"trace[1349030737] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:607; }","duration":"296.342139ms","start":"2026-02-10T18:49:40.528823Z","end":"2026-02-10T18:49:40.825165Z","steps":["trace[1349030737] 'count revisions from in-memory index tree' (duration: 296.228658ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:49:40.825246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.419856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:49:40.825258Z","caller":"traceutil/trace.go:171","msg":"trace[1305341234] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:607; }","duration":"296.472852ms","start":"2026-02-10T18:49:40.528782Z","end":"2026-02-10T18:49:40.825255Z","steps":["trace[1305341234] 'range keys from in-memory index tree' (duration: 296.301965ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:49:40.825348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.733019ms","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:40.825366Z","caller":"traceutil/trace.go:171","msg":"trace[731202526] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:607; }","duration":"217.782384ms","start":"2026-02-10T18:49:40.607578Z","end":"2026-02-10T18:49:40.825361Z","steps":["trace[731202526] 'range keys from in-memory index tree' (duration: 217.643001ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:49:41.647779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.829106ms","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:41.647943Z","caller":"traceutil/trace.go:171","msg":"trace[2092951007] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:614; }","duration":"229.000222ms","start":"2026-02-10T18:49:41.41893Z","end":"2026-02-10T18:49:41.647931Z","steps":["trace[2092951007] 'range keys from in-memory index tree' (duration: 228.753539ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:49:41.649059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.574451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rtkqc\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-10T18:49:41.649147Z","caller":"traceutil/trace.go:171","msg":"trace[866256715] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rtkqc; range_end:; response_count:1; response_revision:614; }","duration":"221.686011ms","start":"2026-02-10T18:49:41.427455Z","end":"2026-02-10T18:49:41.649141Z","steps":["trace[866256715] 'range keys from in-memory index tree' (duration: 221.379383ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:49:43.347223Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605826590299975490,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T18:49:43.847834Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605826590299975490,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T18:49:44.29948Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.596836424s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-10T18:49:44.499895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.995574277s","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:49:44.499949Z","caller":"traceutil/trace.go:171","msg":"trace[217478589] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:615; }","duration":"1.995660755s","start":"2026-02-10T18:49:42.504276Z","end":"2026-02-10T18:49:44.499937Z","steps":["trace[217478589] 'range keys from in-memory index tree' (duration: 1.995457616s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:49:44.499984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:49:42.504264Z","time spent":"1.995714749s","remote":"127.0.0.1:47608","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:49:44.501214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.538004ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:49:44.501258Z","caller":"traceutil/trace.go:171","msg":"trace[1746874453] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:632; }","duration":"1.65477709s","start":"2026-02-10T18:49:42.846474Z","end":"2026-02-10T18:49:44.501251Z","steps":["trace[1746874453] 'read index received' (duration: 1.453119495s)","trace[1746874453] 'applied index is now lower than readState.Index' (duration: 201.656805ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:49:44.50139Z","caller":"traceutil/trace.go:171","msg":"trace[495395645] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"1.79881259s","start":"2026-02-10T18:49:42.70257Z","end":"2026-02-10T18:49:44.501382Z","steps":["trace[495395645] 'process raft request' (duration: 1.597065796s)","trace[495395645] 'compare' (duration: 201.440176ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:49:44.501432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:49:42.702553Z","time spent":"1.798859593s","remote":"127.0.0.1:47608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:49:44.501552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.655075907s","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-10T18:49:44.501576Z","caller":"traceutil/trace.go:171","msg":"trace[1073391535] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:616; }","duration":"1.65511615s","start":"2026-02-10T18:49:42.846454Z","end":"2026-02-10T18:49:44.50157Z","steps":["trace[1073391535] 'agreement among raft nodes before linearized reading' (duration: 1.655054315s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:49:44.501596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:49:42.846441Z","time spent":"1.655152153s","remote":"127.0.0.1:47608","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:44.50175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.643381267s","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:44.501774Z","caller":"traceutil/trace.go:171","msg":"trace[2137580639] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:616; }","duration":"1.643432933s","start":"2026-02-10T18:49:42.858336Z","end":"2026-02-10T18:49:44.501769Z","steps":["trace[2137580639] 'agreement among raft nodes before linearized reading' (duration: 1.643367507s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:49:44.501795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:49:42.858326Z","time spent":"1.643465996s","remote":"127.0.0.1:47608","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:44.501939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"949.93316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.169\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-10T18:49:44.501962Z","caller":"traceutil/trace.go:171","msg":"trace[406252167] range","detail":"{range_begin:/registry/masterleases/199.204.45.169; range_end:; response_count:1; response_revision:616; }","duration":"949.977714ms","start":"2026-02-10T18:49:43.551979Z","end":"2026-02-10T18:49:44.501957Z","steps":["trace[406252167] 'agreement among raft nodes before linearized reading' (duration: 949.923209ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:49:44.501983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:49:43.551965Z","time spent":"950.012928ms","remote":"127.0.0.1:47404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.169\" "} {"level":"warn","ts":"2026-02-10T18:49:44.502068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.129162977s","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:44.502092Z","caller":"traceutil/trace.go:171","msg":"trace[551600718] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:616; }","duration":"1.129184308s","start":"2026-02-10T18:49:43.372901Z","end":"2026-02-10T18:49:44.502086Z","steps":["trace[551600718] 'agreement among raft nodes before linearized reading' (duration: 1.129154416s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:49:44.502118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:49:43.372864Z","time spent":"1.129248405s","remote":"127.0.0.1:47364","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:44.502226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.353235802s","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-10T18:49:44.502248Z","caller":"traceutil/trace.go:171","msg":"trace[1473064752] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:616; }","duration":"1.353290717s","start":"2026-02-10T18:49:43.148951Z","end":"2026-02-10T18:49:44.502242Z","steps":["trace[1473064752] 'agreement among raft nodes before linearized reading' (duration: 1.353239203s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:49:44.502269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:49:43.148934Z","time spent":"1.353330761s","remote":"127.0.0.1:47608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-10T18:49:44.514242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.654180395s","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:44.514284Z","caller":"traceutil/trace.go:171","msg":"trace[1522185942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:616; }","duration":"1.65424116s","start":"2026-02-10T18:49:42.860033Z","end":"2026-02-10T18:49:44.514274Z","steps":["trace[1522185942] 'agreement among raft nodes before linearized reading' (duration: 1.654097307s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:49:44.514329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:49:42.860028Z","time spent":"1.654295045s","remote":"127.0.0.1:47608","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:44.984604Z","caller":"traceutil/trace.go:171","msg":"trace[768700780] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:633; }","duration":"459.917248ms","start":"2026-02-10T18:49:44.524658Z","end":"2026-02-10T18:49:44.984576Z","steps":["trace[768700780] 'read index received' (duration: 407.61711ms)","trace[768700780] 'applied index is now lower than readState.Index' (duration: 52.298788ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:49:44.984803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.124596ms","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:44.984837Z","caller":"traceutil/trace.go:171","msg":"trace[960814923] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:617; }","duration":"460.191302ms","start":"2026-02-10T18:49:44.524633Z","end":"2026-02-10T18:49:44.984825Z","steps":["trace[960814923] 'agreement among raft nodes before linearized reading' (duration: 460.032908ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:49:44.984868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:49:44.524617Z","time spent":"460.243496ms","remote":"127.0.0.1:47366","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:49:44.984945Z","caller":"traceutil/trace.go:171","msg":"trace[1958287536] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"460.360816ms","start":"2026-02-10T18:49:44.524562Z","end":"2026-02-10T18:49:44.984923Z","steps":["trace[1958287536] 'process raft request' (duration: 407.702437ms)","trace[1958287536] 'compare' (duration: 51.992501ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:49:44.985046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:49:44.524546Z","time spent":"460.447015ms","remote":"127.0.0.1:47608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:49:44.988347Z","caller":"traceutil/trace.go:171","msg":"trace[221015317] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"459.909356ms","start":"2026-02-10T18:49:44.528424Z","end":"2026-02-10T18:49:44.988333Z","steps":["trace[221015317] 'process raft request' (duration: 459.887094ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:49:44.990943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:49:44.528419Z","time spent":"462.460012ms","remote":"127.0.0.1:47608","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:49:44.988597Z","caller":"traceutil/trace.go:171","msg":"trace[606389493] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"463.80024ms","start":"2026-02-10T18:49:44.524778Z","end":"2026-02-10T18:49:44.988578Z","steps":["trace[606389493] 'process raft request' (duration: 462.44045ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:49:44.991204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:49:44.524772Z","time spent":"466.414891ms","remote":"127.0.0.1:47608","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:49:44.988641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:49:44.524705Z","time spent":"463.934962ms","remote":"127.0.0.1:47404","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-10T18:49:44.988957Z","caller":"traceutil/trace.go:171","msg":"trace[532428558] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"464.164533ms","start":"2026-02-10T18:49:44.524779Z","end":"2026-02-10T18:49:44.988943Z","steps":["trace[532428558] 'process raft request' (duration: 463.478032ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:49:44.992028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:49:44.524772Z","time spent":"466.770674ms","remote":"127.0.0.1:47608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:49:44.989008Z","caller":"traceutil/trace.go:171","msg":"trace[407266974] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"460.636612ms","start":"2026-02-10T18:49:44.528362Z","end":"2026-02-10T18:49:44.988998Z","steps":["trace[407266974] 'process raft request' (duration: 459.929979ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:49:44.992593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:49:44.528355Z","time spent":"464.218068ms","remote":"127.0.0.1:47608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:50:09.383545Z","caller":"traceutil/trace.go:171","msg":"trace[1429071893] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"142.29878ms","start":"2026-02-10T18:50:09.241231Z","end":"2026-02-10T18:50:09.383529Z","steps":["trace[1429071893] 'process raft request' (duration: 124.241909ms)","trace[1429071893] 'compare' (duration: 17.980427ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:50:39.650345Z","caller":"traceutil/trace.go:171","msg":"trace[419364810] linearizableReadLoop","detail":"{readStateIndex:915; appliedIndex:914; }","duration":"145.022488ms","start":"2026-02-10T18:50:39.505308Z","end":"2026-02-10T18:50:39.650331Z","steps":["trace[419364810] 'read index received' (duration: 144.858613ms)","trace[419364810] 'applied index is now lower than readState.Index' (duration: 163.275µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:50:39.650491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.173334ms","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-10T18:50:39.650517Z","caller":"traceutil/trace.go:171","msg":"trace[1957484207] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:884; }","duration":"145.248927ms","start":"2026-02-10T18:50:39.50526Z","end":"2026-02-10T18:50:39.650509Z","steps":["trace[1957484207] 'agreement among raft nodes before linearized reading' (duration: 145.130573ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:50:39.650699Z","caller":"traceutil/trace.go:171","msg":"trace[743662300] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"191.743292ms","start":"2026-02-10T18:50:39.458949Z","end":"2026-02-10T18:50:39.650692Z","steps":["trace[743662300] 'process raft request' (duration: 191.256796ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:51:06.600549Z","caller":"traceutil/trace.go:171","msg":"trace[357587997] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"212.391937ms","start":"2026-02-10T18:51:06.388139Z","end":"2026-02-10T18:51:06.600531Z","steps":["trace[357587997] 'process raft request' (duration: 212.256495ms)"],"step_count":1}