{"level":"warn","ts":"2026-02-14T04:53:14.690371Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-14T04:53:14.690511Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.141: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.141:2380","--initial-cluster=instance=https://199.204.45.141:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.141:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.141:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-14T04:53:14.690582Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-14T04:53:14.69059Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.141:2380"]} {"level":"info","ts":"2026-02-14T04:53:14.690609Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-14T04:53:14.691263Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.141:2379"]} {"level":"info","ts":"2026-02-14T04:53:14.691334Z","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.141:2380"],"listen-peer-urls":["https://199.204.45.141:2380"],"advertise-client-urls":["https://199.204.45.141:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.141:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.141:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-14T04:53:14.76857Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"76.979887ms"} {"level":"info","ts":"2026-02-14T04:53:14.946734Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9ef3b4f68930dc2","cluster-id":"b19a5957e6e65747"} {"level":"info","ts":"2026-02-14T04:53:14.946807Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 switched to configuration voters=()"} {"level":"info","ts":"2026-02-14T04:53:14.946827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became follower at term 0"} {"level":"info","ts":"2026-02-14T04:53:14.946838Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9ef3b4f68930dc2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-14T04:53:14.946851Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became follower at term 1"} {"level":"info","ts":"2026-02-14T04:53:14.946898Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 switched to configuration voters=(715856078018121154)"} {"level":"warn","ts":"2026-02-14T04:53:14.994591Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-14T04:53:15.022554Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-14T04:53:15.060993Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-14T04:53:15.119665Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9ef3b4f68930dc2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-14T04:53:15.12659Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-14T04:53:15.126645Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-14T04:53:15.126657Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-14T04:53:15.126741Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9ef3b4f68930dc2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-14T04:53:15.128874Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-14T04:53:15.129055Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9ef3b4f68930dc2","initial-advertise-peer-urls":["https://199.204.45.141:2380"],"listen-peer-urls":["https://199.204.45.141:2380"],"advertise-client-urls":["https://199.204.45.141:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.141:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-14T04:53:15.129082Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-14T04:53:15.129156Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.141:2380"} {"level":"info","ts":"2026-02-14T04:53:15.129172Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.141:2380"} {"level":"info","ts":"2026-02-14T04:53:15.183804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 switched to configuration voters=(715856078018121154)"} {"level":"info","ts":"2026-02-14T04:53:15.1839Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b19a5957e6e65747","local-member-id":"9ef3b4f68930dc2","added-peer-id":"9ef3b4f68930dc2","added-peer-peer-urls":["https://199.204.45.141:2380"]} {"level":"info","ts":"2026-02-14T04:53:15.183932Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 is starting a new election at term 1"} {"level":"info","ts":"2026-02-14T04:53:15.183939Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-14T04:53:15.18395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 received MsgPreVoteResp from 9ef3b4f68930dc2 at term 1"} {"level":"info","ts":"2026-02-14T04:53:15.183959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became candidate at term 2"} {"level":"info","ts":"2026-02-14T04:53:15.183964Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 received MsgVoteResp from 9ef3b4f68930dc2 at term 2"} {"level":"info","ts":"2026-02-14T04:53:15.183971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became leader at term 2"} {"level":"info","ts":"2026-02-14T04:53:15.183982Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9ef3b4f68930dc2 elected leader 9ef3b4f68930dc2 at term 2"} {"level":"info","ts":"2026-02-14T04:53:15.185187Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-14T04:53:15.186044Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9ef3b4f68930dc2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.141:2379]}","request-path":"/0/members/9ef3b4f68930dc2/attributes","cluster-id":"b19a5957e6e65747","publish-timeout":"7s"} {"level":"info","ts":"2026-02-14T04:53:15.186163Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-14T04:53:15.187009Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-14T04:53:15.189715Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-14T04:53:15.193321Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.141:2379"} {"level":"info","ts":"2026-02-14T04:53:15.197501Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-14T04:53:15.197519Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-14T04:53:15.220901Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b19a5957e6e65747","local-member-id":"9ef3b4f68930dc2","cluster-version":"3.5"} {"level":"info","ts":"2026-02-14T04:53:15.225626Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-14T04:53:15.235424Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-14T04:53:17.388999Z","caller":"traceutil/trace.go:171","msg":"trace[1942591013] transaction","detail":"{read_only:false; response_revision:45; number_of_response:1; }","duration":"117.099108ms","start":"2026-02-14T04:53:17.271871Z","end":"2026-02-14T04:53:17.38897Z","steps":["trace[1942591013] 'process raft request' (duration: 86.085125ms)","trace[1942591013] 'compare' (duration: 29.807833ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:53:17.390648Z","caller":"traceutil/trace.go:171","msg":"trace[2078367538] transaction","detail":"{read_only:false; response_revision:46; number_of_response:1; }","duration":"118.182636ms","start":"2026-02-14T04:53:17.272449Z","end":"2026-02-14T04:53:17.390631Z","steps":["trace[2078367538] 'process raft request' (duration: 117.391673ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:53:17.722624Z","caller":"traceutil/trace.go:171","msg":"trace[1423971813] transaction","detail":"{read_only:false; response_revision:70; number_of_response:1; }","duration":"105.919497ms","start":"2026-02-14T04:53:17.616671Z","end":"2026-02-14T04:53:17.722591Z","steps":["trace[1423971813] 'process raft request' (duration: 53.565048ms)","trace[1423971813] 'compare' (duration: 48.991454ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:53:17.723296Z","caller":"traceutil/trace.go:171","msg":"trace[1466115560] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"106.431585ms","start":"2026-02-14T04:53:17.616852Z","end":"2026-02-14T04:53:17.723284Z","steps":["trace[1466115560] 'process raft request' (duration: 102.495881ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:53:17.872081Z","caller":"traceutil/trace.go:171","msg":"trace[1335712070] linearizableReadLoop","detail":"{readStateIndex:86; appliedIndex:85; }","duration":"111.094601ms","start":"2026-02-14T04:53:17.760962Z","end":"2026-02-14T04:53:17.872057Z","steps":["trace[1335712070] 'read index received' (duration: 6.915742ms)","trace[1335712070] 'applied index is now lower than readState.Index' (duration: 104.177889ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:53:17.872371Z","caller":"traceutil/trace.go:171","msg":"trace[1108274527] transaction","detail":"{read_only:false; response_revision:82; number_of_response:1; }","duration":"111.326525ms","start":"2026-02-14T04:53:17.761032Z","end":"2026-02-14T04:53:17.872358Z","steps":["trace[1108274527] 'process raft request' (duration: 110.959369ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:53:17.872647Z","caller":"traceutil/trace.go:171","msg":"trace[470248898] transaction","detail":"{read_only:false; response_revision:81; number_of_response:1; }","duration":"111.753702ms","start":"2026-02-14T04:53:17.76085Z","end":"2026-02-14T04:53:17.872604Z","steps":["trace[470248898] 'process raft request' (duration: 88.938742ms)","trace[470248898] 'compare' (duration: 22.107228ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:53:17.872974Z","caller":"traceutil/trace.go:171","msg":"trace[1270831475] transaction","detail":"{read_only:false; number_of_response:0; response_revision:82; }","duration":"111.115072ms","start":"2026-02-14T04:53:17.761801Z","end":"2026-02-14T04:53:17.872916Z","steps":["trace[1270831475] 'process raft request' (duration: 110.232508ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:53:17.873338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.355951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-14T04:53:17.873559Z","caller":"traceutil/trace.go:171","msg":"trace[1838695115] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:0; response_revision:82; }","duration":"112.595876ms","start":"2026-02-14T04:53:17.76094Z","end":"2026-02-14T04:53:17.873536Z","steps":["trace[1838695115] 'agreement among raft nodes before linearized reading' (duration: 112.348132ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:53:21.894187Z","caller":"traceutil/trace.go:171","msg":"trace[48117349] transaction","detail":"{read_only:false; number_of_response:0; response_revision:316; }","duration":"113.957991ms","start":"2026-02-14T04:53:21.780211Z","end":"2026-02-14T04:53:21.894169Z","steps":["trace[48117349] 'process raft request' (duration: 113.825889ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:53:25.932062Z","caller":"traceutil/trace.go:171","msg":"trace[1160522043] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"218.990842ms","start":"2026-02-14T04:53:25.713027Z","end":"2026-02-14T04:53:25.932018Z","steps":["trace[1160522043] 'process raft request' (duration: 177.140815ms)","trace[1160522043] 'compare' (duration: 39.891675ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:53:25.936134Z","caller":"traceutil/trace.go:171","msg":"trace[704293998] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"220.344763ms","start":"2026-02-14T04:53:25.715781Z","end":"2026-02-14T04:53:25.936126Z","steps":["trace[704293998] 'process raft request' (duration: 219.398598ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:53:25.935235Z","caller":"traceutil/trace.go:171","msg":"trace[276121319] linearizableReadLoop","detail":"{readStateIndex:399; appliedIndex:397; }","duration":"153.884794ms","start":"2026-02-14T04:53:25.78134Z","end":"2026-02-14T04:53:25.935225Z","steps":["trace[276121319] 'read index received' (duration: 108.925767ms)","trace[276121319] 'applied index is now lower than readState.Index' (duration: 44.957867ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:53:25.937019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.662062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4013"} {"level":"info","ts":"2026-02-14T04:53:25.937046Z","caller":"traceutil/trace.go:171","msg":"trace[141035847] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:389; }","duration":"155.727013ms","start":"2026-02-14T04:53:25.78131Z","end":"2026-02-14T04:53:25.937037Z","steps":["trace[141035847] 'agreement among raft nodes before linearized reading' (duration: 155.647532ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:53:26.127616Z","caller":"traceutil/trace.go:171","msg":"trace[1213189062] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"128.824851ms","start":"2026-02-14T04:53:25.998769Z","end":"2026-02-14T04:53:26.127594Z","steps":["trace[1213189062] 'process raft request' (duration: 125.699841ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:53:26.293796Z","caller":"traceutil/trace.go:171","msg":"trace[1969973501] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"159.841124ms","start":"2026-02-14T04:53:26.133929Z","end":"2026-02-14T04:53:26.29377Z","steps":["trace[1969973501] 'process raft request' (duration: 159.684271ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:53:33.735069Z","caller":"traceutil/trace.go:171","msg":"trace[147266769] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"154.470591ms","start":"2026-02-14T04:53:33.580581Z","end":"2026-02-14T04:53:33.735051Z","steps":["trace[147266769] 'process raft request' (duration: 145.764144ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:53:35.042296Z","caller":"traceutil/trace.go:171","msg":"trace[2076700272] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"334.147991ms","start":"2026-02-14T04:53:34.708129Z","end":"2026-02-14T04:53:35.042277Z","steps":["trace[2076700272] 'process raft request' (duration: 334.033049ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:53:35.043353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:53:34.708114Z","time spent":"334.244742ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4158,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:53:35.194964Z","caller":"traceutil/trace.go:171","msg":"trace[12620177] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"145.130207ms","start":"2026-02-14T04:53:35.049813Z","end":"2026-02-14T04:53:35.194943Z","steps":["trace[12620177] 'process raft request' (duration: 64.37459ms)","trace[12620177] 'compare' (duration: 80.643735ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:53:37.290357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000549716s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-14T04:53:37.290417Z","caller":"traceutil/trace.go:171","msg":"trace[849585613] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000648679s","start":"2026-02-14T04:53:35.289753Z","end":"2026-02-14T04:53:37.290401Z","steps":["trace[849585613] 'agreement among raft nodes before linearized reading' (duration: 2.000574347s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:53:37.290452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:53:35.289739Z","time spent":"2.0007029s","remote":"127.0.0.1:38088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-14T04:53:39.291413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000709823s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-14T04:53:39.291488Z","caller":"traceutil/trace.go:171","msg":"trace[697645188] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000814237s","start":"2026-02-14T04:53:37.290654Z","end":"2026-02-14T04:53:39.291468Z","steps":["trace[697645188] 'agreement among raft nodes before linearized reading' (duration: 2.000720944s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:53:39.291532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:53:37.290643Z","time spent":"2.000881279s","remote":"127.0.0.1:38100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-14T04:53:40.386508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999742784s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-14T04:53:40.386739Z","caller":"traceutil/trace.go:171","msg":"trace[1805950334] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"5.000010041s","start":"2026-02-14T04:53:35.386708Z","end":"2026-02-14T04:53:40.386718Z","steps":["trace[1805950334] 'agreement among raft nodes before linearized reading' (duration: 4.999761754s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:53:40.386792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:53:35.386696Z","time spent":"5.000084173s","remote":"127.0.0.1:38418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-14T04:53:40.980012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.302872ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:53:40.980126Z","caller":"traceutil/trace.go:171","msg":"trace[904263774] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:424; }","duration":"274.426165ms","start":"2026-02-14T04:53:40.705684Z","end":"2026-02-14T04:53:40.98011Z","steps":["trace[904263774] 'range keys from in-memory index tree' (duration: 274.289531ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:53:40.980454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.495892062s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-14T04:53:40.980495Z","caller":"traceutil/trace.go:171","msg":"trace[701020334] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"5.781924243s","start":"2026-02-14T04:53:35.198562Z","end":"2026-02-14T04:53:40.980487Z","steps":["trace[701020334] 'process raft request' (duration: 285.861316ms)","trace[701020334] 'compare' (duration: 5.494352097s)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:53:40.980525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:53:35.198547Z","time spent":"5.781964484s","remote":"127.0.0.1:38328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3541,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-14T04:53:41.190216Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"5.705697927s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-14T04:53:41.300457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000146117s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-14T04:53:41.300533Z","caller":"traceutil/trace.go:171","msg":"trace[2079556835] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00028654s","start":"2026-02-14T04:53:39.300229Z","end":"2026-02-14T04:53:41.300515Z","steps":["trace[2079556835] 'agreement among raft nodes before linearized reading' (duration: 2.000190398s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:53:41.300577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:53:39.300193Z","time spent":"2.000375233s","remote":"127.0.0.1:38088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-14T04:53:41.553545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.997401347s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-14T04:53:41.553615Z","caller":"traceutil/trace.go:171","msg":"trace[1821102962] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.99750553s","start":"2026-02-14T04:53:36.556093Z","end":"2026-02-14T04:53:41.553599Z","steps":["trace[1821102962] 'agreement among raft nodes before linearized reading' (duration: 4.997419468s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:53:41.55366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:53:36.556086Z","time spent":"4.997566022s","remote":"127.0.0.1:38418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/02/14 04:53:41 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-14T04:53:42.202773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:53:35.201951Z","time spent":"7.000816036s","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-14T04:53:42.202788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:53:35.201783Z","time spent":"7.000999458s","remote":"127.0.0.1:38218","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-14T04:53:42.281671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.091195255s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-14T04:53:42.282959Z","caller":"traceutil/trace.go:171","msg":"trace[856853294] linearizableReadLoop","detail":"{readStateIndex:440; appliedIndex:437; }","duration":"6.993162112s","start":"2026-02-14T04:53:35.289785Z","end":"2026-02-14T04:53:42.282947Z","steps":["trace[856853294] 'read index received' (duration: 194.821727ms)","trace[856853294] 'applied index is now lower than readState.Index' (duration: 6.798339755s)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:53:42.783221Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991526780465727168,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-14T04:53:43.095832Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.904621913s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-14T04:53:43.305607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.004072387s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-14T04:53:43.305666Z","caller":"traceutil/trace.go:171","msg":"trace[1272339954] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.004199521s","start":"2026-02-14T04:53:41.301453Z","end":"2026-02-14T04:53:43.305652Z","steps":["trace[1272339954] 'agreement among raft nodes before linearized reading' (duration: 2.004126218s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:53:43.305869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:53:41.301438Z","time spent":"2.004413466s","remote":"127.0.0.1:38100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/14 04:53:43 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-14T04:53:44.021191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"925.064611ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T04:53:44.02194Z","caller":"traceutil/trace.go:171","msg":"trace[2135426240] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"5.397872973s","start":"2026-02-14T04:53:38.624053Z","end":"2026-02-14T04:53:44.021926Z","steps":["trace[2135426240] 'process raft request' (duration: 4.47202053s)","trace[2135426240] 'compare' (duration: 924.837485ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:53:44.022088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:53:38.624037Z","time spent":"5.398004348s","remote":"127.0.0.1:38418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:53:44.022305Z","caller":"traceutil/trace.go:171","msg":"trace[1197083185] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"3.0320086s","start":"2026-02-14T04:53:40.990275Z","end":"2026-02-14T04:53:44.022284Z","steps":["trace[1197083185] 'process raft request' (duration: 3.031614279s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:53:44.024627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:53:40.990258Z","time spent":"3.034299205s","remote":"127.0.0.1:38328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4015,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:53:44.022614Z","caller":"traceutil/trace.go:171","msg":"trace[1814743919] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"2.890844258s","start":"2026-02-14T04:53:41.131749Z","end":"2026-02-14T04:53:44.022593Z","steps":["trace[1814743919] 'process raft request' (duration: 2.890804357s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:53:44.024781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:53:41.131723Z","time spent":"2.893038019s","remote":"127.0.0.1:38418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:53:44.0228Z","caller":"traceutil/trace.go:171","msg":"trace[1944152734] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"3.032302959s","start":"2026-02-14T04:53:40.990485Z","end":"2026-02-14T04:53:44.022788Z","steps":["trace[1944152734] 'process raft request' (duration: 3.031718762s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:53:44.024897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:53:40.990479Z","time spent":"3.034402798s","remote":"127.0.0.1:38218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-14T04:53:44.02285Z","caller":"traceutil/trace.go:171","msg":"trace[1349863096] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"3.032319599s","start":"2026-02-14T04:53:40.990524Z","end":"2026-02-14T04:53:44.022843Z","steps":["trace[1349863096] 'process raft request' (duration: 3.031951349s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:53:44.025057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:53:40.990519Z","time spent":"3.034523201s","remote":"127.0.0.1:38218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-14T04:53:44.023003Z","caller":"traceutil/trace.go:171","msg":"trace[909450404] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"3.030313491s","start":"2026-02-14T04:53:40.99268Z","end":"2026-02-14T04:53:44.022994Z","steps":["trace[909450404] 'process raft request' (duration: 3.029830848s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:53:44.025234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:53:40.992672Z","time spent":"3.032541314s","remote":"127.0.0.1:38616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3773,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:53:44.023118Z","caller":"traceutil/trace.go:171","msg":"trace[1505579861] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"4.726245719s","start":"2026-02-14T04:53:39.296862Z","end":"2026-02-14T04:53:44.023108Z","steps":["trace[1505579861] 'process raft request' (duration: 4.724983413s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:53:44.026026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:53:39.296834Z","time spent":"4.729168771s","remote":"127.0.0.1:38218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-14T04:53:44.477603Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.381631306s","expected-duration":"1s"} {"level":"info","ts":"2026-02-14T04:53:44.484886Z","caller":"traceutil/trace.go:171","msg":"trace[1910119687] linearizableReadLoop","detail":"{readStateIndex:448; appliedIndex:440; }","duration":"2.201734242s","start":"2026-02-14T04:53:42.283137Z","end":"2026-02-14T04:53:44.484871Z","steps":["trace[1910119687] 'read index received' (duration: 812.945654ms)","trace[1910119687] 'applied index is now lower than readState.Index' (duration: 1.388788098s)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:53:44.484893Z","caller":"traceutil/trace.go:171","msg":"trace[1191974336] transaction","detail":"{read_only:false; number_of_response:1; response_revision:434; }","duration":"2.2760668s","start":"2026-02-14T04:53:42.208813Z","end":"2026-02-14T04:53:44.484879Z","steps":["trace[1191974336] 'process raft request' (duration: 2.268988381s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:53:44.484959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.535875475s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.141\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-14T04:53:44.484975Z","caller":"traceutil/trace.go:171","msg":"trace[1078476247] range","detail":"{range_begin:/registry/masterleases/199.204.45.141; range_end:; response_count:1; response_revision:434; }","duration":"5.535925207s","start":"2026-02-14T04:53:38.949045Z","end":"2026-02-14T04:53:44.48497Z","steps":["trace[1078476247] 'agreement among raft nodes before linearized reading' (duration: 5.535883676s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:53:44.484981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:53:42.208788Z","time spent":"2.276142873s","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":45,"response count":0,"response size":4207,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:53:44.485009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.92975302s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-14T04:53:44.48502Z","caller":"traceutil/trace.go:171","msg":"trace[181979294] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:434; }","duration":"7.92977146s","start":"2026-02-14T04:53:36.555245Z","end":"2026-02-14T04:53:44.485017Z","steps":["trace[181979294] 'agreement among raft nodes before linearized reading' (duration: 7.92975135s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:53:44.485032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:53:36.555242Z","time spent":"7.929785731s","remote":"127.0.0.1:38418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-14T04:53:44.484989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:53:38.94902Z","time spent":"5.535965808s","remote":"127.0.0.1:38134","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.141\" "} {"level":"warn","ts":"2026-02-14T04:53:44.485136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.929921324s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-14T04:53:44.48515Z","caller":"traceutil/trace.go:171","msg":"trace[1727098340] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:434; }","duration":"7.929960616s","start":"2026-02-14T04:53:36.555185Z","end":"2026-02-14T04:53:44.485146Z","steps":["trace[1727098340] 'agreement among raft nodes before linearized reading' (duration: 7.929934695s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:53:44.485161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:53:36.555172Z","time spent":"7.929986596s","remote":"127.0.0.1:38418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-14T04:53:44.490641Z","caller":"traceutil/trace.go:171","msg":"trace[615665124] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"458.068228ms","start":"2026-02-14T04:53:44.032558Z","end":"2026-02-14T04:53:44.490626Z","steps":["trace[615665124] 'process raft request' (duration: 457.180473ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:53:44.490769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:53:44.032526Z","time spent":"458.195011ms","remote":"127.0.0.1:38616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3818,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:53:44.520837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.132792864s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-14T04:53:44.520882Z","caller":"traceutil/trace.go:171","msg":"trace[1041176019] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:436; }","duration":"2.132871096s","start":"2026-02-14T04:53:42.388001Z","end":"2026-02-14T04:53:44.520872Z","steps":["trace[1041176019] 'agreement among raft nodes before linearized reading' (duration: 2.132798224s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:53:44.520901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:53:42.387988Z","time spent":"2.132909207s","remote":"127.0.0.1:38418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-14T04:53:44.520959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"965.541358ms","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-14T04:53:44.520995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.867614486s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:53:44.521009Z","caller":"traceutil/trace.go:171","msg":"trace[30686338] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:436; }","duration":"1.867655167s","start":"2026-02-14T04:53:42.653349Z","end":"2026-02-14T04:53:44.521004Z","steps":["trace[30686338] 'agreement among raft nodes before linearized reading' (duration: 1.867635217s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:53:44.521023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:53:42.653334Z","time spent":"1.867685898s","remote":"127.0.0.1:38088","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-14T04:53:44.521237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.752048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.189403e13731e62a\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-02-14T04:53:44.521254Z","caller":"traceutil/trace.go:171","msg":"trace[1075070301] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.189403e13731e62a; range_end:; response_count:1; response_revision:436; }","duration":"490.807429ms","start":"2026-02-14T04:53:44.030441Z","end":"2026-02-14T04:53:44.521249Z","steps":["trace[1075070301] 'agreement among raft nodes before linearized reading' (duration: 490.774508ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:53:44.521268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:53:44.030429Z","time spent":"490.834959ms","remote":"127.0.0.1:38218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":797,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.189403e13731e62a\" "} {"level":"warn","ts":"2026-02-14T04:53:44.520977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.213424427s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:53:44.523694Z","caller":"traceutil/trace.go:171","msg":"trace[1119623536] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:436; }","duration":"1.216151923s","start":"2026-02-14T04:53:43.307526Z","end":"2026-02-14T04:53:44.523678Z","steps":["trace[1119623536] 'agreement among raft nodes before linearized reading' (duration: 1.213438287s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:53:44.523752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:53:43.307515Z","time spent":"1.216220796s","remote":"127.0.0.1:38100","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-14T04:53:44.526744Z","caller":"traceutil/trace.go:171","msg":"trace[381167070] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:436; }","duration":"965.624981ms","start":"2026-02-14T04:53:43.555367Z","end":"2026-02-14T04:53:44.520992Z","steps":["trace[381167070] 'agreement among raft nodes before linearized reading' (duration: 965.515188ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:53:44.527235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:53:43.555349Z","time spent":"971.433454ms","remote":"127.0.0.1:38418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-14T04:53:44.814901Z","caller":"traceutil/trace.go:171","msg":"trace[1955282948] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"189.490101ms","start":"2026-02-14T04:53:44.625386Z","end":"2026-02-14T04:53:44.814876Z","steps":["trace[1955282948] 'process raft request' (duration: 158.241252ms)","trace[1955282948] 'compare' (duration: 31.126366ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:53:44.816151Z","caller":"traceutil/trace.go:171","msg":"trace[87037081] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"173.188833ms","start":"2026-02-14T04:53:44.642953Z","end":"2026-02-14T04:53:44.816142Z","steps":["trace[87037081] 'process raft request' (duration: 173.168812ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:53:44.816346Z","caller":"traceutil/trace.go:171","msg":"trace[986921101] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"183.151993ms","start":"2026-02-14T04:53:44.633187Z","end":"2026-02-14T04:53:44.816339Z","steps":["trace[986921101] 'process raft request' (duration: 182.807733ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:53:44.81643Z","caller":"traceutil/trace.go:171","msg":"trace[1620329644] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"183.184024ms","start":"2026-02-14T04:53:44.633241Z","end":"2026-02-14T04:53:44.816425Z","steps":["trace[1620329644] 'process raft request' (duration: 182.810863ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:53:44.816505Z","caller":"traceutil/trace.go:171","msg":"trace[5211273] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"183.229966ms","start":"2026-02-14T04:53:44.63327Z","end":"2026-02-14T04:53:44.8165Z","steps":["trace[5211273] 'process raft request' (duration: 182.810454ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:53:44.816633Z","caller":"traceutil/trace.go:171","msg":"trace[597201466] transaction","detail":"{read_only:false; number_of_response:1; response_revision:450; }","duration":"174.809828ms","start":"2026-02-14T04:53:44.641818Z","end":"2026-02-14T04:53:44.816628Z","steps":["trace[597201466] 'process raft request' (duration: 174.282493ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:53:55.364792Z","caller":"traceutil/trace.go:171","msg":"trace[917732084] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"132.088765ms","start":"2026-02-14T04:53:55.232658Z","end":"2026-02-14T04:53:55.364747Z","steps":["trace[917732084] 'process raft request' (duration: 127.267148ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:53:55.828729Z","caller":"traceutil/trace.go:171","msg":"trace[497605177] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"136.08739ms","start":"2026-02-14T04:53:55.692623Z","end":"2026-02-14T04:53:55.828711Z","steps":["trace[497605177] 'process raft request' (duration: 135.955067ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:53:55.833102Z","caller":"traceutil/trace.go:171","msg":"trace[1491493317] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"108.72841ms","start":"2026-02-14T04:53:55.724366Z","end":"2026-02-14T04:53:55.833094Z","steps":["trace[1491493317] 'process raft request' (duration: 108.571586ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:53:56.628192Z","caller":"traceutil/trace.go:171","msg":"trace[1695859551] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:571; }","duration":"339.240674ms","start":"2026-02-14T04:53:56.288937Z","end":"2026-02-14T04:53:56.628178Z","steps":["trace[1695859551] 'read index received' (duration: 339.159251ms)","trace[1695859551] 'applied index is now lower than readState.Index' (duration: 80.963µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:53:56.628382Z","caller":"traceutil/trace.go:171","msg":"trace[1510958236] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"505.598716ms","start":"2026-02-14T04:53:56.122775Z","end":"2026-02-14T04:53:56.628374Z","steps":["trace[1510958236] 'process raft request' (duration: 505.272367ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:53:56.628503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:53:56.122756Z","time spent":"505.696629ms","remote":"127.0.0.1:38182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-14T04:53:56.628497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.533242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:53:56.628578Z","caller":"traceutil/trace.go:171","msg":"trace[1626572891] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:554; }","duration":"339.656455ms","start":"2026-02-14T04:53:56.288903Z","end":"2026-02-14T04:53:56.628559Z","steps":["trace[1626572891] 'agreement among raft nodes before linearized reading' (duration: 339.48223ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:53:56.628613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:53:56.288889Z","time spent":"339.716937ms","remote":"127.0.0.1:38088","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-14T04:53:56.638868Z","caller":"traceutil/trace.go:171","msg":"trace[402609973] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"315.796201ms","start":"2026-02-14T04:53:56.323057Z","end":"2026-02-14T04:53:56.638854Z","steps":["trace[402609973] 'process raft request' (duration: 315.677037ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:53:56.639031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:53:56.323024Z","time spent":"315.963885ms","remote":"127.0.0.1:38182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-14T04:53:56.639952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.334655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:53:56.640004Z","caller":"traceutil/trace.go:171","msg":"trace[1542040658] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:555; }","duration":"118.956231ms","start":"2026-02-14T04:53:56.521037Z","end":"2026-02-14T04:53:56.639993Z","steps":["trace[1542040658] 'agreement among raft nodes before linearized reading' (duration: 118.347665ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:53:57.229354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.642087ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T04:53:57.229419Z","caller":"traceutil/trace.go:171","msg":"trace[1387569467] linearizableReadLoop","detail":"{readStateIndex:576; appliedIndex:575; }","duration":"553.229778ms","start":"2026-02-14T04:53:56.676178Z","end":"2026-02-14T04:53:57.229408Z","steps":["trace[1387569467] 'read index received' (duration: 69.424357ms)","trace[1387569467] 'applied index is now lower than readState.Index' (duration: 483.804621ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:53:57.229587Z","caller":"traceutil/trace.go:171","msg":"trace[1653756371] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"561.841074ms","start":"2026-02-14T04:53:56.667737Z","end":"2026-02-14T04:53:57.229579Z","steps":["trace[1653756371] 'process raft request' (duration: 77.916809ms)","trace[1653756371] 'compare' (duration: 483.431522ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:53:57.229627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:53:56.667726Z","time spent":"561.879984ms","remote":"127.0.0.1:38182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10362,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:53:57.229794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.615058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-14T04:53:57.229812Z","caller":"traceutil/trace.go:171","msg":"trace[1484559512] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:558; }","duration":"553.657469ms","start":"2026-02-14T04:53:56.676149Z","end":"2026-02-14T04:53:57.229806Z","steps":["trace[1484559512] 'agreement among raft nodes before linearized reading' (duration: 553.583318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:53:57.22983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:53:56.676136Z","time spent":"553.68965ms","remote":"127.0.0.1:38418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-14T04:53:57.229924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.217492ms","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-14T04:53:57.22994Z","caller":"traceutil/trace.go:171","msg":"trace[915888184] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:558; }","duration":"432.255674ms","start":"2026-02-14T04:53:56.797679Z","end":"2026-02-14T04:53:57.229935Z","steps":["trace[915888184] 'agreement among raft nodes before linearized reading' (duration: 432.232113ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:53:57.22996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:53:56.797667Z","time spent":"432.288174ms","remote":"127.0.0.1:38182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-14T04:53:57.230228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.949879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-14T04:53:57.230248Z","caller":"traceutil/trace.go:171","msg":"trace[1013466665] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:558; }","duration":"109.990161ms","start":"2026-02-14T04:53:57.120251Z","end":"2026-02-14T04:53:57.230242Z","steps":["trace[1013466665] 'agreement among raft nodes before linearized reading' (duration: 109.94104ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:53:57.230321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.634291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:53:57.230336Z","caller":"traceutil/trace.go:171","msg":"trace[1381055566] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:558; }","duration":"305.649352ms","start":"2026-02-14T04:53:56.924682Z","end":"2026-02-14T04:53:57.230331Z","steps":["trace[1381055566] 'agreement among raft nodes before linearized reading' (duration: 305.626642ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:53:57.230352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:53:56.924666Z","time spent":"305.681633ms","remote":"127.0.0.1:38182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-14T04:53:57.230445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.475008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-14T04:53:57.230462Z","caller":"traceutil/trace.go:171","msg":"trace[476531338] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:558; }","duration":"318.490028ms","start":"2026-02-14T04:53:56.911966Z","end":"2026-02-14T04:53:57.230456Z","steps":["trace[476531338] 'agreement among raft nodes before linearized reading' (duration: 318.439217ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:53:57.230477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:53:56.91195Z","time spent":"318.523749ms","remote":"127.0.0.1:38418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-14T04:53:57.230569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.599373ms","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-14T04:53:57.230584Z","caller":"traceutil/trace.go:171","msg":"trace[376770361] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:558; }","duration":"320.621454ms","start":"2026-02-14T04:53:56.909958Z","end":"2026-02-14T04:53:57.230579Z","steps":["trace[376770361] 'agreement among raft nodes before linearized reading' (duration: 320.577632ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:53:57.230598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:53:56.909955Z","time spent":"320.640175ms","remote":"127.0.0.1:38418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-14T04:53:57.230685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.733877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-14T04:53:57.2307Z","caller":"traceutil/trace.go:171","msg":"trace[1226315332] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:558; }","duration":"320.771568ms","start":"2026-02-14T04:53:56.909924Z","end":"2026-02-14T04:53:57.230696Z","steps":["trace[1226315332] 'agreement among raft nodes before linearized reading' (duration: 320.729057ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:53:57.230715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:53:56.909912Z","time spent":"320.799469ms","remote":"127.0.0.1:38418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-14T04:54:00.202132Z","caller":"traceutil/trace.go:171","msg":"trace[1219803791] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"558.711622ms","start":"2026-02-14T04:53:59.6434Z","end":"2026-02-14T04:54:00.202112Z","steps":["trace[1219803791] 'process raft request' (duration: 547.570617ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:54:00.218864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:53:59.643378Z","time spent":"575.404149ms","remote":"127.0.0.1:38182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188237,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:54:00.515055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.573859ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-14T04:54:00.571564Z","caller":"traceutil/trace.go:171","msg":"trace[1082611329] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:618; }","duration":"491.382998ms","start":"2026-02-14T04:54:00.080138Z","end":"2026-02-14T04:54:00.571521Z","steps":["trace[1082611329] 'read index received' (duration: 110.741782ms)","trace[1082611329] 'applied index is now lower than readState.Index' (duration: 380.634106ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:54:00.571781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.623714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188486"} {"level":"info","ts":"2026-02-14T04:54:00.571804Z","caller":"traceutil/trace.go:171","msg":"trace[1569560624] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:600; }","duration":"491.718247ms","start":"2026-02-14T04:54:00.080079Z","end":"2026-02-14T04:54:00.571797Z","steps":["trace[1569560624] 'agreement among raft nodes before linearized reading' (duration: 491.561783ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:54:00.571825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:54:00.080061Z","time spent":"491.760198ms","remote":"127.0.0.1:38182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":1,"response size":188509,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-02-14T04:54:00.572944Z","caller":"traceutil/trace.go:171","msg":"trace[366605181] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"156.235026ms","start":"2026-02-14T04:54:00.416701Z","end":"2026-02-14T04:54:00.572936Z","steps":["trace[366605181] 'process raft request' (duration: 154.787169ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:54:00.572414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.367701ms","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-14T04:54:00.573839Z","caller":"traceutil/trace.go:171","msg":"trace[1909490449] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:601; }","duration":"437.824169ms","start":"2026-02-14T04:54:00.135994Z","end":"2026-02-14T04:54:00.573818Z","steps":["trace[1909490449] 'agreement among raft nodes before linearized reading' (duration: 436.172667ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:54:00.574044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:54:00.13598Z","time spent":"438.046194ms","remote":"127.0.0.1:38182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":1,"response size":188275,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-14T04:54:06.291176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000437031s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-14T04:54:06.291234Z","caller":"traceutil/trace.go:171","msg":"trace[123055550] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000529283s","start":"2026-02-14T04:54:04.290691Z","end":"2026-02-14T04:54:06.291221Z","steps":["trace[123055550] 'agreement among raft nodes before linearized reading' (duration: 2.000452151s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:54:06.291264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:54:04.290684Z","time spent":"2.000574644s","remote":"127.0.0.1:38088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-14T04:54:07.371831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.863923739s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-02-14T04:54:07.97566Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.467677189s","expected-duration":"1s"} {"level":"info","ts":"2026-02-14T04:54:07.976017Z","caller":"traceutil/trace.go:171","msg":"trace[1011044169] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:633; }","duration":"3.685273087s","start":"2026-02-14T04:54:04.290713Z","end":"2026-02-14T04:54:07.975986Z","steps":["trace[1011044169] 'read index received' (duration: 217.428873ms)","trace[1011044169] 'applied index is now lower than readState.Index' (duration: 3.467843574s)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:54:07.97607Z","caller":"traceutil/trace.go:171","msg":"trace[1175080283] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"3.688678161s","start":"2026-02-14T04:54:04.287385Z","end":"2026-02-14T04:54:07.976064Z","steps":["trace[1175080283] 'process raft request' (duration: 3.688452736s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:54:07.976185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:54:04.287369Z","time spent":"3.688734352s","remote":"127.0.0.1:38418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:54:08.292666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000533869s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-14T04:54:08.292744Z","caller":"traceutil/trace.go:171","msg":"trace[1901440703] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000656452s","start":"2026-02-14T04:54:06.29207Z","end":"2026-02-14T04:54:08.292726Z","steps":["trace[1901440703] 'agreement among raft nodes before linearized reading' (duration: 2.00055455s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:54:08.292789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:54:06.292054Z","time spent":"2.000726074s","remote":"127.0.0.1:38100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-14T04:54:08.479546Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991526780465727518,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-14T04:54:08.980529Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991526780465727518,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-14T04:54:09.4811Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991526780465727518,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-14T04:54:09.981456Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991526780465727518,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-14T04:54:10.298016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000363697s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-14T04:54:10.298097Z","caller":"traceutil/trace.go:171","msg":"trace[1277447870] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00048044s","start":"2026-02-14T04:54:08.297598Z","end":"2026-02-14T04:54:10.298079Z","steps":["trace[1277447870] 'agreement among raft nodes before linearized reading' (duration: 2.000359577s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:54:10.298144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:54:08.297548Z","time spent":"2.000587123s","remote":"127.0.0.1:38088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-14T04:54:10.482654Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991526780465727518,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-14T04:54:10.497174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.000101486s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-14T04:54:10.497237Z","caller":"traceutil/trace.go:171","msg":"trace[85635896] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"5.000229348s","start":"2026-02-14T04:54:05.496988Z","end":"2026-02-14T04:54:10.497217Z","steps":["trace[85635896] 'agreement among raft nodes before linearized reading' (duration: 5.000121516s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:54:10.497284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:54:05.496954Z","time spent":"5.000319511s","remote":"127.0.0.1:38418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-14T04:54:10.502771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999872025s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-14T04:54:10.502803Z","caller":"traceutil/trace.go:171","msg":"trace[1755709433] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.999920537s","start":"2026-02-14T04:54:05.502874Z","end":"2026-02-14T04:54:10.502795Z","steps":["trace[1755709433] 'agreement among raft nodes before linearized reading' (duration: 4.999884436s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:54:10.502825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:54:05.502867Z","time spent":"4.999953008s","remote":"127.0.0.1:38418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-14T04:54:10.510152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999322948s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-14T04:54:10.510187Z","caller":"traceutil/trace.go:171","msg":"trace[852799222] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"4.999358929s","start":"2026-02-14T04:54:05.510818Z","end":"2026-02-14T04:54:10.510177Z","steps":["trace[852799222] 'agreement among raft nodes before linearized reading' (duration: 4.999322088s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:54:10.510209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:54:05.510735Z","time spent":"4.999469211s","remote":"127.0.0.1:38418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-14T04:54:10.708206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.65117ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T04:54:10.710199Z","caller":"traceutil/trace.go:171","msg":"trace[1488127500] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:635; }","duration":"2.734127892s","start":"2026-02-14T04:54:07.976044Z","end":"2026-02-14T04:54:10.710172Z","steps":["trace[1488127500] 'read index received' (duration: 2.522419982s)","trace[1488127500] 'applied index is now lower than readState.Index' (duration: 211.70644ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:54:10.710343Z","caller":"traceutil/trace.go:171","msg":"trace[1937457155] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"6.091204384s","start":"2026-02-14T04:54:04.619128Z","end":"2026-02-14T04:54:10.710333Z","steps":["trace[1937457155] 'process raft request' (duration: 5.879375111s)","trace[1937457155] 'compare' (duration: 209.475656ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:54:10.710478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:54:04.619112Z","time spent":"6.091311956s","remote":"127.0.0.1:38418","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-14T04:54:10.710759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.195827096s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-14T04:54:10.710783Z","caller":"traceutil/trace.go:171","msg":"trace[695092032] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:614; }","duration":"5.195881098s","start":"2026-02-14T04:54:05.514895Z","end":"2026-02-14T04:54:10.710776Z","steps":["trace[695092032] 'agreement among raft nodes before linearized reading' (duration: 5.195801886s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:54:10.710844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:54:05.514879Z","time spent":"5.195958349s","remote":"127.0.0.1:38418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-14T04:54:10.710985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.199228411s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-14T04:54:10.711005Z","caller":"traceutil/trace.go:171","msg":"trace[1528779741] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:614; }","duration":"5.199271832s","start":"2026-02-14T04:54:05.511727Z","end":"2026-02-14T04:54:10.710999Z","steps":["trace[1528779741] 'agreement among raft nodes before linearized reading' (duration: 5.199223441s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:54:10.711018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:54:05.511717Z","time spent":"5.199298013s","remote":"127.0.0.1:38418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-14T04:54:10.711252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.415291051s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.189403e13731e62a\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-02-14T04:54:10.711485Z","caller":"traceutil/trace.go:171","msg":"trace[1973813668] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.189403e13731e62a; range_end:; response_count:1; response_revision:614; }","duration":"2.415550927s","start":"2026-02-14T04:54:08.295925Z","end":"2026-02-14T04:54:10.711475Z","steps":["trace[1973813668] 'agreement among raft nodes before linearized reading' (duration: 2.415300711s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:54:10.711511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:54:08.295898Z","time spent":"2.415606748s","remote":"127.0.0.1:38218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":797,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.189403e13731e62a\" "} {"level":"warn","ts":"2026-02-14T04:54:10.712214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.076178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:54:10.71224Z","caller":"traceutil/trace.go:171","msg":"trace[1734928954] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:614; }","duration":"413.12726ms","start":"2026-02-14T04:54:10.299103Z","end":"2026-02-14T04:54:10.71223Z","steps":["trace[1734928954] 'agreement among raft nodes before linearized reading' (duration: 413.093889ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:54:10.712467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.754109348s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.141\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-14T04:54:10.712489Z","caller":"traceutil/trace.go:171","msg":"trace[1963508485] range","detail":"{range_begin:/registry/masterleases/199.204.45.141; range_end:; response_count:1; response_revision:614; }","duration":"1.754165778s","start":"2026-02-14T04:54:08.958317Z","end":"2026-02-14T04:54:10.712483Z","steps":["trace[1963508485] 'agreement among raft nodes before linearized reading' (duration: 1.754109617s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:54:10.713115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:54:10.299087Z","time spent":"413.16477ms","remote":"127.0.0.1:38100","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-14T04:54:10.71334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:54:08.958303Z","time spent":"1.7541984s","remote":"127.0.0.1:38134","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.141\" "} {"level":"warn","ts":"2026-02-14T04:54:11.466809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.574735ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-02-14T04:54:11.467079Z","caller":"traceutil/trace.go:171","msg":"trace[282858472] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:637; }","duration":"747.954523ms","start":"2026-02-14T04:54:10.719106Z","end":"2026-02-14T04:54:11.46706Z","steps":["trace[282858472] 'read index received' (duration: 72.364027ms)","trace[282858472] 'applied index is now lower than readState.Index' (duration: 675.589446ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:54:11.467179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:54:10.718926Z","time spent":"748.25037ms","remote":"127.0.0.1:38134","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-14T04:54:11.467465Z","caller":"traceutil/trace.go:171","msg":"trace[532861315] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"748.380774ms","start":"2026-02-14T04:54:10.719072Z","end":"2026-02-14T04:54:11.467453Z","steps":["trace[532861315] 'process raft request' (duration: 747.844021ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:54:11.467544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:54:10.719068Z","time spent":"748.424615ms","remote":"127.0.0.1:38418","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-14T04:54:11.467715Z","caller":"traceutil/trace.go:171","msg":"trace[1539907444] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"747.642526ms","start":"2026-02-14T04:54:10.72006Z","end":"2026-02-14T04:54:11.467702Z","steps":["trace[1539907444] 'process raft request' (duration: 746.961069ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:54:11.467764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:54:10.720052Z","time spent":"747.684607ms","remote":"127.0.0.1:38418","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-14T04:54:11.467924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.812484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:54:11.467954Z","caller":"traceutil/trace.go:171","msg":"trace[1758189701] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:617; }","duration":"748.849395ms","start":"2026-02-14T04:54:10.719094Z","end":"2026-02-14T04:54:11.467944Z","steps":["trace[1758189701] 'agreement among raft nodes before linearized reading' (duration: 748.800643ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:54:11.467981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:54:10.71909Z","time spent":"748.884895ms","remote":"127.0.0.1:38088","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-14T04:54:11.468162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.494928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.189403e13731e62a\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-02-14T04:54:11.468188Z","caller":"traceutil/trace.go:171","msg":"trace[111013180] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.189403e13731e62a; range_end:; response_count:1; response_revision:617; }","duration":"676.542699ms","start":"2026-02-14T04:54:10.791638Z","end":"2026-02-14T04:54:11.46818Z","steps":["trace[111013180] 'agreement among raft nodes before linearized reading' (duration: 676.447237ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:54:11.468213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:54:10.791626Z","time spent":"676.58139ms","remote":"127.0.0.1:38218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":797,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.189403e13731e62a\" "} {"level":"warn","ts":"2026-02-14T04:54:12.789386Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991526780465727542,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-14T04:54:13.290172Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991526780465727542,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-14T04:54:13.791218Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991526780465727542,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-14T04:54:14.289512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.001147819s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-14T04:54:14.289585Z","caller":"traceutil/trace.go:171","msg":"trace[1211230640] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001280033s","start":"2026-02-14T04:54:12.288286Z","end":"2026-02-14T04:54:14.289566Z","steps":["trace[1211230640] 'agreement among raft nodes before linearized reading' (duration: 2.00116643s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:54:14.289632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:54:12.288272Z","time spent":"2.001350374s","remote":"127.0.0.1:38088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-14T04:54:14.292141Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991526780465727542,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-14T04:54:14.793105Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991526780465727542,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-14T04:54:14.901237Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.674298692s","expected-duration":"1s"} {"level":"info","ts":"2026-02-14T04:54:14.901777Z","caller":"traceutil/trace.go:171","msg":"trace[981021859] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"3.120897145s","start":"2026-02-14T04:54:11.780859Z","end":"2026-02-14T04:54:14.901756Z","steps":["trace[981021859] 'process raft request' (duration: 3.120744262s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:54:14.90186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:54:11.780849Z","time spent":"3.120959127s","remote":"127.0.0.1:38328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:54:15.498183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.999425106s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-14T04:54:15.498275Z","caller":"traceutil/trace.go:171","msg":"trace[2081243344] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"2.9995855s","start":"2026-02-14T04:54:12.498669Z","end":"2026-02-14T04:54:15.498254Z","steps":["trace[2081243344] 'agreement among raft nodes before linearized reading' (duration: 2.999450907s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:54:15.498328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:54:12.498647Z","time spent":"2.999671182s","remote":"127.0.0.1:38418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/02/14 04:54:15 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-14T04:54:15.512551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.000835204s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-14T04:54:15.512653Z","caller":"traceutil/trace.go:171","msg":"trace[836949173] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"3.000968718s","start":"2026-02-14T04:54:12.511672Z","end":"2026-02-14T04:54:15.512641Z","steps":["trace[836949173] 'agreement among raft nodes before linearized reading' (duration: 3.000853635s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:54:15.512734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:54:12.511661Z","time spent":"3.00106117s","remote":"127.0.0.1:38418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} 2026/02/14 04:54:15 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-14T04:54:15.551725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.046413577s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-14T04:54:15.551794Z","caller":"traceutil/trace.go:171","msg":"trace[383955548] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"3.04651525s","start":"2026-02-14T04:54:12.505262Z","end":"2026-02-14T04:54:15.551777Z","steps":["trace[383955548] 'agreement among raft nodes before linearized reading' (duration: 3.046435078s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:54:15.551836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:54:12.505247Z","time spent":"3.046580731s","remote":"127.0.0.1:38418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/02/14 04:54:15 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-14T04:54:15.976156Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.074796403s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-14T04:54:16.081689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.331858ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-14T04:54:16.081911Z","caller":"traceutil/trace.go:171","msg":"trace[1375360564] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"566.480917ms","start":"2026-02-14T04:54:15.515418Z","end":"2026-02-14T04:54:16.081899Z","steps":["trace[1375360564] 'process raft request' (duration: 566.424716ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:54:16.081981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:54:15.515406Z","time spent":"566.533819ms","remote":"127.0.0.1:38418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:54:16.08201Z","caller":"traceutil/trace.go:171","msg":"trace[926222421] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"3.793676953s","start":"2026-02-14T04:54:12.288312Z","end":"2026-02-14T04:54:16.081989Z","steps":["trace[926222421] 'read index received' (duration: 2.613926761s)","trace[926222421] 'applied index is now lower than readState.Index' (duration: 1.179748212s)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:54:16.082228Z","caller":"traceutil/trace.go:171","msg":"trace[1337875947] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"581.527054ms","start":"2026-02-14T04:54:15.500695Z","end":"2026-02-14T04:54:16.082222Z","steps":["trace[1337875947] 'process raft request' (duration: 581.067923ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:54:16.082257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:54:15.500673Z","time spent":"581.568225ms","remote":"127.0.0.1:38418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:54:16.082418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.792436201s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:54:16.082433Z","caller":"traceutil/trace.go:171","msg":"trace[1634820348] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:625; }","duration":"1.792477353s","start":"2026-02-14T04:54:14.28995Z","end":"2026-02-14T04:54:16.082428Z","steps":["trace[1634820348] 'agreement among raft nodes before linearized reading' (duration: 1.792451142s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:54:16.082448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:54:14.289936Z","time spent":"1.792507653s","remote":"127.0.0.1:38100","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-14T04:54:16.08262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.604274313s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-14T04:54:16.082636Z","caller":"traceutil/trace.go:171","msg":"trace[184940091] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:625; }","duration":"2.604303194s","start":"2026-02-14T04:54:13.478329Z","end":"2026-02-14T04:54:16.082632Z","steps":["trace[184940091] 'agreement among raft nodes before linearized reading' (duration: 2.604238382s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:54:16.082648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:54:13.478322Z","time spent":"2.604323134s","remote":"127.0.0.1:38418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-14T04:54:16.084108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.606978357s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-14T04:54:16.08413Z","caller":"traceutil/trace.go:171","msg":"trace[741643004] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:625; }","duration":"2.607029559s","start":"2026-02-14T04:54:13.477094Z","end":"2026-02-14T04:54:16.084124Z","steps":["trace[741643004] 'agreement among raft nodes before linearized reading' (duration: 2.605004801s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:54:16.084152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:54:13.477079Z","time spent":"2.607069199s","remote":"127.0.0.1:38418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-14T04:54:17.33921Z","caller":"traceutil/trace.go:171","msg":"trace[1329603968] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"151.503857ms","start":"2026-02-14T04:54:17.187683Z","end":"2026-02-14T04:54:17.339187Z","steps":["trace[1329603968] 'process raft request' (duration: 151.114788ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:54:18.031424Z","caller":"traceutil/trace.go:171","msg":"trace[1859117088] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"103.417336ms","start":"2026-02-14T04:54:17.927993Z","end":"2026-02-14T04:54:18.03141Z","steps":["trace[1859117088] 'process raft request' (duration: 103.045617ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:54:18.612114Z","caller":"traceutil/trace.go:171","msg":"trace[1833336769] linearizableReadLoop","detail":"{readStateIndex:683; appliedIndex:682; }","duration":"230.700402ms","start":"2026-02-14T04:54:18.381399Z","end":"2026-02-14T04:54:18.612099Z","steps":["trace[1833336769] 'read index received' (duration: 171.039308ms)","trace[1833336769] 'applied index is now lower than readState.Index' (duration: 59.660234ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:54:18.612543Z","caller":"traceutil/trace.go:171","msg":"trace[1955137943] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"231.654324ms","start":"2026-02-14T04:54:18.380879Z","end":"2026-02-14T04:54:18.612533Z","steps":["trace[1955137943] 'process raft request' (duration: 171.603521ms)","trace[1955137943] 'compare' (duration: 59.359597ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:54:18.612634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.222754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-02-14T04:54:18.615525Z","caller":"traceutil/trace.go:171","msg":"trace[2123787230] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:658; }","duration":"234.156623ms","start":"2026-02-14T04:54:18.381352Z","end":"2026-02-14T04:54:18.615509Z","steps":["trace[2123787230] 'agreement among raft nodes before linearized reading' (duration: 231.209494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:54:18.612754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.292217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"warn","ts":"2026-02-14T04:54:18.612921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.475569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"warn","ts":"2026-02-14T04:54:18.612976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.092871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"warn","ts":"2026-02-14T04:54:18.613031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.98906ms","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":"warn","ts":"2026-02-14T04:54:18.61307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.821967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"warn","ts":"2026-02-14T04:54:18.613099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.530082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-02-14T04:54:18.625773Z","caller":"traceutil/trace.go:171","msg":"trace[398592236] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:658; }","duration":"244.303781ms","start":"2026-02-14T04:54:18.381456Z","end":"2026-02-14T04:54:18.62576Z","steps":["trace[398592236] 'agreement among raft nodes before linearized reading' (duration: 231.257156ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:54:18.625995Z","caller":"traceutil/trace.go:171","msg":"trace[1269480234] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:658; }","duration":"149.128466ms","start":"2026-02-14T04:54:18.476861Z","end":"2026-02-14T04:54:18.625989Z","steps":["trace[1269480234] 'agreement among raft nodes before linearized reading' (duration: 136.069091ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:54:18.626071Z","caller":"traceutil/trace.go:171","msg":"trace[638028625] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:658; }","duration":"244.511256ms","start":"2026-02-14T04:54:18.381552Z","end":"2026-02-14T04:54:18.626064Z","steps":["trace[638028625] 'agreement among raft nodes before linearized reading' (duration: 231.523812ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:54:18.626086Z","caller":"traceutil/trace.go:171","msg":"trace[239031985] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:658; }","duration":"176.057345ms","start":"2026-02-14T04:54:18.450025Z","end":"2026-02-14T04:54:18.626082Z","steps":["trace[239031985] 'agreement among raft nodes before linearized reading' (duration: 162.958499ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:54:18.626097Z","caller":"traceutil/trace.go:171","msg":"trace[440596191] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:658; }","duration":"187.869891ms","start":"2026-02-14T04:54:18.438224Z","end":"2026-02-14T04:54:18.626094Z","steps":["trace[440596191] 'agreement among raft nodes before linearized reading' (duration: 174.814646ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:54:18.625797Z","caller":"traceutil/trace.go:171","msg":"trace[165232480] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:658; }","duration":"143.367181ms","start":"2026-02-14T04:54:18.482425Z","end":"2026-02-14T04:54:18.625792Z","steps":["trace[165232480] 'agreement among raft nodes before linearized reading' (duration: 130.445959ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:54:19.084075Z","caller":"traceutil/trace.go:171","msg":"trace[1416360389] linearizableReadLoop","detail":"{readStateIndex:685; appliedIndex:684; }","duration":"125.668189ms","start":"2026-02-14T04:54:18.958392Z","end":"2026-02-14T04:54:19.08406Z","steps":["trace[1416360389] 'read index received' (duration: 87.166663ms)","trace[1416360389] 'applied index is now lower than readState.Index' (duration: 38.501006ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:54:19.084325Z","caller":"traceutil/trace.go:171","msg":"trace[730679251] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"138.031038ms","start":"2026-02-14T04:54:18.946284Z","end":"2026-02-14T04:54:19.084315Z","steps":["trace[730679251] 'process raft request' (duration: 99.327608ms)","trace[730679251] 'compare' (duration: 38.106696ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:54:19.084439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.036128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.141\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-14T04:54:19.089184Z","caller":"traceutil/trace.go:171","msg":"trace[203384969] range","detail":"{range_begin:/registry/masterleases/199.204.45.141; range_end:; response_count:1; response_revision:660; }","duration":"130.806709ms","start":"2026-02-14T04:54:18.958369Z","end":"2026-02-14T04:54:19.089176Z","steps":["trace[203384969] 'agreement among raft nodes before linearized reading' (duration: 125.973407ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:54:19.522217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.015578ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T04:54:19.522414Z","caller":"traceutil/trace.go:171","msg":"trace[1357846886] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"407.212246ms","start":"2026-02-14T04:54:19.115186Z","end":"2026-02-14T04:54:19.522398Z","steps":["trace[1357846886] 'process raft request' (duration: 220.951632ms)","trace[1357846886] 'compare' (duration: 185.928146ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:54:19.522527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:54:19.115177Z","time spent":"407.326289ms","remote":"127.0.0.1:38134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:54:19.572631Z","caller":"traceutil/trace.go:171","msg":"trace[643909788] linearizableReadLoop","detail":"{readStateIndex:688; appliedIndex:686; }","duration":"278.785418ms","start":"2026-02-14T04:54:19.29383Z","end":"2026-02-14T04:54:19.572615Z","steps":["trace[643909788] 'read index received' (duration: 42.269734ms)","trace[643909788] 'applied index is now lower than readState.Index' (duration: 236.515154ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:54:19.572943Z","caller":"traceutil/trace.go:171","msg":"trace[42790018] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"453.213106ms","start":"2026-02-14T04:54:19.119724Z","end":"2026-02-14T04:54:19.572937Z","steps":["trace[42790018] 'process raft request' (duration: 452.828637ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:54:19.5731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:54:19.119709Z","time spent":"453.34243ms","remote":"127.0.0.1:38328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6778,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:54:19.573238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.406302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:54:19.573291Z","caller":"traceutil/trace.go:171","msg":"trace[640459358] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:662; }","duration":"279.453503ms","start":"2026-02-14T04:54:19.293826Z","end":"2026-02-14T04:54:19.57328Z","steps":["trace[640459358] 'agreement among raft nodes before linearized reading' (duration: 279.389072ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:54:19.573452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.799127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-14T04:54:19.573529Z","caller":"traceutil/trace.go:171","msg":"trace[912651453] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:662; }","duration":"232.89878ms","start":"2026-02-14T04:54:19.340624Z","end":"2026-02-14T04:54:19.573523Z","steps":["trace[912651453] 'agreement among raft nodes before linearized reading' (duration: 232.773717ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:54:20.102095Z","caller":"traceutil/trace.go:171","msg":"trace[2113214441] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"178.986145ms","start":"2026-02-14T04:54:19.923089Z","end":"2026-02-14T04:54:20.102075Z","steps":["trace[2113214441] 'process raft request' (duration: 107.032798ms)","trace[2113214441] 'compare' (duration: 71.127568ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:54:20.102122Z","caller":"traceutil/trace.go:171","msg":"trace[1630490505] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"122.500771ms","start":"2026-02-14T04:54:19.9796Z","end":"2026-02-14T04:54:20.1021Z","steps":["trace[1630490505] 'process raft request' (duration: 122.427499ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:54:20.981978Z","caller":"traceutil/trace.go:171","msg":"trace[899070890] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"110.833757ms","start":"2026-02-14T04:54:20.871126Z","end":"2026-02-14T04:54:20.98196Z","steps":["trace[899070890] 'process raft request' (duration: 110.731945ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:54:21.118866Z","caller":"traceutil/trace.go:171","msg":"trace[1992713910] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"224.231693ms","start":"2026-02-14T04:54:20.894391Z","end":"2026-02-14T04:54:21.118623Z","steps":["trace[1992713910] 'process raft request' (duration: 205.077271ms)","trace[1992713910] 'compare' (duration: 19.031389ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:54:22.41357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.661797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-14T04:54:22.413642Z","caller":"traceutil/trace.go:171","msg":"trace[1139750068] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:685; }","duration":"139.772399ms","start":"2026-02-14T04:54:22.273851Z","end":"2026-02-14T04:54:22.413624Z","steps":["trace[1139750068] 'range keys from in-memory index tree' (duration: 139.491793ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:54:22.413818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.664863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:54:22.413845Z","caller":"traceutil/trace.go:171","msg":"trace[1599482468] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:685; }","duration":"124.704774ms","start":"2026-02-14T04:54:22.289131Z","end":"2026-02-14T04:54:22.413836Z","steps":["trace[1599482468] 'range keys from in-memory index tree' (duration: 124.634413ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:54:36.428091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.511631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:54:36.428144Z","caller":"traceutil/trace.go:171","msg":"trace[1432138211] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:733; }","duration":"140.594774ms","start":"2026-02-14T04:54:36.287538Z","end":"2026-02-14T04:54:36.428132Z","steps":["trace[1432138211] 'range keys from in-memory index tree' (duration: 140.46451ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:54:36.846427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.274151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-14T04:54:36.846497Z","caller":"traceutil/trace.go:171","msg":"trace[345104227] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:735; }","duration":"103.386064ms","start":"2026-02-14T04:54:36.743095Z","end":"2026-02-14T04:54:36.846481Z","steps":["trace[345104227] 'range keys from in-memory index tree' (duration: 103.121578ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:54:37.186734Z","caller":"traceutil/trace.go:171","msg":"trace[1484468054] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"144.434415ms","start":"2026-02-14T04:54:37.042278Z","end":"2026-02-14T04:54:37.186712Z","steps":["trace[1484468054] 'process raft request' (duration: 144.311793ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:54:42.166001Z","caller":"traceutil/trace.go:171","msg":"trace[2082863754] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"149.346545ms","start":"2026-02-14T04:54:42.016641Z","end":"2026-02-14T04:54:42.165987Z","steps":["trace[2082863754] 'process raft request' (duration: 147.623708ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:54:43.039549Z","caller":"traceutil/trace.go:171","msg":"trace[1446831411] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"108.188797ms","start":"2026-02-14T04:54:42.931334Z","end":"2026-02-14T04:54:43.039523Z","steps":["trace[1446831411] 'process raft request' (duration: 108.059365ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:54:47.790671Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991526780465728064,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-14T04:54:48.291579Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991526780465728064,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-14T04:54:48.421682Z","caller":"traceutil/trace.go:171","msg":"trace[1000792353] linearizableReadLoop","detail":"{readStateIndex:867; appliedIndex:866; }","duration":"1.131757537s","start":"2026-02-14T04:54:47.289908Z","end":"2026-02-14T04:54:48.421665Z","steps":["trace[1000792353] 'read index received' (duration: 1.131585994s)","trace[1000792353] 'applied index is now lower than readState.Index' (duration: 170.913µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:54:48.421924Z","caller":"traceutil/trace.go:171","msg":"trace[776669866] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"1.332343023s","start":"2026-02-14T04:54:47.089572Z","end":"2026-02-14T04:54:48.421915Z","steps":["trace[776669866] 'process raft request' (duration: 1.331992765s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:54:48.421992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:54:47.08956Z","time spent":"1.332382653s","remote":"127.0.0.1:38418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:54:48.422123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.132212977s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:54:48.422144Z","caller":"traceutil/trace.go:171","msg":"trace[1033998052] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:834; }","duration":"1.132253617s","start":"2026-02-14T04:54:47.289883Z","end":"2026-02-14T04:54:48.422137Z","steps":["trace[1033998052] 'agreement among raft nodes before linearized reading' (duration: 1.132212296s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:54:48.422161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:54:47.289872Z","time spent":"1.132285419s","remote":"127.0.0.1:38088","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-14T04:54:48.422304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"949.993487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-14T04:54:48.422325Z","caller":"traceutil/trace.go:171","msg":"trace[131289691] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:834; }","duration":"950.041607ms","start":"2026-02-14T04:54:47.472276Z","end":"2026-02-14T04:54:48.422318Z","steps":["trace[131289691] 'agreement among raft nodes before linearized reading' (duration: 949.972236ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:54:48.422404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:54:47.472262Z","time spent":"950.13618ms","remote":"127.0.0.1:38418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-14T04:54:48.422584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.024402ms","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-14T04:54:48.422608Z","caller":"traceutil/trace.go:171","msg":"trace[1820343360] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:834; }","duration":"691.087133ms","start":"2026-02-14T04:54:47.731515Z","end":"2026-02-14T04:54:48.422603Z","steps":["trace[1820343360] 'agreement among raft nodes before linearized reading' (duration: 691.004001ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:54:48.422624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:54:47.731494Z","time spent":"691.125954ms","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":120,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-02-14T04:54:52.50492Z","caller":"traceutil/trace.go:171","msg":"trace[819963951] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"142.415193ms","start":"2026-02-14T04:54:52.362487Z","end":"2026-02-14T04:54:52.504902Z","steps":["trace[819963951] 'process raft request' (duration: 141.939183ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:54:52.71607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.576234ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T04:54:52.716835Z","caller":"traceutil/trace.go:171","msg":"trace[1623931037] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"169.533271ms","start":"2026-02-14T04:54:52.547279Z","end":"2026-02-14T04:54:52.716812Z","steps":["trace[1623931037] 'process raft request' (duration: 28.990837ms)","trace[1623931037] 'compare' (duration: 139.465531ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:54:52.716785Z","caller":"traceutil/trace.go:171","msg":"trace[779230946] linearizableReadLoop","detail":"{readStateIndex:888; appliedIndex:887; }","duration":"168.381487ms","start":"2026-02-14T04:54:52.548381Z","end":"2026-02-14T04:54:52.716763Z","steps":["trace[779230946] 'read index received' (duration: 27.850803ms)","trace[779230946] 'applied index is now lower than readState.Index' (duration: 140.528824ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:54:52.717606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.219774ms","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-14T04:54:52.718307Z","caller":"traceutil/trace.go:171","msg":"trace[295186004] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:854; }","duration":"169.945439ms","start":"2026-02-14T04:54:52.548347Z","end":"2026-02-14T04:54:52.718293Z","steps":["trace[295186004] 'agreement among raft nodes before linearized reading' (duration: 169.119432ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:55:01.144904Z","caller":"traceutil/trace.go:171","msg":"trace[125528967] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"155.43155ms","start":"2026-02-14T04:55:00.989451Z","end":"2026-02-14T04:55:01.144883Z","steps":["trace[125528967] 'process raft request' (duration: 56.672938ms)","trace[125528967] 'compare' (duration: 98.384845ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:55:07.199176Z","caller":"traceutil/trace.go:171","msg":"trace[1837675121] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"192.988723ms","start":"2026-02-14T04:55:07.006149Z","end":"2026-02-14T04:55:07.199138Z","steps":["trace[1837675121] 'process raft request' (duration: 163.380429ms)","trace[1837675121] 'compare' (duration: 29.283788ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:55:15.47118Z","caller":"traceutil/trace.go:171","msg":"trace[288992534] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"116.455808ms","start":"2026-02-14T04:55:15.354705Z","end":"2026-02-14T04:55:15.471161Z","steps":["trace[288992534] 'process raft request' (duration: 116.342936ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:55:21.703777Z","caller":"traceutil/trace.go:171","msg":"trace[958287176] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"112.776609ms","start":"2026-02-14T04:55:21.590985Z","end":"2026-02-14T04:55:21.703761Z","steps":["trace[958287176] 'process raft request' (duration: 27.518436ms)","trace[958287176] 'compare' (duration: 84.973297ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:55:21.93695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.169485ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-14T04:55:21.937054Z","caller":"traceutil/trace.go:171","msg":"trace[1185566990] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"223.875876ms","start":"2026-02-14T04:55:21.71316Z","end":"2026-02-14T04:55:21.937036Z","steps":["trace[1185566990] 'process raft request' (duration: 120.551928ms)","trace[1185566990] 'compare' (duration: 102.973941ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:55:22.669164Z","caller":"traceutil/trace.go:171","msg":"trace[2026255394] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"103.149291ms","start":"2026-02-14T04:55:22.565988Z","end":"2026-02-14T04:55:22.669138Z","steps":["trace[2026255394] 'process raft request' (duration: 102.807384ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:55:22.965013Z","caller":"traceutil/trace.go:171","msg":"trace[1565671289] linearizableReadLoop","detail":"{readStateIndex:984; appliedIndex:983; }","duration":"222.670905ms","start":"2026-02-14T04:55:22.742318Z","end":"2026-02-14T04:55:22.964989Z","steps":["trace[1565671289] 'read index received' (duration: 190.51316ms)","trace[1565671289] 'applied index is now lower than readState.Index' (duration: 32.156795ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:55:22.965042Z","caller":"traceutil/trace.go:171","msg":"trace[1260083442] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"228.073388ms","start":"2026-02-14T04:55:22.736934Z","end":"2026-02-14T04:55:22.965008Z","steps":["trace[1260083442] 'process raft request' (duration: 195.923714ms)","trace[1260083442] 'compare' (duration: 31.995231ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:55:22.965518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.139084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:55:22.96558Z","caller":"traceutil/trace.go:171","msg":"trace[1600000307] range","detail":"{range_begin:/registry/limitranges/local-path-storage/; range_end:/registry/limitranges/local-path-storage0; response_count:0; response_revision:943; }","duration":"223.269106ms","start":"2026-02-14T04:55:22.742296Z","end":"2026-02-14T04:55:22.965565Z","steps":["trace[1600000307] 'agreement among raft nodes before linearized reading' (duration: 222.812417ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:55:23.300624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.85008ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T04:55:23.300961Z","caller":"traceutil/trace.go:171","msg":"trace[617738564] linearizableReadLoop","detail":"{readStateIndex:987; appliedIndex:986; }","duration":"285.853011ms","start":"2026-02-14T04:55:23.01509Z","end":"2026-02-14T04:55:23.300943Z","steps":["trace[617738564] 'read index received' (duration: 128.544941ms)","trace[617738564] 'applied index is now lower than readState.Index' (duration: 157.30615ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:55:23.30106Z","caller":"traceutil/trace.go:171","msg":"trace[2091947184] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"290.057681ms","start":"2026-02-14T04:55:23.010975Z","end":"2026-02-14T04:55:23.301032Z","steps":["trace[2091947184] 'process raft request' (duration: 132.735481ms)","trace[2091947184] 'compare' (duration: 156.748849ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:55:23.301289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.195527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-16905ed1-d8cb-4ddf-914d-e54eb8518dcc\" ","response":"range_response_count:1 size:3535"} {"level":"info","ts":"2026-02-14T04:55:23.301454Z","caller":"traceutil/trace.go:171","msg":"trace[139692276] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-16905ed1-d8cb-4ddf-914d-e54eb8518dcc; range_end:; response_count:1; response_revision:946; }","duration":"286.36156ms","start":"2026-02-14T04:55:23.015054Z","end":"2026-02-14T04:55:23.301416Z","steps":["trace[139692276] 'agreement among raft nodes before linearized reading' (duration: 286.163036ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:55:23.301819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.091845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-14T04:55:23.304678Z","caller":"traceutil/trace.go:171","msg":"trace[1699967899] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:946; }","duration":"234.94595ms","start":"2026-02-14T04:55:23.069717Z","end":"2026-02-14T04:55:23.304663Z","steps":["trace[1699967899] 'agreement among raft nodes before linearized reading' (duration: 232.022314ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:55:23.707514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.441633ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T04:55:23.707578Z","caller":"traceutil/trace.go:171","msg":"trace[217295548] linearizableReadLoop","detail":"{readStateIndex:989; appliedIndex:988; }","duration":"235.893927ms","start":"2026-02-14T04:55:23.471672Z","end":"2026-02-14T04:55:23.707566Z","steps":["trace[217295548] 'read index received' (duration: 62.961µs)","trace[217295548] 'applied index is now lower than readState.Index' (duration: 235.830246ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:55:23.707832Z","caller":"traceutil/trace.go:171","msg":"trace[796438485] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"397.168822ms","start":"2026-02-14T04:55:23.310654Z","end":"2026-02-14T04:55:23.707823Z","steps":["trace[796438485] 'process raft request' (duration: 155.367342ms)","trace[796438485] 'compare' (duration: 241.26065ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:55:23.70787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:55:23.31065Z","time spent":"397.202483ms","remote":"127.0.0.1:38418","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-14T04:55:23.708012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.341056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/test-pvc.189403f94b37b6de\" ","response":"range_response_count:1 size:915"} {"level":"info","ts":"2026-02-14T04:55:23.70803Z","caller":"traceutil/trace.go:171","msg":"trace[1064814417] range","detail":"{range_begin:/registry/events/default/test-pvc.189403f94b37b6de; range_end:; response_count:1; response_revision:948; }","duration":"236.422508ms","start":"2026-02-14T04:55:23.471603Z","end":"2026-02-14T04:55:23.708025Z","steps":["trace[1064814417] 'agreement among raft nodes before linearized reading' (duration: 236.355537ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:55:23.708122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.105575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-14T04:55:23.708139Z","caller":"traceutil/trace.go:171","msg":"trace[1727013426] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:948; }","duration":"158.150175ms","start":"2026-02-14T04:55:23.549985Z","end":"2026-02-14T04:55:23.708135Z","steps":["trace[1727013426] 'agreement among raft nodes before linearized reading' (duration: 158.111565ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:55:24.025709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.533159ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T04:55:24.025835Z","caller":"traceutil/trace.go:171","msg":"trace[1980572365] linearizableReadLoop","detail":"{readStateIndex:992; appliedIndex:989; }","duration":"307.656086ms","start":"2026-02-14T04:55:23.718169Z","end":"2026-02-14T04:55:24.025825Z","steps":["trace[1980572365] 'read index received' (duration: 54.840316ms)","trace[1980572365] 'applied index is now lower than readState.Index' (duration: 252.81522ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:55:24.025977Z","caller":"traceutil/trace.go:171","msg":"trace[694490851] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"313.678361ms","start":"2026-02-14T04:55:23.712276Z","end":"2026-02-14T04:55:24.025954Z","steps":["trace[694490851] 'process raft request' (duration: 119.805605ms)","trace[694490851] 'compare' (duration: 193.384877ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:55:24.026095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:55:23.712268Z","time spent":"313.755222ms","remote":"127.0.0.1:38418","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-14T04:55:24.026281Z","caller":"traceutil/trace.go:171","msg":"trace[840037296] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"313.673201ms","start":"2026-02-14T04:55:23.712601Z","end":"2026-02-14T04:55:24.026274Z","steps":["trace[840037296] 'process raft request' (duration: 313.192202ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:55:24.026318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:55:23.71258Z","time spent":"313.716562ms","remote":"127.0.0.1:38418","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-14T04:55:24.025884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.715037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:55:24.02707Z","caller":"traceutil/trace.go:171","msg":"trace[864468163] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:951; }","duration":"308.91874ms","start":"2026-02-14T04:55:23.718143Z","end":"2026-02-14T04:55:24.027062Z","steps":["trace[864468163] 'agreement among raft nodes before linearized reading' (duration: 307.708347ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:55:24.027097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:55:23.718132Z","time spent":"308.954811ms","remote":"127.0.0.1:38088","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-14T04:55:24.83013Z","caller":"traceutil/trace.go:171","msg":"trace[1334105347] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"123.212725ms","start":"2026-02-14T04:55:24.706896Z","end":"2026-02-14T04:55:24.830109Z","steps":["trace[1334105347] 'process raft request' (duration: 123.079212ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:55:30.228608Z","caller":"traceutil/trace.go:171","msg":"trace[1384387346] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"169.779335ms","start":"2026-02-14T04:55:30.058799Z","end":"2026-02-14T04:55:30.228578Z","steps":["trace[1384387346] 'process raft request' (duration: 88.989184ms)","trace[1384387346] 'compare' (duration: 80.609677ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:55:30.228823Z","caller":"traceutil/trace.go:171","msg":"trace[594838977] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"170.013649ms","start":"2026-02-14T04:55:30.058799Z","end":"2026-02-14T04:55:30.228813Z","steps":["trace[594838977] 'process raft request' (duration: 169.720883ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:55:31.795017Z","caller":"traceutil/trace.go:171","msg":"trace[1153189854] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"387.137832ms","start":"2026-02-14T04:55:31.407857Z","end":"2026-02-14T04:55:31.794995Z","steps":["trace[1153189854] 'process raft request' (duration: 386.585812ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:55:31.795137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:55:31.407839Z","time spent":"387.236144ms","remote":"127.0.0.1:38418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:55:39.293307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.051513ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T04:55:39.293391Z","caller":"traceutil/trace.go:171","msg":"trace[174530253] linearizableReadLoop","detail":"{readStateIndex:1064; appliedIndex:1063; }","duration":"229.88909ms","start":"2026-02-14T04:55:39.063481Z","end":"2026-02-14T04:55:39.29337Z","steps":["trace[174530253] 'read index received' (duration: 122.660773ms)","trace[174530253] 'applied index is now lower than readState.Index' (duration: 107.227307ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:55:39.293456Z","caller":"traceutil/trace.go:171","msg":"trace[816434386] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"239.06406ms","start":"2026-02-14T04:55:39.054383Z","end":"2026-02-14T04:55:39.293447Z","steps":["trace[816434386] 'process raft request' (duration: 131.816063ms)","trace[816434386] 'compare' (duration: 106.964712ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:55:39.293767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.264606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-14T04:55:39.293815Z","caller":"traceutil/trace.go:171","msg":"trace[1474879975] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1018; }","duration":"230.350159ms","start":"2026-02-14T04:55:39.063455Z","end":"2026-02-14T04:55:39.293805Z","steps":["trace[1474879975] 'agreement among raft nodes before linearized reading' (duration: 230.229856ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:55:39.954658Z","caller":"traceutil/trace.go:171","msg":"trace[1179911870] linearizableReadLoop","detail":"{readStateIndex:1065; appliedIndex:1064; }","duration":"124.755152ms","start":"2026-02-14T04:55:39.829882Z","end":"2026-02-14T04:55:39.954637Z","steps":["trace[1179911870] 'read index received' (duration: 124.590209ms)","trace[1179911870] 'applied index is now lower than readState.Index' (duration: 164.343µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:55:39.954916Z","caller":"traceutil/trace.go:171","msg":"trace[905456824] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"652.748377ms","start":"2026-02-14T04:55:39.302159Z","end":"2026-02-14T04:55:39.954907Z","steps":["trace[905456824] 'process raft request' (duration: 652.366199ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:55:39.954987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:55:39.302145Z","time spent":"652.790727ms","remote":"127.0.0.1:38418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:55:39.955156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.271411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-14T04:55:39.955178Z","caller":"traceutil/trace.go:171","msg":"trace[1571358962] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1019; }","duration":"125.326072ms","start":"2026-02-14T04:55:39.829844Z","end":"2026-02-14T04:55:39.95517Z","steps":["trace[1571358962] 'agreement among raft nodes before linearized reading' (duration: 125.252211ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:55:39.955313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.522121ms","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-14T04:55:39.955332Z","caller":"traceutil/trace.go:171","msg":"trace[1147060552] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1019; }","duration":"108.569172ms","start":"2026-02-14T04:55:39.846758Z","end":"2026-02-14T04:55:39.955327Z","steps":["trace[1147060552] 'agreement among raft nodes before linearized reading' (duration: 108.516291ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:55:40.151123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.925499ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T04:55:40.151254Z","caller":"traceutil/trace.go:171","msg":"trace[1162793136] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"188.580851ms","start":"2026-02-14T04:55:39.962663Z","end":"2026-02-14T04:55:40.151244Z","steps":["trace[1162793136] 'process raft request' (duration: 188.52605ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:55:40.151678Z","caller":"traceutil/trace.go:171","msg":"trace[2091810852] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"190.439995ms","start":"2026-02-14T04:55:39.961229Z","end":"2026-02-14T04:55:40.151669Z","steps":["trace[2091810852] 'process raft request' (duration: 22.909615ms)","trace[2091810852] 'compare' (duration: 166.721305ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:55:40.151909Z","caller":"traceutil/trace.go:171","msg":"trace[538857894] linearizableReadLoop","detail":"{readStateIndex:1066; appliedIndex:1065; }","duration":"190.474266ms","start":"2026-02-14T04:55:39.961429Z","end":"2026-02-14T04:55:40.151903Z","steps":["trace[538857894] 'read index received' (duration: 22.721641ms)","trace[538857894] 'applied index is now lower than readState.Index' (duration: 167.752005ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:55:40.152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.567797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:55:40.152033Z","caller":"traceutil/trace.go:171","msg":"trace[549671701] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1021; }","duration":"190.619408ms","start":"2026-02-14T04:55:39.961408Z","end":"2026-02-14T04:55:40.152027Z","steps":["trace[549671701] 'agreement among raft nodes before linearized reading' (duration: 190.571147ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:55:40.390543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.25494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-14T04:55:40.390596Z","caller":"traceutil/trace.go:171","msg":"trace[1696675808] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1021; }","duration":"123.349632ms","start":"2026-02-14T04:55:40.267235Z","end":"2026-02-14T04:55:40.390584Z","steps":["trace[1696675808] 'range keys from in-memory index tree' (duration: 123.104067ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:55:40.390998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.635549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-14T04:55:40.391026Z","caller":"traceutil/trace.go:171","msg":"trace[1148627317] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1021; }","duration":"122.70212ms","start":"2026-02-14T04:55:40.268317Z","end":"2026-02-14T04:55:40.391019Z","steps":["trace[1148627317] 'range keys from in-memory index tree' (duration: 122.560477ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:55:40.391172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.436834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:55:40.391287Z","caller":"traceutil/trace.go:171","msg":"trace[1429231062] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1021; }","duration":"103.604946ms","start":"2026-02-14T04:55:40.287663Z","end":"2026-02-14T04:55:40.391267Z","steps":["trace[1429231062] 'range keys from in-memory index tree' (duration: 103.358922ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:55:40.896405Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991526780465728593,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-14T04:55:41.397691Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991526780465728593,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-14T04:55:41.624244Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.27660306s","expected-duration":"1s"} {"level":"info","ts":"2026-02-14T04:55:41.62481Z","caller":"traceutil/trace.go:171","msg":"trace[256533958] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"1.277228871s","start":"2026-02-14T04:55:40.347558Z","end":"2026-02-14T04:55:41.624786Z","steps":["trace[256533958] 'process raft request' (duration: 1.277091148s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:55:41.625048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:55:40.34754Z","time spent":"1.277325953s","remote":"127.0.0.1:38418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:55:41.78622Z","caller":"traceutil/trace.go:171","msg":"trace[729467593] linearizableReadLoop","detail":"{readStateIndex:1070; appliedIndex:1068; }","duration":"1.390495673s","start":"2026-02-14T04:55:40.395707Z","end":"2026-02-14T04:55:41.786203Z","steps":["trace[729467593] 'read index received' (duration: 1.230613049s)","trace[729467593] 'applied index is now lower than readState.Index' (duration: 159.881984ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:55:41.786326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.390599065s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:55:41.786342Z","caller":"traceutil/trace.go:171","msg":"trace[305986354] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1024; }","duration":"1.390650715s","start":"2026-02-14T04:55:40.395686Z","end":"2026-02-14T04:55:41.786337Z","steps":["trace[305986354] 'agreement among raft nodes before linearized reading' (duration: 1.390584484s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:55:41.786363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:55:40.395679Z","time spent":"1.390679556s","remote":"127.0.0.1:38100","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-14T04:55:41.786732Z","caller":"traceutil/trace.go:171","msg":"trace[146367786] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"1.390729457s","start":"2026-02-14T04:55:40.395955Z","end":"2026-02-14T04:55:41.786685Z","steps":["trace[146367786] 'process raft request' (duration: 1.390216837s)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:55:41.78682Z","caller":"traceutil/trace.go:171","msg":"trace[1145525269] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"1.391244386s","start":"2026-02-14T04:55:40.395566Z","end":"2026-02-14T04:55:41.78681Z","steps":["trace[1145525269] 'process raft request' (duration: 1.309397444s)","trace[1145525269] 'compare' (duration: 81.096238ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:55:41.786891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:55:40.395544Z","time spent":"1.391299057s","remote":"127.0.0.1:38418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:55:41.786941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:55:40.395945Z","time spent":"1.390878259s","remote":"127.0.0.1:38418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:55:46.293363Z","caller":"traceutil/trace.go:171","msg":"trace[1665968364] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"104.835302ms","start":"2026-02-14T04:55:46.188512Z","end":"2026-02-14T04:55:46.293347Z","steps":["trace[1665968364] 'process raft request' (duration: 104.639678ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:55:47.95938Z","caller":"traceutil/trace.go:171","msg":"trace[1124199757] linearizableReadLoop","detail":"{readStateIndex:1090; appliedIndex:1089; }","duration":"121.255989ms","start":"2026-02-14T04:55:47.8381Z","end":"2026-02-14T04:55:47.959356Z","steps":["trace[1124199757] 'read index received' (duration: 121.086676ms)","trace[1124199757] 'applied index is now lower than readState.Index' (duration: 168.323µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:55:47.95967Z","caller":"traceutil/trace.go:171","msg":"trace[205799779] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"126.321003ms","start":"2026-02-14T04:55:47.833338Z","end":"2026-02-14T04:55:47.959659Z","steps":["trace[205799779] 'process raft request' (duration: 125.907485ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:55:47.9599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.786419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-14T04:55:47.959932Z","caller":"traceutil/trace.go:171","msg":"trace[1920051133] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1043; }","duration":"121.843141ms","start":"2026-02-14T04:55:47.838078Z","end":"2026-02-14T04:55:47.959921Z","steps":["trace[1920051133] 'agreement among raft nodes before linearized reading' (duration: 121.729689ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:55:47.960078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.469256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/kubeadm:get-nodes\" ","response":"range_response_count:1 size:305"} {"level":"info","ts":"2026-02-14T04:55:47.960103Z","caller":"traceutil/trace.go:171","msg":"trace[2072842036] range","detail":"{range_begin:/registry/clusterroles/kubeadm:get-nodes; range_end:; response_count:1; response_revision:1043; }","duration":"114.511396ms","start":"2026-02-14T04:55:47.845584Z","end":"2026-02-14T04:55:47.960095Z","steps":["trace[2072842036] 'agreement among raft nodes before linearized reading' (duration: 114.465685ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:55:52.234328Z","caller":"traceutil/trace.go:171","msg":"trace[708829573] linearizableReadLoop","detail":"{readStateIndex:1103; appliedIndex:1102; }","duration":"161.045779ms","start":"2026-02-14T04:55:52.073262Z","end":"2026-02-14T04:55:52.234307Z","steps":["trace[708829573] 'read index received' (duration: 160.886697ms)","trace[708829573] 'applied index is now lower than readState.Index' (duration: 158.122µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:55:52.234546Z","caller":"traceutil/trace.go:171","msg":"trace[1985334513] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"187.095793ms","start":"2026-02-14T04:55:52.047408Z","end":"2026-02-14T04:55:52.234504Z","steps":["trace[1985334513] 'process raft request' (duration: 186.759497ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:55:52.234687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.679052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-14T04:55:52.234744Z","caller":"traceutil/trace.go:171","msg":"trace[520167954] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1055; }","duration":"160.743753ms","start":"2026-02-14T04:55:52.073983Z","end":"2026-02-14T04:55:52.234727Z","steps":["trace[520167954] 'agreement among raft nodes before linearized reading' (duration: 160.59094ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:55:52.234877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.61125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-14T04:55:52.234901Z","caller":"traceutil/trace.go:171","msg":"trace[1449044144] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1055; }","duration":"161.6585ms","start":"2026-02-14T04:55:52.073235Z","end":"2026-02-14T04:55:52.234893Z","steps":["trace[1449044144] 'agreement among raft nodes before linearized reading' (duration: 161.596179ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:55:52.234985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.428554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:job-controller\" ","response":"range_response_count:1 size:782"} {"level":"info","ts":"2026-02-14T04:55:52.235018Z","caller":"traceutil/trace.go:171","msg":"trace[1803917413] range","detail":"{range_begin:/registry/clusterroles/system:controller:job-controller; range_end:; response_count:1; response_revision:1055; }","duration":"124.465374ms","start":"2026-02-14T04:55:52.110533Z","end":"2026-02-14T04:55:52.234998Z","steps":["trace[1803917413] 'agreement among raft nodes before linearized reading' (duration: 124.423283ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:55:52.235178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.912666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:914"} {"level":"info","ts":"2026-02-14T04:55:52.235206Z","caller":"traceutil/trace.go:171","msg":"trace[218370937] range","detail":"{range_begin:/registry/clusterroles/system:controller:horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:1055; }","duration":"144.959267ms","start":"2026-02-14T04:55:52.090235Z","end":"2026-02-14T04:55:52.235194Z","steps":["trace[218370937] 'agreement among raft nodes before linearized reading' (duration: 144.915746ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:55:52.677518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.236353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:namespace-controller\" ","response":"range_response_count:1 size:725"} {"level":"info","ts":"2026-02-14T04:55:52.678046Z","caller":"traceutil/trace.go:171","msg":"trace[745229671] range","detail":"{range_begin:/registry/clusterroles/system:controller:namespace-controller; range_end:; response_count:1; response_revision:1059; }","duration":"232.802203ms","start":"2026-02-14T04:55:52.445211Z","end":"2026-02-14T04:55:52.678013Z","steps":["trace[745229671] 'range keys from in-memory index tree' (duration: 232.176461ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:55:52.677874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.689822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:ephemeral-volume-controller\" ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-02-14T04:55:52.678417Z","caller":"traceutil/trace.go:171","msg":"trace[577974471] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:ephemeral-volume-controller; range_end:; response_count:1; response_revision:1059; }","duration":"172.272992ms","start":"2026-02-14T04:55:52.506124Z","end":"2026-02-14T04:55:52.678397Z","steps":["trace[577974471] 'range keys from in-memory index tree' (duration: 171.61288ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:55:54.455064Z","caller":"traceutil/trace.go:171","msg":"trace[931273910] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"102.198454ms","start":"2026-02-14T04:55:54.352836Z","end":"2026-02-14T04:55:54.455034Z","steps":["trace[931273910] 'process raft request' (duration: 59.702213ms)","trace[931273910] 'compare' (duration: 42.321197ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:55:56.409882Z","caller":"traceutil/trace.go:171","msg":"trace[570507402] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"103.38972ms","start":"2026-02-14T04:55:56.30647Z","end":"2026-02-14T04:55:56.40986Z","steps":["trace[570507402] 'process raft request' (duration: 44.361003ms)","trace[570507402] 'compare' (duration: 58.501988ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:55:56.571519Z","caller":"traceutil/trace.go:171","msg":"trace[1473772983] linearizableReadLoop","detail":"{readStateIndex:1118; appliedIndex:1117; }","duration":"111.658409ms","start":"2026-02-14T04:55:56.459841Z","end":"2026-02-14T04:55:56.571499Z","steps":["trace[1473772983] 'read index received' (duration: 74.581799ms)","trace[1473772983] 'applied index is now lower than readState.Index' (duration: 37.0757ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:55:56.571827Z","caller":"traceutil/trace.go:171","msg":"trace[1343191933] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"152.861895ms","start":"2026-02-14T04:55:56.418946Z","end":"2026-02-14T04:55:56.571808Z","steps":["trace[1343191933] 'process raft request' (duration: 115.500849ms)","trace[1343191933] 'compare' (duration: 36.946319ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:55:56.572191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.337102ms","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-14T04:55:56.572395Z","caller":"traceutil/trace.go:171","msg":"trace[1512261363] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1069; }","duration":"112.546386ms","start":"2026-02-14T04:55:56.459805Z","end":"2026-02-14T04:55:56.572351Z","steps":["trace[1512261363] 'agreement among raft nodes before linearized reading' (duration: 112.268521ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:00.836722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.238178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"warn","ts":"2026-02-14T04:56:00.836817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.272471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/default/\" range_end:\"/registry/jobs/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:56:00.836836Z","caller":"traceutil/trace.go:171","msg":"trace[83355131] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1082; }","duration":"103.444142ms","start":"2026-02-14T04:56:00.733365Z","end":"2026-02-14T04:56:00.836809Z","steps":["trace[83355131] 'range keys from in-memory index tree' (duration: 103.148676ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:56:00.83689Z","caller":"traceutil/trace.go:171","msg":"trace[1633015154] range","detail":"{range_begin:/registry/jobs/default/; range_end:/registry/jobs/default0; response_count:0; response_revision:1082; }","duration":"137.385923ms","start":"2026-02-14T04:56:00.699486Z","end":"2026-02-14T04:56:00.836872Z","steps":["trace[1633015154] 'range keys from in-memory index tree' (duration: 137.198099ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:00.837575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.450684ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:56:00.837668Z","caller":"traceutil/trace.go:171","msg":"trace[2014948436] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1082; }","duration":"132.551286ms","start":"2026-02-14T04:56:00.705105Z","end":"2026-02-14T04:56:00.837656Z","steps":["trace[2014948436] 'range keys from in-memory index tree' (duration: 132.440004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:00.836718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.265173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/kube-node-lease/\" range_end:\"/registry/ingress/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:56:00.838654Z","caller":"traceutil/trace.go:171","msg":"trace[1119383077] range","detail":"{range_begin:/registry/ingress/kube-node-lease/; range_end:/registry/ingress/kube-node-lease0; response_count:0; response_revision:1082; }","duration":"138.262298ms","start":"2026-02-14T04:56:00.700375Z","end":"2026-02-14T04:56:00.838637Z","steps":["trace[1119383077] 'range keys from in-memory index tree' (duration: 136.205161ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:56:02.543694Z","caller":"traceutil/trace.go:171","msg":"trace[1545543202] linearizableReadLoop","detail":"{readStateIndex:1134; appliedIndex:1133; }","duration":"219.023522ms","start":"2026-02-14T04:56:02.324646Z","end":"2026-02-14T04:56:02.54367Z","steps":["trace[1545543202] 'read index received' (duration: 136.807146ms)","trace[1545543202] 'applied index is now lower than readState.Index' (duration: 82.215476ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:56:02.544299Z","caller":"traceutil/trace.go:171","msg":"trace[1142078246] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"223.681116ms","start":"2026-02-14T04:56:02.320602Z","end":"2026-02-14T04:56:02.544283Z","steps":["trace[1142078246] 'process raft request' (duration: 140.924351ms)","trace[1142078246] 'compare' (duration: 82.035092ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:56:02.544569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.905389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-public/cluster-info\" ","response":"range_response_count:1 size:2178"} {"level":"info","ts":"2026-02-14T04:56:02.544719Z","caller":"traceutil/trace.go:171","msg":"trace[1551892027] range","detail":"{range_begin:/registry/configmaps/kube-public/cluster-info; range_end:; response_count:1; response_revision:1084; }","duration":"220.060801ms","start":"2026-02-14T04:56:02.324618Z","end":"2026-02-14T04:56:02.544679Z","steps":["trace[1551892027] 'agreement among raft nodes before linearized reading' (duration: 219.891968ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:02.544992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.178428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/kube-node-lease/\" range_end:\"/registry/statefulsets/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:56:02.545278Z","caller":"traceutil/trace.go:171","msg":"trace[766764680] range","detail":"{range_begin:/registry/statefulsets/kube-node-lease/; range_end:/registry/statefulsets/kube-node-lease0; response_count:0; response_revision:1084; }","duration":"218.474503ms","start":"2026-02-14T04:56:02.326791Z","end":"2026-02-14T04:56:02.545265Z","steps":["trace[766764680] 'agreement among raft nodes before linearized reading' (duration: 218.170708ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:02.54589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.634309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-14T04:56:02.545961Z","caller":"traceutil/trace.go:171","msg":"trace[344201487] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1084; }","duration":"114.785231ms","start":"2026-02-14T04:56:02.431158Z","end":"2026-02-14T04:56:02.545943Z","steps":["trace[344201487] 'agreement among raft nodes before linearized reading' (duration: 114.599908ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:02.546618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.891631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-public/kube-root-ca.crt\" ","response":"range_response_count:1 size:1746"} {"level":"info","ts":"2026-02-14T04:56:02.546663Z","caller":"traceutil/trace.go:171","msg":"trace[1234013036] range","detail":"{range_begin:/registry/configmaps/kube-public/kube-root-ca.crt; range_end:; response_count:1; response_revision:1084; }","duration":"144.963153ms","start":"2026-02-14T04:56:02.401689Z","end":"2026-02-14T04:56:02.546652Z","steps":["trace[1234013036] 'agreement among raft nodes before linearized reading' (duration: 144.876591ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:02.547368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.366484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-public/\" range_end:\"/registry/deployments/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:56:02.547418Z","caller":"traceutil/trace.go:171","msg":"trace[1282202657] range","detail":"{range_begin:/registry/deployments/kube-public/; range_end:/registry/deployments/kube-public0; response_count:0; response_revision:1084; }","duration":"182.620429ms","start":"2026-02-14T04:56:02.364783Z","end":"2026-02-14T04:56:02.547403Z","steps":["trace[1282202657] 'agreement among raft nodes before linearized reading' (duration: 182.383075ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:04.954179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.619188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/kube-public/\" range_end:\"/registry/statefulsets/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:56:04.95436Z","caller":"traceutil/trace.go:171","msg":"trace[194819473] range","detail":"{range_begin:/registry/statefulsets/kube-public/; range_end:/registry/statefulsets/kube-public0; response_count:0; response_revision:1094; }","duration":"112.832871ms","start":"2026-02-14T04:56:04.841508Z","end":"2026-02-14T04:56:04.954341Z","steps":["trace[194819473] 'range keys from in-memory index tree' (duration: 112.565887ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:10.791683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.300636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-14T04:56:10.792311Z","caller":"traceutil/trace.go:171","msg":"trace[1762035549] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1106; }","duration":"100.959649ms","start":"2026-02-14T04:56:10.691332Z","end":"2026-02-14T04:56:10.792291Z","steps":["trace[1762035549] 'range keys from in-memory index tree' (duration: 100.084383ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:10.792989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.600175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-14T04:56:10.793155Z","caller":"traceutil/trace.go:171","msg":"trace[1741109963] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1106; }","duration":"108.792198ms","start":"2026-02-14T04:56:10.68435Z","end":"2026-02-14T04:56:10.793142Z","steps":["trace[1741109963] 'range keys from in-memory index tree' (duration: 108.464072ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:10.79354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.294282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-14T04:56:10.793694Z","caller":"traceutil/trace.go:171","msg":"trace[1257939662] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:1106; }","duration":"107.494524ms","start":"2026-02-14T04:56:10.686183Z","end":"2026-02-14T04:56:10.793677Z","steps":["trace[1257939662] 'range keys from in-memory index tree' (duration: 107.137538ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:56:10.912335Z","caller":"traceutil/trace.go:171","msg":"trace[858132762] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"106.471956ms","start":"2026-02-14T04:56:10.805609Z","end":"2026-02-14T04:56:10.912081Z","steps":["trace[858132762] 'process raft request' (duration: 106.302843ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:56:10.924583Z","caller":"traceutil/trace.go:171","msg":"trace[1598467794] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"118.51012ms","start":"2026-02-14T04:56:10.806047Z","end":"2026-02-14T04:56:10.924557Z","steps":["trace[1598467794] 'process raft request' (duration: 117.96698ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:56:10.925648Z","caller":"traceutil/trace.go:171","msg":"trace[1061650925] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"119.539559ms","start":"2026-02-14T04:56:10.806094Z","end":"2026-02-14T04:56:10.925633Z","steps":["trace[1061650925] 'process raft request' (duration: 118.088353ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:56:10.926001Z","caller":"traceutil/trace.go:171","msg":"trace[228728613] linearizableReadLoop","detail":"{readStateIndex:1161; appliedIndex:1158; }","duration":"118.616273ms","start":"2026-02-14T04:56:10.806816Z","end":"2026-02-14T04:56:10.925433Z","steps":["trace[228728613] 'read index received' (duration: 105.079782ms)","trace[228728613] 'applied index is now lower than readState.Index' (duration: 13.535611ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:56:10.926114Z","caller":"traceutil/trace.go:171","msg":"trace[1923129491] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"118.862837ms","start":"2026-02-14T04:56:10.807242Z","end":"2026-02-14T04:56:10.926105Z","steps":["trace[1923129491] 'process raft request' (duration: 117.252018ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:10.926256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.425697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-14T04:56:10.926316Z","caller":"traceutil/trace.go:171","msg":"trace[490183486] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:1110; }","duration":"119.504028ms","start":"2026-02-14T04:56:10.806804Z","end":"2026-02-14T04:56:10.926308Z","steps":["trace[490183486] 'agreement among raft nodes before linearized reading' (duration: 119.400456ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:10.926774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.918685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-02-14T04:56:10.926838Z","caller":"traceutil/trace.go:171","msg":"trace[1305322695] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:1110; }","duration":"119.982437ms","start":"2026-02-14T04:56:10.806846Z","end":"2026-02-14T04:56:10.926828Z","steps":["trace[1305322695] 'agreement among raft nodes before linearized reading' (duration: 119.59229ms)"],"step_count":1}