{"level":"warn","ts":"2026-02-25T19:24:55.109249Z","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-25T19:24:55.109318Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.176: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.176:2380","--initial-cluster=instance=https://199.19.213.176:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.176:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.176: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-25T19:24:55.109374Z","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-25T19:24:55.109381Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.176:2380"]} {"level":"info","ts":"2026-02-25T19:24:55.109434Z","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-25T19:24:55.109999Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.176:2379"]} {"level":"info","ts":"2026-02-25T19:24:55.110067Z","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":8,"max-cpu-available":8,"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.176:2380"],"listen-peer-urls":["https://199.19.213.176:2380"],"advertise-client-urls":["https://199.19.213.176:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.176:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.176: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-25T19:24:55.271502Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"161.237479ms"} {"level":"info","ts":"2026-02-25T19:24:55.310728Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6e120e57916b9bd4","cluster-id":"e644555f29703ff6"} {"level":"info","ts":"2026-02-25T19:24:55.310839Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T19:24:55.310866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 became follower at term 0"} {"level":"info","ts":"2026-02-25T19:24:55.310886Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6e120e57916b9bd4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T19:24:55.310904Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 became follower at term 1"} {"level":"info","ts":"2026-02-25T19:24:55.310956Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 switched to configuration voters=(7931417663017556948)"} {"level":"warn","ts":"2026-02-25T19:24:55.403025Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T19:24:55.411801Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T19:24:55.417028Z","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-25T19:24:55.422599Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6e120e57916b9bd4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T19:24:55.42721Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6e120e57916b9bd4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T19:24:55.427278Z","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-25T19:24:55.427506Z","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-25T19:24:55.427535Z","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-25T19:24:55.428188Z","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-25T19:24:55.42834Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.176:2380"} {"level":"info","ts":"2026-02-25T19:24:55.428408Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.176:2380"} {"level":"info","ts":"2026-02-25T19:24:55.428508Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 switched to configuration voters=(7931417663017556948)"} {"level":"info","ts":"2026-02-25T19:24:55.428644Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e644555f29703ff6","local-member-id":"6e120e57916b9bd4","added-peer-id":"6e120e57916b9bd4","added-peer-peer-urls":["https://199.19.213.176:2380"]} {"level":"info","ts":"2026-02-25T19:24:55.428897Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6e120e57916b9bd4","initial-advertise-peer-urls":["https://199.19.213.176:2380"],"listen-peer-urls":["https://199.19.213.176:2380"],"advertise-client-urls":["https://199.19.213.176:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.176:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T19:24:55.428948Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T19:24:56.01146Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T19:24:56.011532Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T19:24:56.011583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 received MsgPreVoteResp from 6e120e57916b9bd4 at term 1"} {"level":"info","ts":"2026-02-25T19:24:56.011604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 became candidate at term 2"} {"level":"info","ts":"2026-02-25T19:24:56.011623Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 received MsgVoteResp from 6e120e57916b9bd4 at term 2"} {"level":"info","ts":"2026-02-25T19:24:56.011641Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 became leader at term 2"} {"level":"info","ts":"2026-02-25T19:24:56.011662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6e120e57916b9bd4 elected leader 6e120e57916b9bd4 at term 2"} {"level":"info","ts":"2026-02-25T19:24:56.018059Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:24:56.020021Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T19:24:56.020021Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6e120e57916b9bd4","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.176:2379]}","request-path":"/0/members/6e120e57916b9bd4/attributes","cluster-id":"e644555f29703ff6","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T19:24:56.020126Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T19:24:56.020704Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T19:24:56.020739Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T19:24:56.021657Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e644555f29703ff6","local-member-id":"6e120e57916b9bd4","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:24:56.021766Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:24:56.021814Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:24:56.022561Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T19:24:56.024001Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.176:2379"} {"level":"info","ts":"2026-02-25T19:24:57.414986Z","caller":"traceutil/trace.go:171","msg":"trace[1024177920] linearizableReadLoop","detail":"{readStateIndex:13; appliedIndex:7; }","duration":"100.977645ms","start":"2026-02-25T19:24:57.313986Z","end":"2026-02-25T19:24:57.414964Z","steps":["trace[1024177920] 'read index received' (duration: 55.868323ms)","trace[1024177920] 'applied index is now lower than readState.Index' (duration: 45.108252ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:24:57.415212Z","caller":"traceutil/trace.go:171","msg":"trace[2108219951] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"116.205203ms","start":"2026-02-25T19:24:57.298993Z","end":"2026-02-25T19:24:57.415198Z","steps":["trace[2108219951] 'process raft request' (duration: 70.78726ms)","trace[2108219951] 'compare' (duration: 44.93604ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:24:57.41533Z","caller":"traceutil/trace.go:171","msg":"trace[1834511270] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"115.801191ms","start":"2026-02-25T19:24:57.299507Z","end":"2026-02-25T19:24:57.415308Z","steps":["trace[1834511270] 'process raft request' (duration: 115.393398ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:24:57.415518Z","caller":"traceutil/trace.go:171","msg":"trace[916517271] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"116.416216ms","start":"2026-02-25T19:24:57.299095Z","end":"2026-02-25T19:24:57.415511Z","steps":["trace[916517271] 'process raft request' (duration: 115.779671ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:24:57.415524Z","caller":"traceutil/trace.go:171","msg":"trace[1271362554] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"103.917003ms","start":"2026-02-25T19:24:57.311584Z","end":"2026-02-25T19:24:57.415501Z","steps":["trace[1271362554] 'process raft request' (duration: 103.336919ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:24:57.415647Z","caller":"traceutil/trace.go:171","msg":"trace[150852703] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"116.514896ms","start":"2026-02-25T19:24:57.299083Z","end":"2026-02-25T19:24:57.415598Z","steps":["trace[150852703] 'process raft request' (duration: 115.763871ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:57.41584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.66213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T19:24:57.415886Z","caller":"traceutil/trace.go:171","msg":"trace[416670975] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10; }","duration":"115.734971ms","start":"2026-02-25T19:24:57.300142Z","end":"2026-02-25T19:24:57.415877Z","steps":["trace[416670975] 'agreement among raft nodes before linearized reading' (duration: 115.585961ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:24:57.416114Z","caller":"traceutil/trace.go:171","msg":"trace[1406039003] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"117.031299ms","start":"2026-02-25T19:24:57.299058Z","end":"2026-02-25T19:24:57.416089Z","steps":["trace[1406039003] 'process raft request' (duration: 115.756111ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:24:57.442645Z","caller":"traceutil/trace.go:171","msg":"trace[1140020410] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"121.241923ms","start":"2026-02-25T19:24:57.321385Z","end":"2026-02-25T19:24:57.442627Z","steps":["trace[1140020410] 'process raft request' (duration: 121.132903ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:24:57.442619Z","caller":"traceutil/trace.go:171","msg":"trace[427633603] transaction","detail":"{read_only:false; number_of_response:0; response_revision:11; }","duration":"126.982936ms","start":"2026-02-25T19:24:57.315582Z","end":"2026-02-25T19:24:57.442565Z","steps":["trace[427633603] 'process raft request' (duration: 126.856715ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:24:57.442894Z","caller":"traceutil/trace.go:171","msg":"trace[171699191] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"127.257698ms","start":"2026-02-25T19:24:57.315501Z","end":"2026-02-25T19:24:57.442758Z","steps":["trace[171699191] 'process raft request' (duration: 126.846186ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:24:57.442934Z","caller":"traceutil/trace.go:171","msg":"trace[1839777384] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"125.784159ms","start":"2026-02-25T19:24:57.317037Z","end":"2026-02-25T19:24:57.442822Z","steps":["trace[1839777384] 'process raft request' (duration: 125.424137ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:58.611952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.895666ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-25T19:24:58.61223Z","caller":"traceutil/trace.go:171","msg":"trace[735630273] linearizableReadLoop","detail":"{readStateIndex:100; appliedIndex:99; }","duration":"292.193258ms","start":"2026-02-25T19:24:58.31998Z","end":"2026-02-25T19:24:58.612174Z","steps":["trace[735630273] 'read index received' (duration: 79.357818ms)","trace[735630273] 'applied index is now lower than readState.Index' (duration: 212.83356ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:24:58.612241Z","caller":"traceutil/trace.go:171","msg":"trace[251780332] transaction","detail":"{read_only:false; response_revision:96; number_of_response:1; }","duration":"297.7024ms","start":"2026-02-25T19:24:58.314498Z","end":"2026-02-25T19:24:58.612201Z","steps":["trace[251780332] 'process raft request' (duration: 84.93542ms)","trace[251780332] 'compare' (duration: 211.706074ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:24:58.612512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.5198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T19:24:58.612561Z","caller":"traceutil/trace.go:171","msg":"trace[1247080840] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:96; }","duration":"292.621771ms","start":"2026-02-25T19:24:58.319926Z","end":"2026-02-25T19:24:58.612548Z","steps":["trace[1247080840] 'agreement among raft nodes before linearized reading' (duration: 292.36405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:59.493621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.965796ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:24:59.493775Z","caller":"traceutil/trace.go:171","msg":"trace[809017217] transaction","detail":"{read_only:false; response_revision:198; number_of_response:1; }","duration":"162.544137ms","start":"2026-02-25T19:24:59.331206Z","end":"2026-02-25T19:24:59.49375Z","steps":["trace[809017217] 'process raft request' (duration: 50.35642ms)","trace[809017217] 'compare' (duration: 111.785615ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:25:18.226591Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11228771940123579048,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:25:18.727799Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11228771940123579048,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:25:18.746645Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.022124822s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T19:25:18.746971Z","caller":"traceutil/trace.go:171","msg":"trace[545696600] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"1.022549385s","start":"2026-02-25T19:25:17.724398Z","end":"2026-02-25T19:25:18.746947Z","steps":["trace[545696600] 'process raft request' (duration: 1.022417054s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:18.747282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:17.724381Z","time spent":"1.022647115s","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:25:19.084225Z","caller":"traceutil/trace.go:171","msg":"trace[402230283] linearizableReadLoop","detail":"{readStateIndex:433; appliedIndex:431; }","duration":"1.358108639s","start":"2026-02-25T19:25:17.726092Z","end":"2026-02-25T19:25:19.0842Z","steps":["trace[402230283] 'read index received' (duration: 1.020751675s)","trace[402230283] 'applied index is now lower than readState.Index' (duration: 337.355994ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:25:19.084366Z","caller":"traceutil/trace.go:171","msg":"trace[596852997] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"1.109200844s","start":"2026-02-25T19:25:17.975116Z","end":"2026-02-25T19:25:19.084317Z","steps":["trace[596852997] 'process raft request' (duration: 1.06347041s)","trace[596852997] 'compare' (duration: 45.512263ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:25:19.08449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.35837652s","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-25T19:25:19.084565Z","caller":"traceutil/trace.go:171","msg":"trace[391094807] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:418; }","duration":"1.358487241s","start":"2026-02-25T19:25:17.726065Z","end":"2026-02-25T19:25:19.084553Z","steps":["trace[391094807] 'agreement among raft nodes before linearized reading' (duration: 1.35829093s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:19.084564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.211981685s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-25T19:25:19.084604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:17.726053Z","time spent":"1.358542811s","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T19:25:19.084649Z","caller":"traceutil/trace.go:171","msg":"trace[381286946] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:418; }","duration":"1.212088926s","start":"2026-02-25T19:25:17.872538Z","end":"2026-02-25T19:25:19.084627Z","steps":["trace[381286946] 'agreement among raft nodes before linearized reading' (duration: 1.211842835s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:19.084641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.358521001s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-25T19:25:19.084714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:17.975082Z","time spent":"1.109491455s","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:25:19.084766Z","caller":"traceutil/trace.go:171","msg":"trace[1831152895] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:418; }","duration":"1.358679702s","start":"2026-02-25T19:25:17.726052Z","end":"2026-02-25T19:25:19.084732Z","steps":["trace[1831152895] 'agreement among raft nodes before linearized reading' (duration: 1.358442641s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:19.084703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:17.872519Z","time spent":"1.212176947s","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T19:25:19.084831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:17.726038Z","time spent":"1.358779482s","remote":"127.0.0.1:56772","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-25T19:25:19.38966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.083303ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:25:19.39Z","caller":"traceutil/trace.go:171","msg":"trace[2134768966] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"301.894215ms","start":"2026-02-25T19:25:19.087893Z","end":"2026-02-25T19:25:19.389787Z","steps":["trace[2134768966] 'process raft request' (duration: 115.54501ms)","trace[2134768966] 'compare' (duration: 185.952503ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:25:19.390099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:19.087878Z","time spent":"302.181496ms","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:25:19.390465Z","caller":"traceutil/trace.go:171","msg":"trace[1477629186] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"302.001967ms","start":"2026-02-25T19:25:19.08845Z","end":"2026-02-25T19:25:19.390452Z","steps":["trace[1477629186] 'process raft request' (duration: 301.565704ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:19.390543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:19.088441Z","time spent":"302.062047ms","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:25:19.39069Z","caller":"traceutil/trace.go:171","msg":"trace[197289492] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"300.729069ms","start":"2026-02-25T19:25:19.089938Z","end":"2026-02-25T19:25:19.390667Z","steps":["trace[197289492] 'process raft request' (duration: 300.319946ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:19.390843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:19.089932Z","time spent":"300.837919ms","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:25:24.619676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.218931ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T19:25:24.6198Z","caller":"traceutil/trace.go:171","msg":"trace[1438439736] linearizableReadLoop","detail":"{readStateIndex:448; appliedIndex:447; }","duration":"145.870745ms","start":"2026-02-25T19:25:24.473904Z","end":"2026-02-25T19:25:24.619775Z","steps":["trace[1438439736] 'read index received' (duration: 7.499212ms)","trace[1438439736] 'applied index is now lower than readState.Index' (duration: 138.369193ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:25:24.619907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.985525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:25:24.619959Z","caller":"traceutil/trace.go:171","msg":"trace[1720115709] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:0; response_revision:431; }","duration":"146.071596ms","start":"2026-02-25T19:25:24.473871Z","end":"2026-02-25T19:25:24.619942Z","steps":["trace[1720115709] 'agreement among raft nodes before linearized reading' (duration: 145.975525ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:25.268289Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11228771940123579100,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T19:25:25.62577Z","caller":"traceutil/trace.go:171","msg":"trace[992347806] linearizableReadLoop","detail":"{readStateIndex:449; appliedIndex:448; }","duration":"858.531269ms","start":"2026-02-25T19:25:24.76718Z","end":"2026-02-25T19:25:25.625712Z","steps":["trace[992347806] 'read index received' (duration: 857.368622ms)","trace[992347806] 'applied index is now lower than readState.Index' (duration: 1.161447ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:25:25.626271Z","caller":"traceutil/trace.go:171","msg":"trace[1021797325] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"907.634969ms","start":"2026-02-25T19:25:24.718601Z","end":"2026-02-25T19:25:25.626236Z","steps":["trace[1021797325] 'process raft request' (duration: 906.604684ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:25.626409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:24.718567Z","time spent":"907.777461ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317292,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:25:25.626284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"859.086982ms","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-25T19:25:25.62654Z","caller":"traceutil/trace.go:171","msg":"trace[1474888216] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:432; }","duration":"859.384945ms","start":"2026-02-25T19:25:24.767136Z","end":"2026-02-25T19:25:25.626521Z","steps":["trace[1474888216] 'agreement among raft nodes before linearized reading' (duration: 859.008472ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:25.626638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:24.767121Z","time spent":"859.503175ms","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T19:25:25.66597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.430668ms","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-25T19:25:25.666041Z","caller":"traceutil/trace.go:171","msg":"trace[1681444629] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:432; }","duration":"179.555198ms","start":"2026-02-25T19:25:25.486468Z","end":"2026-02-25T19:25:25.666024Z","steps":["trace[1681444629] 'agreement among raft nodes before linearized reading' (duration: 139.795098ms)","trace[1681444629] 'range keys from in-memory index tree' (duration: 39.623289ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:25:25.666073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.817621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-25T19:25:25.666221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.184573ms","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-25T19:25:25.666339Z","caller":"traceutil/trace.go:171","msg":"trace[1629470684] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:432; }","duration":"202.346994ms","start":"2026-02-25T19:25:25.463957Z","end":"2026-02-25T19:25:25.666305Z","steps":["trace[1629470684] 'agreement among raft nodes before linearized reading' (duration: 162.266123ms)","trace[1629470684] 'range keys from in-memory index tree' (duration: 39.87662ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:25:25.666258Z","caller":"traceutil/trace.go:171","msg":"trace[157910762] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:432; }","duration":"179.947621ms","start":"2026-02-25T19:25:25.486179Z","end":"2026-02-25T19:25:25.666127Z","steps":["trace[157910762] 'agreement among raft nodes before linearized reading' (duration: 140.093361ms)","trace[157910762] 'range keys from in-memory index tree' (duration: 39.736399ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:25:28.130128Z","caller":"traceutil/trace.go:171","msg":"trace[1218641400] linearizableReadLoop","detail":"{readStateIndex:488; appliedIndex:487; }","duration":"450.657406ms","start":"2026-02-25T19:25:27.679454Z","end":"2026-02-25T19:25:28.130111Z","steps":["trace[1218641400] 'read index received' (duration: 450.490405ms)","trace[1218641400] 'applied index is now lower than readState.Index' (duration: 166.181µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:25:28.130233Z","caller":"traceutil/trace.go:171","msg":"trace[1875240139] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"455.619523ms","start":"2026-02-25T19:25:27.674591Z","end":"2026-02-25T19:25:28.130211Z","steps":["trace[1875240139] 'process raft request' (duration: 455.399342ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:28.130268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.792736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-25T19:25:28.130344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:27.67456Z","time spent":"455.722483ms","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:25:28.13035Z","caller":"traceutil/trace.go:171","msg":"trace[1826021617] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:471; }","duration":"450.914857ms","start":"2026-02-25T19:25:27.679415Z","end":"2026-02-25T19:25:28.13033Z","steps":["trace[1826021617] 'agreement among raft nodes before linearized reading' (duration: 450.784796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:28.130399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:27.679398Z","time spent":"450.989007ms","remote":"127.0.0.1:56772","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-25T19:25:28.130407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.799956ms","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-25T19:25:28.130461Z","caller":"traceutil/trace.go:171","msg":"trace[2012045824] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:471; }","duration":"450.877976ms","start":"2026-02-25T19:25:27.679568Z","end":"2026-02-25T19:25:28.130446Z","steps":["trace[2012045824] 'agreement among raft nodes before linearized reading' (duration: 450.783346ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:28.130503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:27.679552Z","time spent":"450.939946ms","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T19:25:28.130573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.645787ms","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-25T19:25:28.13067Z","caller":"traceutil/trace.go:171","msg":"trace[763874500] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:471; }","duration":"443.770188ms","start":"2026-02-25T19:25:27.686875Z","end":"2026-02-25T19:25:28.130645Z","steps":["trace[763874500] 'agreement among raft nodes before linearized reading' (duration: 443.552157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:28.130724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:27.686864Z","time spent":"443.846349ms","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T19:25:30.313672Z","caller":"traceutil/trace.go:171","msg":"trace[1471016512] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"116.753359ms","start":"2026-02-25T19:25:30.196903Z","end":"2026-02-25T19:25:30.313656Z","steps":["trace[1471016512] 'process raft request' (duration: 116.545708ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:30.314193Z","caller":"traceutil/trace.go:171","msg":"trace[18361056] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"117.226592ms","start":"2026-02-25T19:25:30.196928Z","end":"2026-02-25T19:25:30.314154Z","steps":["trace[18361056] 'process raft request' (duration: 116.694249ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:30.323773Z","caller":"traceutil/trace.go:171","msg":"trace[1300356009] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"126.268822ms","start":"2026-02-25T19:25:30.19748Z","end":"2026-02-25T19:25:30.323749Z","steps":["trace[1300356009] 'process raft request' (duration: 126.118531ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:32.063645Z","caller":"traceutil/trace.go:171","msg":"trace[810591692] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"182.720789ms","start":"2026-02-25T19:25:31.880894Z","end":"2026-02-25T19:25:32.063614Z","steps":["trace[810591692] 'process raft request' (duration: 181.983586ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:32.078188Z","caller":"traceutil/trace.go:171","msg":"trace[63499193] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"183.163842ms","start":"2026-02-25T19:25:31.895007Z","end":"2026-02-25T19:25:32.078171Z","steps":["trace[63499193] 'process raft request' (duration: 183.072331ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:32.448306Z","caller":"traceutil/trace.go:171","msg":"trace[1646733762] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"100.799931ms","start":"2026-02-25T19:25:32.347483Z","end":"2026-02-25T19:25:32.448283Z","steps":["trace[1646733762] 'process raft request' (duration: 100.744281ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:32.448338Z","caller":"traceutil/trace.go:171","msg":"trace[1673266818] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"100.846101ms","start":"2026-02-25T19:25:32.347462Z","end":"2026-02-25T19:25:32.448308Z","steps":["trace[1673266818] 'process raft request' (duration: 99.864006ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:35.990851Z","caller":"traceutil/trace.go:171","msg":"trace[913898244] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"591.132213ms","start":"2026-02-25T19:25:35.399695Z","end":"2026-02-25T19:25:35.990827Z","steps":["trace[913898244] 'process raft request' (duration: 590.917112ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:35.991093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:35.399669Z","time spent":"591.288684ms","remote":"127.0.0.1:56698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:25:36.63386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.57968ms","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-25T19:25:36.633928Z","caller":"traceutil/trace.go:171","msg":"trace[1264601765] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:517; }","duration":"172.69773ms","start":"2026-02-25T19:25:36.461214Z","end":"2026-02-25T19:25:36.633911Z","steps":["trace[1264601765] 'range keys from in-memory index tree' (duration: 172.379928ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:36.633921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.180642ms","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-25T19:25:36.634Z","caller":"traceutil/trace.go:171","msg":"trace[1129825083] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:517; }","duration":"272.307563ms","start":"2026-02-25T19:25:36.361673Z","end":"2026-02-25T19:25:36.633981Z","steps":["trace[1129825083] 'range keys from in-memory index tree' (duration: 272.012202ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:36.634136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.881212ms","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-25T19:25:36.634157Z","caller":"traceutil/trace.go:171","msg":"trace[1178264634] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:517; }","duration":"172.936672ms","start":"2026-02-25T19:25:36.461213Z","end":"2026-02-25T19:25:36.63415Z","steps":["trace[1178264634] 'range keys from in-memory index tree' (duration: 172.754421ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:36.748394Z","caller":"traceutil/trace.go:171","msg":"trace[973850366] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"110.561112ms","start":"2026-02-25T19:25:36.637807Z","end":"2026-02-25T19:25:36.748368Z","steps":["trace[973850366] 'process raft request' (duration: 110.368241ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:36.764624Z","caller":"traceutil/trace.go:171","msg":"trace[422731270] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"126.700291ms","start":"2026-02-25T19:25:36.637906Z","end":"2026-02-25T19:25:36.764606Z","steps":["trace[422731270] 'process raft request' (duration: 126.56621ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:36.764784Z","caller":"traceutil/trace.go:171","msg":"trace[519602747] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"126.213688ms","start":"2026-02-25T19:25:36.638548Z","end":"2026-02-25T19:25:36.764762Z","steps":["trace[519602747] 'process raft request' (duration: 126.022737ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:38.694185Z","caller":"traceutil/trace.go:171","msg":"trace[1210110048] linearizableReadLoop","detail":"{readStateIndex:540; appliedIndex:539; }","duration":"126.608969ms","start":"2026-02-25T19:25:38.567552Z","end":"2026-02-25T19:25:38.694161Z","steps":["trace[1210110048] 'read index received' (duration: 126.369428ms)","trace[1210110048] 'applied index is now lower than readState.Index' (duration: 238.491µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:25:38.694348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.775659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:25:38.694396Z","caller":"traceutil/trace.go:171","msg":"trace[1967372983] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:521; }","duration":"126.86783ms","start":"2026-02-25T19:25:38.567515Z","end":"2026-02-25T19:25:38.694383Z","steps":["trace[1967372983] 'agreement among raft nodes before linearized reading' (duration: 126.777049ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:38.694238Z","caller":"traceutil/trace.go:171","msg":"trace[896418591] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"417.978584ms","start":"2026-02-25T19:25:38.27624Z","end":"2026-02-25T19:25:38.694218Z","steps":["trace[896418591] 'process raft request' (duration: 417.778953ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:38.694623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:38.276214Z","time spent":"418.338976ms","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:25:40.680635Z","caller":"traceutil/trace.go:171","msg":"trace[469430740] linearizableReadLoop","detail":"{readStateIndex:551; appliedIndex:550; }","duration":"112.114508ms","start":"2026-02-25T19:25:40.568492Z","end":"2026-02-25T19:25:40.680607Z","steps":["trace[469430740] 'read index received' (duration: 111.839396ms)","trace[469430740] 'applied index is now lower than readState.Index' (duration: 273.712µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:25:40.680685Z","caller":"traceutil/trace.go:171","msg":"trace[1960000574] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"265.249461ms","start":"2026-02-25T19:25:40.4154Z","end":"2026-02-25T19:25:40.68065Z","steps":["trace[1960000574] 'process raft request' (duration: 265.03912ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:40.680874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.32844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:25:40.680982Z","caller":"traceutil/trace.go:171","msg":"trace[2104997024] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:531; }","duration":"112.553582ms","start":"2026-02-25T19:25:40.568399Z","end":"2026-02-25T19:25:40.680952Z","steps":["trace[2104997024] 'agreement among raft nodes before linearized reading' (duration: 112.330061ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:41.433917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.016651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-25T19:25:41.433987Z","caller":"traceutil/trace.go:171","msg":"trace[2007107179] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:537; }","duration":"398.093802ms","start":"2026-02-25T19:25:41.035877Z","end":"2026-02-25T19:25:41.433971Z","steps":["trace[2007107179] 'range keys from in-memory index tree' (duration: 397.91371ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:41.434019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:41.035861Z","time spent":"398.150822ms","remote":"127.0.0.1:56638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-02-25T19:25:41.540915Z","caller":"traceutil/trace.go:171","msg":"trace[148948068] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:557; }","duration":"102.689676ms","start":"2026-02-25T19:25:41.438204Z","end":"2026-02-25T19:25:41.540894Z","steps":["trace[148948068] 'read index received' (duration: 102.524106ms)","trace[148948068] 'applied index is now lower than readState.Index' (duration: 164.74µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:25:41.541008Z","caller":"traceutil/trace.go:171","msg":"trace[8902503] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"107.298312ms","start":"2026-02-25T19:25:41.433685Z","end":"2026-02-25T19:25:41.540984Z","steps":["trace[8902503] 'process raft request' (duration: 107.101001ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:41.541312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.332674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:25:41.541432Z","caller":"traceutil/trace.go:171","msg":"trace[360643611] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:538; }","duration":"100.573995ms","start":"2026-02-25T19:25:41.440832Z","end":"2026-02-25T19:25:41.541406Z","steps":["trace[360643611] 'agreement among raft nodes before linearized reading' (duration: 100.261503ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:41.54142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.870047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-jxlds\" ","response":"range_response_count:1 size:17431"} {"level":"info","ts":"2026-02-25T19:25:41.541489Z","caller":"traceutil/trace.go:171","msg":"trace[2000991572] range","detail":"{range_begin:/registry/pods/kube-system/cilium-jxlds; range_end:; response_count:1; response_revision:538; }","duration":"103.300189ms","start":"2026-02-25T19:25:41.438174Z","end":"2026-02-25T19:25:41.541474Z","steps":["trace[2000991572] 'agreement among raft nodes before linearized reading' (duration: 102.826607ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:41.735164Z","caller":"traceutil/trace.go:171","msg":"trace[1451487461] linearizableReadLoop","detail":"{readStateIndex:560; appliedIndex:559; }","duration":"165.7924ms","start":"2026-02-25T19:25:41.569345Z","end":"2026-02-25T19:25:41.735138Z","steps":["trace[1451487461] 'read index received' (duration: 140.14026ms)","trace[1451487461] 'applied index is now lower than readState.Index' (duration: 25.65097ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:25:41.735202Z","caller":"traceutil/trace.go:171","msg":"trace[250907619] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"180.322029ms","start":"2026-02-25T19:25:41.554855Z","end":"2026-02-25T19:25:41.735177Z","steps":["trace[250907619] 'process raft request' (duration: 154.70456ms)","trace[250907619] 'compare' (duration: 25.379268ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:25:41.735281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.91797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:25:41.735351Z","caller":"traceutil/trace.go:171","msg":"trace[1704222791] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:540; }","duration":"166.022532ms","start":"2026-02-25T19:25:41.569307Z","end":"2026-02-25T19:25:41.735329Z","steps":["trace[1704222791] 'agreement among raft nodes before linearized reading' (duration: 165.925861ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:41.780931Z","caller":"traceutil/trace.go:171","msg":"trace[1024075430] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"208.315311ms","start":"2026-02-25T19:25:41.572597Z","end":"2026-02-25T19:25:41.780913Z","steps":["trace[1024075430] 'process raft request' (duration: 208.161659ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:41.781124Z","caller":"traceutil/trace.go:171","msg":"trace[1683208610] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"211.176126ms","start":"2026-02-25T19:25:41.56994Z","end":"2026-02-25T19:25:41.781116Z","steps":["trace[1683208610] 'process raft request' (duration: 210.713483ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:42.376354Z","caller":"traceutil/trace.go:171","msg":"trace[1911537074] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"181.972757ms","start":"2026-02-25T19:25:42.194359Z","end":"2026-02-25T19:25:42.376332Z","steps":["trace[1911537074] 'process raft request' (duration: 181.831546ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:42.715162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.366247ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:25:42.715367Z","caller":"traceutil/trace.go:171","msg":"trace[1471377176] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"405.953621ms","start":"2026-02-25T19:25:42.309392Z","end":"2026-02-25T19:25:42.715346Z","steps":["trace[1471377176] 'process raft request' (duration: 405.868291ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:42.715458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:42.309378Z","time spent":"406.041502ms","remote":"127.0.0.1:56600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T19:25:42.715689Z","caller":"traceutil/trace.go:171","msg":"trace[659340886] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"425.642948ms","start":"2026-02-25T19:25:42.290015Z","end":"2026-02-25T19:25:42.715658Z","steps":["trace[659340886] 'process raft request' (duration: 130.82514ms)","trace[659340886] 'compare' (duration: 294.119695ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:25:42.715839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:42.289988Z","time spent":"425.772939ms","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:25:42.954266Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11228771940123579319,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:25:43.454968Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11228771940123579319,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T19:25:43.660833Z","caller":"traceutil/trace.go:171","msg":"trace[1721269362] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"1.264422524s","start":"2026-02-25T19:25:42.396389Z","end":"2026-02-25T19:25:43.660811Z","steps":["trace[1721269362] 'process raft request' (duration: 1.264383244s)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:43.660915Z","caller":"traceutil/trace.go:171","msg":"trace[1097328567] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"1.276941412s","start":"2026-02-25T19:25:42.383931Z","end":"2026-02-25T19:25:43.660872Z","steps":["trace[1097328567] 'process raft request' (duration: 1.276771051s)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:43.660928Z","caller":"traceutil/trace.go:171","msg":"trace[1682711758] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"1.278789762s","start":"2026-02-25T19:25:42.382116Z","end":"2026-02-25T19:25:43.660905Z","steps":["trace[1682711758] 'process raft request' (duration: 1.278441911s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:43.660992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:42.396371Z","time spent":"1.264554776s","remote":"127.0.0.1:56582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:25:43.661047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:42.382072Z","time spent":"1.278913673s","remote":"127.0.0.1:57002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":950,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:25:43.661059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:42.383903Z","time spent":"1.277080033s","remote":"127.0.0.1:56582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4059,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:25:43.717293Z","caller":"traceutil/trace.go:171","msg":"trace[276420162] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"1.122205383s","start":"2026-02-25T19:25:42.595057Z","end":"2026-02-25T19:25:43.717263Z","steps":["trace[276420162] 'process raft request' (duration: 1.121939271s)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:43.717363Z","caller":"traceutil/trace.go:171","msg":"trace[1078143313] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"523.044475ms","start":"2026-02-25T19:25:43.194284Z","end":"2026-02-25T19:25:43.717329Z","steps":["trace[1078143313] 'process raft request' (duration: 522.908733ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:43.717502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.264316853s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-jxlds\" ","response":"range_response_count:1 size:17524"} {"level":"info","ts":"2026-02-25T19:25:43.717561Z","caller":"traceutil/trace.go:171","msg":"trace[1879517547] range","detail":"{range_begin:/registry/pods/kube-system/cilium-jxlds; range_end:; response_count:1; response_revision:557; }","duration":"1.264410354s","start":"2026-02-25T19:25:42.453135Z","end":"2026-02-25T19:25:43.717546Z","steps":["trace[1879517547] 'agreement among raft nodes before linearized reading' (duration: 1.264226633s)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:43.717492Z","caller":"traceutil/trace.go:171","msg":"trace[264316772] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"922.287369ms","start":"2026-02-25T19:25:42.795189Z","end":"2026-02-25T19:25:43.717477Z","steps":["trace[264316772] 'process raft request' (duration: 921.930427ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:43.717593Z","caller":"traceutil/trace.go:171","msg":"trace[1189977246] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"322.76745ms","start":"2026-02-25T19:25:43.394815Z","end":"2026-02-25T19:25:43.717582Z","steps":["trace[1189977246] 'process raft request' (duration: 322.405487ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:43.717602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:42.453115Z","time spent":"1.264478684s","remote":"127.0.0.1:56698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17547,"request content":"key:\"/registry/pods/kube-system/cilium-jxlds\" "} {"level":"warn","ts":"2026-02-25T19:25:43.71764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:43.394789Z","time spent":"322.82965ms","remote":"127.0.0.1:56582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T19:25:43.717616Z","caller":"traceutil/trace.go:171","msg":"trace[1710497889] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"723.003499ms","start":"2026-02-25T19:25:42.994565Z","end":"2026-02-25T19:25:43.717569Z","steps":["trace[1710497889] 'process raft request' (duration: 722.603216ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:43.717718Z","caller":"traceutil/trace.go:171","msg":"trace[1944997786] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"119.593719ms","start":"2026-02-25T19:25:43.598118Z","end":"2026-02-25T19:25:43.717712Z","steps":["trace[1944997786] 'process raft request' (duration: 119.127425ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:43.717678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:42.79516Z","time spent":"922.43837ms","remote":"127.0.0.1:56582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:25:43.717786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:42.994545Z","time spent":"723.17318ms","remote":"127.0.0.1:56582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T19:25:43.717303Z","caller":"traceutil/trace.go:171","msg":"trace[100613191] linearizableReadLoop","detail":"{readStateIndex:576; appliedIndex:568; }","duration":"1.264091733s","start":"2026-02-25T19:25:42.453188Z","end":"2026-02-25T19:25:43.71728Z","steps":["trace[100613191] 'read index received' (duration: 1.207448265s)","trace[100613191] 'applied index is now lower than readState.Index' (duration: 56.642507ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:25:43.718075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:42.595028Z","time spent":"1.122362414s","remote":"127.0.0.1:56582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:25:43.717497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:43.194262Z","time spent":"523.163185ms","remote":"127.0.0.1:56582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:25:43.798921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.229952378s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:25:43.798986Z","caller":"traceutil/trace.go:171","msg":"trace[1738156869] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:559; }","duration":"1.230065788s","start":"2026-02-25T19:25:42.568907Z","end":"2026-02-25T19:25:43.798972Z","steps":["trace[1738156869] 'agreement among raft nodes before linearized reading' (duration: 1.229921757s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:43.799018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:42.568884Z","time spent":"1.230127008s","remote":"127.0.0.1:56520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T19:25:43.799307Z","caller":"traceutil/trace.go:171","msg":"trace[1741491609] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"130.732527ms","start":"2026-02-25T19:25:43.668565Z","end":"2026-02-25T19:25:43.799297Z","steps":["trace[1741491609] 'process raft request' (duration: 129.770532ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:43.799419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.09409486s","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-25T19:25:43.799437Z","caller":"traceutil/trace.go:171","msg":"trace[840258868] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:559; }","duration":"1.09415392s","start":"2026-02-25T19:25:42.705277Z","end":"2026-02-25T19:25:43.799431Z","steps":["trace[840258868] 'agreement among raft nodes before linearized reading' (duration: 1.09411727s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:43.799453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:42.705246Z","time spent":"1.09420262s","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T19:25:43.799661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"987.481132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-25T19:25:43.799806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"980.776985ms","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-25T19:25:43.799757Z","caller":"traceutil/trace.go:171","msg":"trace[1428751982] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:559; }","duration":"987.780463ms","start":"2026-02-25T19:25:42.811952Z","end":"2026-02-25T19:25:43.799732Z","steps":["trace[1428751982] 'agreement among raft nodes before linearized reading' (duration: 987.358962ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:43.799836Z","caller":"traceutil/trace.go:171","msg":"trace[1105284678] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:559; }","duration":"980.827376ms","start":"2026-02-25T19:25:42.819001Z","end":"2026-02-25T19:25:43.799829Z","steps":["trace[1105284678] 'agreement among raft nodes before linearized reading' (duration: 980.738376ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:43.799857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:42.818992Z","time spent":"980.860516ms","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T19:25:43.79992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"981.312159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-25T19:25:43.799908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:42.811927Z","time spent":"987.923534ms","remote":"127.0.0.1:56772","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-25T19:25:43.799976Z","caller":"traceutil/trace.go:171","msg":"trace[14613068] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"130.401247ms","start":"2026-02-25T19:25:43.669536Z","end":"2026-02-25T19:25:43.799937Z","steps":["trace[14613068] 'process raft request' (duration: 129.254681ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:43.799982Z","caller":"traceutil/trace.go:171","msg":"trace[290737088] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:559; }","duration":"981.40105ms","start":"2026-02-25T19:25:42.81856Z","end":"2026-02-25T19:25:43.799961Z","steps":["trace[290737088] 'agreement among raft nodes before linearized reading' (duration: 981.260818ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:43.800023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:42.818545Z","time spent":"981.46898ms","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T19:25:45.657094Z","caller":"traceutil/trace.go:171","msg":"trace[750411209] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"135.831285ms","start":"2026-02-25T19:25:45.521235Z","end":"2026-02-25T19:25:45.657066Z","steps":["trace[750411209] 'process raft request' (duration: 125.153567ms)","trace[750411209] 'compare' (duration: 10.481147ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:25:48.729595Z","caller":"traceutil/trace.go:171","msg":"trace[943762547] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"218.265448ms","start":"2026-02-25T19:25:48.511309Z","end":"2026-02-25T19:25:48.729574Z","steps":["trace[943762547] 'process raft request' (duration: 218.127148ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:49.686911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.102321ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:25:49.687052Z","caller":"traceutil/trace.go:171","msg":"trace[797169237] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:644; }","duration":"119.072933ms","start":"2026-02-25T19:25:49.567961Z","end":"2026-02-25T19:25:49.687034Z","steps":["trace[797169237] 'read index received' (duration: 50.5µs)","trace[797169237] 'applied index is now lower than readState.Index' (duration: 118.998183ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:25:49.68709Z","caller":"traceutil/trace.go:171","msg":"trace[2084509696] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"318.328099ms","start":"2026-02-25T19:25:49.36872Z","end":"2026-02-25T19:25:49.687048Z","steps":["trace[2084509696] 'process raft request' (duration: 53.023037ms)","trace[2084509696] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/masterleases/199.19.213.176; req_size:118; } (duration: 264.94725ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:25:49.687383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:49.368703Z","time spent":"318.48982ms","remote":"127.0.0.1:56564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:25:49.687131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.177623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:25:49.687639Z","caller":"traceutil/trace.go:171","msg":"trace[348296412] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:623; }","duration":"119.723737ms","start":"2026-02-25T19:25:49.567902Z","end":"2026-02-25T19:25:49.687626Z","steps":["trace[348296412] 'agreement among raft nodes before linearized reading' (duration: 119.177543ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:50.084462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.609189ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-02-25T19:25:50.084784Z","caller":"traceutil/trace.go:171","msg":"trace[757758700] transaction","detail":"{read_only:false; number_of_response:0; response_revision:624; }","duration":"335.591901ms","start":"2026-02-25T19:25:49.749159Z","end":"2026-02-25T19:25:50.084751Z","steps":["trace[757758700] 'process raft request' (duration: 233.6081ms)","trace[757758700] 'compare' (duration: 101.517369ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:25:50.084873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:49.749147Z","time spent":"335.693202ms","remote":"127.0.0.1:43010","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T19:25:50.085107Z","caller":"traceutil/trace.go:171","msg":"trace[814374324] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"333.776292ms","start":"2026-02-25T19:25:49.750943Z","end":"2026-02-25T19:25:50.08472Z","steps":["trace[814374324] 'process raft request' (duration: 333.652461ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:50.085227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:49.750913Z","time spent":"334.225185ms","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:25:50.212809Z","caller":"traceutil/trace.go:171","msg":"trace[1772611765] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"122.367492ms","start":"2026-02-25T19:25:50.090422Z","end":"2026-02-25T19:25:50.21279Z","steps":["trace[1772611765] 'process raft request' (duration: 122.331122ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:50.213029Z","caller":"traceutil/trace.go:171","msg":"trace[147022067] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:646; }","duration":"429.136218ms","start":"2026-02-25T19:25:49.783886Z","end":"2026-02-25T19:25:50.213022Z","steps":["trace[147022067] 'read index received' (duration: 199.032876ms)","trace[147022067] 'applied index is now lower than readState.Index' (duration: 230.102592ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:25:50.213167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.279038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:1 size:1887"} {"level":"info","ts":"2026-02-25T19:25:50.213192Z","caller":"traceutil/trace.go:171","msg":"trace[1551953705] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:1; response_revision:627; }","duration":"429.353939ms","start":"2026-02-25T19:25:49.783831Z","end":"2026-02-25T19:25:50.213185Z","steps":["trace[1551953705] 'agreement among raft nodes before linearized reading' (duration: 429.284898ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:50.213211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:49.783814Z","time spent":"429.392139ms","remote":"127.0.0.1:43010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":1910,"request content":"key:\"/registry/cilium.io/ciliumnodes/instance\" "} {"level":"warn","ts":"2026-02-25T19:25:50.213365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.702604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-25T19:25:50.213395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.530178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:1 size:1887"} {"level":"info","ts":"2026-02-25T19:25:50.213433Z","caller":"traceutil/trace.go:171","msg":"trace[1823176677] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:627; }","duration":"365.816855ms","start":"2026-02-25T19:25:49.847601Z","end":"2026-02-25T19:25:50.213418Z","steps":["trace[1823176677] 'agreement among raft nodes before linearized reading' (duration: 365.607814ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:50.213449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.90539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T19:25:50.213452Z","caller":"traceutil/trace.go:171","msg":"trace[252029965] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:1; response_revision:627; }","duration":"127.632519ms","start":"2026-02-25T19:25:50.085802Z","end":"2026-02-25T19:25:50.213435Z","steps":["trace[252029965] 'agreement among raft nodes before linearized reading' (duration: 127.545788ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:50.213465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.701158ms","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-25T19:25:50.213487Z","caller":"traceutil/trace.go:171","msg":"trace[91347541] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:627; }","duration":"314.9625ms","start":"2026-02-25T19:25:49.898516Z","end":"2026-02-25T19:25:50.213479Z","steps":["trace[91347541] 'agreement among raft nodes before linearized reading' (duration: 314.871019ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:50.213505Z","caller":"traceutil/trace.go:171","msg":"trace[51876642] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:627; }","duration":"362.762948ms","start":"2026-02-25T19:25:49.850728Z","end":"2026-02-25T19:25:50.213491Z","steps":["trace[51876642] 'agreement among raft nodes before linearized reading' (duration: 362.685008ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:50.213517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:49.898502Z","time spent":"315.00798ms","remote":"127.0.0.1:56772","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-25T19:25:50.213547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:49.850714Z","time spent":"362.824148ms","remote":"127.0.0.1:56772","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-25T19:25:50.21347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.570928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-25T19:25:50.213468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:49.847576Z","time spent":"365.886555ms","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T19:25:50.213365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.453756ms","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-25T19:25:50.213722Z","caller":"traceutil/trace.go:171","msg":"trace[1571826637] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:627; }","duration":"362.869899ms","start":"2026-02-25T19:25:49.850842Z","end":"2026-02-25T19:25:50.213712Z","steps":["trace[1571826637] 'agreement among raft nodes before linearized reading' (duration: 362.413427ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:50.213758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:49.850812Z","time spent":"362.937509ms","remote":"127.0.0.1:56772","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-25T19:25:50.213692Z","caller":"traceutil/trace.go:171","msg":"trace[629934707] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:627; }","duration":"362.848389ms","start":"2026-02-25T19:25:49.850793Z","end":"2026-02-25T19:25:50.213642Z","steps":["trace[629934707] 'agreement among raft nodes before linearized reading' (duration: 362.503527ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:50.213858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:49.850776Z","time spent":"363.067241ms","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T19:25:50.213057Z","caller":"traceutil/trace.go:171","msg":"trace[635604576] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"429.511629ms","start":"2026-02-25T19:25:49.78352Z","end":"2026-02-25T19:25:50.213031Z","steps":["trace[635604576] 'process raft request' (duration: 408.123213ms)","trace[635604576] 'compare' (duration: 21.018274ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:25:50.214008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:49.783495Z","time spent":"430.450454ms","remote":"127.0.0.1:43010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1872,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:25:52.836859Z","caller":"traceutil/trace.go:171","msg":"trace[1917590604] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"126.629792ms","start":"2026-02-25T19:25:52.710205Z","end":"2026-02-25T19:25:52.836835Z","steps":["trace[1917590604] 'process raft request' (duration: 125.171944ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:53.015603Z","caller":"traceutil/trace.go:171","msg":"trace[414678250] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"304.685913ms","start":"2026-02-25T19:25:52.710898Z","end":"2026-02-25T19:25:53.015584Z","steps":["trace[414678250] 'process raft request' (duration: 271.176262ms)","trace[414678250] 'compare' (duration: 33.339719ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:25:53.015696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:52.710887Z","time spent":"304.768873ms","remote":"127.0.0.1:42990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2260,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T19:25:53.015894Z","caller":"traceutil/trace.go:171","msg":"trace[449572793] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"292.967959ms","start":"2026-02-25T19:25:52.72292Z","end":"2026-02-25T19:25:53.015888Z","steps":["trace[449572793] 'process raft request' (duration: 292.610247ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:53.017028Z","caller":"traceutil/trace.go:171","msg":"trace[1702705274] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"166.748948ms","start":"2026-02-25T19:25:52.85027Z","end":"2026-02-25T19:25:53.017019Z","steps":["trace[1702705274] 'process raft request' (duration: 166.710698ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:53.017087Z","caller":"traceutil/trace.go:171","msg":"trace[144999084] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"172.40406ms","start":"2026-02-25T19:25:52.844674Z","end":"2026-02-25T19:25:53.017078Z","steps":["trace[144999084] 'process raft request' (duration: 172.266619ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:26:30.849017Z","caller":"traceutil/trace.go:171","msg":"trace[775810000] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"102.561637ms","start":"2026-02-25T19:26:30.746436Z","end":"2026-02-25T19:26:30.848998Z","steps":["trace[775810000] 'process raft request' (duration: 76.571459ms)","trace[775810000] 'compare' (duration: 25.845527ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:26:48.955093Z","caller":"traceutil/trace.go:171","msg":"trace[955927442] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"156.902904ms","start":"2026-02-25T19:26:48.79815Z","end":"2026-02-25T19:26:48.955052Z","steps":["trace[955927442] 'process raft request' (duration: 156.756674ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:26:48.956602Z","caller":"traceutil/trace.go:171","msg":"trace[1974139424] linearizableReadLoop","detail":"{readStateIndex:897; appliedIndex:895; }","duration":"108.700097ms","start":"2026-02-25T19:26:48.847876Z","end":"2026-02-25T19:26:48.956576Z","steps":["trace[1974139424] 'read index received' (duration: 107.035259ms)","trace[1974139424] 'applied index is now lower than readState.Index' (duration: 1.663708ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:26:48.956651Z","caller":"traceutil/trace.go:171","msg":"trace[1003855289] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"157.294346ms","start":"2026-02-25T19:26:48.799327Z","end":"2026-02-25T19:26:48.956622Z","steps":["trace[1003855289] 'process raft request' (duration: 157.112976ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:26:48.956762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.879229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12755"} {"level":"info","ts":"2026-02-25T19:26:48.956808Z","caller":"traceutil/trace.go:171","msg":"trace[1607924605] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:861; }","duration":"108.933319ms","start":"2026-02-25T19:26:48.84786Z","end":"2026-02-25T19:26:48.956794Z","steps":["trace[1607924605] 'agreement among raft nodes before linearized reading' (duration: 108.812909ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:26:49.521119Z","caller":"traceutil/trace.go:171","msg":"trace[962396347] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"101.170808ms","start":"2026-02-25T19:26:49.41992Z","end":"2026-02-25T19:26:49.521091Z","steps":["trace[962396347] 'process raft request' (duration: 51.159873ms)","trace[962396347] 'compare' (duration: 49.876775ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:26:53.13421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.81247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-25T19:26:53.134308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.288762ms","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-25T19:26:53.134415Z","caller":"traceutil/trace.go:171","msg":"trace[495337249] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:871; }","duration":"141.032791ms","start":"2026-02-25T19:26:52.993323Z","end":"2026-02-25T19:26:53.134356Z","steps":["trace[495337249] 'range keys from in-memory index tree' (duration: 140.610558ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:26:53.134438Z","caller":"traceutil/trace.go:171","msg":"trace[1288764265] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:871; }","duration":"162.483314ms","start":"2026-02-25T19:26:52.971931Z","end":"2026-02-25T19:26:53.134414Z","steps":["trace[1288764265] 'range keys from in-memory index tree' (duration: 162.018151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:26:53.134433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.252354ms","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-25T19:26:53.134541Z","caller":"traceutil/trace.go:171","msg":"trace[2087690734] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:871; }","duration":"166.461015ms","start":"2026-02-25T19:26:52.968051Z","end":"2026-02-25T19:26:53.134512Z","steps":["trace[2087690734] 'range keys from in-memory index tree' (duration: 166.083173ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:26:53.135134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.095981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T19:26:53.135192Z","caller":"traceutil/trace.go:171","msg":"trace[820336698] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:871; }","duration":"158.161641ms","start":"2026-02-25T19:26:52.977018Z","end":"2026-02-25T19:26:53.13518Z","steps":["trace[820336698] 'range keys from in-memory index tree' (duration: 157.94698ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:26:53.250052Z","caller":"traceutil/trace.go:171","msg":"trace[515465073] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"112.6337ms","start":"2026-02-25T19:26:53.137402Z","end":"2026-02-25T19:26:53.250035Z","steps":["trace[515465073] 'process raft request' (duration: 112.51762ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:26:53.304158Z","caller":"traceutil/trace.go:171","msg":"trace[28841196] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"165.195618ms","start":"2026-02-25T19:26:53.138934Z","end":"2026-02-25T19:26:53.304129Z","steps":["trace[28841196] 'process raft request' (duration: 165.139848ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:26:53.304193Z","caller":"traceutil/trace.go:171","msg":"trace[1719818436] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"165.302059ms","start":"2026-02-25T19:26:53.13885Z","end":"2026-02-25T19:26:53.304152Z","steps":["trace[1719818436] 'process raft request' (duration: 165.007808ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:26:53.304302Z","caller":"traceutil/trace.go:171","msg":"trace[1233583117] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"165.352009ms","start":"2026-02-25T19:26:53.138915Z","end":"2026-02-25T19:26:53.304267Z","steps":["trace[1233583117] 'process raft request' (duration: 165.102328ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:26:53.734439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.373426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:26:53.734528Z","caller":"traceutil/trace.go:171","msg":"trace[422849687] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:877; }","duration":"166.549997ms","start":"2026-02-25T19:26:53.567957Z","end":"2026-02-25T19:26:53.734507Z","steps":["trace[422849687] 'range keys from in-memory index tree' (duration: 166.305355ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:26:54.498929Z","caller":"traceutil/trace.go:171","msg":"trace[1929819955] linearizableReadLoop","detail":"{readStateIndex:916; appliedIndex:915; }","duration":"373.071045ms","start":"2026-02-25T19:26:54.125834Z","end":"2026-02-25T19:26:54.498905Z","steps":["trace[1929819955] 'read index received' (duration: 373.027484ms)","trace[1929819955] 'applied index is now lower than readState.Index' (duration: 42.701µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:26:54.499037Z","caller":"traceutil/trace.go:171","msg":"trace[2132734174] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"419.521891ms","start":"2026-02-25T19:26:54.079506Z","end":"2026-02-25T19:26:54.499028Z","steps":["trace[2132734174] 'process raft request' (duration: 419.26523ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:26:54.499121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:26:54.079476Z","time spent":"419.587591ms","remote":"127.0.0.1:56698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1475,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:26:54.49931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.469386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T19:26:54.499472Z","caller":"traceutil/trace.go:171","msg":"trace[701754755] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:878; }","duration":"373.688957ms","start":"2026-02-25T19:26:54.125763Z","end":"2026-02-25T19:26:54.499452Z","steps":["trace[701754755] 'agreement among raft nodes before linearized reading' (duration: 373.452486ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:26:54.499559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:26:54.125735Z","time spent":"373.802358ms","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":7,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"info","ts":"2026-02-25T19:26:54.501893Z","caller":"traceutil/trace.go:171","msg":"trace[2136141098] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"192.028681ms","start":"2026-02-25T19:26:54.309812Z","end":"2026-02-25T19:26:54.50184Z","steps":["trace[2136141098] 'process raft request' (duration: 191.81254ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:26:57.451841Z","caller":"traceutil/trace.go:171","msg":"trace[926690425] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"181.479995ms","start":"2026-02-25T19:26:57.270337Z","end":"2026-02-25T19:26:57.451817Z","steps":["trace[926690425] 'process raft request' (duration: 181.335625ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:26:57.546403Z","caller":"traceutil/trace.go:171","msg":"trace[1052445171] linearizableReadLoop","detail":"{readStateIndex:937; appliedIndex:936; }","duration":"230.781999ms","start":"2026-02-25T19:26:57.315582Z","end":"2026-02-25T19:26:57.546364Z","steps":["trace[1052445171] 'read index received' (duration: 136.586517ms)","trace[1052445171] 'applied index is now lower than readState.Index' (duration: 94.194152ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:26:57.546453Z","caller":"traceutil/trace.go:171","msg":"trace[495638595] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"275.861498ms","start":"2026-02-25T19:26:57.270548Z","end":"2026-02-25T19:26:57.546409Z","steps":["trace[495638595] 'process raft request' (duration: 271.824536ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:26:57.546649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.496746ms","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-25T19:26:57.54671Z","caller":"traceutil/trace.go:171","msg":"trace[803427186] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:898; }","duration":"228.595997ms","start":"2026-02-25T19:26:57.318102Z","end":"2026-02-25T19:26:57.546698Z","steps":["trace[803427186] 'agreement among raft nodes before linearized reading' (duration: 228.431855ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:26:57.546796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T19:26:57.546841Z","caller":"traceutil/trace.go:171","msg":"trace[1675847823] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:898; }","duration":"231.323752ms","start":"2026-02-25T19:26:57.315507Z","end":"2026-02-25T19:26:57.546831Z","steps":["trace[1675847823] 'agreement among raft nodes before linearized reading' (duration: 230.920639ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:26:57.546998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.605138ms","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-25T19:26:57.547075Z","caller":"traceutil/trace.go:171","msg":"trace[1557257905] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:898; }","duration":"228.819249ms","start":"2026-02-25T19:26:57.318236Z","end":"2026-02-25T19:26:57.547055Z","steps":["trace[1557257905] 'agreement among raft nodes before linearized reading' (duration: 228.373196ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:26:57.881523Z","caller":"traceutil/trace.go:171","msg":"trace[2071148449] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"129.788241ms","start":"2026-02-25T19:26:57.751703Z","end":"2026-02-25T19:26:57.881491Z","steps":["trace[2071148449] 'process raft request' (duration: 129.515039ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:26:59.538283Z","caller":"traceutil/trace.go:171","msg":"trace[1779734409] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"183.584175ms","start":"2026-02-25T19:26:59.354667Z","end":"2026-02-25T19:26:59.538251Z","steps":["trace[1779734409] 'process raft request' (duration: 88.64263ms)","trace[1779734409] 'compare' (duration: 94.787784ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:26:59.719635Z","caller":"traceutil/trace.go:171","msg":"trace[295125611] linearizableReadLoop","detail":"{readStateIndex:946; appliedIndex:945; }","duration":"177.123913ms","start":"2026-02-25T19:26:59.542467Z","end":"2026-02-25T19:26:59.719591Z","steps":["trace[295125611] 'read index received' (duration: 126.483373ms)","trace[295125611] 'applied index is now lower than readState.Index' (duration: 50.63958ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:26:59.719763Z","caller":"traceutil/trace.go:171","msg":"trace[695102709] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"177.619225ms","start":"2026-02-25T19:26:59.542128Z","end":"2026-02-25T19:26:59.719747Z","steps":["trace[695102709] 'process raft request' (duration: 126.934755ms)","trace[695102709] 'compare' (duration: 50.415928ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:26:59.720198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.719336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T19:26:59.720287Z","caller":"traceutil/trace.go:171","msg":"trace[1393812631] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:906; }","duration":"177.890277ms","start":"2026-02-25T19:26:59.542373Z","end":"2026-02-25T19:26:59.720264Z","steps":["trace[1393812631] 'agreement among raft nodes before linearized reading' (duration: 177.613935ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:26:59.719991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.08146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T19:26:59.720905Z","caller":"traceutil/trace.go:171","msg":"trace[1713436889] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:906; }","duration":"139.993125ms","start":"2026-02-25T19:26:59.580857Z","end":"2026-02-25T19:26:59.72085Z","steps":["trace[1713436889] 'agreement among raft nodes before linearized reading' (duration: 138.991421ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:00.043979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.108217ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:27:00.044272Z","caller":"traceutil/trace.go:171","msg":"trace[1883630849] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"317.482976ms","start":"2026-02-25T19:26:59.72675Z","end":"2026-02-25T19:27:00.044233Z","steps":["trace[1883630849] 'process raft request' (duration: 134.998707ms)","trace[1883630849] 'compare' (duration: 181.937636ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:27:00.044494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:26:59.726729Z","time spent":"317.622366ms","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:27:00.044674Z","caller":"traceutil/trace.go:171","msg":"trace[276083623] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"317.516137ms","start":"2026-02-25T19:26:59.727136Z","end":"2026-02-25T19:27:00.044652Z","steps":["trace[276083623] 'process raft request' (duration: 317.160504ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:00.044809Z","caller":"traceutil/trace.go:171","msg":"trace[1400145929] linearizableReadLoop","detail":"{readStateIndex:950; appliedIndex:947; }","duration":"146.228837ms","start":"2026-02-25T19:26:59.898551Z","end":"2026-02-25T19:27:00.04478Z","steps":["trace[1400145929] 'read index received' (duration: 145.134591ms)","trace[1400145929] 'applied index is now lower than readState.Index' (duration: 1.093126ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:27:00.044859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:26:59.727123Z","time spent":"317.659157ms","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:27:00.04539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.82581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c71693c6-d648-47e1-8630-3c3b81d98fc8\" ","response":"range_response_count:1 size:3884"} {"level":"info","ts":"2026-02-25T19:27:00.045447Z","caller":"traceutil/trace.go:171","msg":"trace[1267750329] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c71693c6-d648-47e1-8630-3c3b81d98fc8; range_end:; response_count:1; response_revision:910; }","duration":"146.93123ms","start":"2026-02-25T19:26:59.898501Z","end":"2026-02-25T19:27:00.045433Z","steps":["trace[1267750329] 'agreement among raft nodes before linearized reading' (duration: 146.380738ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:00.04506Z","caller":"traceutil/trace.go:171","msg":"trace[1441909447] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"228.471983ms","start":"2026-02-25T19:26:59.816559Z","end":"2026-02-25T19:27:00.045031Z","steps":["trace[1441909447] 'process raft request' (duration: 227.964421ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:00.369179Z","caller":"traceutil/trace.go:171","msg":"trace[1628034232] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"117.843627ms","start":"2026-02-25T19:27:00.25131Z","end":"2026-02-25T19:27:00.369154Z","steps":["trace[1628034232] 'process raft request' (duration: 66.202054ms)","trace[1628034232] 'compare' (duration: 51.511592ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:00.748612Z","caller":"traceutil/trace.go:171","msg":"trace[895933945] linearizableReadLoop","detail":"{readStateIndex:959; appliedIndex:958; }","duration":"180.218337ms","start":"2026-02-25T19:27:00.568356Z","end":"2026-02-25T19:27:00.748574Z","steps":["trace[895933945] 'read index received' (duration: 179.910426ms)","trace[895933945] 'applied index is now lower than readState.Index' (duration: 306.871µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:00.748658Z","caller":"traceutil/trace.go:171","msg":"trace[1819875630] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"222.686913ms","start":"2026-02-25T19:27:00.525936Z","end":"2026-02-25T19:27:00.748623Z","steps":["trace[1819875630] 'process raft request' (duration: 222.432782ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:00.74873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.362098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:27:00.748768Z","caller":"traceutil/trace.go:171","msg":"trace[1588486472] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:918; }","duration":"180.4515ms","start":"2026-02-25T19:27:00.568305Z","end":"2026-02-25T19:27:00.748757Z","steps":["trace[1588486472] 'agreement among raft nodes before linearized reading' (duration: 180.379229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:01.035178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.315888ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:27:01.035385Z","caller":"traceutil/trace.go:171","msg":"trace[546363991] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"280.686ms","start":"2026-02-25T19:27:00.754677Z","end":"2026-02-25T19:27:01.035363Z","steps":["trace[546363991] 'process raft request' (duration: 113.109642ms)","trace[546363991] 'compare' (duration: 167.045206ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:01.130997Z","caller":"traceutil/trace.go:171","msg":"trace[1633388928] linearizableReadLoop","detail":"{readStateIndex:961; appliedIndex:959; }","duration":"312.862672ms","start":"2026-02-25T19:27:00.81811Z","end":"2026-02-25T19:27:01.130973Z","steps":["trace[1633388928] 'read index received' (duration: 49.697245ms)","trace[1633388928] 'applied index is now lower than readState.Index' (duration: 263.164427ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:01.131111Z","caller":"traceutil/trace.go:171","msg":"trace[1559913874] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"365.955925ms","start":"2026-02-25T19:27:00.765111Z","end":"2026-02-25T19:27:01.131067Z","steps":["trace[1559913874] 'process raft request' (duration: 365.716233ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:01.131241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:00.765075Z","time spent":"366.080515ms","remote":"127.0.0.1:56698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:27:01.131243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.124304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-02-25T19:27:01.131308Z","caller":"traceutil/trace.go:171","msg":"trace[85543617] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:920; }","duration":"313.236904ms","start":"2026-02-25T19:27:00.818061Z","end":"2026-02-25T19:27:01.131298Z","steps":["trace[85543617] 'agreement among raft nodes before linearized reading' (duration: 313.038643ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:01.131347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:00.818039Z","time spent":"313.299614ms","remote":"127.0.0.1:56676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":1488,"request content":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" "} {"level":"warn","ts":"2026-02-25T19:27:01.131437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.170459ms","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-25T19:27:01.131565Z","caller":"traceutil/trace.go:171","msg":"trace[1859030886] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:920; }","duration":"235.36879ms","start":"2026-02-25T19:27:00.896171Z","end":"2026-02-25T19:27:01.13154Z","steps":["trace[1859030886] 'agreement among raft nodes before linearized reading' (duration: 234.996528ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:02.109194Z","caller":"traceutil/trace.go:171","msg":"trace[987825453] linearizableReadLoop","detail":"{readStateIndex:965; appliedIndex:964; }","duration":"297.47808ms","start":"2026-02-25T19:27:01.811689Z","end":"2026-02-25T19:27:02.109167Z","steps":["trace[987825453] 'read index received' (duration: 251.324684ms)","trace[987825453] 'applied index is now lower than readState.Index' (duration: 46.152266ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:27:02.10946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.411598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T19:27:02.109515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.824391ms","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-25T19:27:02.109567Z","caller":"traceutil/trace.go:171","msg":"trace[1470544298] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:924; }","duration":"257.571498ms","start":"2026-02-25T19:27:01.851972Z","end":"2026-02-25T19:27:02.109543Z","steps":["trace[1470544298] 'agreement among raft nodes before linearized reading' (duration: 257.372397ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:02.109571Z","caller":"traceutil/trace.go:171","msg":"trace[1726092801] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:924; }","duration":"297.904871ms","start":"2026-02-25T19:27:01.811649Z","end":"2026-02-25T19:27:02.109554Z","steps":["trace[1726092801] 'agreement among raft nodes before linearized reading' (duration: 297.62426ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:02.10925Z","caller":"traceutil/trace.go:171","msg":"trace[2132562999] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"346.703311ms","start":"2026-02-25T19:27:01.762501Z","end":"2026-02-25T19:27:02.109205Z","steps":["trace[2132562999] 'process raft request' (duration: 300.629005ms)","trace[2132562999] 'compare' (duration: 45.910545ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:27:02.110157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:01.762471Z","time spent":"347.522015ms","remote":"127.0.0.1:43030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1146,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:27:04.626469Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11228771940123580221,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T19:27:04.64832Z","caller":"traceutil/trace.go:171","msg":"trace[1409999759] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"558.950171ms","start":"2026-02-25T19:27:04.089344Z","end":"2026-02-25T19:27:04.648294Z","steps":["trace[1409999759] 'process raft request' (duration: 558.793281ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:04.648666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:04.089314Z","time spent":"559.250233ms","remote":"127.0.0.1:56688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5572,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:27:04.653981Z","caller":"traceutil/trace.go:171","msg":"trace[91261015] linearizableReadLoop","detail":"{readStateIndex:975; appliedIndex:972; }","duration":"528.260637ms","start":"2026-02-25T19:27:04.125696Z","end":"2026-02-25T19:27:04.653956Z","steps":["trace[91261015] 'read index received' (duration: 522.465437ms)","trace[91261015] 'applied index is now lower than readState.Index' (duration: 5.79403ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:27:04.654206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.506189ms","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-25T19:27:04.654237Z","caller":"traceutil/trace.go:171","msg":"trace[1797558315] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:933; }","duration":"528.58707ms","start":"2026-02-25T19:27:04.125641Z","end":"2026-02-25T19:27:04.654228Z","steps":["trace[1797558315] 'agreement among raft nodes before linearized reading' (duration: 528.427209ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:04.654248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.161035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2476"} {"level":"warn","ts":"2026-02-25T19:27:04.654262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:04.125619Z","time spent":"528.63739ms","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T19:27:04.654259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.626248ms","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-25T19:27:04.654286Z","caller":"traceutil/trace.go:171","msg":"trace[1518990702] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:933; }","duration":"110.242716ms","start":"2026-02-25T19:27:04.544033Z","end":"2026-02-25T19:27:04.654276Z","steps":["trace[1518990702] 'agreement among raft nodes before linearized reading' (duration: 110.128545ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:04.654311Z","caller":"traceutil/trace.go:171","msg":"trace[1061484171] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:933; }","duration":"507.723018ms","start":"2026-02-25T19:27:04.146575Z","end":"2026-02-25T19:27:04.654298Z","steps":["trace[1061484171] 'agreement among raft nodes before linearized reading' (duration: 507.575607ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:04.654346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:04.146558Z","time spent":"507.781948ms","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T19:27:04.654421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.620928ms","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-25T19:27:04.654469Z","caller":"traceutil/trace.go:171","msg":"trace[1118555575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:933; }","duration":"507.683348ms","start":"2026-02-25T19:27:04.146774Z","end":"2026-02-25T19:27:04.654458Z","steps":["trace[1118555575] 'agreement among raft nodes before linearized reading' (duration: 507.565647ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:04.654498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:04.146767Z","time spent":"507.722188ms","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T19:27:10.81553Z","caller":"traceutil/trace.go:171","msg":"trace[842824108] linearizableReadLoop","detail":"{readStateIndex:1000; appliedIndex:998; }","duration":"120.562141ms","start":"2026-02-25T19:27:10.694943Z","end":"2026-02-25T19:27:10.815505Z","steps":["trace[842824108] 'read index received' (duration: 20.358429ms)","trace[842824108] 'applied index is now lower than readState.Index' (duration: 100.202802ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:10.815751Z","caller":"traceutil/trace.go:171","msg":"trace[1035550027] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"128.010392ms","start":"2026-02-25T19:27:10.687719Z","end":"2026-02-25T19:27:10.815729Z","steps":["trace[1035550027] 'process raft request' (duration: 89.470956ms)","trace[1035550027] 'compare' (duration: 38.224924ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:27:10.816044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.920984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T19:27:10.816239Z","caller":"traceutil/trace.go:171","msg":"trace[229115874] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:957; }","duration":"121.313395ms","start":"2026-02-25T19:27:10.694913Z","end":"2026-02-25T19:27:10.816227Z","steps":["trace[229115874] 'agreement among raft nodes before linearized reading' (duration: 120.748613ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:11.349104Z","caller":"traceutil/trace.go:171","msg":"trace[1958348328] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"137.190108ms","start":"2026-02-25T19:27:11.21189Z","end":"2026-02-25T19:27:11.34908Z","steps":["trace[1958348328] 'process raft request' (duration: 136.980806ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:11.80096Z","caller":"traceutil/trace.go:171","msg":"trace[147108403] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:1002; }","duration":"233.068527ms","start":"2026-02-25T19:27:11.567858Z","end":"2026-02-25T19:27:11.800926Z","steps":["trace[147108403] 'read index received' (duration: 182.54691ms)","trace[147108403] 'applied index is now lower than readState.Index' (duration: 50.520027ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:11.801008Z","caller":"traceutil/trace.go:171","msg":"trace[891558591] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"272.078215ms","start":"2026-02-25T19:27:11.528908Z","end":"2026-02-25T19:27:11.800986Z","steps":["trace[891558591] 'process raft request' (duration: 221.491197ms)","trace[891558591] 'compare' (duration: 50.338787ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:27:11.801101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.228068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:27:11.801146Z","caller":"traceutil/trace.go:171","msg":"trace[2110669569] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:960; }","duration":"233.319919ms","start":"2026-02-25T19:27:11.567812Z","end":"2026-02-25T19:27:11.801131Z","steps":["trace[2110669569] 'agreement among raft nodes before linearized reading' (duration: 233.229789ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:12.695775Z","caller":"traceutil/trace.go:171","msg":"trace[1412350995] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"836.166588ms","start":"2026-02-25T19:27:11.859575Z","end":"2026-02-25T19:27:12.695741Z","steps":["trace[1412350995] 'process raft request' (duration: 835.990258ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:12.695974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:11.859548Z","time spent":"836.321609ms","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:27:12.880271Z","caller":"traceutil/trace.go:171","msg":"trace[283660190] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"105.820381ms","start":"2026-02-25T19:27:12.774425Z","end":"2026-02-25T19:27:12.880245Z","steps":["trace[283660190] 'process raft request' (duration: 105.52305ms)"],"step_count":1}