{"level":"warn","ts":"2026-02-03T17:26:46.54485Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-03T17:26:46.551705Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.222:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.222:2380","--initial-cluster=instance=https://199.19.213.222:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.222:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.222:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-03T17:26:46.551823Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-03T17:26:46.55186Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.222:2380"]} {"level":"info","ts":"2026-02-03T17:26:46.558583Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-03T17:26:46.559584Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.222:2379"]} {"level":"info","ts":"2026-02-03T17:26:46.55975Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.222:2380"],"listen-peer-urls":["https://199.19.213.222:2380"],"advertise-client-urls":["https://199.19.213.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.222:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-03T17:26:46.565159Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.918698ms"} {"level":"info","ts":"2026-02-03T17:26:46.583625Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b78f8b1ca6063edc","cluster-id":"eff609df5818f5a9"} {"level":"info","ts":"2026-02-03T17:26:46.58371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc switched to configuration voters=()"} {"level":"info","ts":"2026-02-03T17:26:46.583788Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc became follower at term 0"} {"level":"info","ts":"2026-02-03T17:26:46.583798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b78f8b1ca6063edc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-03T17:26:46.583827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc became follower at term 1"} {"level":"info","ts":"2026-02-03T17:26:46.584702Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc switched to configuration voters=(13226943585771208412)"} {"level":"warn","ts":"2026-02-03T17:26:46.599554Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-03T17:26:46.602542Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-03T17:26:46.608341Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-03T17:26:46.617568Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b78f8b1ca6063edc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-03T17:26:46.618084Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b78f8b1ca6063edc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-03T17:26:46.618283Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-03T17:26:46.618342Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-03T17:26:46.618379Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-03T17:26:46.619099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc switched to configuration voters=(13226943585771208412)"} {"level":"info","ts":"2026-02-03T17:26:46.64057Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"eff609df5818f5a9","local-member-id":"b78f8b1ca6063edc","added-peer-id":"b78f8b1ca6063edc","added-peer-peer-urls":["https://199.19.213.222:2380"]} {"level":"info","ts":"2026-02-03T17:26:46.640999Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-03T17:26:46.641365Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b78f8b1ca6063edc","initial-advertise-peer-urls":["https://199.19.213.222:2380"],"listen-peer-urls":["https://199.19.213.222:2380"],"advertise-client-urls":["https://199.19.213.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-03T17:26:46.64139Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-03T17:26:46.641858Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.222:2380"} {"level":"info","ts":"2026-02-03T17:26:46.642248Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.222:2380"} {"level":"info","ts":"2026-02-03T17:26:46.886807Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc is starting a new election at term 1"} {"level":"info","ts":"2026-02-03T17:26:46.886845Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc became pre-candidate at term 1"} {"level":"info","ts":"2026-02-03T17:26:46.88689Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc received MsgPreVoteResp from b78f8b1ca6063edc at term 1"} {"level":"info","ts":"2026-02-03T17:26:46.886922Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc became candidate at term 2"} {"level":"info","ts":"2026-02-03T17:26:46.886933Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc received MsgVoteResp from b78f8b1ca6063edc at term 2"} {"level":"info","ts":"2026-02-03T17:26:46.886941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc became leader at term 2"} {"level":"info","ts":"2026-02-03T17:26:46.886978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b78f8b1ca6063edc elected leader b78f8b1ca6063edc at term 2"} {"level":"info","ts":"2026-02-03T17:26:46.887921Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T17:26:46.891343Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b78f8b1ca6063edc","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.222:2379]}","request-path":"/0/members/b78f8b1ca6063edc/attributes","cluster-id":"eff609df5818f5a9","publish-timeout":"7s"} {"level":"info","ts":"2026-02-03T17:26:46.89158Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-03T17:26:46.891953Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-03T17:26:46.892719Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-03T17:26:46.892762Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-03T17:26:46.892799Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"eff609df5818f5a9","local-member-id":"b78f8b1ca6063edc","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T17:26:46.892873Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T17:26:46.893287Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T17:26:46.895966Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-03T17:26:46.900509Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.222:2379"} {"level":"info","ts":"2026-02-03T17:26:52.53307Z","caller":"traceutil/trace.go:171","msg":"trace[252318598] linearizableReadLoop","detail":"{readStateIndex:272; appliedIndex:271; }","duration":"101.76888ms","start":"2026-02-03T17:26:52.431276Z","end":"2026-02-03T17:26:52.533045Z","steps":["trace[252318598] 'read index received' (duration: 39.327947ms)","trace[252318598] 'applied index is now lower than readState.Index' (duration: 62.439673ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:26:52.533566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.26619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:26:52.533629Z","caller":"traceutil/trace.go:171","msg":"trace[2020665335] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:0; response_revision:263; }","duration":"102.380063ms","start":"2026-02-03T17:26:52.431237Z","end":"2026-02-03T17:26:52.533617Z","steps":["trace[2020665335] 'agreement among raft nodes before linearized reading' (duration: 101.918153ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:26:52.534159Z","caller":"traceutil/trace.go:171","msg":"trace[2052205902] transaction","detail":"{read_only:false; response_revision:263; number_of_response:1; }","duration":"115.970271ms","start":"2026-02-03T17:26:52.418131Z","end":"2026-02-03T17:26:52.534101Z","steps":["trace[2052205902] 'process raft request' (duration: 52.539848ms)","trace[2052205902] 'compare' (duration: 62.235699ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:26:56.908892Z","caller":"traceutil/trace.go:171","msg":"trace[1544651794] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"151.7845ms","start":"2026-02-03T17:26:56.757088Z","end":"2026-02-03T17:26:56.908873Z","steps":["trace[1544651794] 'process raft request' (duration: 51.859785ms)","trace[1544651794] 'compare' (duration: 99.686699ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:26:56.932134Z","caller":"traceutil/trace.go:171","msg":"trace[1374579242] linearizableReadLoop","detail":"{readStateIndex:328; appliedIndex:323; }","duration":"151.721997ms","start":"2026-02-03T17:26:56.7804Z","end":"2026-02-03T17:26:56.932122Z","steps":["trace[1374579242] 'read index received' (duration: 28.484213ms)","trace[1374579242] 'applied index is now lower than readState.Index' (duration: 123.237314ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:26:56.932193Z","caller":"traceutil/trace.go:171","msg":"trace[215690164] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"172.425437ms","start":"2026-02-03T17:26:56.759763Z","end":"2026-02-03T17:26:56.932188Z","steps":["trace[215690164] 'process raft request' (duration: 172.318625ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:26:56.932321Z","caller":"traceutil/trace.go:171","msg":"trace[145195640] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"172.535598ms","start":"2026-02-03T17:26:56.759739Z","end":"2026-02-03T17:26:56.932274Z","steps":["trace[145195640] 'process raft request' (duration: 172.313704ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:56.93246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.048173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-03T17:26:56.932482Z","caller":"traceutil/trace.go:171","msg":"trace[82248215] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:314; }","duration":"152.121476ms","start":"2026-02-03T17:26:56.780356Z","end":"2026-02-03T17:26:56.932478Z","steps":["trace[82248215] 'agreement among raft nodes before linearized reading' (duration: 152.075194ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:26:56.932682Z","caller":"traceutil/trace.go:171","msg":"trace[1665805300] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"172.972798ms","start":"2026-02-03T17:26:56.759704Z","end":"2026-02-03T17:26:56.932677Z","steps":["trace[1665805300] 'process raft request' (duration: 172.285814ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:26:56.932418Z","caller":"traceutil/trace.go:171","msg":"trace[1590460122] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"169.848505ms","start":"2026-02-03T17:26:56.762563Z","end":"2026-02-03T17:26:56.932412Z","steps":["trace[1590460122] 'process raft request' (duration: 169.531229ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:26:57.036997Z","caller":"traceutil/trace.go:171","msg":"trace[478541232] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"117.984091ms","start":"2026-02-03T17:26:56.918997Z","end":"2026-02-03T17:26:57.036981Z","steps":["trace[478541232] 'process raft request' (duration: 90.025249ms)","trace[478541232] 'compare' (duration: 27.668737ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:26:57.037121Z","caller":"traceutil/trace.go:171","msg":"trace[512122950] linearizableReadLoop","detail":"{readStateIndex:329; appliedIndex:328; }","duration":"104.937014ms","start":"2026-02-03T17:26:56.932174Z","end":"2026-02-03T17:26:57.037111Z","steps":["trace[512122950] 'read index received' (duration: 77.174005ms)","trace[512122950] 'applied index is now lower than readState.Index' (duration: 27.762319ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:26:57.03737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.146457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-03T17:26:57.037402Z","caller":"traceutil/trace.go:171","msg":"trace[1806621471] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:317; }","duration":"107.207517ms","start":"2026-02-03T17:26:56.930186Z","end":"2026-02-03T17:26:57.037394Z","steps":["trace[1806621471] 'agreement among raft nodes before linearized reading' (duration: 107.146796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:57.037745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.045074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-02-03T17:26:57.037776Z","caller":"traceutil/trace.go:171","msg":"trace[1063299825] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:317; }","duration":"156.101584ms","start":"2026-02-03T17:26:56.881667Z","end":"2026-02-03T17:26:57.037768Z","steps":["trace[1063299825] 'agreement among raft nodes before linearized reading' (duration: 156.040563ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:57.037863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.157693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-02-03T17:26:57.037885Z","caller":"traceutil/trace.go:171","msg":"trace[975071658] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:317; }","duration":"206.216044ms","start":"2026-02-03T17:26:56.831664Z","end":"2026-02-03T17:26:57.03788Z","steps":["trace[975071658] 'agreement among raft nodes before linearized reading' (duration: 206.181823ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:57.044499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.579903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-whdnw\" ","response":"range_response_count:1 size:3416"} {"level":"info","ts":"2026-02-03T17:26:57.044534Z","caller":"traceutil/trace.go:171","msg":"trace[1770181388] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-whdnw; range_end:; response_count:1; response_revision:317; }","duration":"125.643533ms","start":"2026-02-03T17:26:56.918881Z","end":"2026-02-03T17:26:57.044525Z","steps":["trace[1770181388] 'agreement among raft nodes before linearized reading' (duration: 118.593674ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:26:58.778218Z","caller":"traceutil/trace.go:171","msg":"trace[2052082153] linearizableReadLoop","detail":"{readStateIndex:370; appliedIndex:369; }","duration":"222.968005ms","start":"2026-02-03T17:26:58.555228Z","end":"2026-02-03T17:26:58.778196Z","steps":["trace[2052082153] 'read index received' (duration: 222.796562ms)","trace[2052082153] 'applied index is now lower than readState.Index' (duration: 170.513µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:26:58.778426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.181309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:26:58.778464Z","caller":"traceutil/trace.go:171","msg":"trace[1974083315] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:355; }","duration":"223.297953ms","start":"2026-02-03T17:26:58.555154Z","end":"2026-02-03T17:26:58.778452Z","steps":["trace[1974083315] 'agreement among raft nodes before linearized reading' (duration: 223.204831ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:26:58.779054Z","caller":"traceutil/trace.go:171","msg":"trace[1055334067] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"367.726475ms","start":"2026-02-03T17:26:58.411314Z","end":"2026-02-03T17:26:58.77904Z","steps":["trace[1055334067] 'process raft request' (duration: 366.771247ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:58.779853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:58.411305Z","time spent":"367.780416ms","remote":"127.0.0.1:35240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7222,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:27:04.660142Z","caller":"traceutil/trace.go:171","msg":"trace[1364251756] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"217.76619ms","start":"2026-02-03T17:27:04.442359Z","end":"2026-02-03T17:27:04.660125Z","steps":["trace[1364251756] 'process raft request' (duration: 217.66881ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:27:04.849206Z","caller":"traceutil/trace.go:171","msg":"trace[105697402] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"184.352727ms","start":"2026-02-03T17:27:04.664806Z","end":"2026-02-03T17:27:04.849159Z","steps":["trace[105697402] 'process raft request' (duration: 161.697249ms)","trace[105697402] 'compare' (duration: 22.518337ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:27:05.120466Z","caller":"traceutil/trace.go:171","msg":"trace[1675579681] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"455.404441ms","start":"2026-02-03T17:27:04.665035Z","end":"2026-02-03T17:27:05.120439Z","steps":["trace[1675579681] 'process raft request' (duration: 424.486862ms)","trace[1675579681] 'compare' (duration: 30.641967ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:27:05.120611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:04.665029Z","time spent":"455.502942ms","remote":"127.0.0.1:35240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3541,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-03T17:27:05.120955Z","caller":"traceutil/trace.go:171","msg":"trace[2015719423] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"455.022629ms","start":"2026-02-03T17:27:04.665911Z","end":"2026-02-03T17:27:05.120934Z","steps":["trace[2015719423] 'process raft request' (duration: 454.373465ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:05.121144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:04.665905Z","time spent":"455.14874ms","remote":"127.0.0.1:35524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4158,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:27:05.420553Z","caller":"traceutil/trace.go:171","msg":"trace[617979743] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"288.792443ms","start":"2026-02-03T17:27:05.131739Z","end":"2026-02-03T17:27:05.420532Z","steps":["trace[617979743] 'process raft request' (duration: 189.751108ms)","trace[617979743] 'compare' (duration: 98.913054ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:27:05.424896Z","caller":"traceutil/trace.go:171","msg":"trace[374688549] linearizableReadLoop","detail":"{readStateIndex:405; appliedIndex:399; }","duration":"286.209457ms","start":"2026-02-03T17:27:05.138673Z","end":"2026-02-03T17:27:05.424883Z","steps":["trace[374688549] 'read index received' (duration: 182.852356ms)","trace[374688549] 'applied index is now lower than readState.Index' (duration: 103.356471ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:27:05.425572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.882411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-03T17:27:05.425638Z","caller":"traceutil/trace.go:171","msg":"trace[2075333271] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:390; }","duration":"286.973021ms","start":"2026-02-03T17:27:05.138653Z","end":"2026-02-03T17:27:05.425626Z","steps":["trace[2075333271] 'agreement among raft nodes before linearized reading' (duration: 286.865101ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:27:05.42831Z","caller":"traceutil/trace.go:171","msg":"trace[279942181] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"292.636926ms","start":"2026-02-03T17:27:05.135662Z","end":"2026-02-03T17:27:05.428299Z","steps":["trace[279942181] 'process raft request' (duration: 289.043685ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:27:05.42845Z","caller":"traceutil/trace.go:171","msg":"trace[1779965521] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"290.764256ms","start":"2026-02-03T17:27:05.137679Z","end":"2026-02-03T17:27:05.428443Z","steps":["trace[1779965521] 'process raft request' (duration: 287.080723ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:27:05.428619Z","caller":"traceutil/trace.go:171","msg":"trace[697567913] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"290.899456ms","start":"2026-02-03T17:27:05.137713Z","end":"2026-02-03T17:27:05.428612Z","steps":["trace[697567913] 'process raft request' (duration: 287.074153ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:27:05.428816Z","caller":"traceutil/trace.go:171","msg":"trace[278618797] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"291.021627ms","start":"2026-02-03T17:27:05.137787Z","end":"2026-02-03T17:27:05.428809Z","steps":["trace[278618797] 'process raft request' (duration: 287.017183ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:27:05.429082Z","caller":"traceutil/trace.go:171","msg":"trace[1906449935] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"291.176348ms","start":"2026-02-03T17:27:05.13789Z","end":"2026-02-03T17:27:05.429074Z","steps":["trace[1906449935] 'process raft request' (duration: 286.936162ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:27:05.42923Z","caller":"traceutil/trace.go:171","msg":"trace[273795944] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"289.483699ms","start":"2026-02-03T17:27:05.13974Z","end":"2026-02-03T17:27:05.429223Z","steps":["trace[273795944] 'process raft request' (duration: 285.123592ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:05.765404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.709437ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-03T17:27:05.765474Z","caller":"traceutil/trace.go:171","msg":"trace[97509527] linearizableReadLoop","detail":"{readStateIndex:407; appliedIndex:406; }","duration":"332.977604ms","start":"2026-02-03T17:27:05.432485Z","end":"2026-02-03T17:27:05.765463Z","steps":["trace[97509527] 'read index received' (duration: 171.124626ms)","trace[97509527] 'applied index is now lower than readState.Index' (duration: 161.852108ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:27:05.765531Z","caller":"traceutil/trace.go:171","msg":"trace[1963435101] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"334.469942ms","start":"2026-02-03T17:27:05.431054Z","end":"2026-02-03T17:27:05.765524Z","steps":["trace[1963435101] 'process raft request' (duration: 172.494014ms)","trace[1963435101] 'compare' (duration: 161.602346ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:27:05.76557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:05.431047Z","time spent":"334.509062ms","remote":"127.0.0.1:35106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":689,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-03T17:27:05.765863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.833375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-9npxq\" ","response":"range_response_count:1 size:3994"} {"level":"info","ts":"2026-02-03T17:27:05.765891Z","caller":"traceutil/trace.go:171","msg":"trace[678520414] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-9npxq; range_end:; response_count:1; response_revision:391; }","duration":"334.874576ms","start":"2026-02-03T17:27:05.43101Z","end":"2026-02-03T17:27:05.765884Z","steps":["trace[678520414] 'agreement among raft nodes before linearized reading' (duration: 334.797005ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:05.765916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:05.431006Z","time spent":"334.904886ms","remote":"127.0.0.1:35240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4018,"request content":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-9npxq\" "} {"level":"info","ts":"2026-02-03T17:27:05.787159Z","caller":"traceutil/trace.go:171","msg":"trace[341454031] transaction","detail":"{read_only:false; number_of_response:1; response_revision:393; }","duration":"339.870095ms","start":"2026-02-03T17:27:05.447274Z","end":"2026-02-03T17:27:05.787144Z","steps":["trace[341454031] 'process raft request' (duration: 339.824165ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:27:05.787504Z","caller":"traceutil/trace.go:171","msg":"trace[1808124715] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"341.639476ms","start":"2026-02-03T17:27:05.445852Z","end":"2026-02-03T17:27:05.787491Z","steps":["trace[1808124715] 'process raft request' (duration: 341.090033ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:05.787712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:05.445834Z","time spent":"341.731507ms","remote":"127.0.0.1:35304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:27:05.789475Z","caller":"traceutil/trace.go:171","msg":"trace[399968017] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"343.505737ms","start":"2026-02-03T17:27:05.445955Z","end":"2026-02-03T17:27:05.78946Z","steps":["trace[399968017] 'process raft request' (duration: 341.088253ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:05.78958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:05.445948Z","time spent":"343.566737ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3773,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:27:05.790156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:05.447258Z","time spent":"339.955266ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":56,"response count":0,"response size":3823,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:27:06.183064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.007889ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-03T17:27:06.183344Z","caller":"traceutil/trace.go:171","msg":"trace[1799039650] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"415.563298ms","start":"2026-02-03T17:27:05.767767Z","end":"2026-02-03T17:27:06.183331Z","steps":["trace[1799039650] 'process raft request' (duration: 415.392457ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:06.183419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:05.767755Z","time spent":"415.620658ms","remote":"127.0.0.1:35240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3991,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:27:06.18351Z","caller":"traceutil/trace.go:171","msg":"trace[937610820] linearizableReadLoop","detail":"{readStateIndex:411; appliedIndex:410; }","duration":"417.997893ms","start":"2026-02-03T17:27:05.765504Z","end":"2026-02-03T17:27:06.183502Z","steps":["trace[937610820] 'read index received' (duration: 21.909884ms)","trace[937610820] 'applied index is now lower than readState.Index' (duration: 396.087219ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:27:06.183611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.09775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:27:06.183628Z","caller":"traceutil/trace.go:171","msg":"trace[745028032] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:395; }","duration":"627.14955ms","start":"2026-02-03T17:27:05.556473Z","end":"2026-02-03T17:27:06.183622Z","steps":["trace[745028032] 'agreement among raft nodes before linearized reading' (duration: 627.071849ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:06.183646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:05.556456Z","time spent":"627.18674ms","remote":"127.0.0.1:35038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-03T17:27:06.183649Z","caller":"traceutil/trace.go:171","msg":"trace[1537999330] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"385.156152ms","start":"2026-02-03T17:27:05.798477Z","end":"2026-02-03T17:27:06.183633Z","steps":["trace[1537999330] 'process raft request' (duration: 384.80604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:06.183747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:05.798464Z","time spent":"385.234342ms","remote":"127.0.0.1:35524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4135,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:27:06.183984Z","caller":"traceutil/trace.go:171","msg":"trace[362848090] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"385.462324ms","start":"2026-02-03T17:27:05.798514Z","end":"2026-02-03T17:27:06.183977Z","steps":["trace[362848090] 'process raft request' (duration: 385.072662ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:06.184011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:05.798496Z","time spent":"385.501854ms","remote":"127.0.0.1:35106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":687,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-03T17:27:06.184012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.474114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-77cccfdc44\" ","response":"range_response_count:1 size:3788"} {"level":"info","ts":"2026-02-03T17:27:06.184038Z","caller":"traceutil/trace.go:171","msg":"trace[1456313470] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-77cccfdc44; range_end:; response_count:1; response_revision:396; }","duration":"385.514144ms","start":"2026-02-03T17:27:05.798518Z","end":"2026-02-03T17:27:06.184032Z","steps":["trace[1456313470] 'agreement among raft nodes before linearized reading' (duration: 385.441624ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:06.18406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:05.798512Z","time spent":"385.539634ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":3812,"request content":"key:\"/registry/replicasets/kube-system/coredns-77cccfdc44\" "} {"level":"warn","ts":"2026-02-03T17:27:06.184134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.659165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6898"} {"level":"info","ts":"2026-02-03T17:27:06.184152Z","caller":"traceutil/trace.go:171","msg":"trace[746475276] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:396; }","duration":"177.679636ms","start":"2026-02-03T17:27:06.006468Z","end":"2026-02-03T17:27:06.184147Z","steps":["trace[746475276] 'agreement among raft nodes before linearized reading' (duration: 177.639405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:06.184159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.043344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-03T17:27:06.184178Z","caller":"traceutil/trace.go:171","msg":"trace[558217119] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:396; }","duration":"320.085054ms","start":"2026-02-03T17:27:05.864087Z","end":"2026-02-03T17:27:06.184172Z","steps":["trace[558217119] 'agreement among raft nodes before linearized reading' (duration: 320.048814ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:06.184195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:05.864067Z","time spent":"320.123125ms","remote":"127.0.0.1:35304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-03T17:27:06.643521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.034791ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T17:27:06.644421Z","caller":"traceutil/trace.go:171","msg":"trace[1341906476] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"284.892259ms","start":"2026-02-03T17:27:06.358713Z","end":"2026-02-03T17:27:06.643605Z","steps":["trace[1341906476] 'process raft request' (duration: 89.697257ms)","trace[1341906476] 'compare' (duration: 194.891871ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:27:06.867249Z","caller":"traceutil/trace.go:171","msg":"trace[1126761661] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"213.375403ms","start":"2026-02-03T17:27:06.653849Z","end":"2026-02-03T17:27:06.867224Z","steps":["trace[1126761661] 'process raft request' (duration: 163.63003ms)","trace[1126761661] 'compare' (duration: 49.655302ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:27:13.593659Z","caller":"traceutil/trace.go:171","msg":"trace[971754150] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"109.581651ms","start":"2026-02-03T17:27:13.484064Z","end":"2026-02-03T17:27:13.593646Z","steps":["trace[971754150] 'process raft request' (duration: 109.422898ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:27:20.078621Z","caller":"traceutil/trace.go:171","msg":"trace[813164716] linearizableReadLoop","detail":"{readStateIndex:497; appliedIndex:496; }","duration":"461.735038ms","start":"2026-02-03T17:27:19.616865Z","end":"2026-02-03T17:27:20.0786Z","steps":["trace[813164716] 'read index received' (duration: 461.519004ms)","trace[813164716] 'applied index is now lower than readState.Index' (duration: 214.954µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:27:20.078788Z","caller":"traceutil/trace.go:171","msg":"trace[2116507019] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"471.395626ms","start":"2026-02-03T17:27:19.607381Z","end":"2026-02-03T17:27:20.078777Z","steps":["trace[2116507019] 'process raft request' (duration: 471.015749ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:20.078897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.068787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4062"} {"level":"warn","ts":"2026-02-03T17:27:20.078933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:19.607371Z","time spent":"471.463637ms","remote":"127.0.0.1:35304","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-02-03T17:27:20.078965Z","caller":"traceutil/trace.go:171","msg":"trace[723638376] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:476; }","duration":"459.153158ms","start":"2026-02-03T17:27:19.619796Z","end":"2026-02-03T17:27:20.078949Z","steps":["trace[723638376] 'agreement among raft nodes before linearized reading' (duration: 459.041536ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:20.079004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:19.619791Z","time spent":"459.20569ms","remote":"127.0.0.1:35230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4086,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-03T17:27:20.079199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.330689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-03T17:27:20.079228Z","caller":"traceutil/trace.go:171","msg":"trace[393928034] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:476; }","duration":"462.376161ms","start":"2026-02-03T17:27:19.616842Z","end":"2026-02-03T17:27:20.079218Z","steps":["trace[393928034] 'agreement among raft nodes before linearized reading' (duration: 462.32542ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:20.079253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:19.616833Z","time spent":"462.414361ms","remote":"127.0.0.1:35304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-03T17:27:20.0794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.2228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-03T17:27:20.079467Z","caller":"traceutil/trace.go:171","msg":"trace[1102832283] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:476; }","duration":"459.277561ms","start":"2026-02-03T17:27:19.620158Z","end":"2026-02-03T17:27:20.079435Z","steps":["trace[1102832283] 'agreement among raft nodes before linearized reading' (duration: 459.172438ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:20.0795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:19.620153Z","time spent":"459.339612ms","remote":"127.0.0.1:35304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-03T17:27:20.079685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.697346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-03T17:27:20.079734Z","caller":"traceutil/trace.go:171","msg":"trace[1643554031] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:476; }","duration":"139.777836ms","start":"2026-02-03T17:27:19.939945Z","end":"2026-02-03T17:27:20.079723Z","steps":["trace[1643554031] 'agreement among raft nodes before linearized reading' (duration: 139.639674ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:22.374769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.55475ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T17:27:22.374939Z","caller":"traceutil/trace.go:171","msg":"trace[1359094468] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"228.519464ms","start":"2026-02-03T17:27:22.146405Z","end":"2026-02-03T17:27:22.374924Z","steps":["trace[1359094468] 'process raft request' (duration: 228.450662ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:27:22.375306Z","caller":"traceutil/trace.go:171","msg":"trace[413932087] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"229.585235ms","start":"2026-02-03T17:27:22.145705Z","end":"2026-02-03T17:27:22.37529Z","steps":["trace[413932087] 'process raft request' (duration: 86.433783ms)","trace[413932087] 'compare' (duration: 142.440578ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:27:22.753021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.828892ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T17:27:22.753187Z","caller":"traceutil/trace.go:171","msg":"trace[446787101] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"115.540856ms","start":"2026-02-03T17:27:22.63763Z","end":"2026-02-03T17:27:22.753171Z","steps":["trace[446787101] 'process raft request' (duration: 115.485875ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:27:22.753194Z","caller":"traceutil/trace.go:171","msg":"trace[2127875698] linearizableReadLoop","detail":"{readStateIndex:508; appliedIndex:507; }","duration":"199.627344ms","start":"2026-02-03T17:27:22.553548Z","end":"2026-02-03T17:27:22.753175Z","steps":["trace[2127875698] 'read index received' (duration: 20.510436ms)","trace[2127875698] 'applied index is now lower than readState.Index' (duration: 179.115358ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:27:22.753278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.718105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:27:22.753296Z","caller":"traceutil/trace.go:171","msg":"trace[193334930] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:487; }","duration":"199.771777ms","start":"2026-02-03T17:27:22.553518Z","end":"2026-02-03T17:27:22.75329Z","steps":["trace[193334930] 'agreement among raft nodes before linearized reading' (duration: 199.714236ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:27:22.753408Z","caller":"traceutil/trace.go:171","msg":"trace[838598312] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"368.84426ms","start":"2026-02-03T17:27:22.384556Z","end":"2026-02-03T17:27:22.7534Z","steps":["trace[838598312] 'process raft request' (duration: 189.561999ms)","trace[838598312] 'compare' (duration: 178.69798ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:27:22.753457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:22.384547Z","time spent":"368.87389ms","remote":"127.0.0.1:35304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:27:27.391868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.987627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-clrjs\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-03T17:27:27.391939Z","caller":"traceutil/trace.go:171","msg":"trace[706550653] range","detail":"{range_begin:/registry/pods/kube-system/cilium-clrjs; range_end:; response_count:1; response_revision:497; }","duration":"236.10219ms","start":"2026-02-03T17:27:27.155821Z","end":"2026-02-03T17:27:27.391923Z","steps":["trace[706550653] 'range keys from in-memory index tree' (duration: 235.790304ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:27.39228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.208541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-03T17:27:27.392311Z","caller":"traceutil/trace.go:171","msg":"trace[2011386539] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:497; }","duration":"627.272332ms","start":"2026-02-03T17:27:26.765029Z","end":"2026-02-03T17:27:27.392301Z","steps":["trace[2011386539] 'range keys from in-memory index tree' (duration: 627.118049ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:27.392337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:26.765015Z","time spent":"627.315113ms","remote":"127.0.0.1:35304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-03T17:27:27.729222Z","caller":"traceutil/trace.go:171","msg":"trace[768415698] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"332.490776ms","start":"2026-02-03T17:27:27.396708Z","end":"2026-02-03T17:27:27.729199Z","steps":["trace[768415698] 'process raft request' (duration: 332.355113ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:27.729371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:27.396682Z","time spent":"332.606968ms","remote":"127.0.0.1:35304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:27:28.262459Z","caller":"traceutil/trace.go:171","msg":"trace[11059967] linearizableReadLoop","detail":"{readStateIndex:522; appliedIndex:521; }","duration":"707.072968ms","start":"2026-02-03T17:27:27.555371Z","end":"2026-02-03T17:27:28.262444Z","steps":["trace[11059967] 'read index received' (duration: 174.417658ms)","trace[11059967] 'applied index is now lower than readState.Index' (duration: 532.65475ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:27:28.262731Z","caller":"traceutil/trace.go:171","msg":"trace[9015511] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"856.611162ms","start":"2026-02-03T17:27:27.406115Z","end":"2026-02-03T17:27:28.262726Z","steps":["trace[9015511] 'process raft request' (duration: 856.238145ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:28.26279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:27.406101Z","time spent":"856.648383ms","remote":"127.0.0.1:35240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:27:28.262899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.533996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:27:28.262917Z","caller":"traceutil/trace.go:171","msg":"trace[2130427788] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:499; }","duration":"707.587647ms","start":"2026-02-03T17:27:27.555324Z","end":"2026-02-03T17:27:28.262912Z","steps":["trace[2130427788] 'agreement among raft nodes before linearized reading' (duration: 707.555286ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:28.26293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:27.555307Z","time spent":"707.620518ms","remote":"127.0.0.1:35038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-03T17:27:33.836621Z","caller":"traceutil/trace.go:171","msg":"trace[140965974] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"109.212983ms","start":"2026-02-03T17:27:33.727389Z","end":"2026-02-03T17:27:33.836602Z","steps":["trace[140965974] 'process raft request' (duration: 68.029897ms)","trace[140965974] 'compare' (duration: 40.615626ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:27:34.766919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.128898ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T17:27:34.767087Z","caller":"traceutil/trace.go:171","msg":"trace[1555482968] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:601; }","duration":"210.797622ms","start":"2026-02-03T17:27:34.556274Z","end":"2026-02-03T17:27:34.767072Z","steps":["trace[1555482968] 'read index received' (duration: 47.309626ms)","trace[1555482968] 'applied index is now lower than readState.Index' (duration: 163.487166ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:27:34.767179Z","caller":"traceutil/trace.go:171","msg":"trace[155739439] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"268.513758ms","start":"2026-02-03T17:27:34.498628Z","end":"2026-02-03T17:27:34.767141Z","steps":["trace[155739439] 'process raft request' (duration: 104.985402ms)","trace[155739439] 'compare' (duration: 163.024576ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:27:34.767222Z","caller":"traceutil/trace.go:171","msg":"trace[1838042518] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"268.143732ms","start":"2026-02-03T17:27:34.499042Z","end":"2026-02-03T17:27:34.767186Z","steps":["trace[1838042518] 'process raft request' (duration: 267.960269ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:34.767536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.24245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:27:34.767586Z","caller":"traceutil/trace.go:171","msg":"trace[441909467] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:579; }","duration":"211.329101ms","start":"2026-02-03T17:27:34.556246Z","end":"2026-02-03T17:27:34.767575Z","steps":["trace[441909467] 'agreement among raft nodes before linearized reading' (duration: 211.24775ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:34.76785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.746463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-02-03T17:27:34.767881Z","caller":"traceutil/trace.go:171","msg":"trace[717999684] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:579; }","duration":"111.810125ms","start":"2026-02-03T17:27:34.656061Z","end":"2026-02-03T17:27:34.767871Z","steps":["trace[717999684] 'agreement among raft nodes before linearized reading' (duration: 111.689763ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:27:42.604896Z","caller":"traceutil/trace.go:171","msg":"trace[1543683233] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"173.617752ms","start":"2026-02-03T17:27:42.431263Z","end":"2026-02-03T17:27:42.604881Z","steps":["trace[1543683233] 'process raft request' (duration: 173.52586ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:27:46.403377Z","caller":"traceutil/trace.go:171","msg":"trace[919455996] transaction","detail":"{read_only:false; number_of_response:0; response_revision:641; }","duration":"126.594992ms","start":"2026-02-03T17:27:46.276768Z","end":"2026-02-03T17:27:46.403363Z","steps":["trace[919455996] 'process raft request' (duration: 126.548711ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:27:46.405855Z","caller":"traceutil/trace.go:171","msg":"trace[792214041] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"117.981124ms","start":"2026-02-03T17:27:46.287866Z","end":"2026-02-03T17:27:46.405848Z","steps":["trace[792214041] 'process raft request' (duration: 117.726899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:49.348543Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4529667005990452660,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-03T17:27:49.467406Z","caller":"traceutil/trace.go:171","msg":"trace[1376832809] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"830.501377ms","start":"2026-02-03T17:27:48.636881Z","end":"2026-02-03T17:27:49.467382Z","steps":["trace[1376832809] 'process raft request' (duration: 830.339204ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:49.46777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:48.63687Z","time spent":"830.797202ms","remote":"127.0.0.1:35304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:27:49.962336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.443359ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-03T17:27:49.962433Z","caller":"traceutil/trace.go:171","msg":"trace[361250124] linearizableReadLoop","detail":"{readStateIndex:713; appliedIndex:712; }","duration":"1.11472251s","start":"2026-02-03T17:27:48.847692Z","end":"2026-02-03T17:27:49.962415Z","steps":["trace[361250124] 'read index received' (duration: 620.800489ms)","trace[361250124] 'applied index is now lower than readState.Index' (duration: 493.920641ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:27:49.962531Z","caller":"traceutil/trace.go:171","msg":"trace[2114871224] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"681.369871ms","start":"2026-02-03T17:27:49.281148Z","end":"2026-02-03T17:27:49.962517Z","steps":["trace[2114871224] 'process raft request' (duration: 261.672316ms)","trace[2114871224] 'compare' (duration: 419.173734ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:27:49.962586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:49.28113Z","time spent":"681.429502ms","remote":"127.0.0.1:35106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":723,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-03T17:27:49.962926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.11523896s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-03T17:27:49.962963Z","caller":"traceutil/trace.go:171","msg":"trace[849019966] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:683; }","duration":"1.115302271s","start":"2026-02-03T17:27:48.847648Z","end":"2026-02-03T17:27:49.96295Z","steps":["trace[849019966] 'agreement among raft nodes before linearized reading' (duration: 1.115141738s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:49.962997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:48.847633Z","time spent":"1.115356372s","remote":"127.0.0.1:35304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-03T17:27:49.963217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.107985178s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-03T17:27:49.963246Z","caller":"traceutil/trace.go:171","msg":"trace[538467398] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:683; }","duration":"1.10802743s","start":"2026-02-03T17:27:48.85521Z","end":"2026-02-03T17:27:49.963237Z","steps":["trace[538467398] 'agreement among raft nodes before linearized reading' (duration: 1.107948958s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:49.96327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:48.855203Z","time spent":"1.10806191s","remote":"127.0.0.1:35304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-03T17:27:49.963491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.016795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:27:49.963519Z","caller":"traceutil/trace.go:171","msg":"trace[1434052565] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:683; }","duration":"411.065557ms","start":"2026-02-03T17:27:49.552445Z","end":"2026-02-03T17:27:49.96351Z","steps":["trace[1434052565] 'agreement among raft nodes before linearized reading' (duration: 411.023566ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:49.963546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:49.552417Z","time spent":"411.123627ms","remote":"127.0.0.1:35038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-03T17:27:49.963708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.861206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-03T17:27:49.963734Z","caller":"traceutil/trace.go:171","msg":"trace[1755421643] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:683; }","duration":"498.921898ms","start":"2026-02-03T17:27:49.464804Z","end":"2026-02-03T17:27:49.963726Z","steps":["trace[1755421643] 'agreement among raft nodes before linearized reading' (duration: 498.847377ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:49.963758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:49.464786Z","time spent":"498.965758ms","remote":"127.0.0.1:35304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-03T17:27:49.963911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.560315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:27:49.963939Z","caller":"traceutil/trace.go:171","msg":"trace[1698462182] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:683; }","duration":"680.611837ms","start":"2026-02-03T17:27:49.283319Z","end":"2026-02-03T17:27:49.963931Z","steps":["trace[1698462182] 'agreement among raft nodes before linearized reading' (duration: 680.566866ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:49.963964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:49.283311Z","time spent":"680.646017ms","remote":"127.0.0.1:35454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-02-03T17:27:49.964127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.585663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-fvpcz\" ","response":"range_response_count:1 size:4821"} {"level":"info","ts":"2026-02-03T17:27:49.964157Z","caller":"traceutil/trace.go:171","msg":"trace[1565821901] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-fvpcz; range_end:; response_count:1; response_revision:683; }","duration":"684.642774ms","start":"2026-02-03T17:27:49.279507Z","end":"2026-02-03T17:27:49.964149Z","steps":["trace[1565821901] 'agreement among raft nodes before linearized reading' (duration: 684.566022ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:49.964181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:49.279492Z","time spent":"684.683204ms","remote":"127.0.0.1:35240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4845,"request content":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-fvpcz\" "} {"level":"warn","ts":"2026-02-03T17:27:50.481209Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4529667005990452665,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-03T17:27:50.952864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.022583ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T17:27:50.952929Z","caller":"traceutil/trace.go:171","msg":"trace[137150759] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"979.855648ms","start":"2026-02-03T17:27:49.973062Z","end":"2026-02-03T17:27:50.952918Z","steps":["trace[137150759] 'process raft request' (duration: 563.730643ms)","trace[137150759] 'compare' (duration: 415.84283ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:27:50.952959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:49.973037Z","time spent":"979.909599ms","remote":"127.0.0.1:35304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:27:51.092686Z","caller":"traceutil/trace.go:171","msg":"trace[2007051512] linearizableReadLoop","detail":"{readStateIndex:716; appliedIndex:713; }","duration":"1.112042327s","start":"2026-02-03T17:27:49.980629Z","end":"2026-02-03T17:27:51.092672Z","steps":["trace[2007051512] 'read index received' (duration: 556.172986ms)","trace[2007051512] 'applied index is now lower than readState.Index' (duration: 555.868811ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:27:51.092919Z","caller":"traceutil/trace.go:171","msg":"trace[1018087968] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"1.119840969s","start":"2026-02-03T17:27:49.97307Z","end":"2026-02-03T17:27:51.092911Z","steps":["trace[1018087968] 'process raft request' (duration: 1.119492552s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:51.092979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:49.973054Z","time spent":"1.119880409s","remote":"127.0.0.1:35304","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-02-03T17:27:51.093079Z","caller":"traceutil/trace.go:171","msg":"trace[1736252301] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"1.11992337s","start":"2026-02-03T17:27:49.973151Z","end":"2026-02-03T17:27:51.093074Z","steps":["trace[1736252301] 'process raft request' (duration: 1.119494882s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:51.093102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:49.973144Z","time spent":"1.11994515s","remote":"127.0.0.1:35304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:27:51.093203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.112574027s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-03T17:27:51.093218Z","caller":"traceutil/trace.go:171","msg":"trace[1537404757] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:686; }","duration":"1.112614949s","start":"2026-02-03T17:27:49.980599Z","end":"2026-02-03T17:27:51.093214Z","steps":["trace[1537404757] 'agreement among raft nodes before linearized reading' (duration: 1.112555508s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:51.093231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:49.980586Z","time spent":"1.112641919s","remote":"127.0.0.1:35304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-03T17:27:51.096517Z","caller":"traceutil/trace.go:171","msg":"trace[1539657139] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"534.888254ms","start":"2026-02-03T17:27:50.561612Z","end":"2026-02-03T17:27:51.0965Z","steps":["trace[1539657139] 'process raft request' (duration: 534.793332ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:51.096625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:50.561593Z","time spent":"534.970706ms","remote":"127.0.0.1:35304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:27:51.096965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.697814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.222\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-03T17:27:51.096999Z","caller":"traceutil/trace.go:171","msg":"trace[1556671154] range","detail":"{range_begin:/registry/masterleases/199.19.213.222; range_end:; response_count:1; response_revision:687; }","duration":"685.754545ms","start":"2026-02-03T17:27:50.411235Z","end":"2026-02-03T17:27:51.09699Z","steps":["trace[1556671154] 'agreement among raft nodes before linearized reading' (duration: 685.661204ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:51.097029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:50.411221Z","time spent":"685.801266ms","remote":"127.0.0.1:35070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.222\" "} {"level":"warn","ts":"2026-02-03T17:27:51.09715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.219405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:27:51.097174Z","caller":"traceutil/trace.go:171","msg":"trace[819645820] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:687; }","duration":"638.275496ms","start":"2026-02-03T17:27:50.458892Z","end":"2026-02-03T17:27:51.097168Z","steps":["trace[819645820] 'agreement among raft nodes before linearized reading' (duration: 638.231995ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:51.097194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:50.458876Z","time spent":"638.313066ms","remote":"127.0.0.1:35272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-02-03T17:27:55.676486Z","caller":"traceutil/trace.go:171","msg":"trace[783043376] linearizableReadLoop","detail":"{readStateIndex:733; appliedIndex:732; }","duration":"152.557373ms","start":"2026-02-03T17:27:55.523915Z","end":"2026-02-03T17:27:55.676472Z","steps":["trace[783043376] 'read index received' (duration: 152.405039ms)","trace[783043376] 'applied index is now lower than readState.Index' (duration: 151.814µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:27:55.676671Z","caller":"traceutil/trace.go:171","msg":"trace[147445797] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"174.462057ms","start":"2026-02-03T17:27:55.502203Z","end":"2026-02-03T17:27:55.676665Z","steps":["trace[147445797] 'process raft request' (duration: 174.176892ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:55.67684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.908459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-03T17:27:55.676857Z","caller":"traceutil/trace.go:171","msg":"trace[1229952540] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:702; }","duration":"152.953421ms","start":"2026-02-03T17:27:55.523898Z","end":"2026-02-03T17:27:55.676852Z","steps":["trace[1229952540] 'agreement among raft nodes before linearized reading' (duration: 152.88887ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:55.676939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.157039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:27:55.676954Z","caller":"traceutil/trace.go:171","msg":"trace[1359195595] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:702; }","duration":"100.17217ms","start":"2026-02-03T17:27:55.576777Z","end":"2026-02-03T17:27:55.676949Z","steps":["trace[1359195595] 'agreement among raft nodes before linearized reading' (duration: 100.149099ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:55.843707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.83058ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-03T17:28:08.002492Z","caller":"traceutil/trace.go:171","msg":"trace[645185359] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"286.005726ms","start":"2026-02-03T17:28:07.716464Z","end":"2026-02-03T17:28:08.00247Z","steps":["trace[645185359] 'process raft request' (duration: 285.790272ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:09.460653Z","caller":"traceutil/trace.go:171","msg":"trace[1494173076] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"258.801846ms","start":"2026-02-03T17:28:09.201827Z","end":"2026-02-03T17:28:09.460629Z","steps":["trace[1494173076] 'process raft request' (duration: 258.649694ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:09.973998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.43273ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T17:28:09.974632Z","caller":"traceutil/trace.go:171","msg":"trace[216155713] linearizableReadLoop","detail":"{readStateIndex:779; appliedIndex:778; }","duration":"752.597957ms","start":"2026-02-03T17:28:09.222016Z","end":"2026-02-03T17:28:09.974613Z","steps":["trace[216155713] 'read index received' (duration: 239.236173ms)","trace[216155713] 'applied index is now lower than readState.Index' (duration: 513.360084ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:28:09.974779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.757881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-03T17:28:09.974826Z","caller":"traceutil/trace.go:171","msg":"trace[1692603194] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:744; }","duration":"752.830772ms","start":"2026-02-03T17:28:09.221986Z","end":"2026-02-03T17:28:09.974817Z","steps":["trace[1692603194] 'agreement among raft nodes before linearized reading' (duration: 752.675749ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:09.974855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:09.221974Z","time spent":"752.872753ms","remote":"127.0.0.1:35304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-03T17:28:09.975096Z","caller":"traceutil/trace.go:171","msg":"trace[1944624170] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"773.12621ms","start":"2026-02-03T17:28:09.201958Z","end":"2026-02-03T17:28:09.975084Z","steps":["trace[1944624170] 'process raft request' (duration: 637.543548ms)","trace[1944624170] 'compare' (duration: 134.282487ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:28:09.975823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:09.201951Z","time spent":"773.176541ms","remote":"127.0.0.1:35304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:28:10.141256Z","caller":"traceutil/trace.go:171","msg":"trace[950090549] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"161.149012ms","start":"2026-02-03T17:28:09.980095Z","end":"2026-02-03T17:28:10.141244Z","steps":["trace[950090549] 'process raft request' (duration: 120.418032ms)","trace[950090549] 'compare' (duration: 39.614248ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:28:10.14193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.03261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-03T17:28:10.141974Z","caller":"traceutil/trace.go:171","msg":"trace[790346256] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:745; }","duration":"136.117842ms","start":"2026-02-03T17:28:10.005844Z","end":"2026-02-03T17:28:10.141962Z","steps":["trace[790346256] 'agreement among raft nodes before linearized reading' (duration: 135.971729ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:10.141199Z","caller":"traceutil/trace.go:171","msg":"trace[250157282] linearizableReadLoop","detail":"{readStateIndex:780; appliedIndex:779; }","duration":"135.275477ms","start":"2026-02-03T17:28:10.005904Z","end":"2026-02-03T17:28:10.141179Z","steps":["trace[250157282] 'read index received' (duration: 94.557097ms)","trace[250157282] 'applied index is now lower than readState.Index' (duration: 40.71706ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:28:22.217185Z","caller":"traceutil/trace.go:171","msg":"trace[1622355262] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"197.591487ms","start":"2026-02-03T17:28:22.019582Z","end":"2026-02-03T17:28:22.217173Z","steps":["trace[1622355262] 'process raft request' (duration: 197.443834ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:22.660853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.410168ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T17:28:22.660945Z","caller":"traceutil/trace.go:171","msg":"trace[885444806] linearizableReadLoop","detail":"{readStateIndex:872; appliedIndex:870; }","duration":"104.972247ms","start":"2026-02-03T17:28:22.555963Z","end":"2026-02-03T17:28:22.660936Z","steps":["trace[885444806] 'read index received' (duration: 24.7µs)","trace[885444806] 'applied index is now lower than readState.Index' (duration: 104.947097ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:28:22.66099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.025708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:28:22.661003Z","caller":"traceutil/trace.go:171","msg":"trace[629166048] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:833; }","duration":"105.059979ms","start":"2026-02-03T17:28:22.555937Z","end":"2026-02-03T17:28:22.660996Z","steps":["trace[629166048] 'agreement among raft nodes before linearized reading' (duration: 105.023668ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:22.661152Z","caller":"traceutil/trace.go:171","msg":"trace[1951266621] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"441.053522ms","start":"2026-02-03T17:28:22.220092Z","end":"2026-02-03T17:28:22.661146Z","steps":["trace[1951266621] 'process raft request' (duration: 44.302916ms)","trace[1951266621] 'compare' (duration: 396.350167ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:28:22.661178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:22.220084Z","time spent":"441.079682ms","remote":"127.0.0.1:35304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:28:22.661233Z","caller":"traceutil/trace.go:171","msg":"trace[574614094] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"440.212526ms","start":"2026-02-03T17:28:22.221017Z","end":"2026-02-03T17:28:22.661229Z","steps":["trace[574614094] 'process raft request' (duration: 439.895549ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:22.661255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:22.221011Z","time spent":"440.231356ms","remote":"127.0.0.1:35304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:28:23.411581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.220554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-03T17:28:23.411641Z","caller":"traceutil/trace.go:171","msg":"trace[2122278076] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:833; }","duration":"283.316847ms","start":"2026-02-03T17:28:23.12831Z","end":"2026-02-03T17:28:23.411627Z","steps":["trace[2122278076] 'range keys from in-memory index tree' (duration: 283.095982ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:23.695771Z","caller":"traceutil/trace.go:171","msg":"trace[1615764042] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"100.668414ms","start":"2026-02-03T17:28:23.595089Z","end":"2026-02-03T17:28:23.695757Z","steps":["trace[1615764042] 'process raft request' (duration: 100.594882ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:23.749624Z","caller":"traceutil/trace.go:171","msg":"trace[1852393555] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"152.573727ms","start":"2026-02-03T17:28:23.597037Z","end":"2026-02-03T17:28:23.749611Z","steps":["trace[1852393555] 'process raft request' (duration: 152.486945ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:26.911333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.544764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:28:26.911386Z","caller":"traceutil/trace.go:171","msg":"trace[535995928] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:842; }","duration":"359.625225ms","start":"2026-02-03T17:28:26.551751Z","end":"2026-02-03T17:28:26.911376Z","steps":["trace[535995928] 'range keys from in-memory index tree' (duration: 359.492363ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:26.911406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:26.551738Z","time spent":"359.663566ms","remote":"127.0.0.1:35050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-03T17:28:26.911513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.086984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-03T17:28:26.911527Z","caller":"traceutil/trace.go:171","msg":"trace[923582081] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:842; }","duration":"303.123615ms","start":"2026-02-03T17:28:26.608399Z","end":"2026-02-03T17:28:26.911523Z","steps":["trace[923582081] 'count revisions from in-memory index tree' (duration: 303.050614ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:26.911549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:26.608387Z","time spent":"303.157545ms","remote":"127.0.0.1:35432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":31,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-03T17:28:26.911689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.664167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-03T17:28:26.911702Z","caller":"traceutil/trace.go:171","msg":"trace[142870099] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:842; }","duration":"221.698518ms","start":"2026-02-03T17:28:26.689999Z","end":"2026-02-03T17:28:26.911698Z","steps":["trace[142870099] 'range keys from in-memory index tree' (duration: 221.577146ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:26.91178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.189926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-03T17:28:26.911794Z","caller":"traceutil/trace.go:171","msg":"trace[1541653236] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:842; }","duration":"179.229947ms","start":"2026-02-03T17:28:26.732558Z","end":"2026-02-03T17:28:26.911788Z","steps":["trace[1541653236] 'range keys from in-memory index tree' (duration: 179.130274ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:27.170236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.703961ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T17:28:27.170326Z","caller":"traceutil/trace.go:171","msg":"trace[979247989] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"254.343523ms","start":"2026-02-03T17:28:26.915964Z","end":"2026-02-03T17:28:27.170308Z","steps":["trace[979247989] 'process raft request' (duration: 125.49672ms)","trace[979247989] 'compare' (duration: 128.619089ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:28:35.377564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.850613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-03T17:28:35.377624Z","caller":"traceutil/trace.go:171","msg":"trace[1710404171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:866; }","duration":"185.947395ms","start":"2026-02-03T17:28:35.191666Z","end":"2026-02-03T17:28:35.377613Z","steps":["trace[1710404171] 'range keys from in-memory index tree' (duration: 185.718991ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:36.573376Z","caller":"traceutil/trace.go:171","msg":"trace[1435100115] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"309.214421ms","start":"2026-02-03T17:28:36.264147Z","end":"2026-02-03T17:28:36.573361Z","steps":["trace[1435100115] 'process raft request' (duration: 309.122129ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:36.57347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:36.264126Z","time spent":"309.297243ms","remote":"127.0.0.1:35304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:28:37.490228Z","caller":"traceutil/trace.go:171","msg":"trace[261842914] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"101.759556ms","start":"2026-02-03T17:28:37.388443Z","end":"2026-02-03T17:28:37.490203Z","steps":["trace[261842914] 'process raft request' (duration: 101.610023ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:38.709042Z","caller":"traceutil/trace.go:171","msg":"trace[1187049903] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"128.368993ms","start":"2026-02-03T17:28:38.580658Z","end":"2026-02-03T17:28:38.709027Z","steps":["trace[1187049903] 'process raft request' (duration: 128.262671ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:39.289108Z","caller":"traceutil/trace.go:171","msg":"trace[216506980] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"268.030774ms","start":"2026-02-03T17:28:39.021061Z","end":"2026-02-03T17:28:39.289092Z","steps":["trace[216506980] 'process raft request' (duration: 267.949693ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:39.585672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.83288ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-03T17:28:39.585761Z","caller":"traceutil/trace.go:171","msg":"trace[1328455254] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"327.422478ms","start":"2026-02-03T17:28:39.258327Z","end":"2026-02-03T17:28:39.58575Z","steps":["trace[1328455254] 'process raft request' (duration: 104.469506ms)","trace[1328455254] 'compare' (duration: 222.589386ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:28:39.585796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:39.258309Z","time spent":"327.473ms","remote":"127.0.0.1:35106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":814,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-03T17:28:40.013785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.747234ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T17:28:40.013928Z","caller":"traceutil/trace.go:171","msg":"trace[815251399] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"422.63604ms","start":"2026-02-03T17:28:39.591273Z","end":"2026-02-03T17:28:40.013909Z","steps":["trace[815251399] 'process raft request' (duration: 183.695492ms)","trace[815251399] 'compare' (duration: 238.653712ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:28:40.013988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:39.591253Z","time spent":"422.705312ms","remote":"127.0.0.1:35304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:28:40.014046Z","caller":"traceutil/trace.go:171","msg":"trace[1049643792] linearizableReadLoop","detail":"{readStateIndex:925; appliedIndex:923; }","duration":"217.161831ms","start":"2026-02-03T17:28:39.796865Z","end":"2026-02-03T17:28:40.014027Z","steps":["trace[1049643792] 'read index received' (duration: 204.167533ms)","trace[1049643792] 'applied index is now lower than readState.Index' (duration: 12.993448ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:28:40.014185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.306134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-03T17:28:40.014206Z","caller":"traceutil/trace.go:171","msg":"trace[733703311] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:883; }","duration":"217.377585ms","start":"2026-02-03T17:28:39.796822Z","end":"2026-02-03T17:28:40.0142Z","steps":["trace[733703311] 'agreement among raft nodes before linearized reading' (duration: 217.266523ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:40.014282Z","caller":"traceutil/trace.go:171","msg":"trace[2082783874] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"422.421575ms","start":"2026-02-03T17:28:39.591853Z","end":"2026-02-03T17:28:40.014274Z","steps":["trace[2082783874] 'process raft request' (duration: 422.017098ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:40.014337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:39.591846Z","time spent":"422.449656ms","remote":"127.0.0.1:35304","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-02-03T17:28:40.300834Z","caller":"traceutil/trace.go:171","msg":"trace[2011440137] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"278.80448ms","start":"2026-02-03T17:28:40.021994Z","end":"2026-02-03T17:28:40.300799Z","steps":["trace[2011440137] 'process raft request' (duration: 212.230958ms)","trace[2011440137] 'compare' (duration: 66.248996ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:28:40.636209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.766619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.222\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-03T17:28:40.636285Z","caller":"traceutil/trace.go:171","msg":"trace[1844213119] range","detail":"{range_begin:/registry/masterleases/199.19.213.222; range_end:; response_count:1; response_revision:884; }","duration":"222.878192ms","start":"2026-02-03T17:28:40.413388Z","end":"2026-02-03T17:28:40.636266Z","steps":["trace[1844213119] 'range keys from in-memory index tree' (duration: 222.589516ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:40.981557Z","caller":"traceutil/trace.go:171","msg":"trace[229488485] linearizableReadLoop","detail":"{readStateIndex:929; appliedIndex:928; }","duration":"260.125904ms","start":"2026-02-03T17:28:40.721406Z","end":"2026-02-03T17:28:40.981532Z","steps":["trace[229488485] 'read index received' (duration: 259.94224ms)","trace[229488485] 'applied index is now lower than readState.Index' (duration: 182.844µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:28:40.98168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.243226ms","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:7"} {"level":"info","ts":"2026-02-03T17:28:40.981713Z","caller":"traceutil/trace.go:171","msg":"trace[31332520] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:886; }","duration":"260.307387ms","start":"2026-02-03T17:28:40.721395Z","end":"2026-02-03T17:28:40.981702Z","steps":["trace[31332520] 'agreement among raft nodes before linearized reading' (duration: 260.224795ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:40.981773Z","caller":"traceutil/trace.go:171","msg":"trace[351329104] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"262.920246ms","start":"2026-02-03T17:28:40.718824Z","end":"2026-02-03T17:28:40.981744Z","steps":["trace[351329104] 'process raft request' (duration: 262.578659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:56.719395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.598758ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-03T17:28:56.719902Z","caller":"traceutil/trace.go:171","msg":"trace[980637702] linearizableReadLoop","detail":"{readStateIndex:1036; appliedIndex:1035; }","duration":"732.688416ms","start":"2026-02-03T17:28:55.987185Z","end":"2026-02-03T17:28:56.719874Z","steps":["trace[980637702] 'read index received' (duration: 33.18µs)","trace[980637702] 'applied index is now lower than readState.Index' (duration: 732.650946ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:28:56.720028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.828409ms","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-02-03T17:28:56.720048Z","caller":"traceutil/trace.go:171","msg":"trace[553291151] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:990; }","duration":"732.897911ms","start":"2026-02-03T17:28:55.987143Z","end":"2026-02-03T17:28:56.720041Z","steps":["trace[553291151] 'agreement among raft nodes before linearized reading' (duration: 732.836249ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:56.720073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:55.987125Z","time spent":"732.941771ms","remote":"127.0.0.1:35496","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-02-03T17:28:56.720198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.589583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:28:56.720263Z","caller":"traceutil/trace.go:171","msg":"trace[1796899461] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:990; }","duration":"654.690865ms","start":"2026-02-03T17:28:56.065554Z","end":"2026-02-03T17:28:56.720245Z","steps":["trace[1796899461] 'agreement among raft nodes before linearized reading' (duration: 654.583283ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:56.720314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:56.06554Z","time spent":"654.766756ms","remote":"127.0.0.1:35038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-03T17:28:56.720512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.719698ms","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:508"} {"level":"info","ts":"2026-02-03T17:28:56.720545Z","caller":"traceutil/trace.go:171","msg":"trace[690498614] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:990; }","duration":"538.782599ms","start":"2026-02-03T17:28:56.181754Z","end":"2026-02-03T17:28:56.720537Z","steps":["trace[690498614] 'agreement among raft nodes before linearized reading' (duration: 538.681587ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:56.720568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:56.181739Z","time spent":"538.82311ms","remote":"127.0.0.1:35304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-03T17:28:56.720673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.619856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:28:56.720746Z","caller":"traceutil/trace.go:171","msg":"trace[990193395] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:990; }","duration":"165.714067ms","start":"2026-02-03T17:28:56.555023Z","end":"2026-02-03T17:28:56.720737Z","steps":["trace[990193395] 'agreement among raft nodes before linearized reading' (duration: 165.635466ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:56.720944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.20443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-03T17:28:56.720971Z","caller":"traceutil/trace.go:171","msg":"trace[672716470] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:990; }","duration":"265.257172ms","start":"2026-02-03T17:28:56.455706Z","end":"2026-02-03T17:28:56.720963Z","steps":["trace[672716470] 'agreement among raft nodes before linearized reading' (duration: 265.19587ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:56.721089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.385782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:28:56.721114Z","caller":"traceutil/trace.go:171","msg":"trace[2049541446] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:990; }","duration":"403.440532ms","start":"2026-02-03T17:28:56.317668Z","end":"2026-02-03T17:28:56.721108Z","steps":["trace[2049541446] 'agreement among raft nodes before linearized reading' (duration: 403.403032ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:56.721132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:56.317653Z","time spent":"403.474014ms","remote":"127.0.0.1:35160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-02-03T17:28:56.72161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.027273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-03T17:28:56.721636Z","caller":"traceutil/trace.go:171","msg":"trace[1195569693] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:990; }","duration":"620.076274ms","start":"2026-02-03T17:28:56.101554Z","end":"2026-02-03T17:28:56.72163Z","steps":["trace[1195569693] 'agreement among raft nodes before linearized reading' (duration: 620.010603ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:56.721654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:56.101544Z","time spent":"620.106344ms","remote":"127.0.0.1:35304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-03T17:28:56.721763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.02103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-03T17:28:56.721784Z","caller":"traceutil/trace.go:171","msg":"trace[2017583267] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:990; }","duration":"611.060642ms","start":"2026-02-03T17:28:56.110719Z","end":"2026-02-03T17:28:56.721779Z","steps":["trace[2017583267] 'agreement among raft nodes before linearized reading' (duration: 611.006651ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:56.721801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:56.110709Z","time spent":"611.088642ms","remote":"127.0.0.1:35304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-03T17:28:56.722012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.241796ms","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:531"} {"level":"info","ts":"2026-02-03T17:28:56.722037Z","caller":"traceutil/trace.go:171","msg":"trace[585029310] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:990; }","duration":"632.288807ms","start":"2026-02-03T17:28:56.089743Z","end":"2026-02-03T17:28:56.722031Z","steps":["trace[585029310] 'agreement among raft nodes before linearized reading' (duration: 632.230806ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:56.722054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:56.089729Z","time spent":"632.321428ms","remote":"127.0.0.1:35304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-02-03T17:28:56.904483Z","caller":"traceutil/trace.go:171","msg":"trace[891249662] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"174.153771ms","start":"2026-02-03T17:28:56.730307Z","end":"2026-02-03T17:28:56.90446Z","steps":["trace[891249662] 'process raft request' (duration: 173.948837ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:56.912403Z","caller":"traceutil/trace.go:171","msg":"trace[1496083363] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"177.742989ms","start":"2026-02-03T17:28:56.734647Z","end":"2026-02-03T17:28:56.91239Z","steps":["trace[1496083363] 'process raft request' (duration: 177.649937ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:56.913104Z","caller":"traceutil/trace.go:171","msg":"trace[157913138] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"182.612832ms","start":"2026-02-03T17:28:56.730482Z","end":"2026-02-03T17:28:56.913094Z","steps":["trace[157913138] 'process raft request' (duration: 181.706094ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:56.913354Z","caller":"traceutil/trace.go:171","msg":"trace[1040574803] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"182.404797ms","start":"2026-02-03T17:28:56.730942Z","end":"2026-02-03T17:28:56.913347Z","steps":["trace[1040574803] 'process raft request' (duration: 181.311736ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:56.913654Z","caller":"traceutil/trace.go:171","msg":"trace[82902951] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"179.032333ms","start":"2026-02-03T17:28:56.734613Z","end":"2026-02-03T17:28:56.913646Z","steps":["trace[82902951] 'process raft request' (duration: 177.663187ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:29:13.133005Z","caller":"traceutil/trace.go:171","msg":"trace[1520648908] linearizableReadLoop","detail":"{readStateIndex:1107; appliedIndex:1106; }","duration":"144.58425ms","start":"2026-02-03T17:29:12.988404Z","end":"2026-02-03T17:29:13.132988Z","steps":["trace[1520648908] 'read index received' (duration: 62.644332ms)","trace[1520648908] 'applied index is now lower than readState.Index' (duration: 81.939288ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:29:13.133083Z","caller":"traceutil/trace.go:171","msg":"trace[1931322050] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"149.704347ms","start":"2026-02-03T17:29:12.98337Z","end":"2026-02-03T17:29:13.133075Z","steps":["trace[1931322050] 'process raft request' (duration: 67.717948ms)","trace[1931322050] 'compare' (duration: 81.820296ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:29:13.133245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.209258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-03T17:29:13.13331Z","caller":"traceutil/trace.go:171","msg":"trace[1121324740] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1058; }","duration":"139.29178ms","start":"2026-02-03T17:29:12.994006Z","end":"2026-02-03T17:29:13.133298Z","steps":["trace[1121324740] 'agreement among raft nodes before linearized reading' (duration: 139.142637ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:29:13.133368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.952008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-03T17:29:13.133393Z","caller":"traceutil/trace.go:171","msg":"trace[849450109] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1058; }","duration":"145.022998ms","start":"2026-02-03T17:29:12.988364Z","end":"2026-02-03T17:29:13.133387Z","steps":["trace[849450109] 'agreement among raft nodes before linearized reading' (duration: 144.938457ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:29:13.13345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.243853ms","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:532"} {"level":"info","ts":"2026-02-03T17:29:13.133467Z","caller":"traceutil/trace.go:171","msg":"trace[1059816496] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1058; }","duration":"135.289344ms","start":"2026-02-03T17:29:12.998173Z","end":"2026-02-03T17:29:13.133462Z","steps":["trace[1059816496] 'agreement among raft nodes before linearized reading' (duration: 135.244063ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:29:13.279756Z","caller":"traceutil/trace.go:171","msg":"trace[1970765491] linearizableReadLoop","detail":"{readStateIndex:1110; appliedIndex:1107; }","duration":"117.237761ms","start":"2026-02-03T17:29:13.162504Z","end":"2026-02-03T17:29:13.279742Z","steps":["trace[1970765491] 'read index received' (duration: 86.369633ms)","trace[1970765491] 'applied index is now lower than readState.Index' (duration: 30.867498ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:29:13.27984Z","caller":"traceutil/trace.go:171","msg":"trace[694657022] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"142.355847ms","start":"2026-02-03T17:29:13.137475Z","end":"2026-02-03T17:29:13.279831Z","steps":["trace[694657022] 'process raft request' (duration: 111.391748ms)","trace[694657022] 'compare' (duration: 30.669784ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:29:13.279943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.431574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-03T17:29:13.279987Z","caller":"traceutil/trace.go:171","msg":"trace[1688339900] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1061; }","duration":"117.500515ms","start":"2026-02-03T17:29:13.162475Z","end":"2026-02-03T17:29:13.279976Z","steps":["trace[1688339900] 'agreement among raft nodes before linearized reading' (duration: 117.387683ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:29:13.280099Z","caller":"traceutil/trace.go:171","msg":"trace[643833275] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"141.885608ms","start":"2026-02-03T17:29:13.138208Z","end":"2026-02-03T17:29:13.280093Z","steps":["trace[643833275] 'process raft request' (duration: 141.43702ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:29:13.280177Z","caller":"traceutil/trace.go:171","msg":"trace[1655022152] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"136.784771ms","start":"2026-02-03T17:29:13.143388Z","end":"2026-02-03T17:29:13.280173Z","steps":["trace[1655022152] 'process raft request' (duration: 136.329942ms)"],"step_count":1}