{"level":"warn","ts":"2026-04-07T01:24:32.824457Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T01:24:32.824535Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.109:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.109:2380","--initial-cluster=instance=https://199.204.45.109:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.109:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.109: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-04-07T01:24:32.824596Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T01:24:32.824631Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-04-07T01:24:32.824661Z","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-04-07T01:24:32.825437Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"]} {"level":"info","ts":"2026-04-07T01:24:32.825504Z","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.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.109: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-04-07T01:24:32.829158Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.358263ms"} {"level":"info","ts":"2026-04-07T01:24:32.834991Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d71a1778b31d4ce","cluster-id":"aaca35652fabf9b2"} {"level":"info","ts":"2026-04-07T01:24:32.835048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T01:24:32.835067Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 0"} {"level":"info","ts":"2026-04-07T01:24:32.835075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d71a1778b31d4ce [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T01:24:32.835106Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 1"} {"level":"info","ts":"2026-04-07T01:24:32.835138Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"warn","ts":"2026-04-07T01:24:32.841363Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T01:24:32.842908Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T01:24:32.844175Z","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-04-07T01:24:32.846303Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d71a1778b31d4ce","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T01:24:32.846593Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d71a1778b31d4ce","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T01:24:32.846705Z","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-04-07T01:24:32.846797Z","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-04-07T01:24:32.846812Z","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-04-07T01:24:32.847946Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"info","ts":"2026-04-07T01:24:32.848209Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","added-peer-id":"d71a1778b31d4ce","added-peer-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-04-07T01:24:32.850988Z","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-04-07T01:24:32.851047Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-04-07T01:24:32.851102Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-04-07T01:24:32.851339Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T01:24:32.851288Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d71a1778b31d4ce","initial-advertise-peer-urls":["https://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T01:24:32.936308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T01:24:32.936355Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T01:24:32.936374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgPreVoteResp from d71a1778b31d4ce at term 1"} {"level":"info","ts":"2026-04-07T01:24:32.936387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became candidate at term 2"} {"level":"info","ts":"2026-04-07T01:24:32.936395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgVoteResp from d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-04-07T01:24:32.936439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became leader at term 2"} {"level":"info","ts":"2026-04-07T01:24:32.936455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d71a1778b31d4ce elected leader d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-04-07T01:24:32.9373Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T01:24:32.938061Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d71a1778b31d4ce","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.109:2379]}","request-path":"/0/members/d71a1778b31d4ce/attributes","cluster-id":"aaca35652fabf9b2","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T01:24:32.938096Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T01:24:32.938422Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T01:24:32.938525Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T01:24:32.938126Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T01:24:32.938566Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T01:24:32.938696Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T01:24:32.938744Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T01:24:32.941741Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T01:24:32.942671Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.109:2379"} {"level":"warn","ts":"2026-04-07T01:24:36.214739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.189192ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-07T01:24:36.214842Z","caller":"traceutil/trace.go:171","msg":"trace[338263670] linearizableReadLoop","detail":"{readStateIndex:79; appliedIndex:78; }","duration":"748.823507ms","start":"2026-04-07T01:24:35.466005Z","end":"2026-04-07T01:24:36.214828Z","steps":["trace[338263670] 'read index received' (duration: 219.182054ms)","trace[338263670] 'applied index is now lower than readState.Index' (duration: 529.640413ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:24:36.214954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.95077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T01:24:36.214934Z","caller":"traceutil/trace.go:171","msg":"trace[939589240] transaction","detail":"{read_only:false; response_revision:75; number_of_response:1; }","duration":"771.004561ms","start":"2026-04-07T01:24:35.443893Z","end":"2026-04-07T01:24:36.214897Z","steps":["trace[939589240] 'process raft request' (duration: 241.186495ms)","trace[939589240] 'compare' (duration: 529.068788ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:24:36.214996Z","caller":"traceutil/trace.go:171","msg":"trace[1151447827] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:75; }","duration":"749.045753ms","start":"2026-04-07T01:24:35.465937Z","end":"2026-04-07T01:24:36.214983Z","steps":["trace[1151447827] 'agreement among raft nodes before linearized reading' (duration: 748.96183ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:24:36.21502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:24:35.465913Z","time spent":"749.101314ms","remote":"127.0.0.1:42252","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-04-07T01:24:36.21508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:24:35.44387Z","time spent":"771.136966ms","remote":"127.0.0.1:42604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":634,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T01:24:36.715144Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334366840685052897,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T01:24:37.153264Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.468062513s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-07T01:24:37.982424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.855329ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-07T01:24:37.982529Z","caller":"traceutil/trace.go:171","msg":"trace[1968665330] linearizableReadLoop","detail":"{readStateIndex:80; appliedIndex:79; }","duration":"1.76763685s","start":"2026-04-07T01:24:36.214875Z","end":"2026-04-07T01:24:37.982511Z","steps":["trace[1968665330] 'read index received' (duration: 938.518224ms)","trace[1968665330] 'applied index is now lower than readState.Index' (duration: 829.117596ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:24:37.982594Z","caller":"traceutil/trace.go:171","msg":"trace[879315940] transaction","detail":"{read_only:false; response_revision:76; number_of_response:1; }","duration":"2.494495636s","start":"2026-04-07T01:24:35.488089Z","end":"2026-04-07T01:24:37.982584Z","steps":["trace[879315940] 'process raft request' (duration: 1.665406481s)","trace[879315940] 'compare' (duration: 828.678575ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:24:37.982655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:24:35.488065Z","time spent":"2.49456666s","remote":"127.0.0.1:42422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T01:24:37.982901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.943605553s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T01:24:37.983001Z","caller":"traceutil/trace.go:171","msg":"trace[1842411930] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:76; }","duration":"1.943743166s","start":"2026-04-07T01:24:36.039235Z","end":"2026-04-07T01:24:37.982978Z","steps":["trace[1842411930] 'agreement among raft nodes before linearized reading' (duration: 1.94348476s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:24:37.983046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:24:36.039216Z","time spent":"1.943820979s","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-04-07T01:24:38.216341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000107857s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-07T01:24:38.216487Z","caller":"traceutil/trace.go:171","msg":"trace[906772629] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000310992s","start":"2026-04-07T01:24:36.216144Z","end":"2026-04-07T01:24:38.216455Z","steps":["trace[906772629] 'agreement among raft nodes before linearized reading' (duration: 2.000148168s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:24:38.216636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:24:36.216112Z","time spent":"2.000468136s","remote":"127.0.0.1:42266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T01:24:38.483447Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334366840685052899,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T01:24:38.54289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000455107s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-07T01:24:38.542984Z","caller":"traceutil/trace.go:171","msg":"trace[2117402336] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000611152s","start":"2026-04-07T01:24:36.542354Z","end":"2026-04-07T01:24:38.542965Z","steps":["trace[2117402336] 'agreement among raft nodes before linearized reading' (duration: 2.000489428s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:24:38.543035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:24:36.542333Z","time spent":"2.000691343s","remote":"127.0.0.1:42252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T01:24:39.259899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.120637ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-07T01:24:39.259994Z","caller":"traceutil/trace.go:171","msg":"trace[485295484] linearizableReadLoop","detail":"{readStateIndex:81; appliedIndex:80; }","duration":"1.277423664s","start":"2026-04-07T01:24:37.982555Z","end":"2026-04-07T01:24:39.259978Z","steps":["trace[485295484] 'read index received' (duration: 882.131192ms)","trace[485295484] 'applied index is now lower than readState.Index' (duration: 395.291562ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:24:39.26015Z","caller":"traceutil/trace.go:171","msg":"trace[2094165520] transaction","detail":"{read_only:false; response_revision:77; number_of_response:1; }","duration":"1.52220073s","start":"2026-04-07T01:24:37.737932Z","end":"2026-04-07T01:24:39.260133Z","steps":["trace[2094165520] 'process raft request' (duration: 1.126770034s)","trace[2094165520] 'compare' (duration: 394.519892ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:24:39.260202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:24:37.737914Z","time spent":"1.522264952s","remote":"127.0.0.1:42422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T01:24:39.260412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.04288333s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:monitoring\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T01:24:39.26044Z","caller":"traceutil/trace.go:171","msg":"trace[1524631271] range","detail":"{range_begin:/registry/clusterroles/system:monitoring; range_end:; response_count:0; response_revision:77; }","duration":"3.042945812s","start":"2026-04-07T01:24:36.217485Z","end":"2026-04-07T01:24:39.260431Z","steps":["trace[1524631271] 'agreement among raft nodes before linearized reading' (duration: 3.04285077s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:24:39.260467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:24:36.217467Z","time spent":"3.042994264s","remote":"127.0.0.1:42604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/system:monitoring\" "} {"level":"info","ts":"2026-04-07T01:24:39.287207Z","caller":"traceutil/trace.go:171","msg":"trace[1209246535] transaction","detail":"{read_only:false; response_revision:78; number_of_response:1; }","duration":"561.181672ms","start":"2026-04-07T01:24:38.726002Z","end":"2026-04-07T01:24:39.287183Z","steps":["trace[1209246535] 'process raft request' (duration: 561.081259ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:24:39.287351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:24:38.725976Z","time spent":"561.306674ms","remote":"127.0.0.1:42422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T01:24:39.287487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.300890641s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3491"} {"level":"warn","ts":"2026-04-07T01:24:39.287498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"902.516134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-07T01:24:39.287496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.02266902s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-07T01:24:39.287521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.063151722s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T01:24:39.287554Z","caller":"traceutil/trace.go:171","msg":"trace[2026763201] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:78; }","duration":"902.628767ms","start":"2026-04-07T01:24:38.384911Z","end":"2026-04-07T01:24:39.28754Z","steps":["trace[2026763201] 'agreement among raft nodes before linearized reading' (duration: 902.507844ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:24:39.287552Z","caller":"traceutil/trace.go:171","msg":"trace[1852985792] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:78; }","duration":"1.300991203s","start":"2026-04-07T01:24:37.986542Z","end":"2026-04-07T01:24:39.287533Z","steps":["trace[1852985792] 'agreement among raft nodes before linearized reading' (duration: 1.300799049s)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:24:39.287568Z","caller":"traceutil/trace.go:171","msg":"trace[1836459910] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:78; }","duration":"1.022760752s","start":"2026-04-07T01:24:38.26479Z","end":"2026-04-07T01:24:39.287551Z","steps":["trace[1836459910] 'agreement among raft nodes before linearized reading' (duration: 1.022591808s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:24:39.28761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:24:38.384894Z","time spent":"902.707338ms","remote":"127.0.0.1:42506","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-04-07T01:24:39.287611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:24:37.986519Z","time spent":"1.301083576s","remote":"127.0.0.1:42408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3514,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-07T01:24:39.28762Z","caller":"traceutil/trace.go:171","msg":"trace[358538338] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:78; }","duration":"1.063299976s","start":"2026-04-07T01:24:38.224296Z","end":"2026-04-07T01:24:39.287596Z","steps":["trace[358538338] 'agreement among raft nodes before linearized reading' (duration: 1.063138202s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:24:39.287631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:24:38.264744Z","time spent":"1.022878915s","remote":"127.0.0.1:42252","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-04-07T01:24:39.28767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:24:38.224274Z","time spent":"1.063384139s","remote":"127.0.0.1:42506","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-04-07T01:25:11.217739Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334366840685053754,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T01:25:11.420376Z","caller":"traceutil/trace.go:171","msg":"trace[172679708] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"827.413835ms","start":"2026-04-07T01:25:10.592941Z","end":"2026-04-07T01:25:11.420355Z","steps":["trace[172679708] 'process raft request' (duration: 827.297162ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:11.420486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:10.592914Z","time spent":"827.518008ms","remote":"127.0.0.1:42288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T01:25:12.531264Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.110381154s","expected-duration":"1s"} {"level":"info","ts":"2026-04-07T01:25:12.531776Z","caller":"traceutil/trace.go:171","msg":"trace[172428490] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"1.930317541s","start":"2026-04-07T01:25:10.60142Z","end":"2026-04-07T01:25:12.531737Z","steps":["trace[172428490] 'process raft request' (duration: 1.930077646s)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:25:12.531844Z","caller":"traceutil/trace.go:171","msg":"trace[2075212745] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"1.930178788s","start":"2026-04-07T01:25:10.601632Z","end":"2026-04-07T01:25:12.531811Z","steps":["trace[2075212745] 'process raft request' (duration: 1.930019754s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:12.532001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:10.601628Z","time spent":"1.930301711s","remote":"127.0.0.1:42288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T01:25:12.532373Z","caller":"traceutil/trace.go:171","msg":"trace[1213298889] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"1.910361062s","start":"2026-04-07T01:25:10.621978Z","end":"2026-04-07T01:25:12.532339Z","steps":["trace[1213298889] 'process raft request' (duration: 1.909817148s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:12.532085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:10.60139Z","time spent":"1.930477546s","remote":"127.0.0.1:42288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T01:25:12.532496Z","caller":"traceutil/trace.go:171","msg":"trace[1631392898] linearizableReadLoop","detail":"{readStateIndex:510; appliedIndex:498; }","duration":"1.81516813s","start":"2026-04-07T01:25:10.717305Z","end":"2026-04-07T01:25:12.532473Z","steps":["trace[1631392898] 'read index received' (duration: 702.828386ms)","trace[1631392898] 'applied index is now lower than readState.Index' (duration: 1.112338654s)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:25:12.53253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:10.621941Z","time spent":"1.910525225s","remote":"127.0.0.1:42288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T01:25:12.532593Z","caller":"traceutil/trace.go:171","msg":"trace[1032778454] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"1.90947398s","start":"2026-04-07T01:25:10.62311Z","end":"2026-04-07T01:25:12.532584Z","steps":["trace[1032778454] 'process raft request' (duration: 1.908752602s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:12.53268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:10.623085Z","time spent":"1.909535361s","remote":"127.0.0.1:42288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T01:25:12.532665Z","caller":"traceutil/trace.go:171","msg":"trace[1848804351] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"1.901458099s","start":"2026-04-07T01:25:10.631188Z","end":"2026-04-07T01:25:12.532646Z","steps":["trace[1848804351] 'process raft request' (duration: 1.900928216s)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:25:12.532663Z","caller":"traceutil/trace.go:171","msg":"trace[1518568355] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"1.908039924s","start":"2026-04-07T01:25:10.624607Z","end":"2026-04-07T01:25:12.532647Z","steps":["trace[1518568355] 'process raft request' (duration: 1.907406208s)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:25:12.532753Z","caller":"traceutil/trace.go:171","msg":"trace[2083358947] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"1.912408464s","start":"2026-04-07T01:25:10.620314Z","end":"2026-04-07T01:25:12.532722Z","steps":["trace[2083358947] 'process raft request' (duration: 1.911390358s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:12.532785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:10.624595Z","time spent":"1.908161237s","remote":"127.0.0.1:42288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T01:25:12.532813Z","caller":"traceutil/trace.go:171","msg":"trace[1834608817] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"1.892330662s","start":"2026-04-07T01:25:10.640473Z","end":"2026-04-07T01:25:12.532803Z","steps":["trace[1834608817] 'process raft request' (duration: 1.891874771s)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:25:12.532803Z","caller":"traceutil/trace.go:171","msg":"trace[2047030043] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"1.907979872s","start":"2026-04-07T01:25:10.624793Z","end":"2026-04-07T01:25:12.532773Z","steps":["trace[2047030043] 'process raft request' (duration: 1.907278155s)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:25:12.532846Z","caller":"traceutil/trace.go:171","msg":"trace[1937340197] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"1.762585404s","start":"2026-04-07T01:25:10.770251Z","end":"2026-04-07T01:25:12.532836Z","steps":["trace[1937340197] 'process raft request' (duration: 1.762163633s)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:25:12.532849Z","caller":"traceutil/trace.go:171","msg":"trace[805550550] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"1.911490851s","start":"2026-04-07T01:25:10.621352Z","end":"2026-04-07T01:25:12.532843Z","steps":["trace[805550550] 'process raft request' (duration: 1.910400283s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:12.532856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:10.640455Z","time spent":"1.892380853s","remote":"127.0.0.1:42288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T01:25:12.532882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:10.621342Z","time spent":"1.911525551s","remote":"127.0.0.1:42288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T01:25:12.532882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:10.770229Z","time spent":"1.762636985s","remote":"127.0.0.1:42288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T01:25:12.532882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:10.624781Z","time spent":"1.908067344s","remote":"127.0.0.1:42288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T01:25:12.53282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:10.620288Z","time spent":"1.912499105s","remote":"127.0.0.1:42288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T01:25:12.533048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.815732973s","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-04-07T01:25:12.533128Z","caller":"traceutil/trace.go:171","msg":"trace[93709711] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:492; }","duration":"1.815852826s","start":"2026-04-07T01:25:10.717262Z","end":"2026-04-07T01:25:12.533115Z","steps":["trace[93709711] 'agreement among raft nodes before linearized reading' (duration: 1.81558798s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:12.533202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:10.717245Z","time spent":"1.815947199s","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T01:25:12.53345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:10.631178Z","time spent":"1.901554372s","remote":"127.0.0.1:42288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T01:25:12.537307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.384723069s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:25:12.537381Z","caller":"traceutil/trace.go:171","msg":"trace[1329157307] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:495; }","duration":"1.384833682s","start":"2026-04-07T01:25:11.15254Z","end":"2026-04-07T01:25:12.537373Z","steps":["trace[1329157307] 'agreement among raft nodes before linearized reading' (duration: 1.384735249s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:12.537402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:11.152517Z","time spent":"1.384879033s","remote":"127.0.0.1:42252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T01:25:12.53755Z","caller":"traceutil/trace.go:171","msg":"trace[1265071408] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"1.11220801s","start":"2026-04-07T01:25:11.425336Z","end":"2026-04-07T01:25:12.537544Z","steps":["trace[1265071408] 'process raft request' (duration: 1.111784979s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:12.537586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:11.425319Z","time spent":"1.112245421s","remote":"127.0.0.1:42796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T01:25:12.537664Z","caller":"traceutil/trace.go:171","msg":"trace[76543680] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"1.111406771s","start":"2026-04-07T01:25:11.426251Z","end":"2026-04-07T01:25:12.537658Z","steps":["trace[76543680] 'process raft request' (duration: 1.110931438s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:12.53785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:11.426239Z","time spent":"1.111438941s","remote":"127.0.0.1:42288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T01:25:12.537923Z","caller":"traceutil/trace.go:171","msg":"trace[97428688] transaction","detail":"{read_only:false; number_of_response:1; response_revision:494; }","duration":"1.111435471s","start":"2026-04-07T01:25:11.426481Z","end":"2026-04-07T01:25:12.537917Z","steps":["trace[97428688] 'process raft request' (duration: 1.110734413s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:12.537964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:11.426471Z","time spent":"1.111470432s","remote":"127.0.0.1:42288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7366,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T01:25:12.538127Z","caller":"traceutil/trace.go:171","msg":"trace[1567156619] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"248.789176ms","start":"2026-04-07T01:25:12.28933Z","end":"2026-04-07T01:25:12.538119Z","steps":["trace[1567156619] 'process raft request' (duration: 247.909723ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:12.538221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.821153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:25:12.538238Z","caller":"traceutil/trace.go:171","msg":"trace[393168778] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:495; }","duration":"130.867935ms","start":"2026-04-07T01:25:12.407365Z","end":"2026-04-07T01:25:12.538233Z","steps":["trace[393168778] 'agreement among raft nodes before linearized reading' (duration: 130.829634ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:12.538323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"861.876097ms","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-04-07T01:25:12.538339Z","caller":"traceutil/trace.go:171","msg":"trace[341169008] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:495; }","duration":"861.910177ms","start":"2026-04-07T01:25:11.676424Z","end":"2026-04-07T01:25:12.538334Z","steps":["trace[341169008] 'agreement among raft nodes before linearized reading' (duration: 861.879556ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:12.538354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:11.676409Z","time spent":"861.942189ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T01:25:12.538421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.063628554s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-07T01:25:12.538437Z","caller":"traceutil/trace.go:171","msg":"trace[1990555304] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:495; }","duration":"1.063671756s","start":"2026-04-07T01:25:11.47476Z","end":"2026-04-07T01:25:12.538432Z","steps":["trace[1990555304] 'agreement among raft nodes before linearized reading' (duration: 1.063642655s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:12.538454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:11.474743Z","time spent":"1.063705367s","remote":"127.0.0.1:42506","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-04-07T01:25:12.538577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.074837515s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-07T01:25:12.538592Z","caller":"traceutil/trace.go:171","msg":"trace[2041398136] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:495; }","duration":"1.074891916s","start":"2026-04-07T01:25:11.463695Z","end":"2026-04-07T01:25:12.538587Z","steps":["trace[2041398136] 'agreement among raft nodes before linearized reading' (duration: 1.074815375s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:12.538608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:11.463675Z","time spent":"1.074928958s","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T01:25:20.053211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"899.165039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:25:20.053313Z","caller":"traceutil/trace.go:171","msg":"trace[1527414512] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:593; }","duration":"899.314762ms","start":"2026-04-07T01:25:19.153977Z","end":"2026-04-07T01:25:20.053292Z","steps":["trace[1527414512] 'range keys from in-memory index tree' (duration: 899.086347ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:20.053359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:19.15396Z","time spent":"899.390755ms","remote":"127.0.0.1:42266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T01:25:20.33308Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.018427542s","expected-duration":"1s"} {"level":"info","ts":"2026-04-07T01:25:20.334748Z","caller":"traceutil/trace.go:171","msg":"trace[1157371472] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:616; }","duration":"466.927924ms","start":"2026-04-07T01:25:19.867778Z","end":"2026-04-07T01:25:20.334706Z","steps":["trace[1157371472] 'read index received' (duration: 465.432916ms)","trace[1157371472] 'applied index is now lower than readState.Index' (duration: 1.492228ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:25:20.334893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.100699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-07T01:25:20.334935Z","caller":"traceutil/trace.go:171","msg":"trace[2003675547] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:594; }","duration":"467.198941ms","start":"2026-04-07T01:25:19.867724Z","end":"2026-04-07T01:25:20.334923Z","steps":["trace[2003675547] 'agreement among raft nodes before linearized reading' (duration: 467.106849ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:20.334978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:19.867699Z","time spent":"467.271012ms","remote":"127.0.0.1:42282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.109\" "} {"level":"info","ts":"2026-04-07T01:25:20.334894Z","caller":"traceutil/trace.go:171","msg":"trace[62860253] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"1.021324256s","start":"2026-04-07T01:25:19.313471Z","end":"2026-04-07T01:25:20.334795Z","steps":["trace[62860253] 'process raft request' (duration: 1.020180627s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:20.335248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.299777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:25:20.335365Z","caller":"traceutil/trace.go:171","msg":"trace[632883979] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:594; }","duration":"181.42602ms","start":"2026-04-07T01:25:20.153907Z","end":"2026-04-07T01:25:20.335333Z","steps":["trace[632883979] 'agreement among raft nodes before linearized reading' (duration: 181.240806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:20.33596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:19.313448Z","time spent":"1.022398083s","remote":"127.0.0.1:42328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T01:25:20.840601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.547794ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T01:25:20.840729Z","caller":"traceutil/trace.go:171","msg":"trace[321367042] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:618; }","duration":"222.015095ms","start":"2026-04-07T01:25:20.618701Z","end":"2026-04-07T01:25:20.840716Z","steps":["trace[321367042] 'read index received' (duration: 92.005462ms)","trace[321367042] 'applied index is now lower than readState.Index' (duration: 130.008883ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:25:20.840797Z","caller":"traceutil/trace.go:171","msg":"trace[1775770039] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"486.948884ms","start":"2026-04-07T01:25:20.35384Z","end":"2026-04-07T01:25:20.840789Z","steps":["trace[1775770039] 'process raft request' (duration: 44.149184ms)","trace[1775770039] 'compare' (duration: 442.41214ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:25:20.840839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:20.353815Z","time spent":"487.001055ms","remote":"127.0.0.1:42282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T01:25:20.841276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.133544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-07T01:25:20.841359Z","caller":"traceutil/trace.go:171","msg":"trace[1596969351] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:596; }","duration":"221.233036ms","start":"2026-04-07T01:25:20.620106Z","end":"2026-04-07T01:25:20.841339Z","steps":["trace[1596969351] 'agreement among raft nodes before linearized reading' (duration: 220.97603ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:20.841432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.720544ms","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-04-07T01:25:20.841434Z","caller":"traceutil/trace.go:171","msg":"trace[330800653] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"432.188215ms","start":"2026-04-07T01:25:20.4092Z","end":"2026-04-07T01:25:20.841388Z","steps":["trace[330800653] 'process raft request' (duration: 431.479247ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:25:20.841481Z","caller":"traceutil/trace.go:171","msg":"trace[1684211788] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:596; }","duration":"222.806335ms","start":"2026-04-07T01:25:20.618659Z","end":"2026-04-07T01:25:20.841466Z","steps":["trace[1684211788] 'agreement among raft nodes before linearized reading' (duration: 222.675302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:20.841485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.582569ms","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-04-07T01:25:20.841539Z","caller":"traceutil/trace.go:171","msg":"trace[416608780] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:596; }","duration":"220.637401ms","start":"2026-04-07T01:25:20.620891Z","end":"2026-04-07T01:25:20.841529Z","steps":["trace[416608780] 'agreement among raft nodes before linearized reading' (duration: 220.481917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:20.841599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:20.409169Z","time spent":"432.363189ms","remote":"127.0.0.1:42492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T01:25:20.841485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.241796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-07T01:25:20.841656Z","caller":"traceutil/trace.go:171","msg":"trace[1286183500] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:596; }","duration":"221.445962ms","start":"2026-04-07T01:25:20.620195Z","end":"2026-04-07T01:25:20.841641Z","steps":["trace[1286183500] 'agreement among raft nodes before linearized reading' (duration: 221.199965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:22.653463Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334366840685054019,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T01:25:23.153692Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334366840685054019,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T01:25:23.654649Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334366840685054019,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T01:25:24.152898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.99979428s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-07T01:25:24.152987Z","caller":"traceutil/trace.go:171","msg":"trace[1027156463] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999920383s","start":"2026-04-07T01:25:22.153035Z","end":"2026-04-07T01:25:24.152955Z","steps":["trace[1027156463] 'agreement among raft nodes before linearized reading' (duration: 1.999826621s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:24.153048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:22.153002Z","time spent":"2.000037197s","remote":"127.0.0.1:42252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T01:25:24.15511Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334366840685054019,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T01:25:24.261187Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.32980725s","expected-duration":"1s"} {"level":"info","ts":"2026-04-07T01:25:24.261651Z","caller":"traceutil/trace.go:171","msg":"trace[170486402] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"3.330394875s","start":"2026-04-07T01:25:20.931234Z","end":"2026-04-07T01:25:24.261628Z","steps":["trace[170486402] 'process raft request' (duration: 3.330247151s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:24.261774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:20.931193Z","time spent":"3.330524778s","remote":"127.0.0.1:42422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T01:25:24.266026Z","caller":"traceutil/trace.go:171","msg":"trace[115897973] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:624; }","duration":"2.11292249s","start":"2026-04-07T01:25:22.153087Z","end":"2026-04-07T01:25:24.26601Z","steps":["trace[115897973] 'read index received' (duration: 2.108347436s)","trace[115897973] 'applied index is now lower than readState.Index' (duration: 4.574174ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:25:24.26621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.365097498s","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-04-07T01:25:24.266235Z","caller":"traceutil/trace.go:171","msg":"trace[245225254] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:602; }","duration":"1.365131219s","start":"2026-04-07T01:25:22.901097Z","end":"2026-04-07T01:25:24.266228Z","steps":["trace[245225254] 'agreement among raft nodes before linearized reading' (duration: 1.365028856s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:24.266262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:22.901024Z","time spent":"1.365232931s","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-07T01:25:24.266293Z","caller":"traceutil/trace.go:171","msg":"trace[1783418513] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"1.548906668s","start":"2026-04-07T01:25:22.717143Z","end":"2026-04-07T01:25:24.26605Z","steps":["trace[1783418513] 'process raft request' (duration: 1.548769315s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:24.266343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.347483758s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-07T01:25:24.26641Z","caller":"traceutil/trace.go:171","msg":"trace[23831379] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:602; }","duration":"1.34757163s","start":"2026-04-07T01:25:22.918822Z","end":"2026-04-07T01:25:24.266393Z","steps":["trace[23831379] 'agreement among raft nodes before linearized reading' (duration: 1.347394235s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:24.266461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:22.918808Z","time spent":"1.347641492s","remote":"127.0.0.1:42506","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-04-07T01:25:24.266493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.348039931s","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-04-07T01:25:24.26659Z","caller":"traceutil/trace.go:171","msg":"trace[1430913808] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:602; }","duration":"1.348221336s","start":"2026-04-07T01:25:22.918351Z","end":"2026-04-07T01:25:24.266572Z","steps":["trace[1430913808] 'agreement among raft nodes before linearized reading' (duration: 1.34796079s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:24.266637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:22.918321Z","time spent":"1.348306478s","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T01:25:24.266648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:22.717116Z","time spent":"1.549253946s","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T01:25:24.26653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.549417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:25:24.267028Z","caller":"traceutil/trace.go:171","msg":"trace[1530143720] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:602; }","duration":"113.07237ms","start":"2026-04-07T01:25:24.153931Z","end":"2026-04-07T01:25:24.267004Z","steps":["trace[1530143720] 'agreement among raft nodes before linearized reading' (duration: 112.552896ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:24.266772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.348898113s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-07T01:25:24.267238Z","caller":"traceutil/trace.go:171","msg":"trace[1700249335] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:602; }","duration":"1.349380496s","start":"2026-04-07T01:25:22.917846Z","end":"2026-04-07T01:25:24.267227Z","steps":["trace[1700249335] 'agreement among raft nodes before linearized reading' (duration: 1.348544675s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:24.267284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:22.91777Z","time spent":"1.349503448s","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-07T01:25:25.002904Z","caller":"traceutil/trace.go:171","msg":"trace[336321122] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"256.61602ms","start":"2026-04-07T01:25:24.746265Z","end":"2026-04-07T01:25:25.002881Z","steps":["trace[336321122] 'process raft request' (duration: 256.478457ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:25:25.010206Z","caller":"traceutil/trace.go:171","msg":"trace[750879490] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"253.839022ms","start":"2026-04-07T01:25:24.756318Z","end":"2026-04-07T01:25:25.010157Z","steps":["trace[750879490] 'process raft request' (duration: 253.607246ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:25:26.479319Z","caller":"traceutil/trace.go:171","msg":"trace[1951340162] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:644; }","duration":"328.242563ms","start":"2026-04-07T01:25:26.151049Z","end":"2026-04-07T01:25:26.479292Z","steps":["trace[1951340162] 'read index received' (duration: 328.013297ms)","trace[1951340162] 'applied index is now lower than readState.Index' (duration: 228.006µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:25:26.479356Z","caller":"traceutil/trace.go:171","msg":"trace[413725423] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"449.774714ms","start":"2026-04-07T01:25:26.029552Z","end":"2026-04-07T01:25:26.479327Z","steps":["trace[413725423] 'process raft request' (duration: 449.583009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:26.479503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:26.029526Z","time spent":"449.899647ms","remote":"127.0.0.1:42308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T01:25:26.479509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.442728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:25:26.479562Z","caller":"traceutil/trace.go:171","msg":"trace[2031266124] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:620; }","duration":"328.52399ms","start":"2026-04-07T01:25:26.151024Z","end":"2026-04-07T01:25:26.479548Z","steps":["trace[2031266124] 'agreement among raft nodes before linearized reading' (duration: 328.397647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:26.479598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:26.151012Z","time spent":"328.578701ms","remote":"127.0.0.1:42252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T01:25:26.479616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.901241ms","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-04-07T01:25:26.479609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.596289ms","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-04-07T01:25:26.47967Z","caller":"traceutil/trace.go:171","msg":"trace[1353114105] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:620; }","duration":"188.689741ms","start":"2026-04-07T01:25:26.290965Z","end":"2026-04-07T01:25:26.479655Z","steps":["trace[1353114105] 'agreement among raft nodes before linearized reading' (duration: 188.483696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:26.479685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.639865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-07T01:25:26.479706Z","caller":"traceutil/trace.go:171","msg":"trace[421672554] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:620; }","duration":"189.675845ms","start":"2026-04-07T01:25:26.290021Z","end":"2026-04-07T01:25:26.479697Z","steps":["trace[421672554] 'agreement among raft nodes before linearized reading' (duration: 189.630664ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:26.479686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.405129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-07T01:25:26.479761Z","caller":"traceutil/trace.go:171","msg":"trace[1300621814] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:620; }","duration":"189.510791ms","start":"2026-04-07T01:25:26.290232Z","end":"2026-04-07T01:25:26.479742Z","steps":["trace[1300621814] 'agreement among raft nodes before linearized reading' (duration: 189.259745ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:25:26.479642Z","caller":"traceutil/trace.go:171","msg":"trace[1529916549] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:620; }","duration":"189.944883ms","start":"2026-04-07T01:25:26.289689Z","end":"2026-04-07T01:25:26.479634Z","steps":["trace[1529916549] 'agreement among raft nodes before linearized reading' (duration: 189.747848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:27.103986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.078894ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T01:25:27.10449Z","caller":"traceutil/trace.go:171","msg":"trace[2117872062] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"621.500641ms","start":"2026-04-07T01:25:26.482619Z","end":"2026-04-07T01:25:27.10412Z","steps":["trace[2117872062] 'process raft request' (duration: 499.216602ms)","trace[2117872062] 'compare' (duration: 121.830818ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:25:27.104618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:26.48261Z","time spent":"621.957072ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T01:25:27.187792Z","caller":"traceutil/trace.go:171","msg":"trace[1023812537] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"704.268852ms","start":"2026-04-07T01:25:26.483503Z","end":"2026-04-07T01:25:27.187772Z","steps":["trace[1023812537] 'process raft request' (duration: 704.068737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:27.188005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:26.483498Z","time spent":"704.443516ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T01:25:27.188173Z","caller":"traceutil/trace.go:171","msg":"trace[894356818] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:645; }","duration":"432.628665ms","start":"2026-04-07T01:25:26.755519Z","end":"2026-04-07T01:25:27.188147Z","steps":["trace[894356818] 'read index received' (duration: 226.331124ms)","trace[894356818] 'applied index is now lower than readState.Index' (duration: 206.296251ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:25:27.18821Z","caller":"traceutil/trace.go:171","msg":"trace[194232508] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"704.6253ms","start":"2026-04-07T01:25:26.483547Z","end":"2026-04-07T01:25:27.188172Z","steps":["trace[194232508] 'process raft request' (duration: 704.161649ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:25:27.188251Z","caller":"traceutil/trace.go:171","msg":"trace[429461259] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"704.140009ms","start":"2026-04-07T01:25:26.484086Z","end":"2026-04-07T01:25:27.188226Z","steps":["trace[429461259] 'process raft request' (duration: 703.941244ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:27.188408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.880181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-hdwsk\" ","response":"range_response_count:1 size:17524"} {"level":"warn","ts":"2026-04-07T01:25:27.188403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:26.484076Z","time spent":"704.234521ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T01:25:27.188417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:26.483527Z","time spent":"704.775355ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T01:25:27.188449Z","caller":"traceutil/trace.go:171","msg":"trace[1562336405] range","detail":"{range_begin:/registry/pods/kube-system/cilium-hdwsk; range_end:; response_count:1; response_revision:624; }","duration":"432.925952ms","start":"2026-04-07T01:25:26.755514Z","end":"2026-04-07T01:25:27.18844Z","steps":["trace[1562336405] 'agreement among raft nodes before linearized reading' (duration: 432.770628ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:27.188481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:26.755468Z","time spent":"433.005834ms","remote":"127.0.0.1:42422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17547,"request content":"key:\"/registry/pods/kube-system/cilium-hdwsk\" "} {"level":"info","ts":"2026-04-07T01:25:27.97721Z","caller":"traceutil/trace.go:171","msg":"trace[820074020] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"201.252076ms","start":"2026-04-07T01:25:27.775933Z","end":"2026-04-07T01:25:27.977185Z","steps":["trace[820074020] 'process raft request' (duration: 197.719828ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:28.650587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.670766ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T01:25:28.650785Z","caller":"traceutil/trace.go:171","msg":"trace[1394639227] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"307.933165ms","start":"2026-04-07T01:25:28.34283Z","end":"2026-04-07T01:25:28.650763Z","steps":["trace[1394639227] 'process raft request' (duration: 66.013032ms)","trace[1394639227] 'compare' (duration: 241.376149ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:25:28.650851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:28.342812Z","time spent":"308.012847ms","remote":"127.0.0.1:42308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":856,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T01:25:29.007718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.594785ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T01:25:29.007846Z","caller":"traceutil/trace.go:171","msg":"trace[801572081] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:654; }","duration":"231.101603ms","start":"2026-04-07T01:25:28.776719Z","end":"2026-04-07T01:25:29.00782Z","steps":["trace[801572081] 'read index received' (duration: 35.338494ms)","trace[801572081] 'applied index is now lower than readState.Index' (duration: 195.761819ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:25:29.007912Z","caller":"traceutil/trace.go:171","msg":"trace[33113201] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"339.328361ms","start":"2026-04-07T01:25:28.668538Z","end":"2026-04-07T01:25:29.007866Z","steps":["trace[33113201] 'process raft request' (duration: 143.517521ms)","trace[33113201] 'compare' (duration: 195.464231ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:25:29.007999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:28.66851Z","time spent":"339.452364ms","remote":"127.0.0.1:42308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":700,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T01:25:29.008133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.404051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-hdwsk\" ","response":"range_response_count:1 size:17790"} {"level":"info","ts":"2026-04-07T01:25:29.008264Z","caller":"traceutil/trace.go:171","msg":"trace[959829806] range","detail":"{range_begin:/registry/pods/kube-system/cilium-hdwsk; range_end:; response_count:1; response_revision:630; }","duration":"231.565784ms","start":"2026-04-07T01:25:28.776682Z","end":"2026-04-07T01:25:29.008248Z","steps":["trace[959829806] 'agreement among raft nodes before linearized reading' (duration: 231.203665ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:25:31.129429Z","caller":"traceutil/trace.go:171","msg":"trace[946728544] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"126.724181ms","start":"2026-04-07T01:25:31.002677Z","end":"2026-04-07T01:25:31.129401Z","steps":["trace[946728544] 'process raft request' (duration: 126.549947ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:25:31.257005Z","caller":"traceutil/trace.go:171","msg":"trace[620891745] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"123.520151ms","start":"2026-04-07T01:25:31.133416Z","end":"2026-04-07T01:25:31.256936Z","steps":["trace[620891745] 'process raft request' (duration: 121.073009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:43.352473Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334366840685054345,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T01:25:43.854495Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334366840685054345,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T01:25:44.08924Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.380000427s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-07T01:25:44.439055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.51369ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T01:25:44.43913Z","caller":"traceutil/trace.go:171","msg":"trace[698431986] linearizableReadLoop","detail":"{readStateIndex:803; appliedIndex:802; }","duration":"1.587463945s","start":"2026-04-07T01:25:42.851654Z","end":"2026-04-07T01:25:44.439118Z","steps":["trace[698431986] 'read index received' (duration: 1.237730279s)","trace[698431986] 'applied index is now lower than readState.Index' (duration: 349.732716ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:25:44.439301Z","caller":"traceutil/trace.go:171","msg":"trace[712907549] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"1.733895426s","start":"2026-04-07T01:25:42.705396Z","end":"2026-04-07T01:25:44.439291Z","steps":["trace[712907549] 'process raft request' (duration: 1.384083997s)","trace[712907549] 'compare' (duration: 348.872002ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:25:44.43934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:42.705366Z","time spent":"1.733953778s","remote":"127.0.0.1:42408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4905,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T01:25:44.439511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.587863306s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-lpgpw\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-07T01:25:44.439542Z","caller":"traceutil/trace.go:171","msg":"trace[1592244592] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-lpgpw; range_end:; response_count:1; response_revision:773; }","duration":"1.587918688s","start":"2026-04-07T01:25:42.851604Z","end":"2026-04-07T01:25:44.439523Z","steps":["trace[1592244592] 'agreement among raft nodes before linearized reading' (duration: 1.587840096s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:44.439561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:42.851583Z","time spent":"1.58797353s","remote":"127.0.0.1:42422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-lpgpw\" "} {"level":"warn","ts":"2026-04-07T01:25:44.439692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.122404543s","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-04-07T01:25:44.43971Z","caller":"traceutil/trace.go:171","msg":"trace[148802893] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:773; }","duration":"1.122457795s","start":"2026-04-07T01:25:43.317246Z","end":"2026-04-07T01:25:44.439704Z","steps":["trace[148802893] 'agreement among raft nodes before linearized reading' (duration: 1.122407714s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:44.439725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:43.317228Z","time spent":"1.122494026s","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T01:25:44.439902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.000391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:25:44.439922Z","caller":"traceutil/trace.go:171","msg":"trace[987664445] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:773; }","duration":"286.045972ms","start":"2026-04-07T01:25:44.153871Z","end":"2026-04-07T01:25:44.439917Z","steps":["trace[987664445] 'agreement among raft nodes before linearized reading' (duration: 286.013291ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:44.440032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.115560354s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-07T01:25:44.440048Z","caller":"traceutil/trace.go:171","msg":"trace[662037824] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:773; }","duration":"1.115595374s","start":"2026-04-07T01:25:43.324448Z","end":"2026-04-07T01:25:44.440043Z","steps":["trace[662037824] 'agreement among raft nodes before linearized reading' (duration: 1.115546423s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:44.440063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:43.324437Z","time spent":"1.115621955s","remote":"127.0.0.1:42506","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-04-07T01:25:44.440162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.11615403s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-07T01:25:44.440181Z","caller":"traceutil/trace.go:171","msg":"trace[85430761] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:773; }","duration":"1.116184511s","start":"2026-04-07T01:25:43.32399Z","end":"2026-04-07T01:25:44.440174Z","steps":["trace[85430761] 'agreement among raft nodes before linearized reading' (duration: 1.11613838s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:44.440216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:43.323983Z","time spent":"1.116213073s","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T01:25:44.44036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.117989424s","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-04-07T01:25:44.440396Z","caller":"traceutil/trace.go:171","msg":"trace[954635192] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:773; }","duration":"1.118050866s","start":"2026-04-07T01:25:43.322326Z","end":"2026-04-07T01:25:44.440377Z","steps":["trace[954635192] 'agreement among raft nodes before linearized reading' (duration: 1.117978184s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:44.440419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:43.322305Z","time spent":"1.118108317s","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T01:25:44.440552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.118460238s","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-04-07T01:25:44.44057Z","caller":"traceutil/trace.go:171","msg":"trace[556306822] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:773; }","duration":"1.118491378s","start":"2026-04-07T01:25:43.322073Z","end":"2026-04-07T01:25:44.440565Z","steps":["trace[556306822] 'agreement among raft nodes before linearized reading' (duration: 1.118442427s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:44.440586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:43.322065Z","time spent":"1.118516529s","remote":"127.0.0.1:42506","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-04-07T01:26:10.271064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.729195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:26:10.271153Z","caller":"traceutil/trace.go:171","msg":"trace[1917002356] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:953; }","duration":"116.876491ms","start":"2026-04-07T01:26:10.154259Z","end":"2026-04-07T01:26:10.271135Z","steps":["trace[1917002356] 'range keys from in-memory index tree' (duration: 116.609812ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:26:18.746273Z","caller":"traceutil/trace.go:171","msg":"trace[1606692206] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"114.999747ms","start":"2026-04-07T01:26:18.631258Z","end":"2026-04-07T01:26:18.746258Z","steps":["trace[1606692206] 'process raft request' (duration: 114.879204ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:25.359486Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334366840685054852,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T01:26:25.860259Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334366840685054852,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T01:26:26.36139Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334366840685054852,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T01:26:26.861525Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334366840685054852,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T01:26:27.154536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000133222s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-07T01:26:27.154665Z","caller":"traceutil/trace.go:171","msg":"trace[641467055] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000316477s","start":"2026-04-07T01:26:25.154323Z","end":"2026-04-07T01:26:27.15464Z","steps":["trace[641467055] 'agreement among raft nodes before linearized reading' (duration: 2.000148392s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:27.154718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:25.154309Z","time spent":"2.000398658s","remote":"127.0.0.1:42252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T01:26:27.361865Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334366840685054852,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T01:26:27.844498Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.027177479s","expected-duration":"1s"} {"level":"info","ts":"2026-04-07T01:26:27.844824Z","caller":"traceutil/trace.go:171","msg":"trace[666374909] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"3.0276088s","start":"2026-04-07T01:26:24.817178Z","end":"2026-04-07T01:26:27.844787Z","steps":["trace[666374909] 'process raft request' (duration: 3.027489127s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:27.844949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:24.81715Z","time spent":"3.027732293s","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T01:26:29.135602Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.290901603s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-07T01:26:29.155569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00038462s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-07T01:26:29.155642Z","caller":"traceutil/trace.go:171","msg":"trace[1316482053] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000505503s","start":"2026-04-07T01:26:27.155122Z","end":"2026-04-07T01:26:29.155627Z","steps":["trace[1316482053] 'agreement among raft nodes before linearized reading' (duration: 2.00041067s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:29.155673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:27.155107Z","time spent":"2.000559755s","remote":"127.0.0.1:42266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T01:26:29.404738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.948847ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-07T01:26:29.405048Z","caller":"traceutil/trace.go:171","msg":"trace[1853749834] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"4.204706036s","start":"2026-04-07T01:26:25.200311Z","end":"2026-04-07T01:26:29.405017Z","steps":["trace[1853749834] 'process raft request' (duration: 4.204520861s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:29.405142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:25.20028Z","time spent":"4.204805529s","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T01:26:29.405174Z","caller":"traceutil/trace.go:171","msg":"trace[1035880145] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"4.144602222s","start":"2026-04-07T01:26:25.260545Z","end":"2026-04-07T01:26:29.405148Z","steps":["trace[1035880145] 'process raft request' (duration: 4.144395087s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:29.405295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:25.260527Z","time spent":"4.144706705s","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T01:26:29.405302Z","caller":"traceutil/trace.go:171","msg":"trace[1920942073] linearizableReadLoop","detail":"{readStateIndex:1047; appliedIndex:1042; }","duration":"4.546764314s","start":"2026-04-07T01:26:24.858508Z","end":"2026-04-07T01:26:29.405272Z","steps":["trace[1920942073] 'read index received' (duration: 2.986222027s)","trace[1920942073] 'applied index is now lower than readState.Index' (duration: 1.560540927s)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:26:29.405415Z","caller":"traceutil/trace.go:171","msg":"trace[1344251153] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"2.515100327s","start":"2026-04-07T01:26:26.890289Z","end":"2026-04-07T01:26:29.405389Z","steps":["trace[1344251153] 'process raft request' (duration: 2.514836921s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:29.405545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:26.890259Z","time spent":"2.515226081s","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T01:26:29.405562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.547035131s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-07T01:26:29.405598Z","caller":"traceutil/trace.go:171","msg":"trace[822416048] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1007; }","duration":"4.547135753s","start":"2026-04-07T01:26:24.85845Z","end":"2026-04-07T01:26:29.405586Z","steps":["trace[822416048] 'agreement among raft nodes before linearized reading' (duration: 4.546973629s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:29.405619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.546439316s","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-04-07T01:26:29.405648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.496064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T01:26:29.405654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.065245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18a3eeb181cfc639\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-04-07T01:26:29.405666Z","caller":"traceutil/trace.go:171","msg":"trace[711750265] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1007; }","duration":"4.546496858s","start":"2026-04-07T01:26:24.859155Z","end":"2026-04-07T01:26:29.405652Z","steps":["trace[711750265] 'agreement among raft nodes before linearized reading' (duration: 4.546346214s)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:26:29.405672Z","caller":"traceutil/trace.go:171","msg":"trace[1812250758] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1007; }","duration":"235.518094ms","start":"2026-04-07T01:26:29.170146Z","end":"2026-04-07T01:26:29.405664Z","steps":["trace[1812250758] 'agreement among raft nodes before linearized reading' (duration: 235.473713ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:26:29.405684Z","caller":"traceutil/trace.go:171","msg":"trace[1617457203] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18a3eeb181cfc639; range_end:; response_count:1; response_revision:1007; }","duration":"247.115406ms","start":"2026-04-07T01:26:29.15856Z","end":"2026-04-07T01:26:29.405675Z","steps":["trace[1617457203] 'agreement among raft nodes before linearized reading' (duration: 246.977813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:29.405691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:24.85915Z","time spent":"4.546536529s","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T01:26:29.405805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.389707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:26:29.405822Z","caller":"traceutil/trace.go:171","msg":"trace[1976966699] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1007; }","duration":"238.418907ms","start":"2026-04-07T01:26:29.167398Z","end":"2026-04-07T01:26:29.405817Z","steps":["trace[1976966699] 'agreement among raft nodes before linearized reading' (duration: 238.387606ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:29.405852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.280318523s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:26:29.405874Z","caller":"traceutil/trace.go:171","msg":"trace[885662465] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1007; }","duration":"2.280385984s","start":"2026-04-07T01:26:27.125481Z","end":"2026-04-07T01:26:29.405867Z","steps":["trace[885662465] 'agreement among raft nodes before linearized reading' (duration: 2.280350113s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:29.405858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.54459536s","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":"warn","ts":"2026-04-07T01:26:29.405891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:27.125457Z","time spent":"2.280429295s","remote":"127.0.0.1:51978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-07T01:26:29.405913Z","caller":"traceutil/trace.go:171","msg":"trace[1044712740] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1007; }","duration":"4.544657921s","start":"2026-04-07T01:26:24.861238Z","end":"2026-04-07T01:26:29.405896Z","steps":["trace[1044712740] 'agreement among raft nodes before linearized reading' (duration: 4.544529298s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:29.405925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.546658071s","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-04-07T01:26:29.40595Z","caller":"traceutil/trace.go:171","msg":"trace[1462458852] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1007; }","duration":"4.546708663s","start":"2026-04-07T01:26:24.859234Z","end":"2026-04-07T01:26:29.405943Z","steps":["trace[1462458852] 'agreement among raft nodes before linearized reading' (duration: 4.546637311s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:29.405955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:24.861233Z","time spent":"4.544709932s","remote":"127.0.0.1:42506","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-04-07T01:26:29.405969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:24.859219Z","time spent":"4.546745244s","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T01:26:29.405629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:24.858427Z","time spent":"4.547193655s","remote":"127.0.0.1:42506","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-04-07T01:26:29.406054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.091774781s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:26:29.406072Z","caller":"traceutil/trace.go:171","msg":"trace[702279863] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1007; }","duration":"4.091820342s","start":"2026-04-07T01:26:25.314246Z","end":"2026-04-07T01:26:29.406067Z","steps":["trace[702279863] 'agreement among raft nodes before linearized reading' (duration: 4.091788661s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:29.40609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:25.314234Z","time spent":"4.091851503s","remote":"127.0.0.1:42668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-04-07T01:26:46.131798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.111617293s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-07T01:26:46.131998Z","caller":"traceutil/trace.go:171","msg":"trace[1667143539] linearizableReadLoop","detail":"{readStateIndex:1104; appliedIndex:1103; }","duration":"976.696842ms","start":"2026-04-07T01:26:45.155258Z","end":"2026-04-07T01:26:46.131955Z","steps":["trace[1667143539] 'read index received' (duration: 195.405µs)","trace[1667143539] 'applied index is now lower than readState.Index' (duration: 976.499267ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:26:46.13224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.803244ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:26:46.132385Z","caller":"traceutil/trace.go:171","msg":"trace[653489137] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1060; }","duration":"536.959637ms","start":"2026-04-07T01:26:45.595404Z","end":"2026-04-07T01:26:46.132364Z","steps":["trace[653489137] 'range keys from in-memory index tree' (duration: 536.786543ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:46.132418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"977.147134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:26:46.132505Z","caller":"traceutil/trace.go:171","msg":"trace[1474968239] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1060; }","duration":"977.307757ms","start":"2026-04-07T01:26:45.155176Z","end":"2026-04-07T01:26:46.132484Z","steps":["trace[1474968239] 'agreement among raft nodes before linearized reading' (duration: 977.161744ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:46.132554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:45.15514Z","time spent":"977.40531ms","remote":"127.0.0.1:42252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T01:26:46.633011Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334366840685055003,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T01:26:46.637113Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.168380519s","expected-duration":"1s"} {"level":"info","ts":"2026-04-07T01:26:46.637527Z","caller":"traceutil/trace.go:171","msg":"trace[573173307] linearizableReadLoop","detail":"{readStateIndex:1105; appliedIndex:1104; }","duration":"505.393085ms","start":"2026-04-07T01:26:46.132092Z","end":"2026-04-07T01:26:46.637485Z","steps":["trace[573173307] 'read index received' (duration: 505.19155ms)","trace[573173307] 'applied index is now lower than readState.Index' (duration: 200.355µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:26:46.637683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.097139708s","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":"warn","ts":"2026-04-07T01:26:46.637738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.098520352s","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-04-07T01:26:46.637741Z","caller":"traceutil/trace.go:171","msg":"trace[894627262] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"1.169088707s","start":"2026-04-07T01:26:45.468606Z","end":"2026-04-07T01:26:46.637695Z","steps":["trace[894627262] 'process raft request' (duration: 1.168733418s)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:26:46.637776Z","caller":"traceutil/trace.go:171","msg":"trace[1562773225] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1061; }","duration":"1.098595393s","start":"2026-04-07T01:26:45.539169Z","end":"2026-04-07T01:26:46.637764Z","steps":["trace[1562773225] 'agreement among raft nodes before linearized reading' (duration: 1.098433109s)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:26:46.637764Z","caller":"traceutil/trace.go:171","msg":"trace[1221903249] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1061; }","duration":"1.09725349s","start":"2026-04-07T01:26:45.540493Z","end":"2026-04-07T01:26:46.637746Z","steps":["trace[1221903249] 'agreement among raft nodes before linearized reading' (duration: 1.097023064s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:46.637814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:45.53916Z","time spent":"1.098645295s","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T01:26:46.637823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:45.540483Z","time spent":"1.097330812s","remote":"127.0.0.1:42506","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-04-07T01:26:46.637817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.431719747s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-07T01:26:46.637867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.57478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-07T01:26:46.637891Z","caller":"traceutil/trace.go:171","msg":"trace[343382916] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1061; }","duration":"1.43183832s","start":"2026-04-07T01:26:45.206019Z","end":"2026-04-07T01:26:46.637858Z","steps":["trace[343382916] 'agreement among raft nodes before linearized reading' (duration: 1.431737297s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:46.637906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:45.468576Z","time spent":"1.16922464s","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T01:26:46.637937Z","caller":"traceutil/trace.go:171","msg":"trace[1109916871] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1061; }","duration":"700.699813ms","start":"2026-04-07T01:26:45.937222Z","end":"2026-04-07T01:26:46.637922Z","steps":["trace[1109916871] 'agreement among raft nodes before linearized reading' (duration: 700.497028ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:46.637944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:45.205989Z","time spent":"1.431941752s","remote":"127.0.0.1:52024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-07T01:26:46.637984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:45.937201Z","time spent":"700.773826ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T01:26:46.637988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.203726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:26:46.638018Z","caller":"traceutil/trace.go:171","msg":"trace[915786865] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1061; }","duration":"504.271427ms","start":"2026-04-07T01:26:46.133737Z","end":"2026-04-07T01:26:46.638008Z","steps":["trace[915786865] 'agreement among raft nodes before linearized reading' (duration: 504.197366ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:46.638054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:46.133717Z","time spent":"504.331959ms","remote":"127.0.0.1:42266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T01:26:46.638059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.10240694s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-07T01:26:46.638111Z","caller":"traceutil/trace.go:171","msg":"trace[643821748] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1061; }","duration":"1.102496343s","start":"2026-04-07T01:26:45.535601Z","end":"2026-04-07T01:26:46.638098Z","steps":["trace[643821748] 'agreement among raft nodes before linearized reading' (duration: 1.102316868s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:46.638173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:45.535581Z","time spent":"1.102574184s","remote":"127.0.0.1:42506","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-04-07T01:26:46.638248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.102537674s","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-04-07T01:26:46.638325Z","caller":"traceutil/trace.go:171","msg":"trace[1286822172] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1061; }","duration":"1.102649216s","start":"2026-04-07T01:26:45.535662Z","end":"2026-04-07T01:26:46.638311Z","steps":["trace[1286822172] 'agreement among raft nodes before linearized reading' (duration: 1.102227855s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:46.63845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:45.535638Z","time spent":"1.10280007s","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T01:26:47.180195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.832085ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T01:26:47.18063Z","caller":"traceutil/trace.go:171","msg":"trace[1132791506] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"537.508643ms","start":"2026-04-07T01:26:46.643059Z","end":"2026-04-07T01:26:47.180567Z","steps":["trace[1132791506] 'process raft request' (duration: 537.231226ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:26:47.180688Z","caller":"traceutil/trace.go:171","msg":"trace[195931349] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"537.843382ms","start":"2026-04-07T01:26:46.642815Z","end":"2026-04-07T01:26:47.180658Z","steps":["trace[195931349] 'process raft request' (duration: 381.509014ms)","trace[195931349] 'compare' (duration: 155.563008ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:26:47.180745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:46.643037Z","time spent":"537.667467ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T01:26:47.180897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:46.64279Z","time spent":"537.987256ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T01:26:47.181062Z","caller":"traceutil/trace.go:171","msg":"trace[1439210015] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"537.529263ms","start":"2026-04-07T01:26:46.64352Z","end":"2026-04-07T01:26:47.181049Z","steps":["trace[1439210015] 'process raft request' (duration: 536.940539ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:26:47.18115Z","caller":"traceutil/trace.go:171","msg":"trace[1719747012] linearizableReadLoop","detail":"{readStateIndex:1110; appliedIndex:1106; }","duration":"535.907152ms","start":"2026-04-07T01:26:46.645228Z","end":"2026-04-07T01:26:47.181136Z","steps":["trace[1719747012] 'read index received' (duration: 30.815203ms)","trace[1719747012] 'applied index is now lower than readState.Index' (duration: 505.091089ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:26:47.181167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:46.64351Z","time spent":"537.617496ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T01:26:47.18124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.011515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:26:47.181306Z","caller":"traceutil/trace.go:171","msg":"trace[2000670922] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1066; }","duration":"536.103286ms","start":"2026-04-07T01:26:46.645187Z","end":"2026-04-07T01:26:47.181291Z","steps":["trace[2000670922] 'agreement among raft nodes before linearized reading' (duration: 536.001654ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:26:47.181309Z","caller":"traceutil/trace.go:171","msg":"trace[1881050149] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"537.254047ms","start":"2026-04-07T01:26:46.644029Z","end":"2026-04-07T01:26:47.181283Z","steps":["trace[1881050149] 'process raft request' (duration: 536.923069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:47.181352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:46.645167Z","time spent":"536.175919ms","remote":"127.0.0.1:42252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T01:26:47.181443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:46.644021Z","time spent":"537.351409ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T01:26:48.294734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.19096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:26:48.294918Z","caller":"traceutil/trace.go:171","msg":"trace[598352466] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1066; }","duration":"429.368724ms","start":"2026-04-07T01:26:47.865458Z","end":"2026-04-07T01:26:48.294826Z","steps":["trace[598352466] 'count revisions from in-memory index tree' (duration: 429.092097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:48.294986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:47.865439Z","time spent":"429.527569ms","remote":"127.0.0.1:51822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-04-07T01:26:48.294982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.616774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:26:48.295073Z","caller":"traceutil/trace.go:171","msg":"trace[1411789983] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1066; }","duration":"141.752768ms","start":"2026-04-07T01:26:48.153295Z","end":"2026-04-07T01:26:48.295048Z","steps":["trace[1411789983] 'range keys from in-memory index tree' (duration: 141.505832ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:26:49.619127Z","caller":"traceutil/trace.go:171","msg":"trace[1532608598] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"429.870695ms","start":"2026-04-07T01:26:49.189241Z","end":"2026-04-07T01:26:49.619112Z","steps":["trace[1532608598] 'process raft request' (duration: 429.774882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:49.619225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:49.189221Z","time spent":"429.953276ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T01:26:49.995405Z","caller":"traceutil/trace.go:171","msg":"trace[549046473] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"806.118006ms","start":"2026-04-07T01:26:49.189259Z","end":"2026-04-07T01:26:49.995377Z","steps":["trace[549046473] 'process raft request' (duration: 805.832799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:49.995569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:49.189236Z","time spent":"806.24713ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T01:26:49.995681Z","caller":"traceutil/trace.go:171","msg":"trace[1033812562] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"806.296311ms","start":"2026-04-07T01:26:49.189369Z","end":"2026-04-07T01:26:49.995665Z","steps":["trace[1033812562] 'process raft request' (duration: 805.965442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:49.995855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:49.189359Z","time spent":"806.419205ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T01:26:49.996052Z","caller":"traceutil/trace.go:171","msg":"trace[291046271] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"804.607447ms","start":"2026-04-07T01:26:49.191432Z","end":"2026-04-07T01:26:49.99604Z","steps":["trace[291046271] 'process raft request' (duration: 804.179186ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:26:49.996053Z","caller":"traceutil/trace.go:171","msg":"trace[1049077802] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"378.039757ms","start":"2026-04-07T01:26:49.617965Z","end":"2026-04-07T01:26:49.996005Z","steps":["trace[1049077802] 'process raft request' (duration: 377.925514ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:49.996115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:49.191424Z","time spent":"804.661009ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T01:26:49.99649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:49.617945Z","time spent":"378.166061ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T01:26:50.001096Z","caller":"traceutil/trace.go:171","msg":"trace[863853630] linearizableReadLoop","detail":"{readStateIndex:1118; appliedIndex:1113; }","duration":"127.667768ms","start":"2026-04-07T01:26:49.873414Z","end":"2026-04-07T01:26:50.001082Z","steps":["trace[863853630] 'read index received' (duration: 121.780399ms)","trace[863853630] 'applied index is now lower than readState.Index' (duration: 5.886659ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:26:50.001283Z","caller":"traceutil/trace.go:171","msg":"trace[432861105] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"262.56049ms","start":"2026-04-07T01:26:49.738677Z","end":"2026-04-07T01:26:50.001237Z","steps":["trace[432861105] 'process raft request' (duration: 262.339815ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:50.001315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.880453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-07T01:26:50.001487Z","caller":"traceutil/trace.go:171","msg":"trace[110290622] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:1074; }","duration":"128.100149ms","start":"2026-04-07T01:26:49.873375Z","end":"2026-04-07T01:26:50.001475Z","steps":["trace[110290622] 'agreement among raft nodes before linearized reading' (duration: 127.776661ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:51.230377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.038895ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T01:26:51.230471Z","caller":"traceutil/trace.go:171","msg":"trace[732499185] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"485.408909ms","start":"2026-04-07T01:26:50.745044Z","end":"2026-04-07T01:26:51.230453Z","steps":["trace[732499185] 'process raft request' (duration: 271.209389ms)","trace[732499185] 'compare' (duration: 213.883091ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:26:51.230821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:50.745021Z","time spent":"485.520342ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T01:26:51.965875Z","caller":"traceutil/trace.go:171","msg":"trace[518847967] linearizableReadLoop","detail":"{readStateIndex:1123; appliedIndex:1122; }","duration":"213.152763ms","start":"2026-04-07T01:26:51.752693Z","end":"2026-04-07T01:26:51.965846Z","steps":["trace[518847967] 'read index received' (duration: 212.819104ms)","trace[518847967] 'applied index is now lower than readState.Index' (duration: 332.579µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:26:51.966115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.397489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T01:26:51.966198Z","caller":"traceutil/trace.go:171","msg":"trace[296027338] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"338.902612ms","start":"2026-04-07T01:26:51.627266Z","end":"2026-04-07T01:26:51.966169Z","steps":["trace[296027338] 'process raft request' (duration: 338.305317ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:26:51.966303Z","caller":"traceutil/trace.go:171","msg":"trace[1784154770] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1078; }","duration":"213.599334ms","start":"2026-04-07T01:26:51.752648Z","end":"2026-04-07T01:26:51.966248Z","steps":["trace[1784154770] 'agreement among raft nodes before linearized reading' (duration: 213.397619ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:51.966352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:51.627248Z","time spent":"339.033795ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T01:26:52.347772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.399518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T01:26:52.347799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.417304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:26:52.347849Z","caller":"traceutil/trace.go:171","msg":"trace[79971324] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1078; }","duration":"193.532222ms","start":"2026-04-07T01:26:52.1543Z","end":"2026-04-07T01:26:52.347832Z","steps":["trace[79971324] 'range keys from in-memory index tree' (duration: 193.256705ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:26:52.347905Z","caller":"traceutil/trace.go:171","msg":"trace[1371079698] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1078; }","duration":"273.567538ms","start":"2026-04-07T01:26:52.074298Z","end":"2026-04-07T01:26:52.347866Z","steps":["trace[1371079698] 'count revisions from in-memory index tree' (duration: 273.314651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:52.34793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.681873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-07T01:26:52.347974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.779955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-07T01:26:52.347986Z","caller":"traceutil/trace.go:171","msg":"trace[329225231] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1078; }","duration":"347.777865ms","start":"2026-04-07T01:26:52.000192Z","end":"2026-04-07T01:26:52.34797Z","steps":["trace[329225231] 'range keys from in-memory index tree' (duration: 347.505699ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:26:52.348026Z","caller":"traceutil/trace.go:171","msg":"trace[1195905482] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1078; }","duration":"347.871357ms","start":"2026-04-07T01:26:52.000141Z","end":"2026-04-07T01:26:52.348012Z","steps":["trace[1195905482] 'range keys from in-memory index tree' (duration: 347.596451ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:52.34803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:52.000173Z","time spent":"347.847807ms","remote":"127.0.0.1:42506","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-04-07T01:26:52.34806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:52.000119Z","time spent":"347.932749ms","remote":"127.0.0.1:42506","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-04-07T01:26:52.348182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.427894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-07T01:26:52.348211Z","caller":"traceutil/trace.go:171","msg":"trace[1705354968] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1078; }","duration":"343.468816ms","start":"2026-04-07T01:26:52.004735Z","end":"2026-04-07T01:26:52.348204Z","steps":["trace[1705354968] 'range keys from in-memory index tree' (duration: 343.356402ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:52.348236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:52.004729Z","time spent":"343.500627ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-07T01:26:52.863253Z","caller":"traceutil/trace.go:171","msg":"trace[2074731704] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"511.048077ms","start":"2026-04-07T01:26:52.352183Z","end":"2026-04-07T01:26:52.863231Z","steps":["trace[2074731704] 'process raft request' (duration: 510.917544ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:52.863402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:52.352161Z","time spent":"511.15633ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T01:26:52.900104Z","caller":"traceutil/trace.go:171","msg":"trace[819079658] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"164.34241ms","start":"2026-04-07T01:26:52.73574Z","end":"2026-04-07T01:26:52.900082Z","steps":["trace[819079658] 'process raft request' (duration: 164.312099ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:26:52.900275Z","caller":"traceutil/trace.go:171","msg":"trace[1609042583] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"547.643929ms","start":"2026-04-07T01:26:52.352589Z","end":"2026-04-07T01:26:52.900233Z","steps":["trace[1609042583] 'process raft request' (duration: 547.34412ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:26:52.900327Z","caller":"traceutil/trace.go:171","msg":"trace[13650166] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"547.358181ms","start":"2026-04-07T01:26:52.352939Z","end":"2026-04-07T01:26:52.900297Z","steps":["trace[13650166] 'process raft request' (duration: 547.088924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:52.900441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:52.352579Z","time spent":"547.775142ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T01:26:52.900473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:52.352929Z","time spent":"547.447603ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T01:27:03.593297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.763712ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T01:27:03.593387Z","caller":"traceutil/trace.go:171","msg":"trace[1479540209] linearizableReadLoop","detail":"{readStateIndex:1213; appliedIndex:1211; }","duration":"656.97635ms","start":"2026-04-07T01:27:02.936396Z","end":"2026-04-07T01:27:03.593372Z","steps":["trace[1479540209] 'read index received' (duration: 58.712803ms)","trace[1479540209] 'applied index is now lower than readState.Index' (duration: 598.262487ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:27:03.593744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.642936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-07T01:27:03.593874Z","caller":"traceutil/trace.go:171","msg":"trace[655411012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1164; }","duration":"655.786599ms","start":"2026-04-07T01:27:02.93807Z","end":"2026-04-07T01:27:03.593857Z","steps":["trace[655411012] 'agreement among raft nodes before linearized reading' (duration: 655.499962ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:03.593932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.280612ms","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-04-07T01:27:03.594007Z","caller":"traceutil/trace.go:171","msg":"trace[598988378] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1164; }","duration":"310.401215ms","start":"2026-04-07T01:27:03.283589Z","end":"2026-04-07T01:27:03.59399Z","steps":["trace[598988378] 'agreement among raft nodes before linearized reading' (duration: 310.226ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:27:03.593778Z","caller":"traceutil/trace.go:171","msg":"trace[1110946111] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"663.386923ms","start":"2026-04-07T01:27:02.930366Z","end":"2026-04-07T01:27:03.593753Z","steps":["trace[1110946111] 'process raft request' (duration: 80.093778ms)","trace[1110946111] 'compare' (duration: 582.184166ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:27:03.593991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.002201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T01:27:03.593954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:02.93803Z","time spent":"655.911592ms","remote":"127.0.0.1:42506","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-04-07T01:27:03.5942Z","caller":"traceutil/trace.go:171","msg":"trace[443218562] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1164; }","duration":"440.246766ms","start":"2026-04-07T01:27:03.153939Z","end":"2026-04-07T01:27:03.594186Z","steps":["trace[443218562] 'agreement among raft nodes before linearized reading' (duration: 439.884527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:03.594277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:03.15392Z","time spent":"440.313519ms","remote":"127.0.0.1:42266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T01:27:03.594146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:02.930343Z","time spent":"663.745262ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T01:27:03.59406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:03.283566Z","time spent":"310.486336ms","remote":"127.0.0.1:42506","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-04-07T01:27:03.59468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.273982ms","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-04-07T01:27:03.594782Z","caller":"traceutil/trace.go:171","msg":"trace[343143425] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1164; }","duration":"658.385955ms","start":"2026-04-07T01:27:02.936375Z","end":"2026-04-07T01:27:03.594761Z","steps":["trace[343143425] 'agreement among raft nodes before linearized reading' (duration: 657.40276ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:03.595507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:02.936311Z","time spent":"658.785045ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T01:27:06.104581Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334366840685055247,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T01:27:06.274283Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.271358388s","expected-duration":"1s"} {"level":"info","ts":"2026-04-07T01:27:06.274532Z","caller":"traceutil/trace.go:171","msg":"trace[1294225977] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"1.271678306s","start":"2026-04-07T01:27:05.002836Z","end":"2026-04-07T01:27:06.274515Z","steps":["trace[1294225977] 'process raft request' (duration: 1.271583594s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:06.274637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:05.002818Z","time spent":"1.271766988s","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T01:27:07.353831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.023332369s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-07T01:27:07.354284Z","caller":"traceutil/trace.go:171","msg":"trace[846899056] linearizableReadLoop","detail":"{readStateIndex:1220; appliedIndex:1218; }","duration":"1.749950761s","start":"2026-04-07T01:27:05.60431Z","end":"2026-04-07T01:27:07.354261Z","steps":["trace[846899056] 'read index received' (duration: 670.635668ms)","trace[846899056] 'applied index is now lower than readState.Index' (duration: 1.079313933s)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:27:07.354773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.750437923s","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-04-07T01:27:07.354837Z","caller":"traceutil/trace.go:171","msg":"trace[1439382511] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1170; }","duration":"1.750544425s","start":"2026-04-07T01:27:05.604278Z","end":"2026-04-07T01:27:07.354822Z","steps":["trace[1439382511] 'agreement among raft nodes before linearized reading' (duration: 1.75033253s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:07.35486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.750487675s","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-04-07T01:27:07.354921Z","caller":"traceutil/trace.go:171","msg":"trace[5701148] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"1.755278237s","start":"2026-04-07T01:27:05.599636Z","end":"2026-04-07T01:27:07.354914Z","steps":["trace[5701148] 'process raft request' (duration: 1.754479787s)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:27:07.354962Z","caller":"traceutil/trace.go:171","msg":"trace[2013298187] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1170; }","duration":"1.750574377s","start":"2026-04-07T01:27:05.60434Z","end":"2026-04-07T01:27:07.354914Z","steps":["trace[2013298187] 'agreement among raft nodes before linearized reading' (duration: 1.750374932s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:07.35499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:05.599608Z","time spent":"1.755331167s","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T01:27:07.355006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:05.604337Z","time spent":"1.750659749s","remote":"127.0.0.1:42506","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-04-07T01:27:07.355153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.328929942s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-04-07T01:27:07.355163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.749947792s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-07T01:27:07.355178Z","caller":"traceutil/trace.go:171","msg":"trace[914810793] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1170; }","duration":"1.329002485s","start":"2026-04-07T01:27:06.026169Z","end":"2026-04-07T01:27:07.355172Z","steps":["trace[914810793] 'agreement among raft nodes before linearized reading' (duration: 1.328913252s)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:27:07.355187Z","caller":"traceutil/trace.go:171","msg":"trace[1886586949] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1170; }","duration":"1.749985132s","start":"2026-04-07T01:27:05.605195Z","end":"2026-04-07T01:27:07.35518Z","steps":["trace[1886586949] 'agreement among raft nodes before linearized reading' (duration: 1.74991037s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:07.355198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:06.026155Z","time spent":"1.329038256s","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T01:27:07.355209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:05.605191Z","time spent":"1.750012934s","remote":"127.0.0.1:42506","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-04-07T01:27:07.354891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:05.604264Z","time spent":"1.750621479s","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T01:27:07.355258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.825369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:27:07.35528Z","caller":"traceutil/trace.go:171","msg":"trace[807273883] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1170; }","duration":"592.882031ms","start":"2026-04-07T01:27:06.762388Z","end":"2026-04-07T01:27:07.35527Z","steps":["trace[807273883] 'agreement among raft nodes before linearized reading' (duration: 592.85341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:07.355303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:06.762361Z","time spent":"592.936652ms","remote":"127.0.0.1:42482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-04-07T01:27:07.355316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.200847385s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:27:07.355343Z","caller":"traceutil/trace.go:171","msg":"trace[660881133] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1170; }","duration":"1.200892296s","start":"2026-04-07T01:27:06.154443Z","end":"2026-04-07T01:27:07.355336Z","steps":["trace[660881133] 'agreement among raft nodes before linearized reading' (duration: 1.200814614s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:07.355362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:06.154432Z","time spent":"1.200925597s","remote":"127.0.0.1:42252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T01:27:19.910329Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334366840685055351,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T01:27:20.254667Z","caller":"traceutil/trace.go:171","msg":"trace[63044823] linearizableReadLoop","detail":"{readStateIndex:1261; appliedIndex:1260; }","duration":"844.566012ms","start":"2026-04-07T01:27:19.410083Z","end":"2026-04-07T01:27:20.254649Z","steps":["trace[63044823] 'read index received' (duration: 844.416758ms)","trace[63044823] 'applied index is now lower than readState.Index' (duration: 148.354µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:27:20.254774Z","caller":"traceutil/trace.go:171","msg":"trace[1781018001] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"851.469418ms","start":"2026-04-07T01:27:19.403295Z","end":"2026-04-07T01:27:20.254764Z","steps":["trace[1781018001] 'process raft request' (duration: 851.236442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:20.254871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:19.403274Z","time spent":"851.52284ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T01:27:20.255041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"840.923629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-07T01:27:20.255097Z","caller":"traceutil/trace.go:171","msg":"trace[1346848610] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1209; }","duration":"841.012782ms","start":"2026-04-07T01:27:19.414071Z","end":"2026-04-07T01:27:20.255084Z","steps":["trace[1346848610] 'agreement among raft nodes before linearized reading' (duration: 840.870198ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:20.255122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:19.414059Z","time spent":"841.058563ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T01:27:20.255195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.077598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-07T01:27:20.255227Z","caller":"traceutil/trace.go:171","msg":"trace[1447908642] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:1209; }","duration":"380.146718ms","start":"2026-04-07T01:27:19.875073Z","end":"2026-04-07T01:27:20.25522Z","steps":["trace[1447908642] 'agreement among raft nodes before linearized reading' (duration: 380.042716ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:20.255247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.651547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:27:20.255273Z","caller":"traceutil/trace.go:171","msg":"trace[45048303] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1209; }","duration":"747.704407ms","start":"2026-04-07T01:27:19.507561Z","end":"2026-04-07T01:27:20.255266Z","steps":["trace[45048303] 'agreement among raft nodes before linearized reading' (duration: 747.665756ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:20.255291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:19.507548Z","time spent":"747.739389ms","remote":"127.0.0.1:51814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-04-07T01:27:20.255308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.228028ms","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-04-07T01:27:20.255336Z","caller":"traceutil/trace.go:171","msg":"trace[2099186406] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1209; }","duration":"845.29332ms","start":"2026-04-07T01:27:19.410033Z","end":"2026-04-07T01:27:20.255327Z","steps":["trace[2099186406] 'agreement among raft nodes before linearized reading' (duration: 845.231688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:20.255353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:19.410006Z","time spent":"845.343122ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T01:27:20.255411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"840.068357ms","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-04-07T01:27:20.255434Z","caller":"traceutil/trace.go:171","msg":"trace[2030467651] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1209; }","duration":"840.108959ms","start":"2026-04-07T01:27:19.415318Z","end":"2026-04-07T01:27:20.255427Z","steps":["trace[2030467651] 'agreement among raft nodes before linearized reading' (duration: 840.057567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:20.255447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:19.415307Z","time spent":"840.136409ms","remote":"127.0.0.1:42506","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-04-07T01:27:20.255464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.927622ms","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-04-07T01:27:20.255252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:19.875057Z","time spent":"380.18859ms","remote":"127.0.0.1:42282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.109\" "} {"level":"info","ts":"2026-04-07T01:27:20.255491Z","caller":"traceutil/trace.go:171","msg":"trace[300948680] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1209; }","duration":"126.022864ms","start":"2026-04-07T01:27:20.129461Z","end":"2026-04-07T01:27:20.255484Z","steps":["trace[300948680] 'agreement among raft nodes before linearized reading' (duration: 125.982153ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:20.255551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"840.386987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-07T01:27:20.255575Z","caller":"traceutil/trace.go:171","msg":"trace[2056855369] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1209; }","duration":"840.429377ms","start":"2026-04-07T01:27:19.415139Z","end":"2026-04-07T01:27:20.255568Z","steps":["trace[2056855369] 'agreement among raft nodes before linearized reading' (duration: 840.372416ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:20.255607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:19.415129Z","time spent":"840.470788ms","remote":"127.0.0.1:42506","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-04-07T01:27:20.413157Z","caller":"traceutil/trace.go:171","msg":"trace[418522157] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"147.080712ms","start":"2026-04-07T01:27:20.266056Z","end":"2026-04-07T01:27:20.413137Z","steps":["trace[418522157] 'process raft request' (duration: 147.02419ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:27:20.413265Z","caller":"traceutil/trace.go:171","msg":"trace[1098662631] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"147.172824ms","start":"2026-04-07T01:27:20.266071Z","end":"2026-04-07T01:27:20.413244Z","steps":["trace[1098662631] 'process raft request' (duration: 147.03697ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:27:20.413343Z","caller":"traceutil/trace.go:171","msg":"trace[1841659200] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"154.295525ms","start":"2026-04-07T01:27:20.259037Z","end":"2026-04-07T01:27:20.413333Z","steps":["trace[1841659200] 'process raft request' (duration: 153.176636ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:27:20.413421Z","caller":"traceutil/trace.go:171","msg":"trace[1970386235] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"153.831693ms","start":"2026-04-07T01:27:20.259582Z","end":"2026-04-07T01:27:20.413413Z","steps":["trace[1970386235] 'process raft request' (duration: 153.456863ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:27:20.76871Z","caller":"traceutil/trace.go:171","msg":"trace[360932343] linearizableReadLoop","detail":"{readStateIndex:1269; appliedIndex:1268; }","duration":"257.862829ms","start":"2026-04-07T01:27:20.51083Z","end":"2026-04-07T01:27:20.768693Z","steps":["trace[360932343] 'read index received' (duration: 247.272539ms)","trace[360932343] 'applied index is now lower than readState.Index' (duration: 10.5897ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:27:20.768794Z","caller":"traceutil/trace.go:171","msg":"trace[91240352] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"346.996496ms","start":"2026-04-07T01:27:20.421788Z","end":"2026-04-07T01:27:20.768785Z","steps":["trace[91240352] 'process raft request' (duration: 336.300354ms)","trace[91240352] 'compare' (duration: 10.22429ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:27:20.769078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.237529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-04-07T01:27:20.769141Z","caller":"traceutil/trace.go:171","msg":"trace[2124357868] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1216; }","duration":"258.335021ms","start":"2026-04-07T01:27:20.510789Z","end":"2026-04-07T01:27:20.769124Z","steps":["trace[2124357868] 'agreement among raft nodes before linearized reading' (duration: 258.228898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:20.769209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:20.421769Z","time spent":"347.042758ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T01:27:20.806093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.735993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:27:20.806155Z","caller":"traceutil/trace.go:171","msg":"trace[1801681281] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1217; }","duration":"240.846246ms","start":"2026-04-07T01:27:20.565294Z","end":"2026-04-07T01:27:20.80614Z","steps":["trace[1801681281] 'agreement among raft nodes before linearized reading' (duration: 240.750513ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:24.345486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"914.265245ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T01:27:24.346542Z","caller":"traceutil/trace.go:171","msg":"trace[786720828] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"1.013964031s","start":"2026-04-07T01:27:23.332553Z","end":"2026-04-07T01:27:24.346517Z","steps":["trace[786720828] 'process raft request' (duration: 98.567387ms)","trace[786720828] 'compare' (duration: 913.957327ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:27:24.346638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:23.332531Z","time spent":"1.014060664s","remote":"127.0.0.1:42522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T01:27:24.346876Z","caller":"traceutil/trace.go:171","msg":"trace[2031976250] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"1.010343589s","start":"2026-04-07T01:27:23.336507Z","end":"2026-04-07T01:27:24.34685Z","steps":["trace[2031976250] 'process raft request' (duration: 1.009900658s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:24.346985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:23.336436Z","time spent":"1.010498253s","remote":"127.0.0.1:42738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T01:27:24.347146Z","caller":"traceutil/trace.go:171","msg":"trace[620885226] linearizableReadLoop","detail":"{readStateIndex:1296; appliedIndex:1294; }","duration":"193.679716ms","start":"2026-04-07T01:27:24.153452Z","end":"2026-04-07T01:27:24.347132Z","steps":["trace[620885226] 'read index received' (duration: 57.692µs)","trace[620885226] 'applied index is now lower than readState.Index' (duration: 193.620204ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:27:24.347227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.778198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:27:24.347261Z","caller":"traceutil/trace.go:171","msg":"trace[1017054765] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1244; }","duration":"193.84975ms","start":"2026-04-07T01:27:24.153401Z","end":"2026-04-07T01:27:24.347251Z","steps":["trace[1017054765] 'agreement among raft nodes before linearized reading' (duration: 193.787799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:25.059628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.063421ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T01:27:25.059698Z","caller":"traceutil/trace.go:171","msg":"trace[1969395044] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"624.892604ms","start":"2026-04-07T01:27:24.434794Z","end":"2026-04-07T01:27:25.059687Z","steps":["trace[1969395044] 'process raft request' (duration: 429.70492ms)","trace[1969395044] 'compare' (duration: 194.998229ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:27:25.059731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:24.434778Z","time spent":"624.938786ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T01:27:25.087223Z","caller":"traceutil/trace.go:171","msg":"trace[1401742335] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"649.859609ms","start":"2026-04-07T01:27:24.437341Z","end":"2026-04-07T01:27:25.0872Z","steps":["trace[1401742335] 'process raft request' (duration: 649.745666ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:25.087476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.04702ms","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-04-07T01:27:25.08753Z","caller":"traceutil/trace.go:171","msg":"trace[1472878534] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1248; }","duration":"305.156012ms","start":"2026-04-07T01:27:24.782363Z","end":"2026-04-07T01:27:25.087519Z","steps":["trace[1472878534] 'agreement among raft nodes before linearized reading' (duration: 304.974678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:25.087559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:24.782324Z","time spent":"305.227244ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-07T01:27:25.087198Z","caller":"traceutil/trace.go:171","msg":"trace[1621912791] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"649.957722ms","start":"2026-04-07T01:27:24.43721Z","end":"2026-04-07T01:27:25.087168Z","steps":["trace[1621912791] 'process raft request' (duration: 649.594293ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:27:25.087272Z","caller":"traceutil/trace.go:171","msg":"trace[370473003] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"649.266624ms","start":"2026-04-07T01:27:24.437988Z","end":"2026-04-07T01:27:25.087254Z","steps":["trace[370473003] 'process raft request' (duration: 649.161641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:25.08776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:24.437326Z","time spent":"650.122127ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T01:27:25.087777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:24.437978Z","time spent":"649.712615ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T01:27:25.087901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:24.437193Z","time spent":"650.437464ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T01:27:25.087286Z","caller":"traceutil/trace.go:171","msg":"trace[612499085] linearizableReadLoop","detail":"{readStateIndex:1301; appliedIndex:1297; }","duration":"304.846334ms","start":"2026-04-07T01:27:24.782412Z","end":"2026-04-07T01:27:25.087259Z","steps":["trace[612499085] 'read index received' (duration: 82.043807ms)","trace[612499085] 'applied index is now lower than readState.Index' (duration: 222.801397ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:27:26.953716Z","caller":"traceutil/trace.go:171","msg":"trace[1313741278] linearizableReadLoop","detail":"{readStateIndex:1305; appliedIndex:1304; }","duration":"262.334423ms","start":"2026-04-07T01:27:26.691364Z","end":"2026-04-07T01:27:26.953699Z","steps":["trace[1313741278] 'read index received' (duration: 262.234721ms)","trace[1313741278] 'applied index is now lower than readState.Index' (duration: 98.852µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:27:26.953801Z","caller":"traceutil/trace.go:171","msg":"trace[972662814] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"601.462109ms","start":"2026-04-07T01:27:26.352333Z","end":"2026-04-07T01:27:26.953795Z","steps":["trace[972662814] 'process raft request' (duration: 601.247272ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:26.953877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:26.352315Z","time spent":"601.50197ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T01:27:26.954124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.739633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:27:26.954212Z","caller":"traceutil/trace.go:171","msg":"trace[1105444255] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1251; }","duration":"262.879997ms","start":"2026-04-07T01:27:26.691311Z","end":"2026-04-07T01:27:26.954191Z","steps":["trace[1105444255] 'agreement among raft nodes before linearized reading' (duration: 262.725803ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:26.95432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.73908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:27:26.954441Z","caller":"traceutil/trace.go:171","msg":"trace[1380638797] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1251; }","duration":"175.920625ms","start":"2026-04-07T01:27:26.778501Z","end":"2026-04-07T01:27:26.954421Z","steps":["trace[1380638797] 'agreement among raft nodes before linearized reading' (duration: 175.591596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:27.603245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.590332ms","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-04-07T01:27:27.603283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.683057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:27:27.603333Z","caller":"traceutil/trace.go:171","msg":"trace[1592724970] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1251; }","duration":"487.724125ms","start":"2026-04-07T01:27:27.115588Z","end":"2026-04-07T01:27:27.603313Z","steps":["trace[1592724970] 'range keys from in-memory index tree' (duration: 487.288175ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:27:27.603347Z","caller":"traceutil/trace.go:171","msg":"trace[173489140] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1251; }","duration":"449.767589ms","start":"2026-04-07T01:27:27.153556Z","end":"2026-04-07T01:27:27.603323Z","steps":["trace[173489140] 'range keys from in-memory index tree' (duration: 449.612845ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:27.603376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:27.153542Z","time spent":"449.827621ms","remote":"127.0.0.1:42252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T01:27:27.603382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:27.115573Z","time spent":"487.797898ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T01:27:27.603742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.815923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-07T01:27:27.603762Z","caller":"traceutil/trace.go:171","msg":"trace[1526484343] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1251; }","duration":"508.845323ms","start":"2026-04-07T01:27:27.09491Z","end":"2026-04-07T01:27:27.603756Z","steps":["trace[1526484343] 'range keys from in-memory index tree' (duration: 508.74157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:27.603778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:27.094906Z","time spent":"508.867374ms","remote":"127.0.0.1:42506","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-04-07T01:27:27.603822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.486983ms","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":"warn","ts":"2026-04-07T01:27:27.603815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.04684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-07T01:27:27.603866Z","caller":"traceutil/trace.go:171","msg":"trace[442032187] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1251; }","duration":"539.584065ms","start":"2026-04-07T01:27:27.064271Z","end":"2026-04-07T01:27:27.603855Z","steps":["trace[442032187] 'range keys from in-memory index tree' (duration: 539.283237ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:27.603904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:27.064243Z","time spent":"539.652227ms","remote":"127.0.0.1:42506","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-04-07T01:27:27.6039Z","caller":"traceutil/trace.go:171","msg":"trace[972096301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1251; }","duration":"511.187433ms","start":"2026-04-07T01:27:27.092687Z","end":"2026-04-07T01:27:27.603874Z","steps":["trace[972096301] 'range keys from in-memory index tree' (duration: 510.852845ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:27.60392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.622483ms","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-04-07T01:27:27.60395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:27.092665Z","time spent":"511.273595ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-07T01:27:27.603992Z","caller":"traceutil/trace.go:171","msg":"trace[2069379540] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1251; }","duration":"509.719035ms","start":"2026-04-07T01:27:27.094254Z","end":"2026-04-07T01:27:27.603973Z","steps":["trace[2069379540] 'range keys from in-memory index tree' (duration: 509.473429ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:27.604039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:27.094248Z","time spent":"509.781047ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-07T01:27:27.767597Z","caller":"traceutil/trace.go:171","msg":"trace[1583617561] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"156.873291ms","start":"2026-04-07T01:27:27.610702Z","end":"2026-04-07T01:27:27.767576Z","steps":["trace[1583617561] 'process raft request' (duration: 156.83803ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:27:27.767668Z","caller":"traceutil/trace.go:171","msg":"trace[757329280] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"159.105268ms","start":"2026-04-07T01:27:27.608552Z","end":"2026-04-07T01:27:27.767657Z","steps":["trace[757329280] 'process raft request' (duration: 125.638496ms)","trace[757329280] 'compare' (duration: 32.97538ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:27:27.767605Z","caller":"traceutil/trace.go:171","msg":"trace[802873041] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"158.935823ms","start":"2026-04-07T01:27:27.608648Z","end":"2026-04-07T01:27:27.767583Z","steps":["trace[802873041] 'process raft request' (duration: 158.795419ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:27:27.767778Z","caller":"traceutil/trace.go:171","msg":"trace[732934037] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"157.115066ms","start":"2026-04-07T01:27:27.610633Z","end":"2026-04-07T01:27:27.767748Z","steps":["trace[732934037] 'process raft request' (duration: 156.88568ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:27:28.114116Z","caller":"traceutil/trace.go:171","msg":"trace[1269197036] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"137.485667ms","start":"2026-04-07T01:27:27.976608Z","end":"2026-04-07T01:27:28.114094Z","steps":["trace[1269197036] 'process raft request' (duration: 137.433176ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:27:28.114124Z","caller":"traceutil/trace.go:171","msg":"trace[469650345] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"137.743704ms","start":"2026-04-07T01:27:27.976344Z","end":"2026-04-07T01:27:28.114087Z","steps":["trace[469650345] 'process raft request' (duration: 137.566769ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:28.736908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.174324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:27:28.737049Z","caller":"traceutil/trace.go:171","msg":"trace[1124392988] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1262; }","duration":"355.369149ms","start":"2026-04-07T01:27:28.381648Z","end":"2026-04-07T01:27:28.737017Z","steps":["trace[1124392988] 'count revisions from in-memory index tree' (duration: 355.093353ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:28.737281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:28.381629Z","time spent":"355.634886ms","remote":"127.0.0.1:42468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-04-07T01:27:28.737736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.253338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:27:28.737849Z","caller":"traceutil/trace.go:171","msg":"trace[1263865570] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1262; }","duration":"210.418292ms","start":"2026-04-07T01:27:28.527405Z","end":"2026-04-07T01:27:28.737823Z","steps":["trace[1263865570] 'count revisions from in-memory index tree' (duration: 209.415386ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:29.658757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.396575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:27:29.658837Z","caller":"traceutil/trace.go:171","msg":"trace[1440042051] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1263; }","duration":"505.527579ms","start":"2026-04-07T01:27:29.153293Z","end":"2026-04-07T01:27:29.65882Z","steps":["trace[1440042051] 'range keys from in-memory index tree' (duration: 505.313093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:29.658876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:29.153269Z","time spent":"505.59809ms","remote":"127.0.0.1:42252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T01:27:30.006811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.518914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-07T01:27:30.006883Z","caller":"traceutil/trace.go:171","msg":"trace[1563987262] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1264; }","duration":"232.620457ms","start":"2026-04-07T01:27:29.774248Z","end":"2026-04-07T01:27:30.006868Z","steps":["trace[1563987262] 'range keys from in-memory index tree' (duration: 232.390622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:30.006877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.817582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-04-07T01:27:30.006904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.267614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-07T01:27:30.006933Z","caller":"traceutil/trace.go:171","msg":"trace[1687394296] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1264; }","duration":"234.908305ms","start":"2026-04-07T01:27:29.77201Z","end":"2026-04-07T01:27:30.006918Z","steps":["trace[1687394296] 'range keys from in-memory index tree' (duration: 234.628908ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:27:30.006971Z","caller":"traceutil/trace.go:171","msg":"trace[501727786] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1264; }","duration":"235.404668ms","start":"2026-04-07T01:27:29.771552Z","end":"2026-04-07T01:27:30.006956Z","steps":["trace[501727786] 'range keys from in-memory index tree' (duration: 235.156092ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:30.006969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.95873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-07T01:27:30.007033Z","caller":"traceutil/trace.go:171","msg":"trace[879866910] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:1264; }","duration":"131.058793ms","start":"2026-04-07T01:27:29.875958Z","end":"2026-04-07T01:27:30.007016Z","steps":["trace[879866910] 'range keys from in-memory index tree' (duration: 130.788086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:30.007127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.097019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-04-07T01:27:30.007217Z","caller":"traceutil/trace.go:171","msg":"trace[1106999458] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1264; }","duration":"233.212312ms","start":"2026-04-07T01:27:29.773979Z","end":"2026-04-07T01:27:30.007191Z","steps":["trace[1106999458] 'range keys from in-memory index tree' (duration: 232.915005ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:27:30.199076Z","caller":"traceutil/trace.go:171","msg":"trace[783994387] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"188.569406ms","start":"2026-04-07T01:27:30.010487Z","end":"2026-04-07T01:27:30.199057Z","steps":["trace[783994387] 'process raft request' (duration: 188.409102ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:27:30.199282Z","caller":"traceutil/trace.go:171","msg":"trace[337799238] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"188.620857ms","start":"2026-04-07T01:27:30.010648Z","end":"2026-04-07T01:27:30.199269Z","steps":["trace[337799238] 'process raft request' (duration: 188.373671ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:27:30.199648Z","caller":"traceutil/trace.go:171","msg":"trace[1938817400] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"188.811653ms","start":"2026-04-07T01:27:30.010825Z","end":"2026-04-07T01:27:30.199637Z","steps":["trace[1938817400] 'process raft request' (duration: 188.401762ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:27:30.199695Z","caller":"traceutil/trace.go:171","msg":"trace[687346125] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"187.49745ms","start":"2026-04-07T01:27:30.012181Z","end":"2026-04-07T01:27:30.199678Z","steps":["trace[687346125] 'process raft request' (duration: 187.371726ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:27:35.516147Z","caller":"traceutil/trace.go:171","msg":"trace[1323811678] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"111.193769ms","start":"2026-04-07T01:27:35.404922Z","end":"2026-04-07T01:27:35.516115Z","steps":["trace[1323811678] 'process raft request' (duration: 110.964263ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:27:35.516359Z","caller":"traceutil/trace.go:171","msg":"trace[1872153798] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"105.131494ms","start":"2026-04-07T01:27:35.411202Z","end":"2026-04-07T01:27:35.516334Z","steps":["trace[1872153798] 'process raft request' (duration: 104.827386ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:27:35.647418Z","caller":"traceutil/trace.go:171","msg":"trace[1350950283] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"119.599413ms","start":"2026-04-07T01:27:35.527794Z","end":"2026-04-07T01:27:35.647393Z","steps":["trace[1350950283] 'process raft request' (duration: 45.22871ms)","trace[1350950283] 'compare' (duration: 73.86215ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:27:38.261812Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334366840685055580,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T01:27:38.585092Z","caller":"traceutil/trace.go:171","msg":"trace[1045115439] linearizableReadLoop","detail":"{readStateIndex:1383; appliedIndex:1382; }","duration":"823.815505ms","start":"2026-04-07T01:27:37.761258Z","end":"2026-04-07T01:27:38.585073Z","steps":["trace[1045115439] 'read index received' (duration: 823.578518ms)","trace[1045115439] 'applied index is now lower than readState.Index' (duration: 236.357µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:27:38.585491Z","caller":"traceutil/trace.go:171","msg":"trace[386091126] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"952.154809ms","start":"2026-04-07T01:27:37.633324Z","end":"2026-04-07T01:27:38.585479Z","steps":["trace[386091126] 'process raft request' (duration: 951.614816ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:38.585598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:37.633297Z","time spent":"952.22097ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T01:27:38.585595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.30127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:27:38.585668Z","caller":"traceutil/trace.go:171","msg":"trace[10477376] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1327; }","duration":"646.443674ms","start":"2026-04-07T01:27:37.939206Z","end":"2026-04-07T01:27:38.58565Z","steps":["trace[10477376] 'agreement among raft nodes before linearized reading' (duration: 646.29985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:38.585714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:37.939176Z","time spent":"646.527285ms","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-04-07T01:27:38.585725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.455971ms","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-04-07T01:27:38.585732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.899369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-07T01:27:38.585778Z","caller":"traceutil/trace.go:171","msg":"trace[822993918] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1327; }","duration":"824.556153ms","start":"2026-04-07T01:27:37.761205Z","end":"2026-04-07T01:27:38.585761Z","steps":["trace[822993918] 'agreement among raft nodes before linearized reading' (duration: 824.291297ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:27:38.585784Z","caller":"traceutil/trace.go:171","msg":"trace[2121457195] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1327; }","duration":"312.97511ms","start":"2026-04-07T01:27:38.272794Z","end":"2026-04-07T01:27:38.585769Z","steps":["trace[2121457195] 'agreement among raft nodes before linearized reading' (duration: 312.802566ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:38.585813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:37.761184Z","time spent":"824.620685ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T01:27:38.58582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:38.272783Z","time spent":"313.029403ms","remote":"127.0.0.1:42506","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-04-07T01:27:38.585817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.598388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:27:38.585911Z","caller":"traceutil/trace.go:171","msg":"trace[1270463806] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1327; }","duration":"431.724321ms","start":"2026-04-07T01:27:38.154157Z","end":"2026-04-07T01:27:38.585881Z","steps":["trace[1270463806] 'agreement among raft nodes before linearized reading' (duration: 431.561097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:38.585945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.862332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-04-07T01:27:38.585953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.014726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-07T01:27:38.585969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:38.154141Z","time spent":"431.819943ms","remote":"127.0.0.1:42252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T01:27:38.585979Z","caller":"traceutil/trace.go:171","msg":"trace[1348642132] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1327; }","duration":"310.060637ms","start":"2026-04-07T01:27:38.275909Z","end":"2026-04-07T01:27:38.58597Z","steps":["trace[1348642132] 'agreement among raft nodes before linearized reading' (duration: 309.985295ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:38.586006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:38.275897Z","time spent":"310.103648ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-07T01:27:38.586017Z","caller":"traceutil/trace.go:171","msg":"trace[808528961] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1327; }","duration":"309.900672ms","start":"2026-04-07T01:27:38.276062Z","end":"2026-04-07T01:27:38.585963Z","steps":["trace[808528961] 'agreement among raft nodes before linearized reading' (duration: 309.81079ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:38.586017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.593277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"warn","ts":"2026-04-07T01:27:38.586041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:38.276056Z","time spent":"309.979755ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-07T01:27:38.58605Z","caller":"traceutil/trace.go:171","msg":"trace[1017123944] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1327; }","duration":"460.662178ms","start":"2026-04-07T01:27:38.125378Z","end":"2026-04-07T01:27:38.586041Z","steps":["trace[1017123944] 'agreement among raft nodes before linearized reading' (duration: 460.558296ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:38.58608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:38.12536Z","time spent":"460.713389ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-07T01:27:38.586149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.940625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"warn","ts":"2026-04-07T01:27:38.5862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.605674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-07T01:27:38.586216Z","caller":"traceutil/trace.go:171","msg":"trace[364035412] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1327; }","duration":"255.043108ms","start":"2026-04-07T01:27:38.331167Z","end":"2026-04-07T01:27:38.58621Z","steps":["trace[364035412] 'agreement among raft nodes before linearized reading' (duration: 254.942205ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:27:38.586226Z","caller":"traceutil/trace.go:171","msg":"trace[1776508609] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1327; }","duration":"320.655667ms","start":"2026-04-07T01:27:38.265563Z","end":"2026-04-07T01:27:38.586218Z","steps":["trace[1776508609] 'agreement among raft nodes before linearized reading' (duration: 320.576155ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:38.586251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:38.265545Z","time spent":"320.700467ms","remote":"127.0.0.1:42506","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":"warn","ts":"2026-04-07T01:27:39.186788Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334366840685055584,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T01:27:39.403194Z","caller":"traceutil/trace.go:171","msg":"trace[874237798] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"813.357869ms","start":"2026-04-07T01:27:38.589807Z","end":"2026-04-07T01:27:39.403165Z","steps":["trace[874237798] 'process raft request' (duration: 810.534608ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:39.403467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:38.589795Z","time spent":"813.584875ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T01:27:39.407996Z","caller":"traceutil/trace.go:171","msg":"trace[120144491] linearizableReadLoop","detail":"{readStateIndex:1391; appliedIndex:1383; }","duration":"721.916492ms","start":"2026-04-07T01:27:38.686061Z","end":"2026-04-07T01:27:39.407978Z","steps":["trace[120144491] 'read index received' (duration: 714.301509ms)","trace[120144491] 'applied index is now lower than readState.Index' (duration: 7.613913ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:27:39.408297Z","caller":"traceutil/trace.go:171","msg":"trace[1519083923] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"818.391537ms","start":"2026-04-07T01:27:38.589889Z","end":"2026-04-07T01:27:39.40828Z","steps":["trace[1519083923] 'process raft request' (duration: 817.711109ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:39.408401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:38.589863Z","time spent":"818.480169ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T01:27:39.408592Z","caller":"traceutil/trace.go:171","msg":"trace[836233455] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"817.645238ms","start":"2026-04-07T01:27:38.590932Z","end":"2026-04-07T01:27:39.408577Z","steps":["trace[836233455] 'process raft request' (duration: 816.756805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:39.408678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:38.590924Z","time spent":"817.711739ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T01:27:39.408799Z","caller":"traceutil/trace.go:171","msg":"trace[962932894] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"817.550185ms","start":"2026-04-07T01:27:38.591235Z","end":"2026-04-07T01:27:39.408786Z","steps":["trace[962932894] 'process raft request' (duration: 816.502848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:39.408879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:38.591229Z","time spent":"817.611407ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T01:27:39.409078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.98968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:27:39.409131Z","caller":"traceutil/trace.go:171","msg":"trace[1296643667] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1335; }","duration":"723.103033ms","start":"2026-04-07T01:27:38.686017Z","end":"2026-04-07T01:27:39.40912Z","steps":["trace[1296643667] 'agreement among raft nodes before linearized reading' (duration: 722.99369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:39.409159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:38.685987Z","time spent":"723.165414ms","remote":"127.0.0.1:58352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-07T01:27:39.409271Z","caller":"traceutil/trace.go:171","msg":"trace[786554922] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"318.76611ms","start":"2026-04-07T01:27:39.09049Z","end":"2026-04-07T01:27:39.409257Z","steps":["trace[786554922] 'process raft request' (duration: 317.442326ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:27:39.409278Z","caller":"traceutil/trace.go:171","msg":"trace[97958270] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"817.968166ms","start":"2026-04-07T01:27:38.591279Z","end":"2026-04-07T01:27:39.409247Z","steps":["trace[97958270] 'process raft request' (duration: 816.511528ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:39.409335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:39.090473Z","time spent":"318.828081ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T01:27:39.409346Z","caller":"traceutil/trace.go:171","msg":"trace[795151061] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"814.146289ms","start":"2026-04-07T01:27:38.595192Z","end":"2026-04-07T01:27:39.409338Z","steps":["trace[795151061] 'process raft request' (duration: 812.698092ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:39.409341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.029775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T01:27:39.409383Z","caller":"traceutil/trace.go:171","msg":"trace[2113891180] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1335; }","duration":"315.078936ms","start":"2026-04-07T01:27:39.094291Z","end":"2026-04-07T01:27:39.409369Z","steps":["trace[2113891180] 'agreement among raft nodes before linearized reading' (duration: 315.012194ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:39.409401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:38.595174Z","time spent":"814.19001ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T01:27:39.409411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:38.591274Z","time spent":"818.064108ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T01:27:39.409414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:39.094286Z","time spent":"315.119637ms","remote":"127.0.0.1:42724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-07T01:27:39.409485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.091695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-04-07T01:27:39.409514Z","caller":"traceutil/trace.go:171","msg":"trace[974182904] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1335; }","duration":"247.136055ms","start":"2026-04-07T01:27:39.162368Z","end":"2026-04-07T01:27:39.409504Z","steps":["trace[974182904] 'agreement among raft nodes before linearized reading' (duration: 247.035913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:39.409566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.614188ms","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-04-07T01:27:39.40959Z","caller":"traceutil/trace.go:171","msg":"trace[1048976631] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1335; }","duration":"320.661898ms","start":"2026-04-07T01:27:39.08892Z","end":"2026-04-07T01:27:39.409582Z","steps":["trace[1048976631] 'agreement among raft nodes before linearized reading' (duration: 320.612167ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:39.409608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:39.088907Z","time spent":"320.69701ms","remote":"127.0.0.1:42734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"info","ts":"2026-04-07T01:27:39.409197Z","caller":"traceutil/trace.go:171","msg":"trace[435511069] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"816.734585ms","start":"2026-04-07T01:27:38.592443Z","end":"2026-04-07T01:27:39.409177Z","steps":["trace[435511069] 'process raft request' (duration: 815.39645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:39.409701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:38.592434Z","time spent":"817.230947ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T01:27:39.409875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.919735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:27:39.40999Z","caller":"traceutil/trace.go:171","msg":"trace[1740824883] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1335; }","duration":"647.776377ms","start":"2026-04-07T01:27:38.762196Z","end":"2026-04-07T01:27:39.409973Z","steps":["trace[1740824883] 'agreement among raft nodes before linearized reading' (duration: 646.926356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:39.410046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:38.762178Z","time spent":"647.852098ms","remote":"127.0.0.1:52014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"info","ts":"2026-04-07T01:27:41.570816Z","caller":"traceutil/trace.go:171","msg":"trace[781754086] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"133.065062ms","start":"2026-04-07T01:27:41.437718Z","end":"2026-04-07T01:27:41.570783Z","steps":["trace[781754086] 'process raft request' (duration: 133.00589ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:27:41.570868Z","caller":"traceutil/trace.go:171","msg":"trace[1130054212] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"134.706193ms","start":"2026-04-07T01:27:41.436125Z","end":"2026-04-07T01:27:41.570831Z","steps":["trace[1130054212] 'process raft request' (duration: 134.383375ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:27:41.57094Z","caller":"traceutil/trace.go:171","msg":"trace[1011822851] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"134.594761ms","start":"2026-04-07T01:27:41.436305Z","end":"2026-04-07T01:27:41.5709Z","steps":["trace[1011822851] 'process raft request' (duration: 134.377235ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:28:09.864894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.306791ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T01:28:09.864999Z","caller":"traceutil/trace.go:171","msg":"trace[116495324] linearizableReadLoop","detail":"{readStateIndex:1531; appliedIndex:1529; }","duration":"269.54122ms","start":"2026-04-07T01:28:09.595445Z","end":"2026-04-07T01:28:09.864986Z","steps":["trace[116495324] 'read index received' (duration: 13.882818ms)","trace[116495324] 'applied index is now lower than readState.Index' (duration: 255.657782ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:28:09.865035Z","caller":"traceutil/trace.go:171","msg":"trace[853949289] transaction","detail":"{read_only:false; response_revision:1469; number_of_response:1; }","duration":"301.963782ms","start":"2026-04-07T01:28:09.56304Z","end":"2026-04-07T01:28:09.865003Z","steps":["trace[853949289] 'process raft request' (duration: 108.469906ms)","trace[853949289] 'compare' (duration: 193.087416ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:28:09.865177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:28:09.563018Z","time spent":"302.083215ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T01:28:09.865199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.349305ms","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-04-07T01:28:09.8653Z","caller":"traceutil/trace.go:171","msg":"trace[1450144857] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1469; }","duration":"257.454847ms","start":"2026-04-07T01:28:09.607826Z","end":"2026-04-07T01:28:09.865281Z","steps":["trace[1450144857] 'agreement among raft nodes before linearized reading' (duration: 257.246121ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:28:09.865358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.269727ms","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-04-07T01:28:09.865386Z","caller":"traceutil/trace.go:171","msg":"trace[682583480] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1469; }","duration":"222.329069ms","start":"2026-04-07T01:28:09.64305Z","end":"2026-04-07T01:28:09.865379Z","steps":["trace[682583480] 'agreement among raft nodes before linearized reading' (duration: 222.273347ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:28:09.865328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.853868ms","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-04-07T01:28:09.86546Z","caller":"traceutil/trace.go:171","msg":"trace[457086042] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1469; }","duration":"270.045013ms","start":"2026-04-07T01:28:09.595387Z","end":"2026-04-07T01:28:09.865432Z","steps":["trace[457086042] 'agreement among raft nodes before linearized reading' (duration: 269.658673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:28:26.141208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.413375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"warn","ts":"2026-04-07T01:28:26.141246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.827636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-07T01:28:26.141297Z","caller":"traceutil/trace.go:171","msg":"trace[1045250424] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1544; }","duration":"102.569069ms","start":"2026-04-07T01:28:26.038705Z","end":"2026-04-07T01:28:26.141274Z","steps":["trace[1045250424] 'range keys from in-memory index tree' (duration: 102.2187ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:28:26.14129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.154464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-04-07T01:28:26.141319Z","caller":"traceutil/trace.go:171","msg":"trace[1910983880] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1544; }","duration":"104.941629ms","start":"2026-04-07T01:28:26.036359Z","end":"2026-04-07T01:28:26.141301Z","steps":["trace[1910983880] 'range keys from in-memory index tree' (duration: 104.66847ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:28:26.141372Z","caller":"traceutil/trace.go:171","msg":"trace[1747506050] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1544; }","duration":"107.330698ms","start":"2026-04-07T01:28:26.034023Z","end":"2026-04-07T01:28:26.141353Z","steps":["trace[1747506050] 'range keys from in-memory index tree' (duration: 107.03635ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:28:26.141436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.233135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-07T01:28:26.141511Z","caller":"traceutil/trace.go:171","msg":"trace[148432404] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1544; }","duration":"103.336008ms","start":"2026-04-07T01:28:26.038156Z","end":"2026-04-07T01:28:26.141492Z","steps":["trace[148432404] 'range keys from in-memory index tree' (duration: 103.063061ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:28:26.142235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.721608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-07T01:28:26.142296Z","caller":"traceutil/trace.go:171","msg":"trace[149338179] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1544; }","duration":"109.853631ms","start":"2026-04-07T01:28:26.032427Z","end":"2026-04-07T01:28:26.142281Z","steps":["trace[149338179] 'range keys from in-memory index tree' (duration: 108.754284ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:29:50.655888Z","caller":"traceutil/trace.go:171","msg":"trace[815291081] transaction","detail":"{read_only:false; response_revision:1932; number_of_response:1; }","duration":"131.954302ms","start":"2026-04-07T01:29:50.523914Z","end":"2026-04-07T01:29:50.655868Z","steps":["trace[815291081] 'process raft request' (duration: 131.843639ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:29:50.972286Z","caller":"traceutil/trace.go:171","msg":"trace[1749797290] transaction","detail":"{read_only:false; response_revision:1933; number_of_response:1; }","duration":"126.254998ms","start":"2026-04-07T01:29:50.846005Z","end":"2026-04-07T01:29:50.97226Z","steps":["trace[1749797290] 'process raft request' (duration: 126.110564ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:29:51.021968Z","caller":"traceutil/trace.go:171","msg":"trace[748072562] transaction","detail":"{read_only:false; response_revision:1937; number_of_response:1; }","duration":"173.773108ms","start":"2026-04-07T01:29:50.848173Z","end":"2026-04-07T01:29:51.021946Z","steps":["trace[748072562] 'process raft request' (duration: 173.732957ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:29:51.021986Z","caller":"traceutil/trace.go:171","msg":"trace[454070197] transaction","detail":"{read_only:false; response_revision:1934; number_of_response:1; }","duration":"174.598198ms","start":"2026-04-07T01:29:50.847352Z","end":"2026-04-07T01:29:51.02195Z","steps":["trace[454070197] 'process raft request' (duration: 174.399193ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:29:51.022284Z","caller":"traceutil/trace.go:171","msg":"trace[299473171] transaction","detail":"{read_only:false; response_revision:1936; number_of_response:1; }","duration":"174.700271ms","start":"2026-04-07T01:29:50.847573Z","end":"2026-04-07T01:29:51.022273Z","steps":["trace[299473171] 'process raft request' (duration: 174.310201ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:29:51.022496Z","caller":"traceutil/trace.go:171","msg":"trace[1457387687] transaction","detail":"{read_only:false; response_revision:1935; number_of_response:1; }","duration":"175.06772ms","start":"2026-04-07T01:29:50.847416Z","end":"2026-04-07T01:29:51.022483Z","steps":["trace[1457387687] 'process raft request' (duration: 174.444225ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:30:25.091913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.635323ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-07T01:30:54.21064Z","caller":"traceutil/trace.go:171","msg":"trace[564362604] transaction","detail":"{read_only:false; response_revision:2245; number_of_response:1; }","duration":"206.743406ms","start":"2026-04-07T01:30:54.003869Z","end":"2026-04-07T01:30:54.210612Z","steps":["trace[564362604] 'process raft request' (duration: 206.563192ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:31:00.786308Z","caller":"traceutil/trace.go:171","msg":"trace[1543071593] transaction","detail":"{read_only:false; response_revision:2282; number_of_response:1; }","duration":"193.419711ms","start":"2026-04-07T01:31:00.592864Z","end":"2026-04-07T01:31:00.786283Z","steps":["trace[1543071593] 'process raft request' (duration: 193.281337ms)"],"step_count":1}