{"level":"warn","ts":"2026-01-27T19:48:47.246745Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-27T19:48:47.24686Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.49: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.49:2380","--initial-cluster=instance=https://199.204.45.49:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.49:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.49: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-01-27T19:48:47.247054Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-27T19:48:47.24707Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-01-27T19:48:47.247247Z","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-01-27T19:48:47.248154Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"]} {"level":"info","ts":"2026-01-27T19:48:47.248674Z","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.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.49: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-01-27T19:48:47.257912Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.820444ms"} {"level":"info","ts":"2026-01-27T19:48:47.271147Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d0ba3c4de8c6ef7a","cluster-id":"e76701b877f442ac"} {"level":"info","ts":"2026-01-27T19:48:47.271211Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=()"} {"level":"info","ts":"2026-01-27T19:48:47.27123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 0"} {"level":"info","ts":"2026-01-27T19:48:47.271243Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d0ba3c4de8c6ef7a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-27T19:48:47.271254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 1"} {"level":"info","ts":"2026-01-27T19:48:47.271318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"warn","ts":"2026-01-27T19:48:47.288618Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-27T19:48:47.292856Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-27T19:48:47.301001Z","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-01-27T19:48:47.304285Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d0ba3c4de8c6ef7a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-27T19:48:47.30464Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d0ba3c4de8c6ef7a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-27T19:48:47.30478Z","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-01-27T19:48:47.304817Z","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-01-27T19:48:47.304825Z","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-01-27T19:48:47.306089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"info","ts":"2026-01-27T19:48:47.306496Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","added-peer-id":"d0ba3c4de8c6ef7a","added-peer-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-01-27T19:48:47.32711Z","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-01-27T19:48:47.327971Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d0ba3c4de8c6ef7a","initial-advertise-peer-urls":["https://199.204.45.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-27T19:48:47.328187Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-27T19:48:47.327357Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-01-27T19:48:47.329966Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-01-27T19:48:47.671603Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a is starting a new election at term 1"} {"level":"info","ts":"2026-01-27T19:48:47.671756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became pre-candidate at term 1"} {"level":"info","ts":"2026-01-27T19:48:47.671856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgPreVoteResp from d0ba3c4de8c6ef7a at term 1"} {"level":"info","ts":"2026-01-27T19:48:47.67189Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became candidate at term 2"} {"level":"info","ts":"2026-01-27T19:48:47.672007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgVoteResp from d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-01-27T19:48:47.67208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became leader at term 2"} {"level":"info","ts":"2026-01-27T19:48:47.672107Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d0ba3c4de8c6ef7a elected leader d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-01-27T19:48:47.771964Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d0ba3c4de8c6ef7a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.49:2379]}","request-path":"/0/members/d0ba3c4de8c6ef7a/attributes","cluster-id":"e76701b877f442ac","publish-timeout":"7s"} {"level":"info","ts":"2026-01-27T19:48:47.772111Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-27T19:48:47.772007Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-27T19:48:47.775047Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-27T19:48:47.776755Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.49:2379"} {"level":"info","ts":"2026-01-27T19:48:47.778009Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-27T19:48:47.778026Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-27T19:48:47.794289Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-27T19:48:47.794503Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","cluster-version":"3.5"} {"level":"info","ts":"2026-01-27T19:48:47.794622Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-27T19:48:47.794697Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-01-27T19:48:55.633673Z","caller":"traceutil/trace.go:171","msg":"trace[1333757450] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"105.173811ms","start":"2026-01-27T19:48:55.528475Z","end":"2026-01-27T19:48:55.633649Z","steps":["trace[1333757450] 'process raft request' (duration: 105.071699ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:48:57.623423Z","caller":"traceutil/trace.go:171","msg":"trace[1183600076] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"187.17444ms","start":"2026-01-27T19:48:57.436229Z","end":"2026-01-27T19:48:57.623404Z","steps":["trace[1183600076] 'process raft request' (duration: 187.067247ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:48:59.495353Z","caller":"traceutil/trace.go:171","msg":"trace[1576034685] linearizableReadLoop","detail":"{readStateIndex:328; appliedIndex:327; }","duration":"107.463097ms","start":"2026-01-27T19:48:59.387875Z","end":"2026-01-27T19:48:59.495338Z","steps":["trace[1576034685] 'read index received' (duration: 64.261359ms)","trace[1576034685] 'applied index is now lower than readState.Index' (duration: 43.200678ms)"],"step_count":2} {"level":"info","ts":"2026-01-27T19:48:59.49546Z","caller":"traceutil/trace.go:171","msg":"trace[1215696563] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"117.27821ms","start":"2026-01-27T19:48:59.378145Z","end":"2026-01-27T19:48:59.495423Z","steps":["trace[1215696563] 'process raft request' (duration: 73.99942ms)","trace[1215696563] 'compare' (duration: 43.016824ms)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:48:59.495502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.60744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-01-27T19:48:59.495551Z","caller":"traceutil/trace.go:171","msg":"trace[2133863448] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:321; }","duration":"107.699993ms","start":"2026-01-27T19:48:59.387844Z","end":"2026-01-27T19:48:59.495544Z","steps":["trace[2133863448] 'agreement among raft nodes before linearized reading' (duration: 107.59004ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:48:59.80343Z","caller":"traceutil/trace.go:171","msg":"trace[26937349] linearizableReadLoop","detail":"{readStateIndex:336; appliedIndex:335; }","duration":"169.166493ms","start":"2026-01-27T19:48:59.634234Z","end":"2026-01-27T19:48:59.8034Z","steps":["trace[26937349] 'read index received' (duration: 33.98642ms)","trace[26937349] 'applied index is now lower than readState.Index' (duration: 135.178623ms)"],"step_count":2} {"level":"info","ts":"2026-01-27T19:48:59.803539Z","caller":"traceutil/trace.go:171","msg":"trace[727777008] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"171.549382ms","start":"2026-01-27T19:48:59.631964Z","end":"2026-01-27T19:48:59.803513Z","steps":["trace[727777008] 'process raft request' (duration: 104.446802ms)","trace[727777008] 'compare' (duration: 66.595517ms)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:48:59.804575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.305352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-01-27T19:48:59.809856Z","caller":"traceutil/trace.go:171","msg":"trace[1633152992] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:331; }","duration":"175.621673ms","start":"2026-01-27T19:48:59.634214Z","end":"2026-01-27T19:48:59.809835Z","steps":["trace[1633152992] 'agreement among raft nodes before linearized reading' (duration: 170.275991ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:48:59.804695Z","caller":"traceutil/trace.go:171","msg":"trace[724279543] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"170.413934ms","start":"2026-01-27T19:48:59.634266Z","end":"2026-01-27T19:48:59.80468Z","steps":["trace[724279543] 'process raft request' (duration: 168.990899ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:48:59.804723Z","caller":"traceutil/trace.go:171","msg":"trace[42894763] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"170.199029ms","start":"2026-01-27T19:48:59.634517Z","end":"2026-01-27T19:48:59.804716Z","steps":["trace[42894763] 'process raft request' (duration: 168.824645ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:48:59.806239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.960589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6894"} {"level":"info","ts":"2026-01-27T19:48:59.818909Z","caller":"traceutil/trace.go:171","msg":"trace[1894934028] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:331; }","duration":"143.653103ms","start":"2026-01-27T19:48:59.67523Z","end":"2026-01-27T19:48:59.818883Z","steps":["trace[1894934028] 'agreement among raft nodes before linearized reading' (duration: 130.955569ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:49:00.022601Z","caller":"traceutil/trace.go:171","msg":"trace[1511955996] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"126.073418ms","start":"2026-01-27T19:48:59.896498Z","end":"2026-01-27T19:49:00.022572Z","steps":["trace[1511955996] 'process raft request' (duration: 53.398151ms)","trace[1511955996] 'compare' (duration: 72.500413ms)"],"step_count":2} {"level":"info","ts":"2026-01-27T19:49:00.029719Z","caller":"traceutil/trace.go:171","msg":"trace[1490520960] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"121.141236ms","start":"2026-01-27T19:48:59.908564Z","end":"2026-01-27T19:49:00.029705Z","steps":["trace[1490520960] 'process raft request' (duration: 121.111985ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:49:00.030055Z","caller":"traceutil/trace.go:171","msg":"trace[2001435732] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"133.06821ms","start":"2026-01-27T19:48:59.896977Z","end":"2026-01-27T19:49:00.030046Z","steps":["trace[2001435732] 'process raft request' (duration: 132.541457ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:49:00.03058Z","caller":"traceutil/trace.go:171","msg":"trace[12129915] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"126.244122ms","start":"2026-01-27T19:48:59.904326Z","end":"2026-01-27T19:49:00.03057Z","steps":["trace[12129915] 'process raft request' (duration: 125.279158ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:49:00.030954Z","caller":"traceutil/trace.go:171","msg":"trace[1665291692] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"126.606531ms","start":"2026-01-27T19:48:59.904339Z","end":"2026-01-27T19:49:00.030946Z","steps":["trace[1665291692] 'process raft request' (duration: 125.307579ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:03.526221Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256276450338548284,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-27T19:49:04.02726Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256276450338548284,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-27T19:49:04.527617Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256276450338548284,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-27T19:49:05.027844Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256276450338548284,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-27T19:49:05.093499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000892842s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-01-27T19:49:05.09359Z","caller":"traceutil/trace.go:171","msg":"trace[2126191707] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001036925s","start":"2026-01-27T19:49:03.092534Z","end":"2026-01-27T19:49:05.093571Z","steps":["trace[2126191707] 'agreement among raft nodes before linearized reading' (duration: 2.000922922s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:05.093641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:03.092513Z","time spent":"2.001118718s","remote":"127.0.0.1:51344","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-01-27T19:49:05.528057Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256276450338548284,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-27T19:49:05.553777Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.549716763s","expected-duration":"1s"} {"level":"info","ts":"2026-01-27T19:49:05.55406Z","caller":"traceutil/trace.go:171","msg":"trace[1160648629] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"2.55003664s","start":"2026-01-27T19:49:03.004007Z","end":"2026-01-27T19:49:05.554043Z","steps":["trace[1160648629] 'process raft request' (duration: 2.549905477s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:05.554489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:03.003996Z","time spent":"2.550105552s","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-27T19:49:05.711287Z","caller":"traceutil/trace.go:171","msg":"trace[1402237470] linearizableReadLoop","detail":"{readStateIndex:377; appliedIndex:375; }","duration":"2.685624454s","start":"2026-01-27T19:49:03.02563Z","end":"2026-01-27T19:49:05.711255Z","steps":["trace[1402237470] 'read index received' (duration: 2.529043542s)","trace[1402237470] 'applied index is now lower than readState.Index' (duration: 156.579802ms)"],"step_count":2} {"level":"info","ts":"2026-01-27T19:49:05.711746Z","caller":"traceutil/trace.go:171","msg":"trace[1374604265] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"1.748226063s","start":"2026-01-27T19:49:03.963506Z","end":"2026-01-27T19:49:05.711732Z","steps":["trace[1374604265] 'process raft request' (duration: 1.747442194s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:05.711892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:03.963482Z","time spent":"1.748298625s","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-27T19:49:05.712045Z","caller":"traceutil/trace.go:171","msg":"trace[499386154] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"613.601204ms","start":"2026-01-27T19:49:05.098427Z","end":"2026-01-27T19:49:05.712028Z","steps":["trace[499386154] 'process raft request' (duration: 612.723863ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:05.712194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:05.098399Z","time spent":"613.681036ms","remote":"127.0.0.1:51406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":757,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-27T19:49:05.712487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.686856356s","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-01-27T19:49:05.712622Z","caller":"traceutil/trace.go:171","msg":"trace[233288227] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:368; }","duration":"2.687011969s","start":"2026-01-27T19:49:03.025591Z","end":"2026-01-27T19:49:05.712603Z","steps":["trace[233288227] 'agreement among raft nodes before linearized reading' (duration: 2.686847815s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:05.712676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:03.025559Z","time spent":"2.687101771s","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-27T19:49:05.712906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.095826089s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4725"} {"level":"info","ts":"2026-01-27T19:49:05.712941Z","caller":"traceutil/trace.go:171","msg":"trace[897244015] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:368; }","duration":"2.09588555s","start":"2026-01-27T19:49:03.617042Z","end":"2026-01-27T19:49:05.712928Z","steps":["trace[897244015] 'agreement among raft nodes before linearized reading' (duration: 2.095798408s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:05.712998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:03.617027Z","time spent":"2.095948262s","remote":"127.0.0.1:51512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":4749,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-01-27T19:49:05.713259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.28141689s","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-01-27T19:49:05.713291Z","caller":"traceutil/trace.go:171","msg":"trace[174569368] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:368; }","duration":"1.281476711s","start":"2026-01-27T19:49:04.431805Z","end":"2026-01-27T19:49:05.713281Z","steps":["trace[174569368] 'agreement among raft nodes before linearized reading' (duration: 1.281417629s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:05.713319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:04.431789Z","time spent":"1.281523512s","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-27T19:49:05.713473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.339795822s","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-01-27T19:49:05.713503Z","caller":"traceutil/trace.go:171","msg":"trace[2107250575] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:368; }","duration":"1.339855054s","start":"2026-01-27T19:49:04.373637Z","end":"2026-01-27T19:49:05.713492Z","steps":["trace[2107250575] 'agreement among raft nodes before linearized reading' (duration: 1.339803062s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:05.713528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:04.373621Z","time spent":"1.339901354s","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-01-27T19:49:11.926701Z","caller":"traceutil/trace.go:171","msg":"trace[1224862781] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"110.733249ms","start":"2026-01-27T19:49:11.81595Z","end":"2026-01-27T19:49:11.926683Z","steps":["trace[1224862781] 'process raft request' (duration: 100.728291ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:49:11.928518Z","caller":"traceutil/trace.go:171","msg":"trace[2077095293] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"112.487681ms","start":"2026-01-27T19:49:11.816018Z","end":"2026-01-27T19:49:11.928506Z","steps":["trace[2077095293] 'process raft request' (duration: 112.084511ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:49:11.929409Z","caller":"traceutil/trace.go:171","msg":"trace[1716158694] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"113.350894ms","start":"2026-01-27T19:49:11.816048Z","end":"2026-01-27T19:49:11.929399Z","steps":["trace[1716158694] 'process raft request' (duration: 112.157344ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:49:19.799617Z","caller":"traceutil/trace.go:171","msg":"trace[268695292] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"148.336888ms","start":"2026-01-27T19:49:19.651262Z","end":"2026-01-27T19:49:19.799599Z","steps":["trace[268695292] 'process raft request' (duration: 147.905118ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:49:25.751597Z","caller":"traceutil/trace.go:171","msg":"trace[508331240] linearizableReadLoop","detail":"{readStateIndex:503; appliedIndex:501; }","duration":"104.131435ms","start":"2026-01-27T19:49:25.647446Z","end":"2026-01-27T19:49:25.751578Z","steps":["trace[508331240] 'read index received' (duration: 3.277261ms)","trace[508331240] 'applied index is now lower than readState.Index' (duration: 100.853514ms)"],"step_count":2} {"level":"info","ts":"2026-01-27T19:49:25.751925Z","caller":"traceutil/trace.go:171","msg":"trace[19292678] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"118.534451ms","start":"2026-01-27T19:49:25.633378Z","end":"2026-01-27T19:49:25.751913Z","steps":["trace[19292678] 'process raft request' (duration: 76.044201ms)","trace[19292678] 'compare' (duration: 42.04252ms)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:49:25.75213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.657568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:49:25.752177Z","caller":"traceutil/trace.go:171","msg":"trace[347938003] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:489; }","duration":"104.730789ms","start":"2026-01-27T19:49:25.647421Z","end":"2026-01-27T19:49:25.752152Z","steps":["trace[347938003] 'agreement among raft nodes before linearized reading' (duration: 104.664828ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:25.752309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.380841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:49:25.75236Z","caller":"traceutil/trace.go:171","msg":"trace[1963949473] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:489; }","duration":"104.415622ms","start":"2026-01-27T19:49:25.64792Z","end":"2026-01-27T19:49:25.752336Z","steps":["trace[1963949473] 'agreement among raft nodes before linearized reading' (duration: 104.371021ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:25.756015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.500258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:49:25.756052Z","caller":"traceutil/trace.go:171","msg":"trace[344208475] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:489; }","duration":"105.55753ms","start":"2026-01-27T19:49:25.650486Z","end":"2026-01-27T19:49:25.756044Z","steps":["trace[344208475] 'agreement among raft nodes before linearized reading' (duration: 105.499499ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:25.756211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.722715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-01-27T19:49:25.756308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.331766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:49:25.756348Z","caller":"traceutil/trace.go:171","msg":"trace[322895703] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:489; }","duration":"107.774536ms","start":"2026-01-27T19:49:25.648466Z","end":"2026-01-27T19:49:25.756241Z","steps":["trace[322895703] 'agreement among raft nodes before linearized reading' (duration: 107.725674ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:49:25.756387Z","caller":"traceutil/trace.go:171","msg":"trace[1145250905] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:489; }","duration":"101.362648ms","start":"2026-01-27T19:49:25.654961Z","end":"2026-01-27T19:49:25.756324Z","steps":["trace[1145250905] 'agreement among raft nodes before linearized reading' (duration: 101.330927ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:25.758752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.222087ms","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-01-27T19:49:25.758794Z","caller":"traceutil/trace.go:171","msg":"trace[1439394900] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:489; }","duration":"104.265448ms","start":"2026-01-27T19:49:25.654521Z","end":"2026-01-27T19:49:25.758786Z","steps":["trace[1439394900] 'agreement among raft nodes before linearized reading' (duration: 104.208427ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:25.758878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.77334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:49:25.758937Z","caller":"traceutil/trace.go:171","msg":"trace[1598303585] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:489; }","duration":"104.828892ms","start":"2026-01-27T19:49:25.65409Z","end":"2026-01-27T19:49:25.758919Z","steps":["trace[1598303585] 'agreement among raft nodes before linearized reading' (duration: 104.771921ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:25.759993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.618706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:49:25.760035Z","caller":"traceutil/trace.go:171","msg":"trace[2053043692] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:489; }","duration":"106.678278ms","start":"2026-01-27T19:49:25.653346Z","end":"2026-01-27T19:49:25.760025Z","steps":["trace[2053043692] 'agreement among raft nodes before linearized reading' (duration: 106.624097ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:26.7358Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256276450338548530,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-27T19:49:26.978307Z","caller":"traceutil/trace.go:171","msg":"trace[1752514404] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"788.786468ms","start":"2026-01-27T19:49:26.189498Z","end":"2026-01-27T19:49:26.978284Z","steps":["trace[1752514404] 'process raft request' (duration: 788.672475ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:26.978453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:26.189471Z","time spent":"788.9039ms","remote":"127.0.0.1:51384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-27T19:49:27.002807Z","caller":"traceutil/trace.go:171","msg":"trace[1471605394] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"611.900941ms","start":"2026-01-27T19:49:26.390873Z","end":"2026-01-27T19:49:27.002774Z","steps":["trace[1471605394] 'process raft request' (duration: 611.468851ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:49:27.002899Z","caller":"traceutil/trace.go:171","msg":"trace[913569747] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"630.614286ms","start":"2026-01-27T19:49:26.372258Z","end":"2026-01-27T19:49:27.002872Z","steps":["trace[913569747] 'process raft request' (duration: 629.980649ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:27.002999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:26.390859Z","time spent":"612.035516ms","remote":"127.0.0.1:51384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-27T19:49:27.003069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:26.372229Z","time spent":"630.714618ms","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-27T19:49:27.003198Z","caller":"traceutil/trace.go:171","msg":"trace[176095904] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"414.567202ms","start":"2026-01-27T19:49:26.588619Z","end":"2026-01-27T19:49:27.003187Z","steps":["trace[176095904] 'process raft request' (duration: 413.751641ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:27.003248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:26.588598Z","time spent":"414.627323ms","remote":"127.0.0.1:51384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-27T19:49:27.003368Z","caller":"traceutil/trace.go:171","msg":"trace[1555535220] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"212.416574ms","start":"2026-01-27T19:49:26.790943Z","end":"2026-01-27T19:49:27.003359Z","steps":["trace[1555535220] 'process raft request' (duration: 211.445509ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:49:27.036858Z","caller":"traceutil/trace.go:171","msg":"trace[431790480] linearizableReadLoop","detail":"{readStateIndex:513; appliedIndex:510; }","duration":"802.094697ms","start":"2026-01-27T19:49:26.234751Z","end":"2026-01-27T19:49:27.036846Z","steps":["trace[431790480] 'read index received' (duration: 744.109093ms)","trace[431790480] 'applied index is now lower than readState.Index' (duration: 57.984974ms)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:49:27.037106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.348053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-01-27T19:49:27.037163Z","caller":"traceutil/trace.go:171","msg":"trace[1669828642] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:501; }","duration":"802.441476ms","start":"2026-01-27T19:49:26.234714Z","end":"2026-01-27T19:49:27.037156Z","steps":["trace[1669828642] 'agreement among raft nodes before linearized reading' (duration: 802.22502ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:27.037193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:26.234697Z","time spent":"802.489806ms","remote":"127.0.0.1:51602","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-01-27T19:49:27.041352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.463088ms","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-01-27T19:49:27.041625Z","caller":"traceutil/trace.go:171","msg":"trace[622667443] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:503; }","duration":"790.760215ms","start":"2026-01-27T19:49:26.250854Z","end":"2026-01-27T19:49:27.041614Z","steps":["trace[622667443] 'agreement among raft nodes before linearized reading' (duration: 790.431637ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:27.041787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:26.250842Z","time spent":"790.886248ms","remote":"127.0.0.1:51602","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-01-27T19:49:27.04234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"789.481485ms","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-01-27T19:49:27.042651Z","caller":"traceutil/trace.go:171","msg":"trace[2133213796] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:503; }","duration":"789.809632ms","start":"2026-01-27T19:49:26.252832Z","end":"2026-01-27T19:49:27.042642Z","steps":["trace[2133213796] 'agreement among raft nodes before linearized reading' (duration: 789.465554ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:27.042839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:26.252823Z","time spent":"790.004477ms","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-27T19:49:27.319671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.975665ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-27T19:49:27.319968Z","caller":"traceutil/trace.go:171","msg":"trace[57919330] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"130.322334ms","start":"2026-01-27T19:49:27.189629Z","end":"2026-01-27T19:49:27.319951Z","steps":["trace[57919330] 'process raft request' (duration: 130.287283ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:49:27.320226Z","caller":"traceutil/trace.go:171","msg":"trace[203557373] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"268.458249ms","start":"2026-01-27T19:49:27.051756Z","end":"2026-01-27T19:49:27.320215Z","steps":["trace[203557373] 'process raft request' (duration: 139.656614ms)","trace[203557373] 'compare' (duration: 127.82337ms)"],"step_count":2} {"level":"info","ts":"2026-01-27T19:49:27.320348Z","caller":"traceutil/trace.go:171","msg":"trace[1652300870] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"264.349038ms","start":"2026-01-27T19:49:27.05599Z","end":"2026-01-27T19:49:27.320339Z","steps":["trace[1652300870] 'process raft request' (duration: 263.883697ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:49:27.320389Z","caller":"traceutil/trace.go:171","msg":"trace[303550058] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"267.938066ms","start":"2026-01-27T19:49:27.052431Z","end":"2026-01-27T19:49:27.320369Z","steps":["trace[303550058] 'process raft request' (duration: 267.336631ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:49:27.32045Z","caller":"traceutil/trace.go:171","msg":"trace[233361936] linearizableReadLoop","detail":"{readStateIndex:524; appliedIndex:520; }","duration":"246.726702ms","start":"2026-01-27T19:49:27.073711Z","end":"2026-01-27T19:49:27.320438Z","steps":["trace[233361936] 'read index received' (duration: 117.710571ms)","trace[233361936] 'applied index is now lower than readState.Index' (duration: 129.015291ms)"],"step_count":2} {"level":"info","ts":"2026-01-27T19:49:27.320534Z","caller":"traceutil/trace.go:171","msg":"trace[533041731] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"265.359133ms","start":"2026-01-27T19:49:27.055169Z","end":"2026-01-27T19:49:27.320528Z","steps":["trace[533041731] 'process raft request' (duration: 264.669676ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:27.320561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.986401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v2alpha1.cilium.io\" ","response":"range_response_count:1 size:965"} {"level":"info","ts":"2026-01-27T19:49:27.320589Z","caller":"traceutil/trace.go:171","msg":"trace[1856237868] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v2alpha1.cilium.io; range_end:; response_count:1; response_revision:509; }","duration":"273.027422ms","start":"2026-01-27T19:49:27.047553Z","end":"2026-01-27T19:49:27.32058Z","steps":["trace[1856237868] 'agreement among raft nodes before linearized reading' (duration: 272.920679ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:49:27.622343Z","caller":"traceutil/trace.go:171","msg":"trace[466985502] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"147.422336ms","start":"2026-01-27T19:49:27.474895Z","end":"2026-01-27T19:49:27.622317Z","steps":["trace[466985502] 'process raft request' (duration: 122.845548ms)","trace[466985502] 'compare' (duration: 24.445905ms)"],"step_count":2} {"level":"info","ts":"2026-01-27T19:49:27.623523Z","caller":"traceutil/trace.go:171","msg":"trace[390132039] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"148.01412ms","start":"2026-01-27T19:49:27.475494Z","end":"2026-01-27T19:49:27.623508Z","steps":["trace[390132039] 'process raft request' (duration: 147.447646ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:49:27.903945Z","caller":"traceutil/trace.go:171","msg":"trace[1157796331] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"119.831094ms","start":"2026-01-27T19:49:27.784096Z","end":"2026-01-27T19:49:27.903928Z","steps":["trace[1157796331] 'process raft request' (duration: 80.802329ms)","trace[1157796331] 'compare' (duration: 37.379865ms)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:49:29.954481Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.604111853s","expected-duration":"1s"} {"level":"warn","ts":"2026-01-27T19:49:30.047826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000358981s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-01-27T19:49:30.047952Z","caller":"traceutil/trace.go:171","msg":"trace[780806036] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000525696s","start":"2026-01-27T19:49:28.047405Z","end":"2026-01-27T19:49:30.047931Z","steps":["trace[780806036] 'agreement among raft nodes before linearized reading' (duration: 2.000371691s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:30.048013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:28.047398Z","time spent":"2.000605198s","remote":"127.0.0.1:51346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/01/27 19:49:30 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-01-27T19:49:30.17901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.947483828s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-01-27T19:49:30.180292Z","caller":"traceutil/trace.go:171","msg":"trace[439355725] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"1.911535399s","start":"2026-01-27T19:49:28.268685Z","end":"2026-01-27T19:49:30.18022Z","steps":["trace[439355725] 'process raft request' (duration: 1.910688588s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:30.180366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:28.268665Z","time spent":"1.911660643s","remote":"127.0.0.1:51384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188237,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-27T19:49:30.18698Z","caller":"traceutil/trace.go:171","msg":"trace[1323891709] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"2.140260689s","start":"2026-01-27T19:49:28.046694Z","end":"2026-01-27T19:49:30.186955Z","steps":["trace[1323891709] 'process raft request' (duration: 184.78627ms)","trace[1323891709] 'compare' (duration: 1.946650889s)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:49:30.187233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:28.04668Z","time spent":"2.140518235s","remote":"127.0.0.1:51384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-27T19:49:30.189255Z","caller":"traceutil/trace.go:171","msg":"trace[799981899] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"2.139052267s","start":"2026-01-27T19:49:28.050193Z","end":"2026-01-27T19:49:30.189245Z","steps":["trace[799981899] 'process raft request' (duration: 2.128884874s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:30.189481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:28.050185Z","time spent":"2.139119309s","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-27T19:49:30.196419Z","caller":"traceutil/trace.go:171","msg":"trace[374155738] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:543; }","duration":"2.148922605s","start":"2026-01-27T19:49:28.047424Z","end":"2026-01-27T19:49:30.196347Z","steps":["trace[374155738] 'read index received' (duration: 5.211979ms)","trace[374155738] 'applied index is now lower than readState.Index' (duration: 2.142747183s)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:49:30.244436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.045986178s","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-01-27T19:49:30.24471Z","caller":"traceutil/trace.go:171","msg":"trace[1058992914] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:531; }","duration":"2.046295595s","start":"2026-01-27T19:49:28.198397Z","end":"2026-01-27T19:49:30.244692Z","steps":["trace[1058992914] 'agreement among raft nodes before linearized reading' (duration: 2.045910916s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:30.244796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:28.198383Z","time spent":"2.046402478s","remote":"127.0.0.1:51384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5610,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-01-27T19:49:30.244893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.117978527s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:49:30.245025Z","caller":"traceutil/trace.go:171","msg":"trace[1159126273] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:531; }","duration":"1.118136911s","start":"2026-01-27T19:49:29.126816Z","end":"2026-01-27T19:49:30.244953Z","steps":["trace[1159126273] 'agreement among raft nodes before linearized reading' (duration: 1.117722801s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:30.245064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:29.126811Z","time spent":"1.118246053s","remote":"127.0.0.1:60564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-01-27T19:49:30.245152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.11852738s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:49:30.245223Z","caller":"traceutil/trace.go:171","msg":"trace[556536642] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:531; }","duration":"1.118610562s","start":"2026-01-27T19:49:29.126602Z","end":"2026-01-27T19:49:30.245213Z","steps":["trace[556536642] 'agreement among raft nodes before linearized reading' (duration: 1.11851952s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:30.246636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:29.126595Z","time spent":"1.120032315s","remote":"127.0.0.1:60564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 "} {"level":"warn","ts":"2026-01-27T19:49:30.247939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.660467711s","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":"info","ts":"2026-01-27T19:49:30.248004Z","caller":"traceutil/trace.go:171","msg":"trace[1121717555] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:531; }","duration":"1.660558952s","start":"2026-01-27T19:49:28.587436Z","end":"2026-01-27T19:49:30.247995Z","steps":["trace[1121717555] 'agreement among raft nodes before linearized reading' (duration: 1.658737228s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:30.248071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:28.587423Z","time spent":"1.660639375s","remote":"127.0.0.1:51384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":13253,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"info","ts":"2026-01-27T19:49:30.245533Z","caller":"traceutil/trace.go:171","msg":"trace[1421816403] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"192.173264ms","start":"2026-01-27T19:49:30.053347Z","end":"2026-01-27T19:49:30.24552Z","steps":["trace[1421816403] 'process raft request' (duration: 190.791582ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:30.248385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.700712265s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:49:30.248452Z","caller":"traceutil/trace.go:171","msg":"trace[1951983206] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:531; }","duration":"1.700800467s","start":"2026-01-27T19:49:28.547643Z","end":"2026-01-27T19:49:30.248444Z","steps":["trace[1951983206] 'agreement among raft nodes before linearized reading' (duration: 1.700693654s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:30.24559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.153101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-01-27T19:49:30.245777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.762812ms","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-01-27T19:49:30.245781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.131966681s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-01-27T19:49:30.245819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.132570475s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-01-27T19:49:30.245844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.132617376s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-01-27T19:49:30.245866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.144690034s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-01-27T19:49:30.245871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.606739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"warn","ts":"2026-01-27T19:49:30.245889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.144728575s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-01-27T19:49:30.245914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.144790826s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-01-27T19:49:30.245938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.631338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-01-27T19:49:30.245945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.154737814s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-01-27T19:49:30.245992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.155174044s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-01-27T19:49:30.245996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.920233ms","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":"warn","ts":"2026-01-27T19:49:30.246018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.155221136s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-01-27T19:49:30.246055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.163741299s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-01-27T19:49:30.246059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"858.638701ms","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":"warn","ts":"2026-01-27T19:49:30.246111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.259002211s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"warn","ts":"2026-01-27T19:49:30.246106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.94856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-01-27T19:49:30.246152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.496626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-01-27T19:49:30.24616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.458882557s","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-01-27T19:49:30.246212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.058781175s","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-01-27T19:49:30.24624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.066454708s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-01-27T19:49:30.246262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.066799816s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-01-27T19:49:30.2463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.067012732s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-01-27T19:49:30.246322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.075248128s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-01-27T19:49:30.246362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.075284029s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-01-27T19:49:30.246383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.07533882s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-01-27T19:49:30.246406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.091872314s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-01-27T19:49:30.246445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.092027678s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-01-27T19:49:30.246467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.092121949s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-01-27T19:49:30.246489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.118284595s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:49:30.249668Z","caller":"traceutil/trace.go:171","msg":"trace[1155144656] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:531; }","duration":"516.413768ms","start":"2026-01-27T19:49:29.733247Z","end":"2026-01-27T19:49:30.249661Z","steps":["trace[1155144656] 'agreement among raft nodes before linearized reading' (duration: 512.651458ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:30.249786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:29.733224Z","time spent":"516.551652ms","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-27T19:49:30.249921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.716032232s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:49:30.249942Z","caller":"traceutil/trace.go:171","msg":"trace[987442233] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:531; }","duration":"1.716058674s","start":"2026-01-27T19:49:28.533877Z","end":"2026-01-27T19:49:30.249936Z","steps":["trace[987442233] 'agreement among raft nodes before linearized reading' (duration: 1.716035043s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:30.250095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:28.533873Z","time spent":"1.716213437s","remote":"127.0.0.1:60520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"info","ts":"2026-01-27T19:49:30.250213Z","caller":"traceutil/trace.go:171","msg":"trace[1240391224] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:531; }","duration":"1.159016056s","start":"2026-01-27T19:49:29.091189Z","end":"2026-01-27T19:49:30.250205Z","steps":["trace[1240391224] 'agreement among raft nodes before linearized reading' (duration: 1.154738404s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:30.257016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:29.091183Z","time spent":"1.165741166s","remote":"127.0.0.1:60548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-01-27T19:49:30.249879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.702247322s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:49:30.25715Z","caller":"traceutil/trace.go:171","msg":"trace[594283142] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:531; }","duration":"1.709544535s","start":"2026-01-27T19:49:28.547595Z","end":"2026-01-27T19:49:30.25714Z","steps":["trace[594283142] 'agreement among raft nodes before linearized reading' (duration: 1.702210481s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:30.257223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:28.547588Z","time spent":"1.709610128s","remote":"127.0.0.1:60536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:500 "} {"level":"warn","ts":"2026-01-27T19:49:30.249904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.715949751s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:49:30.257368Z","caller":"traceutil/trace.go:171","msg":"trace[30473750] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:531; }","duration":"1.723415719s","start":"2026-01-27T19:49:28.533943Z","end":"2026-01-27T19:49:30.257359Z","steps":["trace[30473750] 'agreement among raft nodes before linearized reading' (duration: 1.715947821s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:30.25742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:28.53394Z","time spent":"1.72347205s","remote":"127.0.0.1:60520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 "} {"level":"info","ts":"2026-01-27T19:49:30.25023Z","caller":"traceutil/trace.go:171","msg":"trace[775482726] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:531; }","duration":"1.159420856s","start":"2026-01-27T19:49:29.090805Z","end":"2026-01-27T19:49:30.250226Z","steps":["trace[775482726] 'agreement among raft nodes before linearized reading' (duration: 1.155152193s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:30.257569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:29.090802Z","time spent":"1.16675962s","remote":"127.0.0.1:60548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"info","ts":"2026-01-27T19:49:30.250242Z","caller":"traceutil/trace.go:171","msg":"trace[142912840] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:531; }","duration":"662.199346ms","start":"2026-01-27T19:49:29.588039Z","end":"2026-01-27T19:49:30.250238Z","steps":["trace[142912840] 'agreement among raft nodes before linearized reading' (duration: 657.908853ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:30.257742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:29.588016Z","time spent":"669.664824ms","remote":"127.0.0.1:51384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5201,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"info","ts":"2026-01-27T19:49:30.250254Z","caller":"traceutil/trace.go:171","msg":"trace[1024933610] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:531; }","duration":"1.159480837s","start":"2026-01-27T19:49:29.09077Z","end":"2026-01-27T19:49:30.250251Z","steps":["trace[1024933610] 'agreement among raft nodes before linearized reading' (duration: 1.155233335s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:30.257912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:29.090755Z","time spent":"1.167135689s","remote":"127.0.0.1:60548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 "} {"level":"info","ts":"2026-01-27T19:49:30.250265Z","caller":"traceutil/trace.go:171","msg":"trace[1128183667] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:531; }","duration":"1.167978779s","start":"2026-01-27T19:49:29.082283Z","end":"2026-01-27T19:49:30.250262Z","steps":["trace[1128183667] 'agreement among raft nodes before linearized reading' (duration: 1.163746508s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:30.258071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:29.082265Z","time spent":"1.175783187s","remote":"127.0.0.1:51602","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-01-27T19:49:30.250276Z","caller":"traceutil/trace.go:171","msg":"trace[481461340] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:531; }","duration":"862.900063ms","start":"2026-01-27T19:49:29.387372Z","end":"2026-01-27T19:49:30.250272Z","steps":["trace[481461340] 'agreement among raft nodes before linearized reading' (duration: 858.63263ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:30.258196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:29.387357Z","time spent":"870.831632ms","remote":"127.0.0.1:51384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10401,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"info","ts":"2026-01-27T19:49:30.250286Z","caller":"traceutil/trace.go:171","msg":"trace[1752144275] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:531; }","duration":"1.263215702s","start":"2026-01-27T19:49:28.987068Z","end":"2026-01-27T19:49:30.250283Z","steps":["trace[1752144275] 'agreement among raft nodes before linearized reading' (duration: 1.258996441s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:30.258349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:28.987052Z","time spent":"1.271239083s","remote":"127.0.0.1:51384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4107,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"info","ts":"2026-01-27T19:49:30.250299Z","caller":"traceutil/trace.go:171","msg":"trace[1305141870] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:531; }","duration":"923.165491ms","start":"2026-01-27T19:49:29.327129Z","end":"2026-01-27T19:49:30.250294Z","steps":["trace[1305141870] 'agreement among raft nodes before linearized reading' (duration: 918.94415ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:30.258498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:29.327117Z","time spent":"931.374347ms","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-01-27T19:49:30.250312Z","caller":"traceutil/trace.go:171","msg":"trace[743250995] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:531; }","duration":"924.686207ms","start":"2026-01-27T19:49:29.325622Z","end":"2026-01-27T19:49:30.250309Z","steps":["trace[743250995] 'agreement among raft nodes before linearized reading' (duration: 920.509056ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:30.258684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:29.3256Z","time spent":"933.077408ms","remote":"127.0.0.1:51602","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":"info","ts":"2026-01-27T19:49:30.250322Z","caller":"traceutil/trace.go:171","msg":"trace[1959055170] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:531; }","duration":"1.463082388s","start":"2026-01-27T19:49:28.787237Z","end":"2026-01-27T19:49:30.25032Z","steps":["trace[1959055170] 'agreement among raft nodes before linearized reading' (duration: 1.458885317s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:30.258977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:28.787214Z","time spent":"1.471754554s","remote":"127.0.0.1:51384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9344,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"info","ts":"2026-01-27T19:49:30.250333Z","caller":"traceutil/trace.go:171","msg":"trace[1799982399] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:531; }","duration":"1.062922934s","start":"2026-01-27T19:49:29.187407Z","end":"2026-01-27T19:49:30.25033Z","steps":["trace[1799982399] 'agreement among raft nodes before linearized reading' (duration: 1.058755504s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:30.259115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:29.187397Z","time spent":"1.071668163s","remote":"127.0.0.1:51384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4357,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"info","ts":"2026-01-27T19:49:30.250344Z","caller":"traceutil/trace.go:171","msg":"trace[1323751523] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:531; }","duration":"1.070567426s","start":"2026-01-27T19:49:29.179773Z","end":"2026-01-27T19:49:30.250341Z","steps":["trace[1323751523] 'agreement among raft nodes before linearized reading' (duration: 1.066450228s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:30.259269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:29.179769Z","time spent":"1.079446268s","remote":"127.0.0.1:60594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 "} {"level":"info","ts":"2026-01-27T19:49:30.250356Z","caller":"traceutil/trace.go:171","msg":"trace[1059202156] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:531; }","duration":"1.070906856s","start":"2026-01-27T19:49:29.179445Z","end":"2026-01-27T19:49:30.250352Z","steps":["trace[1059202156] 'agreement among raft nodes before linearized reading' (duration: 1.066802607s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:30.259467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:29.17944Z","time spent":"1.079969672s","remote":"127.0.0.1:60594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"info","ts":"2026-01-27T19:49:30.250367Z","caller":"traceutil/trace.go:171","msg":"trace[2070375596] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:531; }","duration":"1.07108911s","start":"2026-01-27T19:49:29.179275Z","end":"2026-01-27T19:49:30.250364Z","steps":["trace[2070375596] 'agreement among raft nodes before linearized reading' (duration: 1.067011792s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:30.259708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:29.179269Z","time spent":"1.080367771s","remote":"127.0.0.1:60594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 "} {"level":"info","ts":"2026-01-27T19:49:30.250378Z","caller":"traceutil/trace.go:171","msg":"trace[320815805] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:531; }","duration":"1.079310766s","start":"2026-01-27T19:49:29.171064Z","end":"2026-01-27T19:49:30.250375Z","steps":["trace[320815805] 'agreement among raft nodes before linearized reading' (duration: 1.075244889s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:30.259943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:29.171061Z","time spent":"1.088814183s","remote":"127.0.0.1:60582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 "} {"level":"info","ts":"2026-01-27T19:49:30.25039Z","caller":"traceutil/trace.go:171","msg":"trace[1724537427] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:531; }","duration":"1.079334666s","start":"2026-01-27T19:49:29.171051Z","end":"2026-01-27T19:49:30.250386Z","steps":["trace[1724537427] 'agreement among raft nodes before linearized reading' (duration: 1.075282019s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:30.260284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:29.171049Z","time spent":"1.089114459s","remote":"127.0.0.1:60582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-01-27T19:49:30.250401Z","caller":"traceutil/trace.go:171","msg":"trace[531248442] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:531; }","duration":"1.079366187s","start":"2026-01-27T19:49:29.171031Z","end":"2026-01-27T19:49:30.250397Z","steps":["trace[531248442] 'agreement among raft nodes before linearized reading' (duration: 1.07533975s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:30.260471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:29.171025Z","time spent":"1.089432966s","remote":"127.0.0.1:60582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 "} {"level":"info","ts":"2026-01-27T19:49:30.250413Z","caller":"traceutil/trace.go:171","msg":"trace[1402130483] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:531; }","duration":"1.0958868s","start":"2026-01-27T19:49:29.154523Z","end":"2026-01-27T19:49:30.250409Z","steps":["trace[1402130483] 'agreement among raft nodes before linearized reading' (duration: 1.091868654s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:30.260745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:29.154517Z","time spent":"1.106123374s","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:500 "} {"level":"info","ts":"2026-01-27T19:49:30.250424Z","caller":"traceutil/trace.go:171","msg":"trace[2099017850] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:531; }","duration":"1.096020153s","start":"2026-01-27T19:49:29.154401Z","end":"2026-01-27T19:49:30.250421Z","steps":["trace[2099017850] 'agreement among raft nodes before linearized reading' (duration: 1.092013967s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:30.260986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:29.154398Z","time spent":"1.106516624s","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:10000 "} {"level":"info","ts":"2026-01-27T19:49:30.250436Z","caller":"traceutil/trace.go:171","msg":"trace[1565306341] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:531; }","duration":"1.096110315s","start":"2026-01-27T19:49:29.154322Z","end":"2026-01-27T19:49:30.250432Z","steps":["trace[1565306341] 'agreement among raft nodes before linearized reading' (duration: 1.09213247s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:30.261748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:29.1543Z","time spent":"1.107379774s","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"info","ts":"2026-01-27T19:49:30.250448Z","caller":"traceutil/trace.go:171","msg":"trace[636663466] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:531; }","duration":"1.12225842s","start":"2026-01-27T19:49:29.128186Z","end":"2026-01-27T19:49:30.250445Z","steps":["trace[636663466] 'agreement among raft nodes before linearized reading' (duration: 1.118290995s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:30.261863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:29.12818Z","time spent":"1.133676262s","remote":"127.0.0.1:60564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 "} {"level":"warn","ts":"2026-01-27T19:49:30.249702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.702059037s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:49:30.262166Z","caller":"traceutil/trace.go:171","msg":"trace[957465086] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:531; }","duration":"1.714437433s","start":"2026-01-27T19:49:28.547627Z","end":"2026-01-27T19:49:30.262065Z","steps":["trace[957465086] 'agreement among raft nodes before linearized reading' (duration: 1.702055808s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:30.262197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:28.547623Z","time spent":"1.714565546s","remote":"127.0.0.1:60536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:10000 "} {"level":"info","ts":"2026-01-27T19:49:30.250532Z","caller":"traceutil/trace.go:171","msg":"trace[1401841708] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:531; }","duration":"263.540636ms","start":"2026-01-27T19:49:29.986987Z","end":"2026-01-27T19:49:30.250528Z","steps":["trace[1401841708] 'agreement among raft nodes before linearized reading' (duration: 258.615468ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:30.250561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.716721699s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:49:30.262923Z","caller":"traceutil/trace.go:171","msg":"trace[90591562] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:531; }","duration":"1.729101514s","start":"2026-01-27T19:49:28.533814Z","end":"2026-01-27T19:49:30.262916Z","steps":["trace[90591562] 'agreement among raft nodes before linearized reading' (duration: 1.716727589s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:30.263156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:28.5338Z","time spent":"1.729251018s","remote":"127.0.0.1:60520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 "} {"level":"warn","ts":"2026-01-27T19:49:30.250611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.862369681s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-01-27T19:49:30.263668Z","caller":"traceutil/trace.go:171","msg":"trace[588157283] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:531; }","duration":"1.875441624s","start":"2026-01-27T19:49:28.38822Z","end":"2026-01-27T19:49:30.263661Z","steps":["trace[588157283] 'agreement among raft nodes before linearized reading' (duration: 1.862350221s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:30.263799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:28.388206Z","time spent":"1.875542646s","remote":"127.0.0.1:51384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4204,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-01-27T19:49:30.250659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:28.54764Z","time spent":"1.70301348s","remote":"127.0.0.1:60536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"info","ts":"2026-01-27T19:49:30.250673Z","caller":"traceutil/trace.go:171","msg":"trace[211087086] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:531; }","duration":"194.292334ms","start":"2026-01-27T19:49:30.056376Z","end":"2026-01-27T19:49:30.250669Z","steps":["trace[211087086] 'agreement among raft nodes before linearized reading' (duration: 189.175352ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:49:30.250756Z","caller":"traceutil/trace.go:171","msg":"trace[2067913208] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:531; }","duration":"1.149588291s","start":"2026-01-27T19:49:29.101163Z","end":"2026-01-27T19:49:30.250751Z","steps":["trace[2067913208] 'agreement among raft nodes before linearized reading' (duration: 1.144690854s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:30.264636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:29.10116Z","time spent":"1.16337138s","remote":"127.0.0.1:60556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"info","ts":"2026-01-27T19:49:30.250769Z","caller":"traceutil/trace.go:171","msg":"trace[1004377450] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:531; }","duration":"1.1369698s","start":"2026-01-27T19:49:29.113796Z","end":"2026-01-27T19:49:30.250766Z","steps":["trace[1004377450] 'agreement among raft nodes before linearized reading' (duration: 1.13195386s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:30.265205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:29.11379Z","time spent":"1.151403755s","remote":"127.0.0.1:60562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 "} {"level":"info","ts":"2026-01-27T19:49:30.250781Z","caller":"traceutil/trace.go:171","msg":"trace[1269413529] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:531; }","duration":"1.137542324s","start":"2026-01-27T19:49:29.113234Z","end":"2026-01-27T19:49:30.250776Z","steps":["trace[1269413529] 'agreement among raft nodes before linearized reading' (duration: 1.132567885s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:30.265355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:29.113231Z","time spent":"1.152114771s","remote":"127.0.0.1:60562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"info","ts":"2026-01-27T19:49:30.250793Z","caller":"traceutil/trace.go:171","msg":"trace[1649144335] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:531; }","duration":"1.137578575s","start":"2026-01-27T19:49:29.11321Z","end":"2026-01-27T19:49:30.250789Z","steps":["trace[1649144335] 'agreement among raft nodes before linearized reading' (duration: 1.132620166s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:30.265534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:29.113202Z","time spent":"1.152323816s","remote":"127.0.0.1:60562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 "} {"level":"info","ts":"2026-01-27T19:49:30.250809Z","caller":"traceutil/trace.go:171","msg":"trace[1055199150] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:531; }","duration":"1.149649712s","start":"2026-01-27T19:49:29.101156Z","end":"2026-01-27T19:49:30.250805Z","steps":["trace[1055199150] 'agreement among raft nodes before linearized reading' (duration: 1.144720734s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:30.266021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:29.101139Z","time spent":"1.164871046s","remote":"127.0.0.1:60556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 "} {"level":"info","ts":"2026-01-27T19:49:30.250822Z","caller":"traceutil/trace.go:171","msg":"trace[51833006] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:531; }","duration":"463.589629ms","start":"2026-01-27T19:49:29.787227Z","end":"2026-01-27T19:49:30.250817Z","steps":["trace[51833006] 'agreement among raft nodes before linearized reading' (duration: 458.562488ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:30.266213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:29.787211Z","time spent":"478.995015ms","remote":"127.0.0.1:51384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":24268,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"info","ts":"2026-01-27T19:49:30.25084Z","caller":"traceutil/trace.go:171","msg":"trace[2090948450] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:531; }","duration":"1.149732534s","start":"2026-01-27T19:49:29.101102Z","end":"2026-01-27T19:49:30.250834Z","steps":["trace[2090948450] 'agreement among raft nodes before linearized reading' (duration: 1.144798336s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:30.269394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:29.10109Z","time spent":"1.167458877s","remote":"127.0.0.1:60556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 "} {"level":"warn","ts":"2026-01-27T19:49:30.792544Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256276450338548577,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-27T19:49:31.532684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.360023ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-27T19:49:31.533412Z","caller":"traceutil/trace.go:171","msg":"trace[1755677014] linearizableReadLoop","detail":"{readStateIndex:553; appliedIndex:547; }","duration":"1.241306369s","start":"2026-01-27T19:49:30.292082Z","end":"2026-01-27T19:49:31.533388Z","steps":["trace[1755677014] 'read index received' (duration: 704.080234ms)","trace[1755677014] 'applied index is now lower than readState.Index' (duration: 537.224705ms)"],"step_count":2} {"level":"info","ts":"2026-01-27T19:49:31.533791Z","caller":"traceutil/trace.go:171","msg":"trace[272645567] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"1.244892974s","start":"2026-01-27T19:49:30.288878Z","end":"2026-01-27T19:49:31.533771Z","steps":["trace[272645567] 'process raft request' (duration: 707.338672ms)","trace[272645567] 'compare' (duration: 533.918866ms)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:49:31.533926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:30.288864Z","time spent":"1.244967966s","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-27T19:49:31.534241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.242145679s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:49:31.534228Z","caller":"traceutil/trace.go:171","msg":"trace[1641610514] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"770.539999ms","start":"2026-01-27T19:49:30.76366Z","end":"2026-01-27T19:49:31.5342Z","steps":["trace[1641610514] 'process raft request' (duration: 769.670539ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:49:31.534293Z","caller":"traceutil/trace.go:171","msg":"trace[811377688] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:537; }","duration":"1.2421956s","start":"2026-01-27T19:49:30.292079Z","end":"2026-01-27T19:49:31.534274Z","steps":["trace[811377688] 'agreement among raft nodes before linearized reading' (duration: 1.242115098s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:31.534338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:30.292057Z","time spent":"1.242271421s","remote":"127.0.0.1:51346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-27T19:49:31.53437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:30.763637Z","time spent":"770.652782ms","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-27T19:49:31.534563Z","caller":"traceutil/trace.go:171","msg":"trace[762170035] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"1.24218446s","start":"2026-01-27T19:49:30.292368Z","end":"2026-01-27T19:49:31.534552Z","steps":["trace[762170035] 'process raft request' (duration: 1.240448259s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:31.534617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:30.29236Z","time spent":"1.24222636s","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-27T19:49:31.534652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.074429ms","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-01-27T19:49:31.534716Z","caller":"traceutil/trace.go:171","msg":"trace[2025860169] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:537; }","duration":"801.16213ms","start":"2026-01-27T19:49:30.733532Z","end":"2026-01-27T19:49:31.534695Z","steps":["trace[2025860169] 'agreement among raft nodes before linearized reading' (duration: 800.993306ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:49:31.534733Z","caller":"traceutil/trace.go:171","msg":"trace[1293107752] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"1.242314083s","start":"2026-01-27T19:49:30.29241Z","end":"2026-01-27T19:49:31.534724Z","steps":["trace[1293107752] 'process raft request' (duration: 1.24050259s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:31.534761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:30.733513Z","time spent":"801.238992ms","remote":"127.0.0.1:51384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":1,"response size":188510,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-01-27T19:49:31.534787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:30.292406Z","time spent":"1.242349303s","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-27T19:49:31.534897Z","caller":"traceutil/trace.go:171","msg":"trace[1905292870] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"1.242451615s","start":"2026-01-27T19:49:30.292437Z","end":"2026-01-27T19:49:31.534888Z","steps":["trace[1905292870] 'process raft request' (duration: 1.240542761s)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:49:31.537572Z","caller":"traceutil/trace.go:171","msg":"trace[1052085630] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"1.193893308s","start":"2026-01-27T19:49:30.343662Z","end":"2026-01-27T19:49:31.537555Z","steps":["trace[1052085630] 'process raft request' (duration: 1.189478763s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:31.54233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:30.343649Z","time spent":"1.19816674s","remote":"127.0.0.1:51384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188471,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-27T19:49:31.542571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:30.292433Z","time spent":"1.24932435s","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-27T19:49:31.735359Z","caller":"traceutil/trace.go:171","msg":"trace[1790093140] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"106.532261ms","start":"2026-01-27T19:49:31.628806Z","end":"2026-01-27T19:49:31.735338Z","steps":["trace[1790093140] 'process raft request' (duration: 104.760819ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:49:34.688696Z","caller":"traceutil/trace.go:171","msg":"trace[2044818245] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"442.510876ms","start":"2026-01-27T19:49:34.246164Z","end":"2026-01-27T19:49:34.688675Z","steps":["trace[2044818245] 'process raft request' (duration: 442.381323ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:34.688817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:34.246152Z","time spent":"442.607348ms","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-27T19:49:39.327289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.267202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:49:39.327372Z","caller":"traceutil/trace.go:171","msg":"trace[550765722] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:557; }","duration":"349.407625ms","start":"2026-01-27T19:49:38.977946Z","end":"2026-01-27T19:49:39.327353Z","steps":["trace[550765722] 'range keys from in-memory index tree' (duration: 349.216881ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:39.327416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:38.977933Z","time spent":"349.470876ms","remote":"127.0.0.1:51344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-27T19:49:40.088658Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256276450338548644,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-27T19:49:40.58938Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256276450338548644,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-27T19:49:40.67937Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.096011074s","expected-duration":"1s"} {"level":"info","ts":"2026-01-27T19:49:40.679686Z","caller":"traceutil/trace.go:171","msg":"trace[52570826] linearizableReadLoop","detail":"{readStateIndex:576; appliedIndex:575; }","duration":"1.091602058s","start":"2026-01-27T19:49:39.58806Z","end":"2026-01-27T19:49:40.679662Z","steps":["trace[52570826] 'read index received' (duration: 1.091430794s)","trace[52570826] 'applied index is now lower than readState.Index' (duration: 170.374µs)"],"step_count":2} {"level":"info","ts":"2026-01-27T19:49:40.679767Z","caller":"traceutil/trace.go:171","msg":"trace[1116701305] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"1.096529136s","start":"2026-01-27T19:49:39.583227Z","end":"2026-01-27T19:49:40.679757Z","steps":["trace[1116701305] 'process raft request' (duration: 1.096308931s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:40.679874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:39.583202Z","time spent":"1.096591507s","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-27T19:49:40.680138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.08536882s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-27T19:49:40.680206Z","caller":"traceutil/trace.go:171","msg":"trace[1345795132] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:558; }","duration":"1.085467612s","start":"2026-01-27T19:49:39.59472Z","end":"2026-01-27T19:49:40.680188Z","steps":["trace[1345795132] 'agreement among raft nodes before linearized reading' (duration: 1.085256308s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:40.680263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:39.594709Z","time spent":"1.085544674s","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-27T19:49:40.680261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.705906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:49:40.680306Z","caller":"traceutil/trace.go:171","msg":"trace[1971824055] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:558; }","duration":"690.779168ms","start":"2026-01-27T19:49:39.989512Z","end":"2026-01-27T19:49:40.680291Z","steps":["trace[1971824055] 'agreement among raft nodes before linearized reading' (duration: 690.719407ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:40.680338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:39.989493Z","time spent":"690.838819ms","remote":"127.0.0.1:51344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-27T19:49:40.680475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.085290047s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-01-27T19:49:40.680509Z","caller":"traceutil/trace.go:171","msg":"trace[260748040] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:558; }","duration":"1.085341319s","start":"2026-01-27T19:49:39.595159Z","end":"2026-01-27T19:49:40.6805Z","steps":["trace[260748040] 'agreement among raft nodes before linearized reading' (duration: 1.085255567s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:40.680537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:39.595149Z","time spent":"1.08538267s","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-27T19:49:40.680554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.092489689s","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-01-27T19:49:40.680585Z","caller":"traceutil/trace.go:171","msg":"trace[1330205015] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:558; }","duration":"1.0925193s","start":"2026-01-27T19:49:39.588056Z","end":"2026-01-27T19:49:40.680576Z","steps":["trace[1330205015] 'agreement among raft nodes before linearized reading' (duration: 1.092401577s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:40.680612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:39.587999Z","time spent":"1.092607112s","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-27T19:49:41.063814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.088811ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-27T19:49:41.064129Z","caller":"traceutil/trace.go:171","msg":"trace[1637565754] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:576; }","duration":"242.72372ms","start":"2026-01-27T19:49:40.821382Z","end":"2026-01-27T19:49:41.064106Z","steps":["trace[1637565754] 'read index received' (duration: 223.607034ms)","trace[1637565754] 'applied index is now lower than readState.Index' (duration: 19.115606ms)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:49:41.064303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.923785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-01-27T19:49:41.064337Z","caller":"traceutil/trace.go:171","msg":"trace[1837323366] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:561; }","duration":"242.997126ms","start":"2026-01-27T19:49:40.821331Z","end":"2026-01-27T19:49:41.064328Z","steps":["trace[1837323366] 'agreement among raft nodes before linearized reading' (duration: 242.850953ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:49:41.066657Z","caller":"traceutil/trace.go:171","msg":"trace[1815719980] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"375.22496ms","start":"2026-01-27T19:49:40.691418Z","end":"2026-01-27T19:49:41.066643Z","steps":["trace[1815719980] 'process raft request' (duration: 372.640678ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:41.066747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:40.691411Z","time spent":"375.292291ms","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-27T19:49:41.067359Z","caller":"traceutil/trace.go:171","msg":"trace[1460880263] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"377.781691ms","start":"2026-01-27T19:49:40.689561Z","end":"2026-01-27T19:49:41.067342Z","steps":["trace[1460880263] 'process raft request' (duration: 374.410311ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:41.067445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:40.689544Z","time spent":"377.861943ms","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-27T19:49:41.064615Z","caller":"traceutil/trace.go:171","msg":"trace[692383116] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"375.528707ms","start":"2026-01-27T19:49:40.689075Z","end":"2026-01-27T19:49:41.064603Z","steps":["trace[692383116] 'process raft request' (duration: 94.580126ms)","trace[692383116] 'compare' (duration: 279.570229ms)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:49:41.070393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:40.689046Z","time spent":"380.813264ms","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-27T19:49:42.481556Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256276450338548654,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-27T19:49:42.981727Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256276450338548654,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-27T19:49:43.482782Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256276450338548654,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-27T19:49:43.483578Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.762389869s","expected-duration":"1s"} {"level":"warn","ts":"2026-01-27T19:49:43.982395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.001115323s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-01-27T19:49:43.982512Z","caller":"traceutil/trace.go:171","msg":"trace[1749529512] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001284518s","start":"2026-01-27T19:49:41.981201Z","end":"2026-01-27T19:49:43.982486Z","steps":["trace[1749529512] 'agreement among raft nodes before linearized reading' (duration: 2.001134544s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:43.982596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:41.981186Z","time spent":"2.0013996s","remote":"127.0.0.1:51344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/01/27 19:49:43 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-01-27T19:49:44.159834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.72678935s","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-01-27T19:49:44.159964Z","caller":"traceutil/trace.go:171","msg":"trace[1091193809] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:562; }","duration":"1.726935894s","start":"2026-01-27T19:49:42.43301Z","end":"2026-01-27T19:49:44.159946Z","steps":["trace[1091193809] 'range keys from in-memory index tree' (duration: 1.726775549s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:44.160197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.23348ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-27T19:49:44.160355Z","caller":"traceutil/trace.go:171","msg":"trace[1726193558] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"2.443999658s","start":"2026-01-27T19:49:41.716331Z","end":"2026-01-27T19:49:44.160331Z","steps":["trace[1726193558] 'process raft request' (duration: 1.76744285s)","trace[1726193558] 'compare' (duration: 675.428601ms)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:49:44.160428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:41.716313Z","time spent":"2.444082309s","remote":"127.0.0.1:51368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-27T19:49:45.522231Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.038539205s","expected-duration":"1s"} {"level":"info","ts":"2026-01-27T19:49:45.523899Z","caller":"traceutil/trace.go:171","msg":"trace[649613272] linearizableReadLoop","detail":"{readStateIndex:583; appliedIndex:581; }","duration":"3.542647154s","start":"2026-01-27T19:49:41.981231Z","end":"2026-01-27T19:49:45.523878Z","steps":["trace[649613272] 'read index received' (duration: 1.502550251s)","trace[649613272] 'applied index is now lower than readState.Index' (duration: 2.040095853s)"],"step_count":2} {"level":"info","ts":"2026-01-27T19:49:45.523988Z","caller":"traceutil/trace.go:171","msg":"trace[934976381] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"3.762427906s","start":"2026-01-27T19:49:41.761551Z","end":"2026-01-27T19:49:45.523979Z","steps":["trace[934976381] 'process raft request' (duration: 3.760821107s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:45.524076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:41.761527Z","time spent":"3.762486068s","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-27T19:49:45.524083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.837580105s","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-01-27T19:49:45.524125Z","caller":"traceutil/trace.go:171","msg":"trace[1400315282] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:564; }","duration":"2.837677108s","start":"2026-01-27T19:49:42.686435Z","end":"2026-01-27T19:49:45.524113Z","steps":["trace[1400315282] 'agreement among raft nodes before linearized reading' (duration: 2.837548725s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:45.524156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:42.686405Z","time spent":"2.837743729s","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-27T19:49:45.524244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.36038059s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-01-27T19:49:45.52429Z","caller":"traceutil/trace.go:171","msg":"trace[77181064] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:564; }","duration":"1.360437851s","start":"2026-01-27T19:49:44.163822Z","end":"2026-01-27T19:49:45.52426Z","steps":["trace[77181064] 'agreement among raft nodes before linearized reading' (duration: 1.36039578s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:45.524315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:44.163805Z","time spent":"1.360504233s","remote":"127.0.0.1:51496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-01-27T19:49:45.524437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.541430218s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:49:45.524466Z","caller":"traceutil/trace.go:171","msg":"trace[1030194080] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:564; }","duration":"1.541457328s","start":"2026-01-27T19:49:43.983Z","end":"2026-01-27T19:49:45.524457Z","steps":["trace[1030194080] 'agreement among raft nodes before linearized reading' (duration: 1.541417577s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:45.524491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:43.982928Z","time spent":"1.541557991s","remote":"127.0.0.1:51346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-27T19:49:45.524503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.452182252s","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-01-27T19:49:45.524537Z","caller":"traceutil/trace.go:171","msg":"trace[1382276769] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:564; }","duration":"2.45248765s","start":"2026-01-27T19:49:43.072037Z","end":"2026-01-27T19:49:45.524525Z","steps":["trace[1382276769] 'agreement among raft nodes before linearized reading' (duration: 2.452392678s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:45.524567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:43.072017Z","time spent":"2.452543081s","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-27T19:49:45.524644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.452349277s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-27T19:49:45.52467Z","caller":"traceutil/trace.go:171","msg":"trace[1391600781] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:564; }","duration":"2.452375807s","start":"2026-01-27T19:49:43.072287Z","end":"2026-01-27T19:49:45.524663Z","steps":["trace[1391600781] 'agreement among raft nodes before linearized reading' (duration: 2.452298685s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:45.524696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:43.072251Z","time spent":"2.452439749s","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-27T19:49:45.524806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.416222584s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-01-27T19:49:45.524836Z","caller":"traceutil/trace.go:171","msg":"trace[1808442747] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:564; }","duration":"2.416275566s","start":"2026-01-27T19:49:43.108551Z","end":"2026-01-27T19:49:45.524827Z","steps":["trace[1808442747] 'agreement among raft nodes before linearized reading' (duration: 2.416115892s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:45.524865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:43.10854Z","time spent":"2.416318967s","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-27T19:49:45.524966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.450731918s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-01-27T19:49:45.524988Z","caller":"traceutil/trace.go:171","msg":"trace[1468877523] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:564; }","duration":"2.45077261s","start":"2026-01-27T19:49:43.074209Z","end":"2026-01-27T19:49:45.524981Z","steps":["trace[1468877523] 'agreement among raft nodes before linearized reading' (duration: 2.450710468s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:45.525008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:43.074198Z","time spent":"2.450806s","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-27T19:49:49.783662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.870674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-01-27T19:49:49.78374Z","caller":"traceutil/trace.go:171","msg":"trace[1933270389] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:591; }","duration":"198.983997ms","start":"2026-01-27T19:49:49.584739Z","end":"2026-01-27T19:49:49.783722Z","steps":["trace[1933270389] 'range keys from in-memory index tree' (duration: 198.722039ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:49.783781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.654231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-01-27T19:49:49.783825Z","caller":"traceutil/trace.go:171","msg":"trace[928937108] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:591; }","duration":"157.727393ms","start":"2026-01-27T19:49:49.626086Z","end":"2026-01-27T19:49:49.783814Z","steps":["trace[928937108] 'range keys from in-memory index tree' (duration: 157.542838ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:49.783962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.20389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-27T19:49:49.783994Z","caller":"traceutil/trace.go:171","msg":"trace[303675557] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:591; }","duration":"157.247781ms","start":"2026-01-27T19:49:49.626737Z","end":"2026-01-27T19:49:49.783985Z","steps":["trace[303675557] 'range keys from in-memory index tree' (duration: 157.133158ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:49.784003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.154925ms","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-01-27T19:49:49.78402Z","caller":"traceutil/trace.go:171","msg":"trace[1094916141] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:591; }","duration":"169.196346ms","start":"2026-01-27T19:49:49.614819Z","end":"2026-01-27T19:49:49.784015Z","steps":["trace[1094916141] 'range keys from in-memory index tree' (duration: 169.086104ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:49.784079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.92523ms","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-01-27T19:49:49.784091Z","caller":"traceutil/trace.go:171","msg":"trace[555417403] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:591; }","duration":"168.93766ms","start":"2026-01-27T19:49:49.615149Z","end":"2026-01-27T19:49:49.784087Z","steps":["trace[555417403] 'range keys from in-memory index tree' (duration: 168.855958ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:49:50.378282Z","caller":"traceutil/trace.go:171","msg":"trace[1560661274] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:611; }","duration":"536.741363ms","start":"2026-01-27T19:49:49.841527Z","end":"2026-01-27T19:49:50.378268Z","steps":["trace[1560661274] 'read index received' (duration: 3.525944ms)","trace[1560661274] 'applied index is now lower than readState.Index' (duration: 533.214599ms)"],"step_count":2} {"level":"info","ts":"2026-01-27T19:49:50.378315Z","caller":"traceutil/trace.go:171","msg":"trace[960481475] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"582.894704ms","start":"2026-01-27T19:49:49.795395Z","end":"2026-01-27T19:49:50.37829Z","steps":["trace[960481475] 'process raft request' (duration: 582.823332ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:49:50.378364Z","caller":"traceutil/trace.go:171","msg":"trace[2052904374] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"579.543924ms","start":"2026-01-27T19:49:49.798815Z","end":"2026-01-27T19:49:50.378358Z","steps":["trace[2052904374] 'process raft request' (duration: 579.422802ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:50.378431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:49.798808Z","time spent":"579.571955ms","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-27T19:49:50.378438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:49.795392Z","time spent":"582.974116ms","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-27T19:49:50.378589Z","caller":"traceutil/trace.go:171","msg":"trace[1675689959] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"578.804957ms","start":"2026-01-27T19:49:49.799779Z","end":"2026-01-27T19:49:50.378584Z","steps":["trace[1675689959] 'process raft request' (duration: 578.472019ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:50.378615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:49.799773Z","time spent":"578.828127ms","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-27T19:49:50.378711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.183494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-q8n5v\" ","response":"range_response_count:1 size:17411"} {"level":"info","ts":"2026-01-27T19:49:50.378712Z","caller":"traceutil/trace.go:171","msg":"trace[1988786528] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"583.354255ms","start":"2026-01-27T19:49:49.795351Z","end":"2026-01-27T19:49:50.378705Z","steps":["trace[1988786528] 'process raft request' (duration: 582.767521ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:49:50.378726Z","caller":"traceutil/trace.go:171","msg":"trace[816709887] range","detail":"{range_begin:/registry/pods/kube-system/cilium-q8n5v; range_end:; response_count:1; response_revision:596; }","duration":"537.226205ms","start":"2026-01-27T19:49:49.841495Z","end":"2026-01-27T19:49:50.378721Z","steps":["trace[816709887] 'agreement among raft nodes before linearized reading' (duration: 537.161343ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:50.378746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:49.795336Z","time spent":"583.394245ms","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-27T19:49:50.378815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:49.841415Z","time spent":"537.323307ms","remote":"127.0.0.1:51512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17435,"request content":"key:\"/registry/pods/kube-system/cilium-q8n5v\" "} {"level":"warn","ts":"2026-01-27T19:49:50.378896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.681346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:49:50.378909Z","caller":"traceutil/trace.go:171","msg":"trace[959749794] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:596; }","duration":"397.718857ms","start":"2026-01-27T19:49:49.981186Z","end":"2026-01-27T19:49:50.378905Z","steps":["trace[959749794] 'agreement among raft nodes before linearized reading' (duration: 397.697657ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:50.37892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:49.981171Z","time spent":"397.746718ms","remote":"127.0.0.1:51344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-27T19:49:51.920964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.800404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:49:51.921022Z","caller":"traceutil/trace.go:171","msg":"trace[1930633862] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:601; }","duration":"933.898776ms","start":"2026-01-27T19:49:50.98711Z","end":"2026-01-27T19:49:51.921009Z","steps":["trace[1930633862] 'range keys from in-memory index tree' (duration: 933.748103ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:51.921045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:50.987084Z","time spent":"933.956288ms","remote":"127.0.0.1:51344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-27T19:49:51.92133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.669543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.49\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-01-27T19:49:51.921379Z","caller":"traceutil/trace.go:171","msg":"trace[1938045687] range","detail":"{range_begin:/registry/masterleases/199.204.45.49; range_end:; response_count:1; response_revision:601; }","duration":"235.760134ms","start":"2026-01-27T19:49:51.685609Z","end":"2026-01-27T19:49:51.921369Z","steps":["trace[1938045687] 'range keys from in-memory index tree' (duration: 235.602182ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:49:52.182205Z","caller":"traceutil/trace.go:171","msg":"trace[562414540] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:622; }","duration":"258.721971ms","start":"2026-01-27T19:49:51.923459Z","end":"2026-01-27T19:49:52.182181Z","steps":["trace[562414540] 'read index received' (duration: 258.715091ms)","trace[562414540] 'applied index is now lower than readState.Index' (duration: 5.58µs)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:49:52.182601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.861134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:49:52.182644Z","caller":"traceutil/trace.go:171","msg":"trace[1114166393] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:601; }","duration":"259.185303ms","start":"2026-01-27T19:49:51.923447Z","end":"2026-01-27T19:49:52.182632Z","steps":["trace[1114166393] 'agreement among raft nodes before linearized reading' (duration: 258.835265ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:52.691624Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256276450338548736,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-27T19:49:52.779481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.067105ms","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-01-27T19:49:52.779599Z","caller":"traceutil/trace.go:171","msg":"trace[2106486479] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:601; }","duration":"346.197948ms","start":"2026-01-27T19:49:52.433384Z","end":"2026-01-27T19:49:52.779582Z","steps":["trace[2106486479] 'range keys from in-memory index tree' (duration: 346.039065ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:53.192741Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256276450338548736,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-27T19:49:53.218562Z","caller":"traceutil/trace.go:171","msg":"trace[2069681206] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"1.28326859s","start":"2026-01-27T19:49:51.935272Z","end":"2026-01-27T19:49:53.218541Z","steps":["trace[2069681206] 'process raft request' (duration: 1.283163518s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:53.218695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:51.935253Z","time spent":"1.283368722s","remote":"127.0.0.1:51512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17813,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-27T19:49:53.219933Z","caller":"traceutil/trace.go:171","msg":"trace[662173747] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"1.295377078s","start":"2026-01-27T19:49:51.924541Z","end":"2026-01-27T19:49:53.219919Z","steps":["trace[662173747] 'process raft request' (duration: 1.29376562s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:53.220014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:51.924526Z","time spent":"1.29544929s","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-27T19:49:54.191388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999985715s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-01-27T19:49:54.191447Z","caller":"traceutil/trace.go:171","msg":"trace[1496429689] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000088568s","start":"2026-01-27T19:49:52.191345Z","end":"2026-01-27T19:49:54.191434Z","steps":["trace[1496429689] 'agreement among raft nodes before linearized reading' (duration: 2.000008936s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:54.191481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:52.191332Z","time spent":"2.000143179s","remote":"127.0.0.1:51344","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-01-27T19:49:54.726679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.349994662s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-27T19:49:54.726747Z","caller":"traceutil/trace.go:171","msg":"trace[953932802] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:624; }","duration":"2.535352166s","start":"2026-01-27T19:49:52.191386Z","end":"2026-01-27T19:49:54.726738Z","steps":["trace[953932802] 'read index received' (duration: 1.031044273s)","trace[953932802] 'applied index is now lower than readState.Index' (duration: 1.504307263s)"],"step_count":2} {"level":"info","ts":"2026-01-27T19:49:54.726793Z","caller":"traceutil/trace.go:171","msg":"trace[1389807082] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"2.543692205s","start":"2026-01-27T19:49:52.183095Z","end":"2026-01-27T19:49:54.726788Z","steps":["trace[1389807082] 'process raft request' (duration: 1.19354459s)","trace[1389807082] 'compare' (duration: 1.349724745s)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:49:54.726818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:52.183076Z","time spent":"2.543727596s","remote":"127.0.0.1:51368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-27T19:49:54.72696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.340656282s","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-01-27T19:49:54.726974Z","caller":"traceutil/trace.go:171","msg":"trace[1651736646] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:604; }","duration":"2.340676902s","start":"2026-01-27T19:49:52.386293Z","end":"2026-01-27T19:49:54.72697Z","steps":["trace[1651736646] 'agreement among raft nodes before linearized reading' (duration: 2.34059555s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:54.726989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:52.386253Z","time spent":"2.340732203s","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-27T19:49:54.727082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.821872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-27T19:49:54.727094Z","caller":"traceutil/trace.go:171","msg":"trace[1898007826] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:604; }","duration":"429.863823ms","start":"2026-01-27T19:49:54.297227Z","end":"2026-01-27T19:49:54.72709Z","steps":["trace[1898007826] 'agreement among raft nodes before linearized reading' (duration: 429.836532ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:54.727108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:54.297211Z","time spent":"429.891754ms","remote":"127.0.0.1:51430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":12,"response size":31,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-01-27T19:49:54.727173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.93298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:49:54.727185Z","caller":"traceutil/trace.go:171","msg":"trace[543798011] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:604; }","duration":"534.963581ms","start":"2026-01-27T19:49:54.192217Z","end":"2026-01-27T19:49:54.727181Z","steps":["trace[543798011] 'agreement among raft nodes before linearized reading' (duration: 534.946361ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:54.727195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:54.192207Z","time spent":"534.985971ms","remote":"127.0.0.1:51346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-27T19:49:54.727271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.338505751s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-01-27T19:49:54.727286Z","caller":"traceutil/trace.go:171","msg":"trace[235025800] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:604; }","duration":"2.338538741s","start":"2026-01-27T19:49:52.388743Z","end":"2026-01-27T19:49:54.727281Z","steps":["trace[235025800] 'agreement among raft nodes before linearized reading' (duration: 2.33850056s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:54.727303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:52.388735Z","time spent":"2.338564182s","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-27T19:49:54.727374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.340268792s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-01-27T19:49:54.727386Z","caller":"traceutil/trace.go:171","msg":"trace[682230523] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:604; }","duration":"2.340323264s","start":"2026-01-27T19:49:52.387059Z","end":"2026-01-27T19:49:54.727382Z","steps":["trace[682230523] 'agreement among raft nodes before linearized reading' (duration: 2.340288923s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:54.727396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:52.387034Z","time spent":"2.340359504s","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-27T19:49:54.727483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.341167123s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-27T19:49:54.727496Z","caller":"traceutil/trace.go:171","msg":"trace[1130084681] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:604; }","duration":"2.341187313s","start":"2026-01-27T19:49:52.386305Z","end":"2026-01-27T19:49:54.727492Z","steps":["trace[1130084681] 'agreement among raft nodes before linearized reading' (duration: 2.341151932s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:54.727509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:49:52.386302Z","time spent":"2.341202214s","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-01-27T19:49:55.039805Z","caller":"traceutil/trace.go:171","msg":"trace[1093170013] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:625; }","duration":"297.117817ms","start":"2026-01-27T19:49:54.742665Z","end":"2026-01-27T19:49:55.039783Z","steps":["trace[1093170013] 'read index received' (duration: 35.544917ms)","trace[1093170013] 'applied index is now lower than readState.Index' (duration: 261.57203ms)"],"step_count":2} {"level":"info","ts":"2026-01-27T19:49:55.040064Z","caller":"traceutil/trace.go:171","msg":"trace[1800999] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"295.955019ms","start":"2026-01-27T19:49:54.744062Z","end":"2026-01-27T19:49:55.040017Z","steps":["trace[1800999] 'process raft request' (duration: 295.690323ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:55.040163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.458125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:49:55.040201Z","caller":"traceutil/trace.go:171","msg":"trace[1946915487] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:608; }","duration":"297.536956ms","start":"2026-01-27T19:49:54.742654Z","end":"2026-01-27T19:49:55.04019Z","steps":["trace[1946915487] 'agreement among raft nodes before linearized reading' (duration: 297.447914ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:49:55.040414Z","caller":"traceutil/trace.go:171","msg":"trace[1254985389] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"299.582155ms","start":"2026-01-27T19:49:54.74082Z","end":"2026-01-27T19:49:55.040402Z","steps":["trace[1254985389] 'process raft request' (duration: 298.776836ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:49:55.04056Z","caller":"traceutil/trace.go:171","msg":"trace[1838637655] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"296.505172ms","start":"2026-01-27T19:49:54.744024Z","end":"2026-01-27T19:49:55.04053Z","steps":["trace[1838637655] 'process raft request' (duration: 295.694843ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:55.040576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.891695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.188eae51d260af7a\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-01-27T19:49:55.040611Z","caller":"traceutil/trace.go:171","msg":"trace[153430121] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.188eae51d260af7a; range_end:; response_count:1; response_revision:608; }","duration":"297.941966ms","start":"2026-01-27T19:49:54.742659Z","end":"2026-01-27T19:49:55.0406Z","steps":["trace[153430121] 'agreement among raft nodes before linearized reading' (duration: 297.812603ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:55.040808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.178738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-01-27T19:49:55.040835Z","caller":"traceutil/trace.go:171","msg":"trace[894822381] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:608; }","duration":"297.214969ms","start":"2026-01-27T19:49:54.743612Z","end":"2026-01-27T19:49:55.040827Z","steps":["trace[894822381] 'agreement among raft nodes before linearized reading' (duration: 297.165798ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:49:55.161908Z","caller":"traceutil/trace.go:171","msg":"trace[1315911500] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"100.688242ms","start":"2026-01-27T19:49:55.061199Z","end":"2026-01-27T19:49:55.161887Z","steps":["trace[1315911500] 'process raft request' (duration: 56.652072ms)","trace[1315911500] 'compare' (duration: 43.929748ms)"],"step_count":2} {"level":"info","ts":"2026-01-27T19:49:55.165119Z","caller":"traceutil/trace.go:171","msg":"trace[1950859711] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"101.726258ms","start":"2026-01-27T19:49:55.063382Z","end":"2026-01-27T19:49:55.165108Z","steps":["trace[1950859711] 'process raft request' (duration: 101.652345ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:49:56.271151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.795729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:49:56.271222Z","caller":"traceutil/trace.go:171","msg":"trace[372638914] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:615; }","duration":"288.906422ms","start":"2026-01-27T19:49:55.982298Z","end":"2026-01-27T19:49:56.271204Z","steps":["trace[372638914] 'range keys from in-memory index tree' (duration: 288.679096ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:49:56.422699Z","caller":"traceutil/trace.go:171","msg":"trace[1888944569] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"135.716447ms","start":"2026-01-27T19:49:56.286964Z","end":"2026-01-27T19:49:56.42268Z","steps":["trace[1888944569] 'process raft request' (duration: 135.587254ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:50:01.064378Z","caller":"traceutil/trace.go:171","msg":"trace[2090603974] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"264.56065ms","start":"2026-01-27T19:50:00.799799Z","end":"2026-01-27T19:50:01.06436Z","steps":["trace[2090603974] 'process raft request' (duration: 263.836752ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:50:09.379235Z","caller":"traceutil/trace.go:171","msg":"trace[1160752025] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"175.025324ms","start":"2026-01-27T19:50:09.20419Z","end":"2026-01-27T19:50:09.379215Z","steps":["trace[1160752025] 'process raft request' (duration: 117.125264ms)","trace[1160752025] 'compare' (duration: 57.633434ms)"],"step_count":2} {"level":"info","ts":"2026-01-27T19:50:09.38156Z","caller":"traceutil/trace.go:171","msg":"trace[901404516] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"176.236304ms","start":"2026-01-27T19:50:09.205306Z","end":"2026-01-27T19:50:09.381543Z","steps":["trace[901404516] 'process raft request' (duration: 175.826574ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:50:09.381694Z","caller":"traceutil/trace.go:171","msg":"trace[1035164355] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"174.873751ms","start":"2026-01-27T19:50:09.206815Z","end":"2026-01-27T19:50:09.381688Z","steps":["trace[1035164355] 'process raft request' (duration: 174.39823ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:50:21.459324Z","caller":"traceutil/trace.go:171","msg":"trace[1738561176] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"204.19395ms","start":"2026-01-27T19:50:21.255113Z","end":"2026-01-27T19:50:21.459307Z","steps":["trace[1738561176] 'process raft request' (duration: 204.076407ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:50:22.193785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.023085ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-27T19:50:22.19471Z","caller":"traceutil/trace.go:171","msg":"trace[856040102] linearizableReadLoop","detail":"{readStateIndex:788; appliedIndex:787; }","duration":"647.929465ms","start":"2026-01-27T19:50:21.546758Z","end":"2026-01-27T19:50:22.194688Z","steps":["trace[856040102] 'read index received' (duration: 191.766674ms)","trace[856040102] 'applied index is now lower than readState.Index' (duration: 456.161281ms)"],"step_count":2} {"level":"info","ts":"2026-01-27T19:50:22.194783Z","caller":"traceutil/trace.go:171","msg":"trace[1068127611] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"727.959854ms","start":"2026-01-27T19:50:21.466788Z","end":"2026-01-27T19:50:22.194748Z","steps":["trace[1068127611] 'process raft request' (duration: 271.814123ms)","trace[1068127611] 'compare' (duration: 454.767349ms)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:50:22.194938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:50:21.466759Z","time spent":"728.102738ms","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-27T19:50:22.19497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.209562ms","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-01-27T19:50:22.195095Z","caller":"traceutil/trace.go:171","msg":"trace[2116049579] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:759; }","duration":"648.371215ms","start":"2026-01-27T19:50:21.546708Z","end":"2026-01-27T19:50:22.195079Z","steps":["trace[2116049579] 'agreement among raft nodes before linearized reading' (duration: 648.15031ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:50:22.195138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:50:21.546686Z","time spent":"648.439507ms","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-27T19:50:22.195282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.457202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-01-27T19:50:22.19531Z","caller":"traceutil/trace.go:171","msg":"trace[69260905] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:759; }","duration":"644.593065ms","start":"2026-01-27T19:50:21.550709Z","end":"2026-01-27T19:50:22.195302Z","steps":["trace[69260905] 'agreement among raft nodes before linearized reading' (duration: 644.475962ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:50:22.195331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:50:21.550699Z","time spent":"644.628197ms","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-27T19:50:22.195459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.254283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-01-27T19:50:22.19546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.584853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.49\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-01-27T19:50:22.19548Z","caller":"traceutil/trace.go:171","msg":"trace[2064976922] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:759; }","duration":"643.294494ms","start":"2026-01-27T19:50:21.55218Z","end":"2026-01-27T19:50:22.195475Z","steps":["trace[2064976922] 'agreement among raft nodes before linearized reading' (duration: 643.240673ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:50:22.195498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:50:21.55217Z","time spent":"643.323935ms","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-01-27T19:50:22.195494Z","caller":"traceutil/trace.go:171","msg":"trace[1916363595] range","detail":"{range_begin:/registry/masterleases/199.204.45.49; range_end:; response_count:1; response_revision:759; }","duration":"506.651514ms","start":"2026-01-27T19:50:21.688833Z","end":"2026-01-27T19:50:22.195484Z","steps":["trace[1916363595] 'agreement among raft nodes before linearized reading' (duration: 506.553002ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:50:22.195524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:50:21.688814Z","time spent":"506.703815ms","remote":"127.0.0.1:51368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.49\" "} {"level":"warn","ts":"2026-01-27T19:50:23.172776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.456328ms","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-01-27T19:50:23.173007Z","caller":"traceutil/trace.go:171","msg":"trace[675865753] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:759; }","duration":"739.703234ms","start":"2026-01-27T19:50:22.43327Z","end":"2026-01-27T19:50:23.172973Z","steps":["trace[675865753] 'range keys from in-memory index tree' (duration: 739.429838ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:50:23.175483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.18202ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-27T19:50:23.177002Z","caller":"traceutil/trace.go:171","msg":"trace[729409062] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"977.573608ms","start":"2026-01-27T19:50:22.19941Z","end":"2026-01-27T19:50:23.176983Z","steps":["trace[729409062] 'process raft request' (duration: 574.79647ms)","trace[729409062] 'compare' (duration: 401.031196ms)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:50:23.177115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:50:22.19939Z","time spent":"977.658011ms","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-27T19:50:23.179438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:50:22.199903Z","time spent":"979.530966ms","remote":"127.0.0.1:51368","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-01-27T19:50:23.179687Z","caller":"traceutil/trace.go:171","msg":"trace[599261412] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"972.433105ms","start":"2026-01-27T19:50:22.207242Z","end":"2026-01-27T19:50:23.179675Z","steps":["trace[599261412] 'process raft request' (duration: 972.097668ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:50:23.179751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:50:22.207231Z","time spent":"972.483536ms","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-27T19:50:23.179944Z","caller":"traceutil/trace.go:171","msg":"trace[1088635425] linearizableReadLoop","detail":"{readStateIndex:792; appliedIndex:788; }","duration":"482.663504ms","start":"2026-01-27T19:50:22.696793Z","end":"2026-01-27T19:50:23.179456Z","steps":["trace[1088635425] 'read index received' (duration: 78.174645ms)","trace[1088635425] 'applied index is now lower than readState.Index' (duration: 404.487759ms)"],"step_count":2} {"level":"info","ts":"2026-01-27T19:50:23.180035Z","caller":"traceutil/trace.go:171","msg":"trace[8740109] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"972.470467ms","start":"2026-01-27T19:50:22.207553Z","end":"2026-01-27T19:50:23.180023Z","steps":["trace[8740109] 'process raft request' (duration: 971.862492ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:50:23.180188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:50:22.207547Z","time spent":"972.61613ms","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-27T19:50:23.180087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.303909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:50:23.18029Z","caller":"traceutil/trace.go:171","msg":"trace[1108007071] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:762; }","duration":"483.584126ms","start":"2026-01-27T19:50:22.696696Z","end":"2026-01-27T19:50:23.18028Z","steps":["trace[1108007071] 'agreement among raft nodes before linearized reading' (duration: 483.364071ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:50:23.180317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:50:22.696662Z","time spent":"483.649487ms","remote":"127.0.0.1:51344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-27T19:50:23.183135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.443252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:50:23.183178Z","caller":"traceutil/trace.go:171","msg":"trace[210620885] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:762; }","duration":"200.514003ms","start":"2026-01-27T19:50:22.982647Z","end":"2026-01-27T19:50:23.183161Z","steps":["trace[210620885] 'agreement among raft nodes before linearized reading' (duration: 200.463272ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:50:23.448943Z","caller":"traceutil/trace.go:171","msg":"trace[301222683] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"265.998845ms","start":"2026-01-27T19:50:23.182906Z","end":"2026-01-27T19:50:23.448905Z","steps":["trace[301222683] 'process raft request' (duration: 176.644594ms)","trace[301222683] 'compare' (duration: 89.068415ms)"],"step_count":2} {"level":"info","ts":"2026-01-27T19:50:23.638264Z","caller":"traceutil/trace.go:171","msg":"trace[1352868236] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"165.98607ms","start":"2026-01-27T19:50:23.472255Z","end":"2026-01-27T19:50:23.638241Z","steps":["trace[1352868236] 'process raft request' (duration: 118.409505ms)","trace[1352868236] 'compare' (duration: 46.912508ms)"],"step_count":2} {"level":"info","ts":"2026-01-27T19:50:34.893544Z","caller":"traceutil/trace.go:171","msg":"trace[1768714458] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"606.658047ms","start":"2026-01-27T19:50:34.286869Z","end":"2026-01-27T19:50:34.893527Z","steps":["trace[1768714458] 'process raft request' (duration: 606.545645ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:50:34.893709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:50:34.286857Z","time spent":"606.757909ms","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-27T19:50:36.071637Z","caller":"traceutil/trace.go:171","msg":"trace[1873387023] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"375.539113ms","start":"2026-01-27T19:50:35.696083Z","end":"2026-01-27T19:50:36.071622Z","steps":["trace[1873387023] 'process raft request' (duration: 375.300387ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:50:36.071735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:50:35.696072Z","time spent":"375.613614ms","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-27T19:50:36.989224Z","caller":"traceutil/trace.go:171","msg":"trace[1034999919] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"617.41088ms","start":"2026-01-27T19:50:36.371791Z","end":"2026-01-27T19:50:36.989202Z","steps":["trace[1034999919] 'process raft request' (duration: 617.211105ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:50:36.989365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:50:36.371775Z","time spent":"617.519212ms","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-27T19:50:37.102027Z","caller":"traceutil/trace.go:171","msg":"trace[1099198759] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"108.110431ms","start":"2026-01-27T19:50:36.993859Z","end":"2026-01-27T19:50:37.101969Z","steps":["trace[1099198759] 'process raft request' (duration: 103.54614ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:50:37.445754Z","caller":"traceutil/trace.go:171","msg":"trace[991173003] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"147.987803ms","start":"2026-01-27T19:50:37.297749Z","end":"2026-01-27T19:50:37.445737Z","steps":["trace[991173003] 'process raft request' (duration: 147.894491ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:50:37.455496Z","caller":"traceutil/trace.go:171","msg":"trace[1906977367] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"157.608829ms","start":"2026-01-27T19:50:37.297876Z","end":"2026-01-27T19:50:37.455485Z","steps":["trace[1906977367] 'process raft request' (duration: 157.448585ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:50:39.719525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.556516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:50:39.719577Z","caller":"traceutil/trace.go:171","msg":"trace[1172679644] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:857; }","duration":"231.647868ms","start":"2026-01-27T19:50:39.487917Z","end":"2026-01-27T19:50:39.719565Z","steps":["trace[1172679644] 'count revisions from in-memory index tree' (duration: 231.495804ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:50:39.719789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.104596ms","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-01-27T19:50:39.719807Z","caller":"traceutil/trace.go:171","msg":"trace[1699480693] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:857; }","duration":"270.148958ms","start":"2026-01-27T19:50:39.449653Z","end":"2026-01-27T19:50:39.719802Z","steps":["trace[1699480693] 'range keys from in-memory index tree' (duration: 270.006124ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:50:39.719908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.156822ms","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-01-27T19:50:39.719925Z","caller":"traceutil/trace.go:171","msg":"trace[1970393778] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:857; }","duration":"262.189513ms","start":"2026-01-27T19:50:39.457732Z","end":"2026-01-27T19:50:39.719921Z","steps":["trace[1970393778] 'range keys from in-memory index tree' (duration: 262.107991ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:50:39.720134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.887373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:50:39.720156Z","caller":"traceutil/trace.go:171","msg":"trace[1588273113] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:857; }","duration":"129.932323ms","start":"2026-01-27T19:50:39.590216Z","end":"2026-01-27T19:50:39.720149Z","steps":["trace[1588273113] 'count revisions from in-memory index tree' (duration: 129.836361ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:50:40.025669Z","caller":"traceutil/trace.go:171","msg":"trace[1004615189] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"301.941085ms","start":"2026-01-27T19:50:39.723711Z","end":"2026-01-27T19:50:40.025652Z","steps":["trace[1004615189] 'process raft request' (duration: 301.855363ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:50:40.025765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:50:39.723698Z","time spent":"302.017537ms","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-27T19:50:40.277778Z","caller":"traceutil/trace.go:171","msg":"trace[1288043927] linearizableReadLoop","detail":"{readStateIndex:893; appliedIndex:892; }","duration":"380.236807ms","start":"2026-01-27T19:50:39.89752Z","end":"2026-01-27T19:50:40.277757Z","steps":["trace[1288043927] 'read index received' (duration: 128.57823ms)","trace[1288043927] 'applied index is now lower than readState.Index' (duration: 251.657537ms)"],"step_count":2} {"level":"info","ts":"2026-01-27T19:50:40.277934Z","caller":"traceutil/trace.go:171","msg":"trace[67588336] transaction","detail":"{read_only:false; response_revision:859; number_of_response:1; }","duration":"554.026252ms","start":"2026-01-27T19:50:39.723897Z","end":"2026-01-27T19:50:40.277923Z","steps":["trace[67588336] 'process raft request' (duration: 550.590548ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:50:40.278056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:50:39.723891Z","time spent":"554.069503ms","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-27T19:50:40.278115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.708467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:50:40.278166Z","caller":"traceutil/trace.go:171","msg":"trace[1116667930] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:859; }","duration":"296.805379ms","start":"2026-01-27T19:50:39.981346Z","end":"2026-01-27T19:50:40.278152Z","steps":["trace[1116667930] 'agreement among raft nodes before linearized reading' (duration: 296.715417ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:50:40.278313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.78886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:50:40.278328Z","caller":"traceutil/trace.go:171","msg":"trace[1602656821] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:859; }","duration":"380.829661ms","start":"2026-01-27T19:50:39.897493Z","end":"2026-01-27T19:50:40.278323Z","steps":["trace[1602656821] 'agreement among raft nodes before linearized reading' (duration: 380.80299ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:50:40.278342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:50:39.89748Z","time spent":"380.857592ms","remote":"127.0.0.1:51458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-01-27T19:50:40.278456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.824673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-01-27T19:50:40.278494Z","caller":"traceutil/trace.go:171","msg":"trace[1694484974] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:859; }","duration":"188.887554ms","start":"2026-01-27T19:50:40.089595Z","end":"2026-01-27T19:50:40.278483Z","steps":["trace[1694484974] 'agreement among raft nodes before linearized reading' (duration: 188.764351ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:50:47.225209Z","caller":"traceutil/trace.go:171","msg":"trace[760124561] linearizableReadLoop","detail":"{readStateIndex:912; appliedIndex:911; }","duration":"246.537482ms","start":"2026-01-27T19:50:46.978659Z","end":"2026-01-27T19:50:47.225196Z","steps":["trace[760124561] 'read index received' (duration: 230.403159ms)","trace[760124561] 'applied index is now lower than readState.Index' (duration: 16.133593ms)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:50:47.225365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.701476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:50:47.225382Z","caller":"traceutil/trace.go:171","msg":"trace[1187936543] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:876; }","duration":"246.758327ms","start":"2026-01-27T19:50:46.978619Z","end":"2026-01-27T19:50:47.225377Z","steps":["trace[1187936543] 'agreement among raft nodes before linearized reading' (duration: 246.717996ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:50:48.721124Z","caller":"traceutil/trace.go:171","msg":"trace[1966120104] linearizableReadLoop","detail":"{readStateIndex:916; appliedIndex:915; }","duration":"416.017201ms","start":"2026-01-27T19:50:48.305085Z","end":"2026-01-27T19:50:48.721102Z","steps":["trace[1966120104] 'read index received' (duration: 415.833116ms)","trace[1966120104] 'applied index is now lower than readState.Index' (duration: 183.225µs)"],"step_count":2} {"level":"info","ts":"2026-01-27T19:50:48.721645Z","caller":"traceutil/trace.go:171","msg":"trace[527158226] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"559.23132ms","start":"2026-01-27T19:50:48.1624Z","end":"2026-01-27T19:50:48.721631Z","steps":["trace[527158226] 'process raft request' (duration: 558.583534ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:50:48.721755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:50:48.16239Z","time spent":"559.298031ms","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-27T19:50:48.72207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.975965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-01-27T19:50:48.722115Z","caller":"traceutil/trace.go:171","msg":"trace[586666797] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:880; }","duration":"417.045847ms","start":"2026-01-27T19:50:48.305058Z","end":"2026-01-27T19:50:48.722104Z","steps":["trace[586666797] 'agreement among raft nodes before linearized reading' (duration: 416.886803ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:50:48.722154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:50:48.305046Z","time spent":"417.100688ms","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-27T19:50:48.722336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.196353ms","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-01-27T19:50:48.72237Z","caller":"traceutil/trace.go:171","msg":"trace[1765846087] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:880; }","duration":"411.242145ms","start":"2026-01-27T19:50:48.31112Z","end":"2026-01-27T19:50:48.722362Z","steps":["trace[1765846087] 'agreement among raft nodes before linearized reading' (duration: 411.159093ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:50:48.722404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:50:48.311113Z","time spent":"411.285116ms","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-27T19:50:49.183487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.197949ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-27T19:50:49.183556Z","caller":"traceutil/trace.go:171","msg":"trace[1173996601] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"453.221299ms","start":"2026-01-27T19:50:48.730322Z","end":"2026-01-27T19:50:49.183543Z","steps":["trace[1173996601] 'process raft request' (duration: 164.910957ms)","trace[1173996601] 'compare' (duration: 288.101177ms)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:50:49.183587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:50:48.730309Z","time spent":"453.26484ms","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-27T19:50:49.203497Z","caller":"traceutil/trace.go:171","msg":"trace[1159094806] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"473.055244ms","start":"2026-01-27T19:50:48.730422Z","end":"2026-01-27T19:50:49.203477Z","steps":["trace[1159094806] 'process raft request' (duration: 472.949701ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:50:49.20364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:50:48.730417Z","time spent":"473.156696ms","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-27T19:50:49.203941Z","caller":"traceutil/trace.go:171","msg":"trace[1395280695] linearizableReadLoop","detail":"{readStateIndex:918; appliedIndex:918; }","duration":"225.348095ms","start":"2026-01-27T19:50:48.978587Z","end":"2026-01-27T19:50:49.203936Z","steps":["trace[1395280695] 'read index received' (duration: 225.345365ms)","trace[1395280695] 'applied index is now lower than readState.Index' (duration: 2.31µs)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:50:49.20398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.395716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:50:49.204001Z","caller":"traceutil/trace.go:171","msg":"trace[1252221748] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:882; }","duration":"225.440237ms","start":"2026-01-27T19:50:48.978556Z","end":"2026-01-27T19:50:49.203997Z","steps":["trace[1252221748] 'agreement among raft nodes before linearized reading' (duration: 225.403106ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:50:49.263358Z","caller":"traceutil/trace.go:171","msg":"trace[1321798392] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"151.119091ms","start":"2026-01-27T19:50:49.112209Z","end":"2026-01-27T19:50:49.263328Z","steps":["trace[1321798392] 'process raft request' (duration: 150.933827ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:50:49.592671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.439536ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-27T19:50:49.592732Z","caller":"traceutil/trace.go:171","msg":"trace[1157600797] linearizableReadLoop","detail":"{readStateIndex:920; appliedIndex:919; }","duration":"159.728901ms","start":"2026-01-27T19:50:49.432994Z","end":"2026-01-27T19:50:49.592722Z","steps":["trace[1157600797] 'read index received' (duration: 26.871µs)","trace[1157600797] 'applied index is now lower than readState.Index' (duration: 159.70135ms)"],"step_count":2} {"level":"info","ts":"2026-01-27T19:50:49.592755Z","caller":"traceutil/trace.go:171","msg":"trace[1502071667] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"324.201028ms","start":"2026-01-27T19:50:49.268535Z","end":"2026-01-27T19:50:49.592736Z","steps":["trace[1502071667] 'process raft request' (duration: 141.27153ms)","trace[1502071667] 'compare' (duration: 182.360614ms)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:50:49.592809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.813633ms","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-01-27T19:50:49.592825Z","caller":"traceutil/trace.go:171","msg":"trace[1364118609] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:884; }","duration":"159.852265ms","start":"2026-01-27T19:50:49.432967Z","end":"2026-01-27T19:50:49.592819Z","steps":["trace[1364118609] 'agreement among raft nodes before linearized reading' (duration: 159.780383ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:50:49.592855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:50:49.268517Z","time spent":"324.27957ms","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-27T19:50:51.763656Z","caller":"traceutil/trace.go:171","msg":"trace[1280474570] linearizableReadLoop","detail":"{readStateIndex:925; appliedIndex:924; }","duration":"130.557399ms","start":"2026-01-27T19:50:51.633083Z","end":"2026-01-27T19:50:51.76364Z","steps":["trace[1280474570] 'read index received' (duration: 130.447476ms)","trace[1280474570] 'applied index is now lower than readState.Index' (duration: 109.413µs)"],"step_count":2} {"level":"info","ts":"2026-01-27T19:50:51.763837Z","caller":"traceutil/trace.go:171","msg":"trace[1010911809] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"166.537427ms","start":"2026-01-27T19:50:51.597294Z","end":"2026-01-27T19:50:51.763832Z","steps":["trace[1010911809] 'process raft request' (duration: 166.272761ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:50:51.764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.911727ms","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-01-27T19:50:51.764022Z","caller":"traceutil/trace.go:171","msg":"trace[1259872983] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:889; }","duration":"130.956468ms","start":"2026-01-27T19:50:51.633059Z","end":"2026-01-27T19:50:51.764016Z","steps":["trace[1259872983] 'agreement among raft nodes before linearized reading' (duration: 130.875856ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:50:52.980373Z","caller":"traceutil/trace.go:171","msg":"trace[1149092038] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"244.500632ms","start":"2026-01-27T19:50:52.735851Z","end":"2026-01-27T19:50:52.980352Z","steps":["trace[1149092038] 'process raft request' (duration: 244.377739ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:50:56.483074Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256276450338549425,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-27T19:50:56.776823Z","caller":"traceutil/trace.go:171","msg":"trace[290114283] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"986.392402ms","start":"2026-01-27T19:50:55.790417Z","end":"2026-01-27T19:50:56.776809Z","steps":["trace[290114283] 'process raft request' (duration: 986.30096ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:50:56.776928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:50:55.790398Z","time spent":"986.477325ms","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-27T19:50:56.858147Z","caller":"traceutil/trace.go:171","msg":"trace[476784489] linearizableReadLoop","detail":"{readStateIndex:938; appliedIndex:937; }","duration":"875.957006ms","start":"2026-01-27T19:50:55.982163Z","end":"2026-01-27T19:50:56.85812Z","steps":["trace[476784489] 'read index received' (duration: 795.03711ms)","trace[476784489] 'applied index is now lower than readState.Index' (duration: 80.913466ms)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:50:56.858281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"876.092939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:50:56.858316Z","caller":"traceutil/trace.go:171","msg":"trace[480593829] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:901; }","duration":"876.168911ms","start":"2026-01-27T19:50:55.982136Z","end":"2026-01-27T19:50:56.858305Z","steps":["trace[480593829] 'agreement among raft nodes before linearized reading' (duration: 876.089829ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:50:56.858353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:50:55.982121Z","time spent":"876.223212ms","remote":"127.0.0.1:51344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-27T19:50:56.858624Z","caller":"traceutil/trace.go:171","msg":"trace[777286301] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"1.067526774s","start":"2026-01-27T19:50:55.791086Z","end":"2026-01-27T19:50:56.858613Z","steps":["trace[777286301] 'process raft request' (duration: 1.066851188s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:50:56.858693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:50:55.79108Z","time spent":"1.067565515s","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-27T19:50:56.981792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.256747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:50:56.981886Z","caller":"traceutil/trace.go:171","msg":"trace[946290507] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:901; }","duration":"122.380009ms","start":"2026-01-27T19:50:56.859476Z","end":"2026-01-27T19:50:56.981856Z","steps":["trace[946290507] 'range keys from in-memory index tree' (duration: 122.194225ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:50:57.159813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.724284ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-01-27T19:50:57.159911Z","caller":"traceutil/trace.go:171","msg":"trace[739151622] linearizableReadLoop","detail":"{readStateIndex:939; appliedIndex:938; }","duration":"169.164052ms","start":"2026-01-27T19:50:56.990735Z","end":"2026-01-27T19:50:57.159899Z","steps":["trace[739151622] 'read index received' (duration: 56.296285ms)","trace[739151622] 'applied index is now lower than readState.Index' (duration: 112.866477ms)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:50:57.160004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.258215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:50:57.160017Z","caller":"traceutil/trace.go:171","msg":"trace[1454157281] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:901; }","duration":"169.315776ms","start":"2026-01-27T19:50:56.990697Z","end":"2026-01-27T19:50:57.160013Z","steps":["trace[1454157281] 'agreement among raft nodes before linearized reading' (duration: 169.268565ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:50:57.160232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.871989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-01-27T19:50:57.160338Z","caller":"traceutil/trace.go:171","msg":"trace[2000088910] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:901; }","duration":"145.019352ms","start":"2026-01-27T19:50:57.015293Z","end":"2026-01-27T19:50:57.160312Z","steps":["trace[2000088910] 'agreement among raft nodes before linearized reading' (duration: 144.764866ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:50:57.449333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.103468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-01-27T19:50:57.449392Z","caller":"traceutil/trace.go:171","msg":"trace[24969563] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:902; }","duration":"105.197779ms","start":"2026-01-27T19:50:57.344181Z","end":"2026-01-27T19:50:57.449379Z","steps":["trace[24969563] 'range keys from in-memory index tree' (duration: 105.001355ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:50:57.449873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.709836ms","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-01-27T19:50:57.449898Z","caller":"traceutil/trace.go:171","msg":"trace[1666587947] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:902; }","duration":"167.764308ms","start":"2026-01-27T19:50:57.282128Z","end":"2026-01-27T19:50:57.449893Z","steps":["trace[1666587947] 'range keys from in-memory index tree' (duration: 167.642895ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:50:58.395185Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256276450338549435,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-27T19:50:58.473283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.619281ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-01-27T19:50:58.473358Z","caller":"traceutil/trace.go:171","msg":"trace[736364477] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"1.014447928s","start":"2026-01-27T19:50:57.458895Z","end":"2026-01-27T19:50:58.473343Z","steps":["trace[736364477] 'process raft request' (duration: 275.726135ms)","trace[736364477] 'compare' (duration: 737.849952ms)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:50:58.473394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:50:57.458873Z","time spent":"1.014506091s","remote":"127.0.0.1:51406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":816,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-27T19:50:58.522875Z","caller":"traceutil/trace.go:171","msg":"trace[622367163] linearizableReadLoop","detail":"{readStateIndex:945; appliedIndex:942; }","duration":"629.135836ms","start":"2026-01-27T19:50:57.893717Z","end":"2026-01-27T19:50:58.522853Z","steps":["trace[622367163] 'read index received' (duration: 627.458496ms)","trace[622367163] 'applied index is now lower than readState.Index' (duration: 1.67599ms)"],"step_count":2} {"level":"info","ts":"2026-01-27T19:50:58.52369Z","caller":"traceutil/trace.go:171","msg":"trace[583950009] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"1.061378424s","start":"2026-01-27T19:50:57.462297Z","end":"2026-01-27T19:50:58.523675Z","steps":["trace[583950009] 'process raft request' (duration: 1.059174361s)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:50:58.523959Z","caller":"traceutil/trace.go:171","msg":"trace[750298001] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"1.061520899s","start":"2026-01-27T19:50:57.462423Z","end":"2026-01-27T19:50:58.523944Z","steps":["trace[750298001] 'process raft request' (duration: 1.059235563s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:50:58.527155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:50:57.462417Z","time spent":"1.064714406s","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-27T19:50:58.526609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:50:57.462266Z","time spent":"1.064281794s","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-27T19:50:58.523999Z","caller":"traceutil/trace.go:171","msg":"trace[1559361079] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"853.845086ms","start":"2026-01-27T19:50:57.670145Z","end":"2026-01-27T19:50:58.52399Z","steps":["trace[1559361079] 'process raft request' (duration: 851.677183ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:50:58.523461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.69353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-27T19:50:58.52766Z","caller":"traceutil/trace.go:171","msg":"trace[106219716] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:906; }","duration":"633.986706ms","start":"2026-01-27T19:50:57.893665Z","end":"2026-01-27T19:50:58.527651Z","steps":["trace[106219716] 'agreement among raft nodes before linearized reading' (duration: 629.492256ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:50:58.527723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:50:57.893646Z","time spent":"634.069918ms","remote":"127.0.0.1:51800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-01-27T19:50:58.524262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.974499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:50:58.527944Z","caller":"traceutil/trace.go:171","msg":"trace[216622970] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:906; }","duration":"545.71838ms","start":"2026-01-27T19:50:57.982219Z","end":"2026-01-27T19:50:58.527938Z","steps":["trace[216622970] 'agreement among raft nodes before linearized reading' (duration: 541.979109ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:50:58.528026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:50:57.982197Z","time spent":"545.821543ms","remote":"127.0.0.1:51346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-27T19:50:58.530351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:50:57.670129Z","time spent":"857.456103ms","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-27T19:51:03.657217Z","caller":"traceutil/trace.go:171","msg":"trace[965731525] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"297.900816ms","start":"2026-01-27T19:51:03.359289Z","end":"2026-01-27T19:51:03.65719Z","steps":["trace[965731525] 'process raft request' (duration: 297.765343ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:51:19.672431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.369755ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-01-27T19:51:19.672571Z","caller":"traceutil/trace.go:171","msg":"trace[585815504] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"292.442712ms","start":"2026-01-27T19:51:19.380093Z","end":"2026-01-27T19:51:19.672536Z","steps":["trace[585815504] 'process raft request' (duration: 50.897443ms)","trace[585815504] 'compare' (duration: 240.562236ms)"],"step_count":2} {"level":"info","ts":"2026-01-27T19:51:19.703875Z","caller":"traceutil/trace.go:171","msg":"trace[1862465685] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"186.617108ms","start":"2026-01-27T19:51:19.517232Z","end":"2026-01-27T19:51:19.703849Z","steps":["trace[1862465685] 'process raft request' (duration: 186.489385ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:54:12.930261Z","caller":"traceutil/trace.go:171","msg":"trace[638435764] transaction","detail":"{read_only:false; response_revision:1756; number_of_response:1; }","duration":"132.746914ms","start":"2026-01-27T19:54:12.797468Z","end":"2026-01-27T19:54:12.930214Z","steps":["trace[638435764] 'process raft request' (duration: 132.548619ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:54:14.740821Z","caller":"traceutil/trace.go:171","msg":"trace[686566940] linearizableReadLoop","detail":"{readStateIndex:1840; appliedIndex:1839; }","duration":"126.621418ms","start":"2026-01-27T19:54:14.614182Z","end":"2026-01-27T19:54:14.740803Z","steps":["trace[686566940] 'read index received' (duration: 126.568947ms)","trace[686566940] 'applied index is now lower than readState.Index' (duration: 51.721µs)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:54:14.741009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.807873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-01-27T19:54:14.741049Z","caller":"traceutil/trace.go:171","msg":"trace[563636864] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1761; }","duration":"126.876524ms","start":"2026-01-27T19:54:14.614163Z","end":"2026-01-27T19:54:14.741039Z","steps":["trace[563636864] 'agreement among raft nodes before linearized reading' (duration: 126.722821ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:54:14.741154Z","caller":"traceutil/trace.go:171","msg":"trace[665425756] transaction","detail":"{read_only:false; response_revision:1761; number_of_response:1; }","duration":"145.592644ms","start":"2026-01-27T19:54:14.595555Z","end":"2026-01-27T19:54:14.741147Z","steps":["trace[665425756] 'process raft request' (duration: 145.102301ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:54:20.400771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.894978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-01-27T19:54:20.400836Z","caller":"traceutil/trace.go:171","msg":"trace[1905282215] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1788; }","duration":"355.999611ms","start":"2026-01-27T19:54:20.044823Z","end":"2026-01-27T19:54:20.400823Z","steps":["trace[1905282215] 'range keys from in-memory index tree' (duration: 355.704044ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:54:20.400876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:54:20.044805Z","time spent":"356.065032ms","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "}