{"level":"warn","ts":"2026-05-08T02:51:09.09177Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-08T02:51:09.091856Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.142: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.142:2380","--initial-cluster=instance=https://199.19.213.142:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.142:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.142: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-05-08T02:51:09.091932Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-08T02:51:09.091942Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.142:2380"]} {"level":"info","ts":"2026-05-08T02:51:09.091959Z","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-05-08T02:51:09.092572Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.142:2379"]} {"level":"info","ts":"2026-05-08T02:51:09.09265Z","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.142:2380"],"listen-peer-urls":["https://199.19.213.142:2380"],"advertise-client-urls":["https://199.19.213.142:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.142:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.142: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-05-08T02:51:09.096943Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.080213ms"} {"level":"info","ts":"2026-05-08T02:51:09.109983Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"54fca804e66e40f4","cluster-id":"374317481b3c77b9"} {"level":"info","ts":"2026-05-08T02:51:09.110095Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54fca804e66e40f4 switched to configuration voters=()"} {"level":"info","ts":"2026-05-08T02:51:09.11013Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54fca804e66e40f4 became follower at term 0"} {"level":"info","ts":"2026-05-08T02:51:09.110154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 54fca804e66e40f4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-08T02:51:09.11017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54fca804e66e40f4 became follower at term 1"} {"level":"info","ts":"2026-05-08T02:51:09.110238Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54fca804e66e40f4 switched to configuration voters=(6123954332316352756)"} {"level":"warn","ts":"2026-05-08T02:51:09.122643Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-08T02:51:09.12603Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-08T02:51:09.128619Z","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-05-08T02:51:09.651644Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"54fca804e66e40f4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-08T02:51:09.652057Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"54fca804e66e40f4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-08T02:51:09.654806Z","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-05-08T02:51:09.654928Z","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-05-08T02:51:09.654954Z","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-05-08T02:51:09.660392Z","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-05-08T02:51:09.66066Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"54fca804e66e40f4","initial-advertise-peer-urls":["https://199.19.213.142:2380"],"listen-peer-urls":["https://199.19.213.142:2380"],"advertise-client-urls":["https://199.19.213.142:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.142:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-08T02:51:09.660738Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-08T02:51:09.660855Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.142:2380"} {"level":"info","ts":"2026-05-08T02:51:09.660868Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.142:2380"} {"level":"info","ts":"2026-05-08T02:51:09.661392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54fca804e66e40f4 switched to configuration voters=(6123954332316352756)"} {"level":"info","ts":"2026-05-08T02:51:09.661535Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"374317481b3c77b9","local-member-id":"54fca804e66e40f4","added-peer-id":"54fca804e66e40f4","added-peer-peer-urls":["https://199.19.213.142:2380"]} {"level":"info","ts":"2026-05-08T02:51:10.51143Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54fca804e66e40f4 is starting a new election at term 1"} {"level":"info","ts":"2026-05-08T02:51:10.511499Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54fca804e66e40f4 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-08T02:51:10.511518Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54fca804e66e40f4 received MsgPreVoteResp from 54fca804e66e40f4 at term 1"} {"level":"info","ts":"2026-05-08T02:51:10.511556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54fca804e66e40f4 became candidate at term 2"} {"level":"info","ts":"2026-05-08T02:51:10.511569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54fca804e66e40f4 received MsgVoteResp from 54fca804e66e40f4 at term 2"} {"level":"info","ts":"2026-05-08T02:51:10.51158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54fca804e66e40f4 became leader at term 2"} {"level":"info","ts":"2026-05-08T02:51:10.511593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 54fca804e66e40f4 elected leader 54fca804e66e40f4 at term 2"} {"level":"info","ts":"2026-05-08T02:51:10.527895Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"54fca804e66e40f4","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.142:2379]}","request-path":"/0/members/54fca804e66e40f4/attributes","cluster-id":"374317481b3c77b9","publish-timeout":"7s"} {"level":"info","ts":"2026-05-08T02:51:10.528178Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-08T02:51:10.528206Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-08T02:51:10.528286Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T02:51:10.52948Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"374317481b3c77b9","local-member-id":"54fca804e66e40f4","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T02:51:10.529607Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T02:51:10.529653Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T02:51:10.532506Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.142:2379"} {"level":"info","ts":"2026-05-08T02:51:10.532575Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-08T02:51:10.532685Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-08T02:51:10.53276Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-08T02:51:12.173684Z","caller":"traceutil/trace.go:171","msg":"trace[176452013] transaction","detail":"{read_only:false; response_revision:60; number_of_response:1; }","duration":"108.327942ms","start":"2026-05-08T02:51:12.065332Z","end":"2026-05-08T02:51:12.17366Z","steps":["trace[176452013] 'process raft request' (duration: 66.673567ms)","trace[176452013] 'compare' (duration: 41.150458ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:51:12.173737Z","caller":"traceutil/trace.go:171","msg":"trace[1707776876] transaction","detail":"{read_only:false; response_revision:61; number_of_response:1; }","duration":"106.543152ms","start":"2026-05-08T02:51:12.06717Z","end":"2026-05-08T02:51:12.173714Z","steps":["trace[1707776876] 'process raft request' (duration: 106.128768ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:51:13.787182Z","caller":"traceutil/trace.go:171","msg":"trace[925402935] transaction","detail":"{read_only:false; response_revision:174; number_of_response:1; }","duration":"287.05668ms","start":"2026-05-08T02:51:13.500094Z","end":"2026-05-08T02:51:13.787151Z","steps":["trace[925402935] 'process raft request' (duration: 242.881907ms)","trace[925402935] 'compare' (duration: 44.032498ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:51:14.290406Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4680539659187369897,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-08T02:51:14.750767Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.005172356s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-08T02:51:15.935093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000692169s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-08T02:51:15.935199Z","caller":"traceutil/trace.go:171","msg":"trace[1738361673] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000851674s","start":"2026-05-08T02:51:13.934328Z","end":"2026-05-08T02:51:15.93518Z","steps":["trace[1738361673] 'agreement among raft nodes before linearized reading' (duration: 2.0007248s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:15.935237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:13.934283Z","time spent":"2.000945758s","remote":"127.0.0.1:36424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/08 02:51:15 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-08T02:51:17.269039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999638982s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-08T02:51:17.269164Z","caller":"traceutil/trace.go:171","msg":"trace[1127851187] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999856798s","start":"2026-05-08T02:51:15.269281Z","end":"2026-05-08T02:51:17.269138Z","steps":["trace[1127851187] 'agreement among raft nodes before linearized reading' (duration: 1.999675892s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:17.269224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:15.269254Z","time spent":"1.999959491s","remote":"127.0.0.1:36424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/08 02:51:17 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-08T02:51:17.806787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.054090049s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-08T02:51:17.806902Z","caller":"traceutil/trace.go:171","msg":"trace[266313921] linearizableReadLoop","detail":"{readStateIndex:179; appliedIndex:178; }","duration":"4.017290034s","start":"2026-05-08T02:51:13.789591Z","end":"2026-05-08T02:51:17.806881Z","steps":["trace[266313921] 'read index received' (duration: 961.366636ms)","trace[266313921] 'applied index is now lower than readState.Index' (duration: 3.055921858s)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:51:17.806953Z","caller":"traceutil/trace.go:171","msg":"trace[889869521] transaction","detail":"{read_only:false; response_revision:175; number_of_response:1; }","duration":"4.185393598s","start":"2026-05-08T02:51:13.621524Z","end":"2026-05-08T02:51:17.806918Z","steps":["trace[889869521] 'process raft request' (duration: 1.12976661s)","trace[889869521] 'compare' (duration: 3.053429278s)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:51:17.807045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.017446898s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:pvc-protection-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T02:51:17.807069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:13.621493Z","time spent":"4.185528452s","remote":"127.0.0.1:36632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T02:51:17.807088Z","caller":"traceutil/trace.go:171","msg":"trace[970238361] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:pvc-protection-controller; range_end:; response_count:0; response_revision:175; }","duration":"4.0174964s","start":"2026-05-08T02:51:13.789577Z","end":"2026-05-08T02:51:17.807074Z","steps":["trace[970238361] 'agreement among raft nodes before linearized reading' (duration: 4.017392977s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:17.807133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:13.789526Z","time spent":"4.017596724s","remote":"127.0.0.1:36800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":0,"response size":27,"request content":"key:\"/registry/clusterrolebindings/system:controller:pvc-protection-controller\" "} {"level":"warn","ts":"2026-05-08T02:51:17.935315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999537935s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-08T02:51:17.935451Z","caller":"traceutil/trace.go:171","msg":"trace[2144163920] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999749601s","start":"2026-05-08T02:51:15.935665Z","end":"2026-05-08T02:51:17.935415Z","steps":["trace[2144163920] 'agreement among raft nodes before linearized reading' (duration: 1.999556665s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:17.935528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:15.935634Z","time spent":"1.999877525s","remote":"127.0.0.1:36414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/08 02:51:17 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-08T02:51:18.307044Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4680539659187369902,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-08T02:51:18.563099Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.592689196s","expected-duration":"1s"} {"level":"info","ts":"2026-05-08T02:51:18.577154Z","caller":"traceutil/trace.go:171","msg":"trace[1921082786] transaction","detail":"{read_only:false; response_revision:176; number_of_response:1; }","duration":"1.606992278s","start":"2026-05-08T02:51:16.970103Z","end":"2026-05-08T02:51:18.577096Z","steps":["trace[1921082786] 'process raft request' (duration: 1.593381289s)","trace[1921082786] 'compare' (duration: 13.434834ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:51:18.5775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:16.970067Z","time spent":"1.607194524s","remote":"127.0.0.1:36632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T02:51:18.577694Z","caller":"traceutil/trace.go:171","msg":"trace[1463038601] linearizableReadLoop","detail":"{readStateIndex:182; appliedIndex:179; }","duration":"770.724123ms","start":"2026-05-08T02:51:17.806938Z","end":"2026-05-08T02:51:18.577662Z","steps":["trace[1463038601] 'read index received' (duration: 756.567076ms)","trace[1463038601] 'applied index is now lower than readState.Index' (duration: 14.155867ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:51:18.577758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.81799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:51:18.577797Z","caller":"traceutil/trace.go:171","msg":"trace[690865309] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:178; }","duration":"808.892282ms","start":"2026-05-08T02:51:17.768887Z","end":"2026-05-08T02:51:18.577779Z","steps":["trace[690865309] 'agreement among raft nodes before linearized reading' (duration: 808.801279ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:18.577836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:17.768859Z","time spent":"808.971244ms","remote":"127.0.0.1:36424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-08T02:51:18.577804Z","caller":"traceutil/trace.go:171","msg":"trace[1618245170] transaction","detail":"{read_only:false; response_revision:177; number_of_response:1; }","duration":"1.278088807s","start":"2026-05-08T02:51:17.299652Z","end":"2026-05-08T02:51:18.577741Z","steps":["trace[1618245170] 'process raft request' (duration: 1.277777348s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:18.577849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.195936259s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T02:51:18.577868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.745329694s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:51:18.577893Z","caller":"traceutil/trace.go:171","msg":"trace[668504271] transaction","detail":"{read_only:false; response_revision:178; number_of_response:1; }","duration":"766.83362ms","start":"2026-05-08T02:51:17.811039Z","end":"2026-05-08T02:51:18.577873Z","steps":["trace[668504271] 'process raft request' (duration: 766.569291ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:51:18.577918Z","caller":"traceutil/trace.go:171","msg":"trace[1535018156] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:178; }","duration":"2.196057823s","start":"2026-05-08T02:51:16.381846Z","end":"2026-05-08T02:51:18.577904Z","steps":["trace[1535018156] 'agreement among raft nodes before linearized reading' (duration: 2.195949819s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:18.577967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:16.38182Z","time spent":"2.196137445s","remote":"127.0.0.1:36702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-08T02:51:18.577974Z","caller":"traceutil/trace.go:171","msg":"trace[284084183] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:178; }","duration":"2.745440177s","start":"2026-05-08T02:51:15.832485Z","end":"2026-05-08T02:51:18.577925Z","steps":["trace[284084183] 'agreement among raft nodes before linearized reading' (duration: 2.745277992s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:18.577978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:17.811012Z","time spent":"766.920852ms","remote":"127.0.0.1:36800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":760,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T02:51:18.577992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:17.299624Z","time spent":"1.278292253s","remote":"127.0.0.1:36632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T02:51:18.577988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.835502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T02:51:18.577919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.626256824s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T02:51:18.578038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:15.832456Z","time spent":"2.745556421s","remote":"127.0.0.1:36702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-08T02:51:18.578056Z","caller":"traceutil/trace.go:171","msg":"trace[342644313] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:178; }","duration":"4.626416929s","start":"2026-05-08T02:51:13.951629Z","end":"2026-05-08T02:51:18.578046Z","steps":["trace[342644313] 'agreement among raft nodes before linearized reading' (duration: 4.626267224s)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:51:18.578065Z","caller":"traceutil/trace.go:171","msg":"trace[1356971182] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:178; }","duration":"543.927125ms","start":"2026-05-08T02:51:18.034115Z","end":"2026-05-08T02:51:18.578042Z","steps":["trace[1356971182] 'agreement among raft nodes before linearized reading' (duration: 543.839172ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:18.578089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:13.951611Z","time spent":"4.626469881s","remote":"127.0.0.1:36702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-08T02:51:18.577864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.603651288s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T02:51:18.578121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:18.034094Z","time spent":"544.010068ms","remote":"127.0.0.1:36414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-08T02:51:18.578142Z","caller":"traceutil/trace.go:171","msg":"trace[957826300] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:178; }","duration":"3.60401306s","start":"2026-05-08T02:51:14.974115Z","end":"2026-05-08T02:51:18.578128Z","steps":["trace[957826300] 'agreement among raft nodes before linearized reading' (duration: 3.603672089s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:18.578186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:14.974087Z","time spent":"3.604082552s","remote":"127.0.0.1:36702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-08T02:51:18.5782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.028719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"info","ts":"2026-05-08T02:51:18.578224Z","caller":"traceutil/trace.go:171","msg":"trace[27446503] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:178; }","duration":"271.113331ms","start":"2026-05-08T02:51:18.307103Z","end":"2026-05-08T02:51:18.578216Z","steps":["trace[27446503] 'agreement among raft nodes before linearized reading' (duration: 271.048399ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:51:21.281347Z","caller":"traceutil/trace.go:171","msg":"trace[1104014916] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"314.956325ms","start":"2026-05-08T02:51:20.966359Z","end":"2026-05-08T02:51:21.281315Z","steps":["trace[1104014916] 'process raft request' (duration: 301.316758ms)","trace[1104014916] 'compare' (duration: 13.177013ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:51:21.28147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:20.966331Z","time spent":"315.085939ms","remote":"127.0.0.1:36646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":178,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T02:51:21.597644Z","caller":"traceutil/trace.go:171","msg":"trace[1192531849] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"108.015934ms","start":"2026-05-08T02:51:21.489606Z","end":"2026-05-08T02:51:21.597622Z","steps":["trace[1192531849] 'process raft request' (duration: 33.144294ms)","trace[1192531849] 'compare' (duration: 74.480678ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:51:21.598094Z","caller":"traceutil/trace.go:171","msg":"trace[1277705384] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"107.912779ms","start":"2026-05-08T02:51:21.490144Z","end":"2026-05-08T02:51:21.598057Z","steps":["trace[1277705384] 'process raft request' (duration: 107.287591ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:51:21.84978Z","caller":"traceutil/trace.go:171","msg":"trace[57142125] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"132.899636ms","start":"2026-05-08T02:51:21.716617Z","end":"2026-05-08T02:51:21.849517Z","steps":["trace[57142125] 'process raft request' (duration: 99.979899ms)","trace[57142125] 'compare' (duration: 32.764512ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:51:22.602138Z","caller":"traceutil/trace.go:171","msg":"trace[1472856628] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"117.906779ms","start":"2026-05-08T02:51:22.484208Z","end":"2026-05-08T02:51:22.602115Z","steps":["trace[1472856628] 'process raft request' (duration: 117.765384ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:51:47.355065Z","caller":"traceutil/trace.go:171","msg":"trace[1546699534] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"521.826575ms","start":"2026-05-08T02:51:46.833219Z","end":"2026-05-08T02:51:47.355045Z","steps":["trace[1546699534] 'process raft request' (duration: 521.694902ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:47.355326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:46.833207Z","time spent":"521.922688ms","remote":"127.0.0.1:36702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T02:51:47.379457Z","caller":"traceutil/trace.go:171","msg":"trace[1810450704] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"546.166862ms","start":"2026-05-08T02:51:46.833253Z","end":"2026-05-08T02:51:47.37942Z","steps":["trace[1810450704] 'process raft request' (duration: 545.963897ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:47.379745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.038777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:51:47.379782Z","caller":"traceutil/trace.go:171","msg":"trace[1814777967] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:525; }","duration":"446.125829ms","start":"2026-05-08T02:51:46.933645Z","end":"2026-05-08T02:51:47.379771Z","steps":["trace[1814777967] 'agreement among raft nodes before linearized reading' (duration: 446.012486ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:47.379809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:46.933622Z","time spent":"446.17939ms","remote":"127.0.0.1:36424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-08T02:51:47.380037Z","caller":"traceutil/trace.go:171","msg":"trace[1235748867] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"546.538771ms","start":"2026-05-08T02:51:46.833481Z","end":"2026-05-08T02:51:47.38002Z","steps":["trace[1235748867] 'process raft request' (duration: 545.866805ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:51:47.379628Z","caller":"traceutil/trace.go:171","msg":"trace[1543337558] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:543; }","duration":"445.919584ms","start":"2026-05-08T02:51:46.933694Z","end":"2026-05-08T02:51:47.379613Z","steps":["trace[1543337558] 'read index received' (duration: 421.27511ms)","trace[1543337558] 'applied index is now lower than readState.Index' (duration: 24.643564ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:51:47.380129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:46.833454Z","time spent":"546.624973ms","remote":"127.0.0.1:36702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:51:47.380243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.603963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:51:47.380378Z","caller":"traceutil/trace.go:171","msg":"trace[1715319949] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:525; }","duration":"332.770097ms","start":"2026-05-08T02:51:47.047562Z","end":"2026-05-08T02:51:47.380332Z","steps":["trace[1715319949] 'agreement among raft nodes before linearized reading' (duration: 332.615773ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:47.380423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:47.047544Z","time spent":"332.86779ms","remote":"127.0.0.1:36528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-05-08T02:51:47.380657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:46.833233Z","time spent":"546.49064ms","remote":"127.0.0.1:36702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T02:51:48.32531Z","caller":"traceutil/trace.go:171","msg":"trace[1932780405] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"124.488515ms","start":"2026-05-08T02:51:48.200792Z","end":"2026-05-08T02:51:48.32528Z","steps":["trace[1932780405] 'process raft request' (duration: 80.819809ms)","trace[1932780405] 'compare' (duration: 43.43895ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:51:48.348041Z","caller":"traceutil/trace.go:171","msg":"trace[1063511076] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"106.040211ms","start":"2026-05-08T02:51:48.241978Z","end":"2026-05-08T02:51:48.348019Z","steps":["trace[1063511076] 'process raft request' (duration: 105.969759ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:51:48.348064Z","caller":"traceutil/trace.go:171","msg":"trace[836139258] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"118.622816ms","start":"2026-05-08T02:51:48.229413Z","end":"2026-05-08T02:51:48.348036Z","steps":["trace[836139258] 'process raft request' (duration: 118.36934ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:48.544319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.920221ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T02:51:48.544573Z","caller":"traceutil/trace.go:171","msg":"trace[1494734470] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"168.695923ms","start":"2026-05-08T02:51:48.375842Z","end":"2026-05-08T02:51:48.544538Z","steps":["trace[1494734470] 'process raft request' (duration: 61.466884ms)","trace[1494734470] 'compare' (duration: 106.736647ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:51:48.546798Z","caller":"traceutil/trace.go:171","msg":"trace[691170814] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"134.589482ms","start":"2026-05-08T02:51:48.41215Z","end":"2026-05-08T02:51:48.546739Z","steps":["trace[691170814] 'process raft request' (duration: 132.367819ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:51:48.546965Z","caller":"traceutil/trace.go:171","msg":"trace[582478526] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"124.634628ms","start":"2026-05-08T02:51:48.422308Z","end":"2026-05-08T02:51:48.546943Z","steps":["trace[582478526] 'process raft request' (duration: 122.997659ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:51:48.73489Z","caller":"traceutil/trace.go:171","msg":"trace[1356774021] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"162.266642ms","start":"2026-05-08T02:51:48.572565Z","end":"2026-05-08T02:51:48.734832Z","steps":["trace[1356774021] 'process raft request' (duration: 74.815127ms)","trace[1356774021] 'compare' (duration: 86.935563ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:51:48.734961Z","caller":"traceutil/trace.go:171","msg":"trace[1111938575] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"124.295779ms","start":"2026-05-08T02:51:48.610631Z","end":"2026-05-08T02:51:48.734927Z","steps":["trace[1111938575] 'process raft request' (duration: 124.092584ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:51:48.734988Z","caller":"traceutil/trace.go:171","msg":"trace[423282298] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"100.05395ms","start":"2026-05-08T02:51:48.634894Z","end":"2026-05-08T02:51:48.734947Z","steps":["trace[423282298] 'process raft request' (duration: 99.941168ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:51:49.14527Z","caller":"traceutil/trace.go:171","msg":"trace[724621573] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"122.115614ms","start":"2026-05-08T02:51:49.023129Z","end":"2026-05-08T02:51:49.145245Z","steps":["trace[724621573] 'process raft request' (duration: 104.938023ms)","trace[724621573] 'compare' (duration: 16.964785ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:51:49.145652Z","caller":"traceutil/trace.go:171","msg":"trace[408648699] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:616; }","duration":"112.885849ms","start":"2026-05-08T02:51:49.032743Z","end":"2026-05-08T02:51:49.145629Z","steps":["trace[408648699] 'read index received' (duration: 29.881486ms)","trace[408648699] 'applied index is now lower than readState.Index' (duration: 83.003323ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:51:49.145726Z","caller":"traceutil/trace.go:171","msg":"trace[1394243331] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"114.519156ms","start":"2026-05-08T02:51:49.031182Z","end":"2026-05-08T02:51:49.145701Z","steps":["trace[1394243331] 'process raft request' (duration: 114.348662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:49.145861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.090283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:51:49.145956Z","caller":"traceutil/trace.go:171","msg":"trace[1957263789] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:596; }","duration":"113.218627ms","start":"2026-05-08T02:51:49.03272Z","end":"2026-05-08T02:51:49.145938Z","steps":["trace[1957263789] 'agreement among raft nodes before linearized reading' (duration: 113.008542ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:51:49.146152Z","caller":"traceutil/trace.go:171","msg":"trace[698839246] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"116.41812ms","start":"2026-05-08T02:51:49.029714Z","end":"2026-05-08T02:51:49.146132Z","steps":["trace[698839246] 'process raft request' (duration: 115.470547ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:49.153119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.263969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:51:49.153199Z","caller":"traceutil/trace.go:171","msg":"trace[699443792] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:598; }","duration":"120.367663ms","start":"2026-05-08T02:51:49.032811Z","end":"2026-05-08T02:51:49.153179Z","steps":["trace[699443792] 'agreement among raft nodes before linearized reading' (duration: 120.200359ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:49.153442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.915528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:51:49.153474Z","caller":"traceutil/trace.go:171","msg":"trace[1941816016] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:598; }","duration":"118.953509ms","start":"2026-05-08T02:51:49.034511Z","end":"2026-05-08T02:51:49.153464Z","steps":["trace[1941816016] 'agreement among raft nodes before linearized reading' (duration: 118.908538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:49.153523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.97655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:51:49.153573Z","caller":"traceutil/trace.go:171","msg":"trace[44998511] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:598; }","duration":"119.058991ms","start":"2026-05-08T02:51:49.034501Z","end":"2026-05-08T02:51:49.15356Z","steps":["trace[44998511] 'agreement among raft nodes before linearized reading' (duration: 118.957299ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:49.153597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.034924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:51:49.153633Z","caller":"traceutil/trace.go:171","msg":"trace[415677243] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:598; }","duration":"120.075866ms","start":"2026-05-08T02:51:49.033547Z","end":"2026-05-08T02:51:49.153622Z","steps":["trace[415677243] 'agreement among raft nodes before linearized reading' (duration: 120.029854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:49.153699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.728215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:51:49.153815Z","caller":"traceutil/trace.go:171","msg":"trace[166807114] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:598; }","duration":"115.845998ms","start":"2026-05-08T02:51:49.037915Z","end":"2026-05-08T02:51:49.153761Z","steps":["trace[166807114] 'agreement among raft nodes before linearized reading' (duration: 115.747716ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:49.153844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.149575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:51:49.153873Z","caller":"traceutil/trace.go:171","msg":"trace[382424242] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:598; }","duration":"119.194175ms","start":"2026-05-08T02:51:49.034667Z","end":"2026-05-08T02:51:49.153861Z","steps":["trace[382424242] 'agreement among raft nodes before linearized reading' (duration: 118.722463ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:49.153883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.761545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:51:49.15408Z","caller":"traceutil/trace.go:171","msg":"trace[1522824470] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:598; }","duration":"118.929809ms","start":"2026-05-08T02:51:49.035061Z","end":"2026-05-08T02:51:49.15399Z","steps":["trace[1522824470] 'agreement among raft nodes before linearized reading' (duration: 118.638203ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:51:49.602097Z","caller":"traceutil/trace.go:171","msg":"trace[336303943] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"122.243257ms","start":"2026-05-08T02:51:49.479832Z","end":"2026-05-08T02:51:49.602075Z","steps":["trace[336303943] 'process raft request' (duration: 87.492287ms)","trace[336303943] 'compare' (duration: 34.571926ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:51:50.160291Z","caller":"traceutil/trace.go:171","msg":"trace[468252300] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"243.544907ms","start":"2026-05-08T02:51:49.916723Z","end":"2026-05-08T02:51:50.160268Z","steps":["trace[468252300] 'read index received' (duration: 236.002623ms)","trace[468252300] 'applied index is now lower than readState.Index' (duration: 7.541494ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:51:50.160373Z","caller":"traceutil/trace.go:171","msg":"trace[725467127] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"249.749992ms","start":"2026-05-08T02:51:49.910573Z","end":"2026-05-08T02:51:50.160323Z","steps":["trace[725467127] 'process raft request' (duration: 242.028264ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:50.160502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.758102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xbackendtrafficpolicies.gateway.networking.x-k8s.io\" ","response":"range_response_count:1 size:23081"} {"level":"info","ts":"2026-05-08T02:51:50.16053Z","caller":"traceutil/trace.go:171","msg":"trace[1194024264] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/xbackendtrafficpolicies.gateway.networking.x-k8s.io; range_end:; response_count:1; response_revision:623; }","duration":"243.827954ms","start":"2026-05-08T02:51:49.916693Z","end":"2026-05-08T02:51:50.160521Z","steps":["trace[1194024264] 'agreement among raft nodes before linearized reading' (duration: 243.6757ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:50.168824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.000228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:51:50.168893Z","caller":"traceutil/trace.go:171","msg":"trace[1186923540] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:625; }","duration":"220.1215ms","start":"2026-05-08T02:51:49.948756Z","end":"2026-05-08T02:51:50.168878Z","steps":["trace[1186923540] 'agreement among raft nodes before linearized reading' (duration: 219.995068ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:51:50.169164Z","caller":"traceutil/trace.go:171","msg":"trace[399796117] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"250.754543ms","start":"2026-05-08T02:51:49.918401Z","end":"2026-05-08T02:51:50.169156Z","steps":["trace[399796117] 'process raft request' (duration: 250.18806ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:51:50.169252Z","caller":"traceutil/trace.go:171","msg":"trace[1696319736] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"145.752883ms","start":"2026-05-08T02:51:50.023493Z","end":"2026-05-08T02:51:50.169246Z","steps":["trace[1696319736] 'process raft request' (duration: 145.215871ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:51.36675Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4680539659187370777,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-08T02:51:51.867862Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4680539659187370777,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-08T02:51:51.945142Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.521015595s","expected-duration":"1s"} {"level":"info","ts":"2026-05-08T02:51:51.945702Z","caller":"traceutil/trace.go:171","msg":"trace[511978395] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"1.52166029s","start":"2026-05-08T02:51:50.424015Z","end":"2026-05-08T02:51:51.945675Z","steps":["trace[511978395] 'process raft request' (duration: 1.521333402s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:51.945999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:50.423995Z","time spent":"1.521832844s","remote":"127.0.0.1:36468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T02:51:51.971976Z","caller":"traceutil/trace.go:171","msg":"trace[287963689] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"1.147493802s","start":"2026-05-08T02:51:50.82445Z","end":"2026-05-08T02:51:51.971944Z","steps":["trace[287963689] 'process raft request' (duration: 1.147096822s)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:51:51.972005Z","caller":"traceutil/trace.go:171","msg":"trace[1651420076] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"1.348632993s","start":"2026-05-08T02:51:50.623219Z","end":"2026-05-08T02:51:51.971852Z","steps":["trace[1651420076] 'process raft request' (duration: 1.348080149s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:51.972138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:50.824432Z","time spent":"1.147620364s","remote":"127.0.0.1:36468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T02:51:51.97232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:50.623191Z","time spent":"1.349017582s","remote":"127.0.0.1:36468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T02:51:51.976006Z","caller":"traceutil/trace.go:171","msg":"trace[482292324] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"950.318646ms","start":"2026-05-08T02:51:51.025656Z","end":"2026-05-08T02:51:51.975975Z","steps":["trace[482292324] 'process raft request' (duration: 945.938825ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:51.97618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:51.025636Z","time spent":"950.435578ms","remote":"127.0.0.1:36468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T02:51:52.001334Z","caller":"traceutil/trace.go:171","msg":"trace[1105704710] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"544.608216ms","start":"2026-05-08T02:51:51.456697Z","end":"2026-05-08T02:51:52.001305Z","steps":["trace[1105704710] 'process raft request' (duration: 544.365231ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:51:52.001335Z","caller":"traceutil/trace.go:171","msg":"trace[188665729] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"715.890114ms","start":"2026-05-08T02:51:51.285415Z","end":"2026-05-08T02:51:52.001305Z","steps":["trace[188665729] 'process raft request' (duration: 690.501874ms)","trace[188665729] 'compare' (duration: 24.804817ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:51:52.001514Z","caller":"traceutil/trace.go:171","msg":"trace[1893354042] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:655; }","duration":"1.135432217s","start":"2026-05-08T02:51:50.865997Z","end":"2026-05-08T02:51:52.001429Z","steps":["trace[1893354042] 'read index received' (duration: 1.079448266s)","trace[1893354042] 'applied index is now lower than readState.Index' (duration: 55.978181ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:51:52.001659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.135662442s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-08T02:51:52.001687Z","caller":"traceutil/trace.go:171","msg":"trace[1845689060] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:638; }","duration":"1.135732273s","start":"2026-05-08T02:51:50.865947Z","end":"2026-05-08T02:51:52.001679Z","steps":["trace[1845689060] 'agreement among raft nodes before linearized reading' (duration: 1.1355925s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:52.001711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:50.865929Z","time spent":"1.135777615s","remote":"127.0.0.1:36702","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-05-08T02:51:52.00178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.049867933s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T02:51:52.001553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:51.285392Z","time spent":"716.091448ms","remote":"127.0.0.1:36468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T02:51:52.001441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:51.456672Z","time spent":"544.718728ms","remote":"127.0.0.1:36468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T02:51:52.001975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.302487ms","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-05-08T02:51:52.00197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.925308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-05-08T02:51:52.002044Z","caller":"traceutil/trace.go:171","msg":"trace[63925802] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:638; }","duration":"181.006279ms","start":"2026-05-08T02:51:51.821022Z","end":"2026-05-08T02:51:52.002028Z","steps":["trace[63925802] 'agreement among raft nodes before linearized reading' (duration: 180.826435ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:52.002067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.517653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-05-08T02:51:52.00209Z","caller":"traceutil/trace.go:171","msg":"trace[1358091980] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:638; }","duration":"380.574845ms","start":"2026-05-08T02:51:51.621506Z","end":"2026-05-08T02:51:52.002081Z","steps":["trace[1358091980] 'agreement among raft nodes before linearized reading' (duration: 380.511483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:52.002112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:51.621485Z","time spent":"380.621235ms","remote":"127.0.0.1:36468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5608,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"info","ts":"2026-05-08T02:51:52.001999Z","caller":"traceutil/trace.go:171","msg":"trace[1848948658] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:638; }","duration":"620.369159ms","start":"2026-05-08T02:51:51.381623Z","end":"2026-05-08T02:51:52.001993Z","steps":["trace[1848948658] 'agreement among raft nodes before linearized reading' (duration: 620.307827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:52.002082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.353237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-08T02:51:52.002157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:51.381599Z","time spent":"620.541652ms","remote":"127.0.0.1:36702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-08T02:51:52.002205Z","caller":"traceutil/trace.go:171","msg":"trace[1591094737] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:638; }","duration":"564.50818ms","start":"2026-05-08T02:51:51.437683Z","end":"2026-05-08T02:51:52.002192Z","steps":["trace[1591094737] 'agreement among raft nodes before linearized reading' (duration: 564.295645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:52.002232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:51.437664Z","time spent":"564.562302ms","remote":"127.0.0.1:36702","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-05-08T02:51:52.002254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.632141ms","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-05-08T02:51:52.002319Z","caller":"traceutil/trace.go:171","msg":"trace[962814051] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:638; }","duration":"852.713052ms","start":"2026-05-08T02:51:51.149579Z","end":"2026-05-08T02:51:52.002292Z","steps":["trace[962814051] 'agreement among raft nodes before linearized reading' (duration: 852.590059ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:52.002342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:51.149558Z","time spent":"852.777924ms","remote":"127.0.0.1:36702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-08T02:51:52.001823Z","caller":"traceutil/trace.go:171","msg":"trace[1218172919] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"1.049970155s","start":"2026-05-08T02:51:50.951841Z","end":"2026-05-08T02:51:52.001812Z","steps":["trace[1218172919] 'agreement among raft nodes before linearized reading' (duration: 1.049869873s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:52.002527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:50.951825Z","time spent":"1.050692481s","remote":"127.0.0.1:36424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-08T02:51:52.002479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.107439ms","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-05-08T02:51:52.002671Z","caller":"traceutil/trace.go:171","msg":"trace[895454600] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:638; }","duration":"560.330356ms","start":"2026-05-08T02:51:51.442324Z","end":"2026-05-08T02:51:52.002655Z","steps":["trace[895454600] 'agreement among raft nodes before linearized reading' (duration: 559.448446ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:52.002779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:51.442303Z","time spent":"560.465139ms","remote":"127.0.0.1:36702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-08T02:51:52.46128Z","caller":"traceutil/trace.go:171","msg":"trace[1048871215] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"320.592148ms","start":"2026-05-08T02:51:52.14064Z","end":"2026-05-08T02:51:52.461232Z","steps":["trace[1048871215] 'process raft request' (duration: 278.095685ms)","trace[1048871215] 'compare' (duration: 42.193336ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:51:52.462336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:52.140612Z","time spent":"321.604302ms","remote":"127.0.0.1:36468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":24229,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:51:54.537088Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.58231211s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-08T02:51:54.538369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.583528717s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T02:51:54.539263Z","caller":"traceutil/trace.go:171","msg":"trace[251494874] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:673; }","duration":"2.296884129s","start":"2026-05-08T02:51:52.242364Z","end":"2026-05-08T02:51:54.539248Z","steps":["trace[251494874] 'read index received' (duration: 176.746617ms)","trace[251494874] 'applied index is now lower than readState.Index' (duration: 2.120136322s)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:51:54.539322Z","caller":"traceutil/trace.go:171","msg":"trace[1129816476] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"2.363625913s","start":"2026-05-08T02:51:52.175689Z","end":"2026-05-08T02:51:54.539315Z","steps":["trace[1129816476] 'process raft request' (duration: 779.097984ms)","trace[1129816476] 'compare' (duration: 1.583371713s)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:51:54.539397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:52.175663Z","time spent":"2.363697424s","remote":"127.0.0.1:36468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188471,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T02:51:54.539532Z","caller":"traceutil/trace.go:171","msg":"trace[643863627] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"2.246815503s","start":"2026-05-08T02:51:52.29271Z","end":"2026-05-08T02:51:54.539525Z","steps":["trace[643863627] 'process raft request' (duration: 2.245705199s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:54.539577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:52.292647Z","time spent":"2.246910386s","remote":"127.0.0.1:36702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T02:51:54.539884Z","caller":"traceutil/trace.go:171","msg":"trace[290834099] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"1.522127586s","start":"2026-05-08T02:51:53.017749Z","end":"2026-05-08T02:51:54.539876Z","steps":["trace[290834099] 'process raft request' (duration: 1.520736636s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:54.539954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:53.017714Z","time spent":"1.522196508s","remote":"127.0.0.1:36702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T02:51:54.539356Z","caller":"traceutil/trace.go:171","msg":"trace[1897630644] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"2.064593362s","start":"2026-05-08T02:51:52.47474Z","end":"2026-05-08T02:51:54.539334Z","steps":["trace[1897630644] 'process raft request' (duration: 2.063724583s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:54.542959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:52.474709Z","time spent":"2.068181032s","remote":"127.0.0.1:36622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4559,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:51:54.563884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.321499248s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-05-08T02:51:54.563988Z","caller":"traceutil/trace.go:171","msg":"trace[298749230] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:654; }","duration":"2.321609351s","start":"2026-05-08T02:51:52.242359Z","end":"2026-05-08T02:51:54.563969Z","steps":["trace[298749230] 'agreement among raft nodes before linearized reading' (duration: 2.297305528s)","trace[298749230] 'range keys from in-memory index tree' (duration: 24.118308ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:51:54.564028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:52.242345Z","time spent":"2.321673382s","remote":"127.0.0.1:36468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4202,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-05-08T02:51:54.564284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.015761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T02:51:54.56444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.878957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:51:54.5645Z","caller":"traceutil/trace.go:171","msg":"trace[1225539663] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:654; }","duration":"584.98711ms","start":"2026-05-08T02:51:53.979499Z","end":"2026-05-08T02:51:54.564486Z","steps":["trace[1225539663] 'agreement among raft nodes before linearized reading' (duration: 584.865017ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:51:54.564409Z","caller":"traceutil/trace.go:171","msg":"trace[1465087237] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:654; }","duration":"434.167515ms","start":"2026-05-08T02:51:54.130217Z","end":"2026-05-08T02:51:54.564385Z","steps":["trace[1465087237] 'agreement among raft nodes before linearized reading' (duration: 433.94836ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:54.564539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:53.979484Z","time spent":"585.046331ms","remote":"127.0.0.1:36822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 "} {"level":"warn","ts":"2026-05-08T02:51:54.564595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.713554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T02:51:54.564462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.700047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:51:54.564689Z","caller":"traceutil/trace.go:171","msg":"trace[729638113] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:654; }","duration":"630.967782ms","start":"2026-05-08T02:51:53.933708Z","end":"2026-05-08T02:51:54.564676Z","steps":["trace[729638113] 'agreement among raft nodes before linearized reading' (duration: 630.711806ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:54.56472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.090135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T02:51:54.564733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:53.933682Z","time spent":"631.036134ms","remote":"127.0.0.1:36414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-08T02:51:54.564752Z","caller":"traceutil/trace.go:171","msg":"trace[555362568] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:654; }","duration":"435.130615ms","start":"2026-05-08T02:51:54.12961Z","end":"2026-05-08T02:51:54.564741Z","steps":["trace[555362568] 'agreement among raft nodes before linearized reading' (duration: 435.085124ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:54.564773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:54.129605Z","time spent":"435.163476ms","remote":"127.0.0.1:36846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-08T02:51:54.564699Z","caller":"traceutil/trace.go:171","msg":"trace[1830619859] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:654; }","duration":"126.900978ms","start":"2026-05-08T02:51:54.437754Z","end":"2026-05-08T02:51:54.564655Z","steps":["trace[1830619859] 'agreement among raft nodes before linearized reading' (duration: 126.696273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:54.564827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.124361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T02:51:54.564895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.651607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:51:54.564939Z","caller":"traceutil/trace.go:171","msg":"trace[780175808] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:654; }","duration":"461.721559ms","start":"2026-05-08T02:51:54.103204Z","end":"2026-05-08T02:51:54.564926Z","steps":["trace[780175808] 'agreement among raft nodes before linearized reading' (duration: 461.669937ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:54.564992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:54.103184Z","time spent":"461.798401ms","remote":"127.0.0.1:36830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 "} {"level":"info","ts":"2026-05-08T02:51:54.564968Z","caller":"traceutil/trace.go:171","msg":"trace[1428030355] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:654; }","duration":"148.247084ms","start":"2026-05-08T02:51:54.416635Z","end":"2026-05-08T02:51:54.564883Z","steps":["trace[1428030355] 'agreement among raft nodes before linearized reading' (duration: 148.002928ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:54.565125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.734435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:51:54.565161Z","caller":"traceutil/trace.go:171","msg":"trace[886766953] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:654; }","duration":"148.796095ms","start":"2026-05-08T02:51:54.416355Z","end":"2026-05-08T02:51:54.565151Z","steps":["trace[886766953] 'agreement among raft nodes before linearized reading' (duration: 148.742264ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:54.565163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.182133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:51:54.565198Z","caller":"traceutil/trace.go:171","msg":"trace[955850564] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:654; }","duration":"464.230803ms","start":"2026-05-08T02:51:54.100958Z","end":"2026-05-08T02:51:54.565189Z","steps":["trace[955850564] 'agreement among raft nodes before linearized reading' (duration: 464.177922ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:54.565267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:54.100949Z","time spent":"464.294876ms","remote":"127.0.0.1:36830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 "} {"level":"warn","ts":"2026-05-08T02:51:54.565381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.927529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T02:51:54.565388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.941257976s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"warn","ts":"2026-05-08T02:51:54.565401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.011752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:51:54.56541Z","caller":"traceutil/trace.go:171","msg":"trace[1149586514] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:654; }","duration":"148.99972ms","start":"2026-05-08T02:51:54.416403Z","end":"2026-05-08T02:51:54.565403Z","steps":["trace[1149586514] 'agreement among raft nodes before linearized reading' (duration: 148.920938ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:54.565521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.814914ms","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-05-08T02:51:54.565566Z","caller":"traceutil/trace.go:171","msg":"trace[1637227503] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:654; }","duration":"128.065363ms","start":"2026-05-08T02:51:54.437363Z","end":"2026-05-08T02:51:54.565429Z","steps":["trace[1637227503] 'agreement among raft nodes before linearized reading' (duration: 128.000271ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:54.565601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.336396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:51:54.565645Z","caller":"traceutil/trace.go:171","msg":"trace[2006616821] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:654; }","duration":"267.420698ms","start":"2026-05-08T02:51:54.298216Z","end":"2026-05-08T02:51:54.565636Z","steps":["trace[2006616821] 'agreement among raft nodes before linearized reading' (duration: 267.322236ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:54.565694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.632934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-08T02:51:54.565728Z","caller":"traceutil/trace.go:171","msg":"trace[2035763854] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:654; }","duration":"504.688456ms","start":"2026-05-08T02:51:54.061032Z","end":"2026-05-08T02:51:54.56572Z","steps":["trace[2035763854] 'agreement among raft nodes before linearized reading' (duration: 504.597914ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:54.565762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:54.06102Z","time spent":"504.734207ms","remote":"127.0.0.1:36702","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-05-08T02:51:54.565858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.321738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:51:54.565909Z","caller":"traceutil/trace.go:171","msg":"trace[463060434] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:654; }","duration":"237.38335ms","start":"2026-05-08T02:51:54.328518Z","end":"2026-05-08T02:51:54.565901Z","steps":["trace[463060434] 'agreement among raft nodes before linearized reading' (duration: 237.315497ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:54.565942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"944.239405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188486"} {"level":"info","ts":"2026-05-08T02:51:54.56598Z","caller":"traceutil/trace.go:171","msg":"trace[1074381263] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:654; }","duration":"944.296885ms","start":"2026-05-08T02:51:53.621675Z","end":"2026-05-08T02:51:54.565972Z","steps":["trace[1074381263] 'agreement among raft nodes before linearized reading' (duration: 944.223464ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:54.566007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:53.621664Z","time spent":"944.337677ms","remote":"127.0.0.1:36468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":1,"response size":188508,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-05-08T02:51:54.566083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.830839ms","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-05-08T02:51:54.566095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.145079988s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-05-08T02:51:54.56612Z","caller":"traceutil/trace.go:171","msg":"trace[833742193] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:654; }","duration":"539.913541ms","start":"2026-05-08T02:51:54.026196Z","end":"2026-05-08T02:51:54.56611Z","steps":["trace[833742193] 'agreement among raft nodes before linearized reading' (duration: 539.799118ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:54.566158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"962.599446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:51:54.566186Z","caller":"traceutil/trace.go:171","msg":"trace[404926370] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:654; }","duration":"962.656418ms","start":"2026-05-08T02:51:53.603523Z","end":"2026-05-08T02:51:54.566179Z","steps":["trace[404926370] 'agreement among raft nodes before linearized reading' (duration: 962.614657ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:54.566207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:53.603482Z","time spent":"962.72089ms","remote":"127.0.0.1:36424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-08T02:51:54.56622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.762996ms","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-05-08T02:51:54.566207Z","caller":"traceutil/trace.go:171","msg":"trace[1908194619] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:654; }","duration":"2.145160369s","start":"2026-05-08T02:51:52.420964Z","end":"2026-05-08T02:51:54.566125Z","steps":["trace[1908194619] 'agreement among raft nodes before linearized reading' (duration: 2.144878212s)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:51:54.566253Z","caller":"traceutil/trace.go:171","msg":"trace[1266990921] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:654; }","duration":"543.815107ms","start":"2026-05-08T02:51:54.022431Z","end":"2026-05-08T02:51:54.566246Z","steps":["trace[1266990921] 'agreement among raft nodes before linearized reading' (duration: 543.741066ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:54.566283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:54.02242Z","time spent":"543.857248ms","remote":"127.0.0.1:36702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-08T02:51:54.566277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:52.420945Z","time spent":"2.145321133s","remote":"127.0.0.1:36468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10399,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-05-08T02:51:54.566357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.865121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:51:54.566409Z","caller":"traceutil/trace.go:171","msg":"trace[974172964] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:654; }","duration":"237.942302ms","start":"2026-05-08T02:51:54.328459Z","end":"2026-05-08T02:51:54.566401Z","steps":["trace[974172964] 'agreement among raft nodes before linearized reading' (duration: 237.779168ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:54.566439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.478544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:51:54.566469Z","caller":"traceutil/trace.go:171","msg":"trace[1734135060] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:654; }","duration":"586.530124ms","start":"2026-05-08T02:51:53.979932Z","end":"2026-05-08T02:51:54.566462Z","steps":["trace[1734135060] 'agreement among raft nodes before linearized reading' (duration: 586.484563ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:54.566495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:53.979909Z","time spent":"586.579506ms","remote":"127.0.0.1:36822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 "} {"level":"warn","ts":"2026-05-08T02:51:54.56662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.044582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:51:54.566673Z","caller":"traceutil/trace.go:171","msg":"trace[685820467] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:654; }","duration":"268.111775ms","start":"2026-05-08T02:51:54.298554Z","end":"2026-05-08T02:51:54.566666Z","steps":["trace[685820467] 'agreement among raft nodes before linearized reading' (duration: 268.042243ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:54.56667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.642235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:51:54.566727Z","caller":"traceutil/trace.go:171","msg":"trace[217024588] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:654; }","duration":"180.718148ms","start":"2026-05-08T02:51:54.385998Z","end":"2026-05-08T02:51:54.566716Z","steps":["trace[217024588] 'agreement among raft nodes before linearized reading' (duration: 180.628796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:54.56684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.563684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:51:54.566889Z","caller":"traceutil/trace.go:171","msg":"trace[1612792109] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:654; }","duration":"268.647136ms","start":"2026-05-08T02:51:54.298234Z","end":"2026-05-08T02:51:54.566882Z","steps":["trace[1612792109] 'agreement among raft nodes before linearized reading' (duration: 268.577665ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:51:54.565532Z","caller":"traceutil/trace.go:171","msg":"trace[505686072] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:654; }","duration":"1.941356978s","start":"2026-05-08T02:51:52.624065Z","end":"2026-05-08T02:51:54.565422Z","steps":["trace[505686072] 'agreement among raft nodes before linearized reading' (duration: 1.941026081s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:54.565397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.419048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T02:51:54.565879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.144318151s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:187261"} {"level":"info","ts":"2026-05-08T02:51:54.567084Z","caller":"traceutil/trace.go:171","msg":"trace[1647636613] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:654; }","duration":"1.145521048s","start":"2026-05-08T02:51:53.421552Z","end":"2026-05-08T02:51:54.567073Z","steps":["trace[1647636613] 'agreement among raft nodes before linearized reading' (duration: 1.144208478s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:54.567135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:53.421493Z","time spent":"1.14563258s","remote":"127.0.0.1:36468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":1,"response size":187283,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-05-08T02:51:54.567296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.577887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T02:51:54.566167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.626125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T02:51:54.566155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:54.026187Z","time spent":"539.960251ms","remote":"127.0.0.1:36702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-08T02:51:54.565976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.286898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-08T02:51:54.565581Z","caller":"traceutil/trace.go:171","msg":"trace[564064812] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:654; }","duration":"498.890687ms","start":"2026-05-08T02:51:54.066678Z","end":"2026-05-08T02:51:54.565568Z","steps":["trace[564064812] 'agreement among raft nodes before linearized reading' (duration: 498.739153ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:54.565543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.181096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T02:51:54.564858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.257088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T02:51:54.566996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:52.624048Z","time spent":"1.942936975s","remote":"127.0.0.1:36468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":13251,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-05-08T02:51:54.567655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.506941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:51:54.56769Z","caller":"traceutil/trace.go:171","msg":"trace[1257305223] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:654; }","duration":"588.013077ms","start":"2026-05-08T02:51:53.979667Z","end":"2026-05-08T02:51:54.56768Z","steps":["trace[1257305223] 'agreement among raft nodes before linearized reading' (duration: 587.605198ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:54.567764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:53.97966Z","time spent":"588.094199ms","remote":"127.0.0.1:36822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-05-08T02:51:54.567765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.101393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:51:54.567838Z","caller":"traceutil/trace.go:171","msg":"trace[1885811885] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:654; }","duration":"421.186524ms","start":"2026-05-08T02:51:54.146644Z","end":"2026-05-08T02:51:54.567831Z","steps":["trace[1885811885] 'agreement among raft nodes before linearized reading' (duration: 421.098042ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:54.567838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.545841785s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"warn","ts":"2026-05-08T02:51:54.567877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:54.146639Z","time spent":"421.226125ms","remote":"127.0.0.1:36850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 "} {"level":"info","ts":"2026-05-08T02:51:54.567884Z","caller":"traceutil/trace.go:171","msg":"trace[1158447759] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:654; }","duration":"1.545994238s","start":"2026-05-08T02:51:53.021873Z","end":"2026-05-08T02:51:54.567867Z","steps":["trace[1158447759] 'agreement among raft nodes before linearized reading' (duration: 1.545810334s)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:51:54.567912Z","caller":"traceutil/trace.go:171","msg":"trace[1442881595] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:654; }","duration":"239.402264ms","start":"2026-05-08T02:51:54.328501Z","end":"2026-05-08T02:51:54.567903Z","steps":["trace[1442881595] 'agreement among raft nodes before linearized reading' (duration: 237.589134ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:54.567942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:53.021857Z","time spent":"1.546054039s","remote":"127.0.0.1:36468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9342,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-05-08T02:51:54.567884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:54.066667Z","time spent":"501.206818ms","remote":"127.0.0.1:36702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-08T02:51:54.568075Z","caller":"traceutil/trace.go:171","msg":"trace[192630288] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:654; }","duration":"520.403585ms","start":"2026-05-08T02:51:54.047663Z","end":"2026-05-08T02:51:54.568067Z","steps":["trace[192630288] 'agreement among raft nodes before linearized reading' (duration: 518.239388ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:54.568107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:54.047653Z","time spent":"520.446526ms","remote":"127.0.0.1:36702","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-05-08T02:51:54.567384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.364843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:51:54.568143Z","caller":"traceutil/trace.go:171","msg":"trace[1057772958] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:654; }","duration":"323.15364ms","start":"2026-05-08T02:51:54.244979Z","end":"2026-05-08T02:51:54.568133Z","steps":["trace[1057772958] 'agreement among raft nodes before linearized reading' (duration: 322.375403ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:54.568181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:54.244961Z","time spent":"323.210392ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-08T02:51:54.568292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.438362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:51:54.568009Z","caller":"traceutil/trace.go:171","msg":"trace[822774124] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:654; }","duration":"130.707682ms","start":"2026-05-08T02:51:54.437285Z","end":"2026-05-08T02:51:54.567993Z","steps":["trace[822774124] 'agreement among raft nodes before linearized reading' (duration: 128.197646ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:51:54.568351Z","caller":"traceutil/trace.go:171","msg":"trace[320281384] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:654; }","duration":"422.468733ms","start":"2026-05-08T02:51:54.145845Z","end":"2026-05-08T02:51:54.568314Z","steps":["trace[320281384] 'agreement among raft nodes before linearized reading' (duration: 422.423992ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:54.567425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.434712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:51:54.568427Z","caller":"traceutil/trace.go:171","msg":"trace[1653661076] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:654; }","duration":"322.455335ms","start":"2026-05-08T02:51:54.245956Z","end":"2026-05-08T02:51:54.568411Z","steps":["trace[1653661076] 'agreement among raft nodes before linearized reading' (duration: 321.450843ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:54.568485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:54.245945Z","time spent":"322.521846ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 "} {"level":"info","ts":"2026-05-08T02:51:54.568545Z","caller":"traceutil/trace.go:171","msg":"trace[863150859] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:654; }","duration":"438.94252ms","start":"2026-05-08T02:51:54.129595Z","end":"2026-05-08T02:51:54.568537Z","steps":["trace[863150859] 'agreement among raft nodes before linearized reading' (duration: 435.247598ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:54.568578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:54.129554Z","time spent":"439.014321ms","remote":"127.0.0.1:36846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-05-08T02:51:54.567475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.344682493s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-05-08T02:51:54.567042Z","caller":"traceutil/trace.go:171","msg":"trace[342409542] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:654; }","duration":"466.080816ms","start":"2026-05-08T02:51:54.100948Z","end":"2026-05-08T02:51:54.567028Z","steps":["trace[342409542] 'agreement among raft nodes before linearized reading' (duration: 464.425708ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:54.568667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:54.100936Z","time spent":"467.722351ms","remote":"127.0.0.1:36830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"info","ts":"2026-05-08T02:51:54.568672Z","caller":"traceutil/trace.go:171","msg":"trace[1242337986] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:654; }","duration":"1.34591719s","start":"2026-05-08T02:51:53.222737Z","end":"2026-05-08T02:51:54.568654Z","steps":["trace[1242337986] 'agreement among raft nodes before linearized reading' (duration: 1.344640882s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:54.56872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:53.222712Z","time spent":"1.345989973s","remote":"127.0.0.1:36468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":24266,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-05-08T02:51:54.568388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:54.145813Z","time spent":"422.567164ms","remote":"127.0.0.1:36850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 "} {"level":"info","ts":"2026-05-08T02:51:54.567721Z","caller":"traceutil/trace.go:171","msg":"trace[1101082422] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:654; }","duration":"421.768058ms","start":"2026-05-08T02:51:54.14594Z","end":"2026-05-08T02:51:54.567708Z","steps":["trace[1101082422] 'agreement among raft nodes before linearized reading' (duration: 421.681176ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:54.569009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:54.145932Z","time spent":"423.059817ms","remote":"127.0.0.1:36850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-05-08T02:51:54.567461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.354573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:51:54.569396Z","caller":"traceutil/trace.go:171","msg":"trace[1842346551] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:654; }","duration":"324.327847ms","start":"2026-05-08T02:51:54.245056Z","end":"2026-05-08T02:51:54.569384Z","steps":["trace[1842346551] 'agreement among raft nodes before linearized reading' (duration: 322.384714ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:54.569464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:54.245036Z","time spent":"324.416529ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 "} {"level":"warn","ts":"2026-05-08T02:51:54.572949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.962056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:51:54.573004Z","caller":"traceutil/trace.go:171","msg":"trace[316357900] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:654; }","duration":"187.053158ms","start":"2026-05-08T02:51:54.385944Z","end":"2026-05-08T02:51:54.572997Z","steps":["trace[316357900] 'agreement among raft nodes before linearized reading' (duration: 186.978156ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:54.575115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.754462817s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-05-08T02:51:54.575168Z","caller":"traceutil/trace.go:171","msg":"trace[1449596720] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:654; }","duration":"1.754553259s","start":"2026-05-08T02:51:52.820601Z","end":"2026-05-08T02:51:54.575155Z","steps":["trace[1449596720] 'agreement among raft nodes before linearized reading' (duration: 1.754420877s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:51:54.575197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:52.82058Z","time spent":"1.754610731s","remote":"127.0.0.1:36468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4105,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-05-08T02:51:54.575367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:51:54.130211Z","time spent":"434.338988ms","remote":"127.0.0.1:36846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-05-08T02:52:01.142648Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4680539659187370969,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-08T02:52:01.643113Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4680539659187370969,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-08T02:52:01.752712Z","caller":"traceutil/trace.go:171","msg":"trace[1681440708] linearizableReadLoop","detail":"{readStateIndex:727; appliedIndex:726; }","duration":"1.110562886s","start":"2026-05-08T02:52:00.642122Z","end":"2026-05-08T02:52:01.752685Z","steps":["trace[1681440708] 'read index received' (duration: 1.1102966s)","trace[1681440708] 'applied index is now lower than readState.Index' (duration: 265.106µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:52:01.752809Z","caller":"traceutil/trace.go:171","msg":"trace[1330773310] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"1.210442572s","start":"2026-05-08T02:52:00.542347Z","end":"2026-05-08T02:52:01.75279Z","steps":["trace[1330773310] 'process raft request' (duration: 1.210164906s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:52:01.752957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.064894109s","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-05-08T02:52:01.752966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.110842232s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-08T02:52:01.753Z","caller":"traceutil/trace.go:171","msg":"trace[909582] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:701; }","duration":"1.065010512s","start":"2026-05-08T02:52:00.687978Z","end":"2026-05-08T02:52:01.752988Z","steps":["trace[909582] 'agreement among raft nodes before linearized reading' (duration: 1.064854869s)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:52:01.753033Z","caller":"traceutil/trace.go:171","msg":"trace[607196574] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:701; }","duration":"1.110919213s","start":"2026-05-08T02:52:00.642076Z","end":"2026-05-08T02:52:01.752996Z","steps":["trace[607196574] 'agreement among raft nodes before linearized reading' (duration: 1.11077783s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:52:01.753039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:52:00.687955Z","time spent":"1.065075993s","remote":"127.0.0.1:36702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-08T02:52:01.753009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.715121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T02:52:01.753046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.062992329s","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-05-08T02:52:01.75307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:52:00.642059Z","time spent":"1.111002585s","remote":"127.0.0.1:36702","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-05-08T02:52:01.753092Z","caller":"traceutil/trace.go:171","msg":"trace[1626260918] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:701; }","duration":"1.06305128s","start":"2026-05-08T02:52:00.69003Z","end":"2026-05-08T02:52:01.753081Z","steps":["trace[1626260918] 'agreement among raft nodes before linearized reading' (duration: 1.062921277s)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:52:01.753095Z","caller":"traceutil/trace.go:171","msg":"trace[202529424] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:701; }","duration":"815.835593ms","start":"2026-05-08T02:52:00.93724Z","end":"2026-05-08T02:52:01.753075Z","steps":["trace[202529424] 'agreement among raft nodes before linearized reading' (duration: 815.70656ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:52:01.753132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:52:00.690023Z","time spent":"1.063101421s","remote":"127.0.0.1:36702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-08T02:52:01.753143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:52:00.937225Z","time spent":"815.910975ms","remote":"127.0.0.1:36414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-08T02:52:01.753113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.054992989s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-08T02:52:01.753316Z","caller":"traceutil/trace.go:171","msg":"trace[556493423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:701; }","duration":"1.055270525s","start":"2026-05-08T02:52:00.698029Z","end":"2026-05-08T02:52:01.753299Z","steps":["trace[556493423] 'agreement among raft nodes before linearized reading' (duration: 1.054874537s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:52:01.753345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.060524176s","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-05-08T02:52:01.753535Z","caller":"traceutil/trace.go:171","msg":"trace[2093936298] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:701; }","duration":"1.06072985s","start":"2026-05-08T02:52:00.692793Z","end":"2026-05-08T02:52:01.753523Z","steps":["trace[2093936298] 'agreement among raft nodes before linearized reading' (duration: 1.060413723s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:52:01.753563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:52:00.692784Z","time spent":"1.060770421s","remote":"127.0.0.1:36702","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-05-08T02:52:01.753443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:52:00.698015Z","time spent":"1.055403308s","remote":"127.0.0.1:36702","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-05-08T02:52:01.753479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:52:00.542318Z","time spent":"1.210539544s","remote":"127.0.0.1:36632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:52:02.434159Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4680539659187370973,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-08T02:52:02.646486Z","caller":"traceutil/trace.go:171","msg":"trace[403139582] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"878.208781ms","start":"2026-05-08T02:52:01.768238Z","end":"2026-05-08T02:52:02.646446Z","steps":["trace[403139582] 'process raft request' (duration: 877.881584ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:52:02.646515Z","caller":"traceutil/trace.go:171","msg":"trace[228528882] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"879.734843ms","start":"2026-05-08T02:52:01.766594Z","end":"2026-05-08T02:52:02.646329Z","steps":["trace[228528882] 'process raft request' (duration: 814.868292ms)","trace[228528882] 'compare' (duration: 64.076145ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:52:02.64664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:52:01.766566Z","time spent":"880.020329ms","remote":"127.0.0.1:36702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:52:02.646647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:52:01.768222Z","time spent":"878.336773ms","remote":"127.0.0.1:36702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T02:52:02.646977Z","caller":"traceutil/trace.go:171","msg":"trace[225894540] linearizableReadLoop","detail":"{readStateIndex:732; appliedIndex:727; }","duration":"713.47742ms","start":"2026-05-08T02:52:01.933486Z","end":"2026-05-08T02:52:02.646964Z","steps":["trace[225894540] 'read index received' (duration: 647.983846ms)","trace[225894540] 'applied index is now lower than readState.Index' (duration: 65.492634ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:52:02.646998Z","caller":"traceutil/trace.go:171","msg":"trace[1115875088] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"878.744722ms","start":"2026-05-08T02:52:01.768242Z","end":"2026-05-08T02:52:02.646986Z","steps":["trace[1115875088] 'process raft request' (duration: 877.933975ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:52:02.647053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:52:01.768226Z","time spent":"878.796782ms","remote":"127.0.0.1:36702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:52:02.647088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.598303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:52:02.647131Z","caller":"traceutil/trace.go:171","msg":"trace[1871357601] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:706; }","duration":"713.677524ms","start":"2026-05-08T02:52:01.933443Z","end":"2026-05-08T02:52:02.647121Z","steps":["trace[1871357601] 'agreement among raft nodes before linearized reading' (duration: 713.564522ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:52:02.647165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:52:01.933421Z","time spent":"713.737436ms","remote":"127.0.0.1:36424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-08T02:52:02.647347Z","caller":"traceutil/trace.go:171","msg":"trace[1012170276] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"876.063146ms","start":"2026-05-08T02:52:01.77127Z","end":"2026-05-08T02:52:02.647334Z","steps":["trace[1012170276] 'process raft request' (duration: 875.649967ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:52:02.647413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:52:01.771264Z","time spent":"876.109467ms","remote":"127.0.0.1:36702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T02:52:02.647427Z","caller":"traceutil/trace.go:171","msg":"trace[1041964055] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"877.265891ms","start":"2026-05-08T02:52:01.770146Z","end":"2026-05-08T02:52:02.647412Z","steps":["trace[1041964055] 'process raft request' (duration: 876.677069ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:52:02.647579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:52:01.770136Z","time spent":"877.342463ms","remote":"127.0.0.1:36702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:52:04.690297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.756331355s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:52:04.690415Z","caller":"traceutil/trace.go:171","msg":"trace[1543599859] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:706; }","duration":"1.756493509s","start":"2026-05-08T02:52:02.933897Z","end":"2026-05-08T02:52:04.690391Z","steps":["trace[1543599859] 'range keys from in-memory index tree' (duration: 1.756182362s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:52:04.690471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:52:02.933877Z","time spent":"1.75658022s","remote":"127.0.0.1:36424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-08T02:52:05.152211Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4680539659187370981,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-08T02:52:05.652957Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4680539659187370981,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-08T02:52:06.153361Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4680539659187370981,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-08T02:52:06.305549Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.106264663s","expected-duration":"1s"} {"level":"info","ts":"2026-05-08T02:52:06.388348Z","caller":"traceutil/trace.go:171","msg":"trace[1831214193] linearizableReadLoop","detail":"{readStateIndex:735; appliedIndex:732; }","duration":"1.736736458s","start":"2026-05-08T02:52:04.651587Z","end":"2026-05-08T02:52:06.388323Z","steps":["trace[1831214193] 'read index received' (duration: 1.654376945s)","trace[1831214193] 'applied index is now lower than readState.Index' (duration: 82.358313ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:52:06.388701Z","caller":"traceutil/trace.go:171","msg":"trace[1323687254] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"1.793553513s","start":"2026-05-08T02:52:04.595132Z","end":"2026-05-08T02:52:06.388685Z","steps":["trace[1323687254] 'process raft request' (duration: 1.792925161s)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:52:06.388971Z","caller":"traceutil/trace.go:171","msg":"trace[2072411427] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"1.766347845s","start":"2026-05-08T02:52:04.622596Z","end":"2026-05-08T02:52:06.388944Z","steps":["trace[2072411427] 'process raft request' (duration: 1.765665542s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:52:06.389066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.737445882s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-08T02:52:06.389116Z","caller":"traceutil/trace.go:171","msg":"trace[1108976514] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:708; }","duration":"1.737563014s","start":"2026-05-08T02:52:04.65154Z","end":"2026-05-08T02:52:06.389103Z","steps":["trace[1108976514] 'agreement among raft nodes before linearized reading' (duration: 1.73735519s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:52:06.389121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:52:04.622564Z","time spent":"1.766487758s","remote":"127.0.0.1:36702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:52:06.38915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:52:04.651522Z","time spent":"1.737620955s","remote":"127.0.0.1:36702","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-05-08T02:52:06.389267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.737610805s","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-05-08T02:52:06.389243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.737619255s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-08T02:52:06.389316Z","caller":"traceutil/trace.go:171","msg":"trace[1874053261] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:708; }","duration":"1.737680906s","start":"2026-05-08T02:52:04.651622Z","end":"2026-05-08T02:52:06.389303Z","steps":["trace[1874053261] 'agreement among raft nodes before linearized reading' (duration: 1.737590204s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:52:06.38935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:52:04.651609Z","time spent":"1.737733138s","remote":"127.0.0.1:36702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-08T02:52:06.389348Z","caller":"traceutil/trace.go:171","msg":"trace[1945202783] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:708; }","duration":"1.737782839s","start":"2026-05-08T02:52:04.651539Z","end":"2026-05-08T02:52:06.389322Z","steps":["trace[1945202783] 'agreement among raft nodes before linearized reading' (duration: 1.737583575s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:52:06.389369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.737643106s","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-05-08T02:52:06.38938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.69641573s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:52:06.389409Z","caller":"traceutil/trace.go:171","msg":"trace[1804097950] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:708; }","duration":"1.737717087s","start":"2026-05-08T02:52:04.651682Z","end":"2026-05-08T02:52:06.389399Z","steps":["trace[1804097950] 'agreement among raft nodes before linearized reading' (duration: 1.737643546s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:52:06.389393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.737665536s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-08T02:52:06.389418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:52:04.651513Z","time spent":"1.737888871s","remote":"127.0.0.1:36702","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-05-08T02:52:06.38944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:52:04.651663Z","time spent":"1.737769619s","remote":"127.0.0.1:36702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-08T02:52:06.389443Z","caller":"traceutil/trace.go:171","msg":"trace[1990902757] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:708; }","duration":"1.696510702s","start":"2026-05-08T02:52:04.692917Z","end":"2026-05-08T02:52:06.389427Z","steps":["trace[1990902757] 'agreement among raft nodes before linearized reading' (duration: 1.69641195s)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:52:06.389447Z","caller":"traceutil/trace.go:171","msg":"trace[145687419] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:708; }","duration":"1.737741008s","start":"2026-05-08T02:52:04.651694Z","end":"2026-05-08T02:52:06.389435Z","steps":["trace[145687419] 'agreement among raft nodes before linearized reading' (duration: 1.737628196s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:52:06.389485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:52:04.692897Z","time spent":"1.696577994s","remote":"127.0.0.1:36414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-08T02:52:06.389726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:52:04.595105Z","time spent":"1.793655735s","remote":"127.0.0.1:36702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:52:06.38985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:52:04.651678Z","time spent":"1.737803489s","remote":"127.0.0.1:36702","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":"info","ts":"2026-05-08T02:52:06.933347Z","caller":"traceutil/trace.go:171","msg":"trace[1412931499] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"102.995448ms","start":"2026-05-08T02:52:06.830316Z","end":"2026-05-08T02:52:06.933312Z","steps":["trace[1412931499] 'process raft request' (duration: 102.167412ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:52:23.006375Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4680539659187371209,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-08T02:52:23.507512Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4680539659187371209,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-08T02:52:23.59336Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.931197361s","expected-duration":"1s"} {"level":"info","ts":"2026-05-08T02:52:23.593925Z","caller":"traceutil/trace.go:171","msg":"trace[914624104] linearizableReadLoop","detail":"{readStateIndex:853; appliedIndex:852; }","duration":"1.088051606s","start":"2026-05-08T02:52:22.50585Z","end":"2026-05-08T02:52:23.593901Z","steps":["trace[914624104] 'read index received' (duration: 1.087655538s)","trace[914624104] 'applied index is now lower than readState.Index' (duration: 394.648µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:52:23.594031Z","caller":"traceutil/trace.go:171","msg":"trace[1332858426] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"1.931940315s","start":"2026-05-08T02:52:21.662063Z","end":"2026-05-08T02:52:23.594003Z","steps":["trace[1332858426] 'process raft request' (duration: 1.931663331s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:52:23.594166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:52:21.662046Z","time spent":"1.932030877s","remote":"127.0.0.1:36632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:52:23.594294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.088403972s","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-05-08T02:52:23.594473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.054477283s","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-05-08T02:52:23.59455Z","caller":"traceutil/trace.go:171","msg":"trace[912780901] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:821; }","duration":"1.054612476s","start":"2026-05-08T02:52:22.539919Z","end":"2026-05-08T02:52:23.594532Z","steps":["trace[912780901] 'agreement among raft nodes before linearized reading' (duration: 1.054420152s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:52:23.594612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:52:22.539891Z","time spent":"1.054711588s","remote":"127.0.0.1:36702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-08T02:52:23.59481Z","caller":"traceutil/trace.go:171","msg":"trace[1647199436] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:821; }","duration":"1.088588225s","start":"2026-05-08T02:52:22.505815Z","end":"2026-05-08T02:52:23.594403Z","steps":["trace[1647199436] 'agreement among raft nodes before linearized reading' (duration: 1.088209539s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:52:23.594843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.050609133s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-08T02:52:23.594882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.053480875s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-08T02:52:23.594895Z","caller":"traceutil/trace.go:171","msg":"trace[1871918666] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:821; }","duration":"1.050705675s","start":"2026-05-08T02:52:22.544175Z","end":"2026-05-08T02:52:23.594881Z","steps":["trace[1871918666] 'agreement among raft nodes before linearized reading' (duration: 1.05043931s)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:52:23.594919Z","caller":"traceutil/trace.go:171","msg":"trace[914624057] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:821; }","duration":"1.053560026s","start":"2026-05-08T02:52:22.541349Z","end":"2026-05-08T02:52:23.59491Z","steps":["trace[914624057] 'agreement among raft nodes before linearized reading' (duration: 1.053468585s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:52:23.594937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:52:22.544162Z","time spent":"1.050764065s","remote":"127.0.0.1:36702","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-05-08T02:52:23.595077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.054038004s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-08T02:52:23.595136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.116988ms","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-05-08T02:52:23.595155Z","caller":"traceutil/trace.go:171","msg":"trace[682523668] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:821; }","duration":"1.054190537s","start":"2026-05-08T02:52:22.540947Z","end":"2026-05-08T02:52:23.595137Z","steps":["trace[682523668] 'agreement among raft nodes before linearized reading' (duration: 1.053919962s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:52:23.595201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.666338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T02:52:23.595206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:52:22.540922Z","time spent":"1.054276478s","remote":"127.0.0.1:36702","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-05-08T02:52:23.595233Z","caller":"traceutil/trace.go:171","msg":"trace[662786196] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:821; }","duration":"660.72135ms","start":"2026-05-08T02:52:22.934505Z","end":"2026-05-08T02:52:23.595226Z","steps":["trace[662786196] 'agreement among raft nodes before linearized reading' (duration: 660.680459ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:52:23.595258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:52:22.934492Z","time spent":"660.761ms","remote":"127.0.0.1:36424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-08T02:52:23.595168Z","caller":"traceutil/trace.go:171","msg":"trace[1887947093] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:821; }","duration":"723.1878ms","start":"2026-05-08T02:52:22.871971Z","end":"2026-05-08T02:52:23.595159Z","steps":["trace[1887947093] 'agreement among raft nodes before linearized reading' (duration: 723.133359ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:52:23.595429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:52:22.871948Z","time spent":"723.460634ms","remote":"127.0.0.1:36702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":7,"response size":29,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-05-08T02:52:23.594951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:52:22.541332Z","time spent":"1.053613207s","remote":"127.0.0.1:36702","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-05-08T02:52:23.596651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:52:22.5058Z","time spent":"1.089068083s","remote":"127.0.0.1:36702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-08T02:52:37.250695Z","caller":"traceutil/trace.go:171","msg":"trace[678405350] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"159.952295ms","start":"2026-05-08T02:52:37.090719Z","end":"2026-05-08T02:52:37.250671Z","steps":["trace[678405350] 'process raft request' (duration: 63.756818ms)","trace[678405350] 'compare' (duration: 96.091565ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:54:10.781385Z","caller":"traceutil/trace.go:171","msg":"trace[1993394083] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"312.261169ms","start":"2026-05-08T02:54:10.469097Z","end":"2026-05-08T02:54:10.781359Z","steps":["trace[1993394083] 'process raft request' (duration: 312.094977ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:54:10.781578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:54:10.469069Z","time spent":"312.411101ms","remote":"127.0.0.1:36702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:54:11.062522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.208468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:54:11.06263Z","caller":"traceutil/trace.go:171","msg":"trace[1302849990] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1340; }","duration":"129.36972ms","start":"2026-05-08T02:54:10.933235Z","end":"2026-05-08T02:54:11.062605Z","steps":["trace[1302849990] 'range keys from in-memory index tree' (duration: 129.078967ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:54:19.41033Z","caller":"traceutil/trace.go:171","msg":"trace[1243628316] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"128.334365ms","start":"2026-05-08T02:54:19.28197Z","end":"2026-05-08T02:54:19.410305Z","steps":["trace[1243628316] 'process raft request' (duration: 128.172013ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:54:19.699812Z","caller":"traceutil/trace.go:171","msg":"trace[1307142353] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"231.551307ms","start":"2026-05-08T02:54:19.468227Z","end":"2026-05-08T02:54:19.699779Z","steps":["trace[1307142353] 'process raft request' (duration: 231.342485ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:54:20.766874Z","caller":"traceutil/trace.go:171","msg":"trace[410617379] linearizableReadLoop","detail":"{readStateIndex:1433; appliedIndex:1432; }","duration":"478.495536ms","start":"2026-05-08T02:54:20.288358Z","end":"2026-05-08T02:54:20.766853Z","steps":["trace[410617379] 'read index received' (duration: 478.376305ms)","trace[410617379] 'applied index is now lower than readState.Index' (duration: 118.161µs)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:54:20.76712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.74541ms","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-05-08T02:54:20.767154Z","caller":"traceutil/trace.go:171","msg":"trace[2127047224] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1374; }","duration":"478.837181ms","start":"2026-05-08T02:54:20.288303Z","end":"2026-05-08T02:54:20.76714Z","steps":["trace[2127047224] 'agreement among raft nodes before linearized reading' (duration: 478.666579ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:54:20.767184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:54:20.288284Z","time spent":"478.893831ms","remote":"127.0.0.1:36702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-08T02:54:20.767315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.43702ms","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-05-08T02:54:20.767338Z","caller":"traceutil/trace.go:171","msg":"trace[565655134] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1374; }","duration":"429.512232ms","start":"2026-05-08T02:54:20.33782Z","end":"2026-05-08T02:54:20.767332Z","steps":["trace[565655134] 'agreement among raft nodes before linearized reading' (duration: 429.447201ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:54:20.767355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:54:20.337791Z","time spent":"429.560612ms","remote":"127.0.0.1:36702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-08T02:54:20.767421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.167963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:54:20.767438Z","caller":"traceutil/trace.go:171","msg":"trace[266021163] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1374; }","duration":"311.220003ms","start":"2026-05-08T02:54:20.456214Z","end":"2026-05-08T02:54:20.767434Z","steps":["trace[266021163] 'agreement among raft nodes before linearized reading' (duration: 311.191253ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:54:20.767455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:54:20.456192Z","time spent":"311.257584ms","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-05-08T02:54:20.767668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.246236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-08T02:54:20.767696Z","caller":"traceutil/trace.go:171","msg":"trace[1883519845] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1374; }","duration":"412.291637ms","start":"2026-05-08T02:54:20.355397Z","end":"2026-05-08T02:54:20.767688Z","steps":["trace[1883519845] 'agreement among raft nodes before linearized reading' (duration: 412.216436ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:54:20.767714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:54:20.355387Z","time spent":"412.321927ms","remote":"127.0.0.1:36702","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-05-08T02:54:20.767942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.762292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-08T02:54:20.768032Z","caller":"traceutil/trace.go:171","msg":"trace[1824888661] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1374; }","duration":"412.921453ms","start":"2026-05-08T02:54:20.355091Z","end":"2026-05-08T02:54:20.768012Z","steps":["trace[1824888661] 'agreement among raft nodes before linearized reading' (duration: 412.64961ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:54:20.768074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:54:20.355076Z","time spent":"412.990505ms","remote":"127.0.0.1:36702","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-05-08T02:54:20.767297Z","caller":"traceutil/trace.go:171","msg":"trace[1838617931] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"529.077659ms","start":"2026-05-08T02:54:20.23789Z","end":"2026-05-08T02:54:20.766968Z","steps":["trace[1838617931] 'process raft request' (duration: 528.819826ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:54:20.768353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:54:20.237873Z","time spent":"530.349883ms","remote":"127.0.0.1:36702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"}