{"level":"warn","ts":"2026-07-30T23:48:52.179735Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-30T23:48:52.179881Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.156: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.156:2380","--initial-cluster=instance=https://199.204.45.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.156: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-07-30T23:48:52.179955Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-30T23:48:52.179967Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-07-30T23:48:52.179984Z","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-07-30T23:48:52.180678Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"]} {"level":"info","ts":"2026-07-30T23:48:52.180755Z","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.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.156: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-07-30T23:48:52.185378Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.196245ms"} {"level":"info","ts":"2026-07-30T23:48:52.192922Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5ac240a6daec2148","cluster-id":"62163637a46c33c2"} {"level":"info","ts":"2026-07-30T23:48:52.192999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=()"} {"level":"info","ts":"2026-07-30T23:48:52.193025Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 0"} {"level":"info","ts":"2026-07-30T23:48:52.19304Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5ac240a6daec2148 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-30T23:48:52.193082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 1"} {"level":"info","ts":"2026-07-30T23:48:52.193134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"warn","ts":"2026-07-30T23:48:52.197886Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-30T23:48:52.199238Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-30T23:48:52.201495Z","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-07-30T23:48:52.204571Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5ac240a6daec2148","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-30T23:48:52.204778Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5ac240a6daec2148","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-30T23:48:52.205075Z","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-07-30T23:48:52.205199Z","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-07-30T23:48:52.205337Z","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-07-30T23:48:52.207537Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"info","ts":"2026-07-30T23:48:52.207903Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","added-peer-id":"5ac240a6daec2148","added-peer-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-07-30T23:48:52.209915Z","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-07-30T23:48:52.210342Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-30T23:48:52.210215Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5ac240a6daec2148","initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-30T23:48:52.211313Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-07-30T23:48:52.21142Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-07-30T23:48:52.793736Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 is starting a new election at term 1"} {"level":"info","ts":"2026-07-30T23:48:52.793968Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-30T23:48:52.794085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgPreVoteResp from 5ac240a6daec2148 at term 1"} {"level":"info","ts":"2026-07-30T23:48:52.794181Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became candidate at term 2"} {"level":"info","ts":"2026-07-30T23:48:52.794261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgVoteResp from 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-07-30T23:48:52.794338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became leader at term 2"} {"level":"info","ts":"2026-07-30T23:48:52.794405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5ac240a6daec2148 elected leader 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-07-30T23:48:52.795439Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T23:48:52.796254Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5ac240a6daec2148","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.156:2379]}","request-path":"/0/members/5ac240a6daec2148/attributes","cluster-id":"62163637a46c33c2","publish-timeout":"7s"} {"level":"info","ts":"2026-07-30T23:48:52.796389Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T23:48:52.796425Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T23:48:52.796638Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-30T23:48:52.802966Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-30T23:48:52.797258Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T23:48:52.80326Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T23:48:52.803383Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T23:48:52.799654Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.156:2379"} {"level":"info","ts":"2026-07-30T23:48:52.80276Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-30T23:49:16.218979Z","caller":"traceutil/trace.go:171","msg":"trace[232367410] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"231.794605ms","start":"2026-07-30T23:49:15.987148Z","end":"2026-07-30T23:49:16.218943Z","steps":["trace[232367410] 'process raft request' (duration: 231.599939ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:49:16.330193Z","caller":"traceutil/trace.go:171","msg":"trace[1336032226] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"106.061727ms","start":"2026-07-30T23:49:16.224083Z","end":"2026-07-30T23:49:16.330145Z","steps":["trace[1336032226] 'process raft request' (duration: 97.194126ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:49:26.500911Z","caller":"traceutil/trace.go:171","msg":"trace[1217804494] linearizableReadLoop","detail":"{readStateIndex:461; appliedIndex:460; }","duration":"102.481973ms","start":"2026-07-30T23:49:26.39841Z","end":"2026-07-30T23:49:26.500892Z","steps":["trace[1217804494] 'read index received' (duration: 102.267227ms)","trace[1217804494] 'applied index is now lower than readState.Index' (duration: 213.976µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:49:26.50107Z","caller":"traceutil/trace.go:171","msg":"trace[1226334221] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"132.913817ms","start":"2026-07-30T23:49:26.368116Z","end":"2026-07-30T23:49:26.50103Z","steps":["trace[1226334221] 'process raft request' (duration: 132.655459ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:26.501123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.660909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:49:26.50118Z","caller":"traceutil/trace.go:171","msg":"trace[2088299735] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:0; response_revision:441; }","duration":"102.766592ms","start":"2026-07-30T23:49:26.3984Z","end":"2026-07-30T23:49:26.501166Z","steps":["trace[2088299735] 'agreement among raft nodes before linearized reading' (duration: 102.609517ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:49:29.129438Z","caller":"traceutil/trace.go:171","msg":"trace[1612111931] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"116.756545ms","start":"2026-07-30T23:49:29.012622Z","end":"2026-07-30T23:49:29.129379Z","steps":["trace[1612111931] 'process raft request' (duration: 108.738271ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:49:29.307204Z","caller":"traceutil/trace.go:171","msg":"trace[2017018503] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"135.472348ms","start":"2026-07-30T23:49:29.171701Z","end":"2026-07-30T23:49:29.307174Z","steps":["trace[2017018503] 'process raft request' (duration: 134.27586ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:49:29.318341Z","caller":"traceutil/trace.go:171","msg":"trace[469529932] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"101.112101ms","start":"2026-07-30T23:49:29.217208Z","end":"2026-07-30T23:49:29.31832Z","steps":["trace[469529932] 'process raft request' (duration: 100.726228ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:30.044335Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398342403163121054,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T23:49:30.50103Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.114511162s","expected-duration":"1s"} {"level":"info","ts":"2026-07-30T23:49:30.508095Z","caller":"traceutil/trace.go:171","msg":"trace[1006737686] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"1.121649297s","start":"2026-07-30T23:49:29.386396Z","end":"2026-07-30T23:49:30.508045Z","steps":["trace[1006737686] 'process raft request' (duration: 1.114837661s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:30.509698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:29.38637Z","time spent":"1.121918076s","remote":"127.0.0.1:39202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20842,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:49:30.513997Z","caller":"traceutil/trace.go:171","msg":"trace[1627433149] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"1.089630263s","start":"2026-07-30T23:49:29.423777Z","end":"2026-07-30T23:49:30.513407Z","steps":["trace[1627433149] 'process raft request' (duration: 1.089344554s)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:49:30.514038Z","caller":"traceutil/trace.go:171","msg":"trace[1255685066] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"1.078971556s","start":"2026-07-30T23:49:29.435034Z","end":"2026-07-30T23:49:30.514006Z","steps":["trace[1255685066] 'process raft request' (duration: 1.078217053s)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:49:30.514059Z","caller":"traceutil/trace.go:171","msg":"trace[2042148744] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"166.115077ms","start":"2026-07-30T23:49:30.347902Z","end":"2026-07-30T23:49:30.514017Z","steps":["trace[2042148744] 'process raft request' (duration: 165.474507ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:30.514333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:29.42373Z","time spent":"1.090386828s","remote":"127.0.0.1:39202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14661,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T23:49:30.514663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:29.435007Z","time spent":"1.079127332s","remote":"127.0.0.1:39202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74754,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:49:30.514899Z","caller":"traceutil/trace.go:171","msg":"trace[1519174389] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:589; }","duration":"969.664807ms","start":"2026-07-30T23:49:29.54377Z","end":"2026-07-30T23:49:30.513434Z","steps":["trace[1519174389] 'read index received' (duration: 957.478052ms)","trace[1519174389] 'applied index is now lower than readState.Index' (duration: 12.185655ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:49:30.516377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"972.59532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:49:30.516522Z","caller":"traceutil/trace.go:171","msg":"trace[2000682348] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:572; }","duration":"972.757005ms","start":"2026-07-30T23:49:29.543725Z","end":"2026-07-30T23:49:30.516482Z","steps":["trace[2000682348] 'agreement among raft nodes before linearized reading' (duration: 969.729699ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:30.516608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:29.543701Z","time spent":"972.889359ms","remote":"127.0.0.1:39202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-07-30T23:49:30.517125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.740416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T23:49:30.517271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.343077ms","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-07-30T23:49:30.517216Z","caller":"traceutil/trace.go:171","msg":"trace[831696832] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:572; }","duration":"373.19088ms","start":"2026-07-30T23:49:30.143999Z","end":"2026-07-30T23:49:30.51719Z","steps":["trace[831696832] 'agreement among raft nodes before linearized reading' (duration: 372.738706ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:49:30.517365Z","caller":"traceutil/trace.go:171","msg":"trace[40975907] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:572; }","duration":"241.472422ms","start":"2026-07-30T23:49:30.275876Z","end":"2026-07-30T23:49:30.517348Z","steps":["trace[40975907] 'agreement among raft nodes before linearized reading' (duration: 241.032258ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:30.517897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:30.143968Z","time spent":"373.473719ms","remote":"127.0.0.1:39202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-07-30T23:49:30.518485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.511882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T23:49:30.518511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.276484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:49:30.518768Z","caller":"traceutil/trace.go:171","msg":"trace[104297461] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:572; }","duration":"574.849653ms","start":"2026-07-30T23:49:29.943742Z","end":"2026-07-30T23:49:30.518591Z","steps":["trace[104297461] 'agreement among raft nodes before linearized reading' (duration: 574.528372ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:49:30.519172Z","caller":"traceutil/trace.go:171","msg":"trace[581911015] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:572; }","duration":"774.596734ms","start":"2026-07-30T23:49:29.744183Z","end":"2026-07-30T23:49:30.51878Z","steps":["trace[581911015] 'agreement among raft nodes before linearized reading' (duration: 774.285414ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:30.519278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:29.744163Z","time spent":"775.10061ms","remote":"127.0.0.1:39202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-07-30T23:49:30.518879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:29.94371Z","time spent":"575.151062ms","remote":"127.0.0.1:39202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-07-30T23:49:30.519797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"965.00068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-cmfzz\" ","response":"range_response_count:1 size:4683"} {"level":"info","ts":"2026-07-30T23:49:30.519888Z","caller":"traceutil/trace.go:171","msg":"trace[694774618] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-cmfzz; range_end:; response_count:1; response_revision:572; }","duration":"965.160715ms","start":"2026-07-30T23:49:29.554706Z","end":"2026-07-30T23:49:30.519866Z","steps":["trace[694774618] 'agreement among raft nodes before linearized reading' (duration: 961.251571ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:30.51999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:29.554698Z","time spent":"965.278848ms","remote":"127.0.0.1:39348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4705,"request content":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-cmfzz\" "} {"level":"warn","ts":"2026-07-30T23:49:36.800778Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398342403163121309,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T23:49:37.301955Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398342403163121309,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T23:49:37.802391Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398342403163121309,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T23:49:38.300075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999821528s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-30T23:49:38.300167Z","caller":"traceutil/trace.go:171","msg":"trace[1447349009] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999929942s","start":"2026-07-30T23:49:36.300224Z","end":"2026-07-30T23:49:38.300154Z","steps":["trace[1447349009] 'agreement among raft nodes before linearized reading' (duration: 1.999820918s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:38.300195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:36.300152Z","time spent":"2.000036946s","remote":"127.0.0.1:39148","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-07-30T23:49:38.302977Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398342403163121309,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T23:49:38.674005Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.483221837s","expected-duration":"1s"} {"level":"info","ts":"2026-07-30T23:49:38.675187Z","caller":"traceutil/trace.go:171","msg":"trace[909676640] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"2.493150061s","start":"2026-07-30T23:49:36.18201Z","end":"2026-07-30T23:49:38.67516Z","steps":["trace[909676640] 'process raft request' (duration: 2.492558253s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:38.675343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:36.181974Z","time spent":"2.493288936s","remote":"127.0.0.1:39246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036297,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:49:39.406019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.467336ms","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-07-30T23:49:39.406145Z","caller":"traceutil/trace.go:171","msg":"trace[1709972871] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:639; }","duration":"298.607971ms","start":"2026-07-30T23:49:39.107519Z","end":"2026-07-30T23:49:39.406127Z","steps":["trace[1709972871] 'range keys from in-memory index tree' (duration: 298.451135ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:39.406857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.190438ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T23:49:39.40698Z","caller":"traceutil/trace.go:171","msg":"trace[1137628698] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"1.39604296s","start":"2026-07-30T23:49:38.010924Z","end":"2026-07-30T23:49:39.406967Z","steps":["trace[1137628698] 'process raft request' (duration: 1.084551392s)","trace[1137628698] 'compare' (duration: 310.996373ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:49:39.407236Z","caller":"traceutil/trace.go:171","msg":"trace[1126267197] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:661; }","duration":"3.106954796s","start":"2026-07-30T23:49:36.300244Z","end":"2026-07-30T23:49:39.407199Z","steps":["trace[1126267197] 'read index received' (duration: 2.373946769s)","trace[1126267197] 'applied index is now lower than readState.Index' (duration: 733.005386ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:49:39.407465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:38.010902Z","time spent":"1.396523155s","remote":"127.0.0.1:39442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:49:39.407318Z","caller":"traceutil/trace.go:171","msg":"trace[369562514] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"1.104204345s","start":"2026-07-30T23:49:38.303062Z","end":"2026-07-30T23:49:39.407266Z","steps":["trace[369562514] 'process raft request' (duration: 1.103860224s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:39.407745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:38.303046Z","time spent":"1.104615597s","remote":"127.0.0.1:39220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T23:49:39.435482Z","caller":"traceutil/trace.go:171","msg":"trace[491415171] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"697.845074ms","start":"2026-07-30T23:49:38.737613Z","end":"2026-07-30T23:49:39.435458Z","steps":["trace[491415171] 'process raft request' (duration: 697.73497ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:39.435663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:38.737585Z","time spent":"697.988108ms","remote":"127.0.0.1:39418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T23:49:39.43576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.630501577s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-30T23:49:39.435798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.876837793s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-30T23:49:39.43582Z","caller":"traceutil/trace.go:171","msg":"trace[1173090260] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:642; }","duration":"2.630629771s","start":"2026-07-30T23:49:36.805173Z","end":"2026-07-30T23:49:39.435803Z","steps":["trace[1173090260] 'agreement among raft nodes before linearized reading' (duration: 2.630475116s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:39.435807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.867919791s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-z2n62\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-07-30T23:49:39.435854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:36.805139Z","time spent":"2.630709553s","remote":"127.0.0.1:39442","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-07-30T23:49:39.435856Z","caller":"traceutil/trace.go:171","msg":"trace[194495507] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:642; }","duration":"2.876950607s","start":"2026-07-30T23:49:36.55889Z","end":"2026-07-30T23:49:39.43584Z","steps":["trace[194495507] 'agreement among raft nodes before linearized reading' (duration: 2.87673761s)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:49:39.435867Z","caller":"traceutil/trace.go:171","msg":"trace[42311871] range","detail":"{range_begin:/registry/pods/kube-system/cilium-z2n62; range_end:; response_count:1; response_revision:642; }","duration":"2.868005013s","start":"2026-07-30T23:49:36.567847Z","end":"2026-07-30T23:49:39.435852Z","steps":["trace[42311871] 'agreement among raft nodes before linearized reading' (duration: 2.867868099s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:39.435866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.875222672s","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-07-30T23:49:39.435901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:36.55886Z","time spent":"2.877030229s","remote":"127.0.0.1:39442","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-07-30T23:49:39.435904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:36.567823Z","time spent":"2.868072975s","remote":"127.0.0.1:39348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-z2n62\" "} {"level":"info","ts":"2026-07-30T23:49:39.435914Z","caller":"traceutil/trace.go:171","msg":"trace[1365731074] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:642; }","duration":"2.875307855s","start":"2026-07-30T23:49:36.560594Z","end":"2026-07-30T23:49:39.435902Z","steps":["trace[1365731074] 'agreement among raft nodes before linearized reading' (duration: 2.875181891s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:39.435949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:36.560568Z","time spent":"2.875374447s","remote":"127.0.0.1:39442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-30T23:49:39.436033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.62618411s","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-07-30T23:49:39.436051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.725996389s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-30T23:49:39.436082Z","caller":"traceutil/trace.go:171","msg":"trace[1936912427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:642; }","duration":"2.72602575s","start":"2026-07-30T23:49:36.710046Z","end":"2026-07-30T23:49:39.436072Z","steps":["trace[1936912427] 'agreement among raft nodes before linearized reading' (duration: 2.725942767s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:39.43612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:36.709958Z","time spent":"2.726155294s","remote":"127.0.0.1:39442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-30T23:49:39.436381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.127844881s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:49:39.436424Z","caller":"traceutil/trace.go:171","msg":"trace[1225860942] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"1.127907334s","start":"2026-07-30T23:49:38.308506Z","end":"2026-07-30T23:49:39.436413Z","steps":["trace[1225860942] 'agreement among raft nodes before linearized reading' (duration: 1.127833591s)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:49:39.43608Z","caller":"traceutil/trace.go:171","msg":"trace[1619441605] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:642; }","duration":"2.626265643s","start":"2026-07-30T23:49:36.809803Z","end":"2026-07-30T23:49:39.436068Z","steps":["trace[1619441605] 'agreement among raft nodes before linearized reading' (duration: 2.62617762s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:39.436506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:38.308496Z","time spent":"1.127994196s","remote":"127.0.0.1:39150","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-07-30T23:49:39.436518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:36.809782Z","time spent":"2.626720018s","remote":"127.0.0.1:39442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-30T23:49:40.331944Z","caller":"traceutil/trace.go:171","msg":"trace[1823625451] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:665; }","duration":"889.343585ms","start":"2026-07-30T23:49:39.44258Z","end":"2026-07-30T23:49:40.331924Z","steps":["trace[1823625451] 'read index received' (duration: 17.481443ms)","trace[1823625451] 'applied index is now lower than readState.Index' (duration: 871.860862ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:49:40.332243Z","caller":"traceutil/trace.go:171","msg":"trace[1199860460] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"890.697269ms","start":"2026-07-30T23:49:39.441514Z","end":"2026-07-30T23:49:40.332211Z","steps":["trace[1199860460] 'process raft request' (duration: 874.64366ms)","trace[1199860460] 'compare' (duration: 15.1499ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:49:40.332321Z","caller":"traceutil/trace.go:171","msg":"trace[51973890] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"878.847043ms","start":"2026-07-30T23:49:39.453452Z","end":"2026-07-30T23:49:40.332299Z","steps":["trace[51973890] 'process raft request' (duration: 878.43517ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:40.332339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"884.529783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:49:40.332347Z","caller":"traceutil/trace.go:171","msg":"trace[1248370151] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"890.491551ms","start":"2026-07-30T23:49:39.441837Z","end":"2026-07-30T23:49:40.332329Z","steps":["trace[1248370151] 'process raft request' (duration: 890.023736ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:49:40.332372Z","caller":"traceutil/trace.go:171","msg":"trace[144232564] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:648; }","duration":"884.608165ms","start":"2026-07-30T23:49:39.447756Z","end":"2026-07-30T23:49:40.332364Z","steps":["trace[144232564] 'agreement among raft nodes before linearized reading' (duration: 884.539723ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:49:40.332343Z","caller":"traceutil/trace.go:171","msg":"trace[126866404] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"890.601865ms","start":"2026-07-30T23:49:39.44171Z","end":"2026-07-30T23:49:40.332312Z","steps":["trace[126866404] 'process raft request' (duration: 890.107299ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:40.332399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:39.447738Z","time spent":"884.653347ms","remote":"127.0.0.1:39150","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-07-30T23:49:40.332389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:39.441507Z","time spent":"890.801392ms","remote":"127.0.0.1:39442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:49:40.33224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"889.635165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-07-30T23:49:40.332457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:39.453438Z","time spent":"878.924005ms","remote":"127.0.0.1:39348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:49:40.332477Z","caller":"traceutil/trace.go:171","msg":"trace[2082061594] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:648; }","duration":"889.921324ms","start":"2026-07-30T23:49:39.442545Z","end":"2026-07-30T23:49:40.332466Z","steps":["trace[2082061594] 'agreement among raft nodes before linearized reading' (duration: 889.575683ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:40.332481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:39.441829Z","time spent":"890.561064ms","remote":"127.0.0.1:39442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:49:40.332511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:39.442527Z","time spent":"889.974696ms","remote":"127.0.0.1:39370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":215,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-07-30T23:49:40.332536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:39.441689Z","time spent":"890.724489ms","remote":"127.0.0.1:39442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:49:40.33258Z","caller":"traceutil/trace.go:171","msg":"trace[1181697258] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"890.993528ms","start":"2026-07-30T23:49:39.441569Z","end":"2026-07-30T23:49:40.332562Z","steps":["trace[1181697258] 'process raft request' (duration: 890.167612ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:40.332667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:39.441564Z","time spent":"891.055019ms","remote":"127.0.0.1:39442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:49:40.622626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.688463ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-30T23:49:40.623189Z","caller":"traceutil/trace.go:171","msg":"trace[283979990] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"218.867006ms","start":"2026-07-30T23:49:40.404062Z","end":"2026-07-30T23:49:40.622929Z","steps":["trace[283979990] 'process raft request' (duration: 218.814304ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:49:40.623333Z","caller":"traceutil/trace.go:171","msg":"trace[574272420] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"220.24231ms","start":"2026-07-30T23:49:40.403078Z","end":"2026-07-30T23:49:40.623321Z","steps":["trace[574272420] 'process raft request' (duration: 219.748965ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:49:40.623343Z","caller":"traceutil/trace.go:171","msg":"trace[1114532373] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"221.561502ms","start":"2026-07-30T23:49:40.40172Z","end":"2026-07-30T23:49:40.623282Z","steps":["trace[1114532373] 'process raft request' (duration: 92.142756ms)","trace[1114532373] 'compare' (duration: 128.567079ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:49:40.623429Z","caller":"traceutil/trace.go:171","msg":"trace[2074826405] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"220.756966ms","start":"2026-07-30T23:49:40.402629Z","end":"2026-07-30T23:49:40.623386Z","steps":["trace[2074826405] 'process raft request' (duration: 220.107976ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:41.801947Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398342403163121326,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T23:49:42.302489Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398342403163121326,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T23:49:42.573402Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.316617056s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-30T23:49:43.301302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000470878s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-30T23:49:43.301397Z","caller":"traceutil/trace.go:171","msg":"trace[630238006] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000618753s","start":"2026-07-30T23:49:41.300758Z","end":"2026-07-30T23:49:43.301377Z","steps":["trace[630238006] 'agreement among raft nodes before linearized reading' (duration: 2.00050748s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:43.301448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:41.300732Z","time spent":"2.000703556s","remote":"127.0.0.1:39150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/30 23:49:43 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-30T23:49:43.69849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.124864499s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-30T23:49:43.698593Z","caller":"traceutil/trace.go:171","msg":"trace[696468515] linearizableReadLoop","detail":"{readStateIndex:677; appliedIndex:676; }","duration":"2.397773362s","start":"2026-07-30T23:49:41.300803Z","end":"2026-07-30T23:49:43.698577Z","steps":["trace[696468515] 'read index received' (duration: 1.272709786s)","trace[696468515] 'applied index is now lower than readState.Index' (duration: 1.125062116s)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:49:43.698839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.252962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T23:49:43.698866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.360121553s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-30T23:49:43.698892Z","caller":"traceutil/trace.go:171","msg":"trace[518697438] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:653; }","duration":"397.311544ms","start":"2026-07-30T23:49:43.301565Z","end":"2026-07-30T23:49:43.698877Z","steps":["trace[518697438] 'agreement among raft nodes before linearized reading' (duration: 397.19025ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:43.698909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.360155934s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-30T23:49:43.69893Z","caller":"traceutil/trace.go:171","msg":"trace[1275799012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:653; }","duration":"1.360209556s","start":"2026-07-30T23:49:42.338705Z","end":"2026-07-30T23:49:43.698915Z","steps":["trace[1275799012] 'agreement among raft nodes before linearized reading' (duration: 1.360031501s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:43.698944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:43.301511Z","time spent":"397.420577ms","remote":"127.0.0.1:39148","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-07-30T23:49:43.698962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.360818296s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-30T23:49:43.699002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.360659631s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-30T23:49:43.699066Z","caller":"traceutil/trace.go:171","msg":"trace[679827804] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:653; }","duration":"1.360773304s","start":"2026-07-30T23:49:42.338278Z","end":"2026-07-30T23:49:43.699051Z","steps":["trace[679827804] 'agreement among raft nodes before linearized reading' (duration: 1.360566567s)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:49:43.698967Z","caller":"traceutil/trace.go:171","msg":"trace[864550108] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:653; }","duration":"1.360250128s","start":"2026-07-30T23:49:42.338704Z","end":"2026-07-30T23:49:43.698954Z","steps":["trace[864550108] 'agreement among raft nodes before linearized reading' (duration: 1.360067862s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:43.699115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:42.338244Z","time spent":"1.360859986s","remote":"127.0.0.1:39442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-30T23:49:43.699146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:42.338693Z","time spent":"1.360445803s","remote":"127.0.0.1:39442","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-07-30T23:49:43.699014Z","caller":"traceutil/trace.go:171","msg":"trace[1193718833] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:653; }","duration":"1.360862377s","start":"2026-07-30T23:49:42.338135Z","end":"2026-07-30T23:49:43.698998Z","steps":["trace[1193718833] 'agreement among raft nodes before linearized reading' (duration: 1.360772064s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:43.699239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:42.338091Z","time spent":"1.361141035s","remote":"127.0.0.1:39442","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-07-30T23:49:43.698972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:42.338699Z","time spent":"1.360265158s","remote":"127.0.0.1:39442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-30T23:49:43.69887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.234793614s","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-07-30T23:49:43.69935Z","caller":"traceutil/trace.go:171","msg":"trace[1242737893] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:653; }","duration":"2.235332801s","start":"2026-07-30T23:49:41.464009Z","end":"2026-07-30T23:49:43.699342Z","steps":["trace[1242737893] 'agreement among raft nodes before linearized reading' (duration: 2.234716891s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:43.699388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:41.463989Z","time spent":"2.235391123s","remote":"127.0.0.1:39442","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-07-30T23:50:00.118842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.566845336s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T23:50:00.118948Z","caller":"traceutil/trace.go:171","msg":"trace[984449733] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:767; }","duration":"1.567006571s","start":"2026-07-30T23:49:58.551919Z","end":"2026-07-30T23:50:00.118926Z","steps":["trace[984449733] 'count revisions from in-memory index tree' (duration: 1.566769644s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:50:00.119017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:58.5519Z","time spent":"1.567103275s","remote":"127.0.0.1:39604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-07-30T23:50:00.119935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.011503852s","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-07-30T23:50:00.120093Z","caller":"traceutil/trace.go:171","msg":"trace[122821202] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:767; }","duration":"1.011615785s","start":"2026-07-30T23:49:59.108412Z","end":"2026-07-30T23:50:00.120028Z","steps":["trace[122821202] 'range keys from in-memory index tree' (duration: 1.011493701s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:50:00.120202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.441743507s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-48b7q\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-07-30T23:50:00.120357Z","caller":"traceutil/trace.go:171","msg":"trace[2031830894] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-48b7q; range_end:; response_count:1; response_revision:767; }","duration":"1.442621455s","start":"2026-07-30T23:49:58.6777Z","end":"2026-07-30T23:50:00.120321Z","steps":["trace[2031830894] 'range keys from in-memory index tree' (duration: 1.44150311s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:50:00.120467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:58.677674Z","time spent":"1.442748999s","remote":"127.0.0.1:39348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-48b7q\" "} {"level":"warn","ts":"2026-07-30T23:50:00.194963Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.321831352s","expected-duration":"1s"} {"level":"info","ts":"2026-07-30T23:50:00.195443Z","caller":"traceutil/trace.go:171","msg":"trace[2132087225] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"1.322422931s","start":"2026-07-30T23:49:58.872992Z","end":"2026-07-30T23:50:00.195415Z","steps":["trace[2132087225] 'process raft request' (duration: 1.322240635s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:50:00.195609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:58.872962Z","time spent":"1.322557215s","remote":"127.0.0.1:39342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4905,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:50:00.20259Z","caller":"traceutil/trace.go:171","msg":"trace[1868697781] linearizableReadLoop","detail":"{readStateIndex:798; appliedIndex:796; }","duration":"408.228189ms","start":"2026-07-30T23:49:59.794335Z","end":"2026-07-30T23:50:00.202563Z","steps":["trace[1868697781] 'read index received' (duration: 400.913938ms)","trace[1868697781] 'applied index is now lower than readState.Index' (duration: 7.313371ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:50:00.202682Z","caller":"traceutil/trace.go:171","msg":"trace[709635331] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"489.735509ms","start":"2026-07-30T23:49:59.712911Z","end":"2026-07-30T23:50:00.202646Z","steps":["trace[709635331] 'process raft request' (duration: 489.4718ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:50:00.203205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.86007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-30T23:50:00.203262Z","caller":"traceutil/trace.go:171","msg":"trace[1773633248] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:770; }","duration":"408.964013ms","start":"2026-07-30T23:49:59.794283Z","end":"2026-07-30T23:50:00.203247Z","steps":["trace[1773633248] 'agreement among raft nodes before linearized reading' (duration: 408.774367ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:50:00.203308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:59.79426Z","time spent":"409.042146ms","remote":"127.0.0.1:39442","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-07-30T23:50:00.203568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.230189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-30T23:50:00.203625Z","caller":"traceutil/trace.go:171","msg":"trace[1932764171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:770; }","duration":"408.496888ms","start":"2026-07-30T23:49:59.795113Z","end":"2026-07-30T23:50:00.20361Z","steps":["trace[1932764171] 'agreement among raft nodes before linearized reading' (duration: 408.174068ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:50:00.203647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.617465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-30T23:50:00.203697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:59.79509Z","time spent":"408.596001ms","remote":"127.0.0.1:39442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-30T23:50:00.203666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.411055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-30T23:50:00.203874Z","caller":"traceutil/trace.go:171","msg":"trace[599084443] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:770; }","duration":"408.663093ms","start":"2026-07-30T23:49:59.795198Z","end":"2026-07-30T23:50:00.203861Z","steps":["trace[599084443] 'agreement among raft nodes before linearized reading' (duration: 408.278651ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:50:00.203912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:59.795188Z","time spent":"408.713615ms","remote":"127.0.0.1:39442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-30T23:50:00.203745Z","caller":"traceutil/trace.go:171","msg":"trace[1225654051] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:770; }","duration":"404.7592ms","start":"2026-07-30T23:49:59.798966Z","end":"2026-07-30T23:50:00.203726Z","steps":["trace[1225654051] 'agreement among raft nodes before linearized reading' (duration: 404.534693ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:50:00.204024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:59.798945Z","time spent":"405.065599ms","remote":"127.0.0.1:39442","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-07-30T23:50:00.203753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.64454ms","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-07-30T23:50:00.204154Z","caller":"traceutil/trace.go:171","msg":"trace[563949293] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:770; }","duration":"407.282589ms","start":"2026-07-30T23:49:59.796864Z","end":"2026-07-30T23:50:00.204147Z","steps":["trace[563949293] 'agreement among raft nodes before linearized reading' (duration: 406.597448ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:50:00.204177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:59.796855Z","time spent":"407.31665ms","remote":"127.0.0.1:39442","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-07-30T23:50:00.204976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:59.712882Z","time spent":"490.284326ms","remote":"127.0.0.1:39442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:50:18.92294Z","caller":"traceutil/trace.go:171","msg":"trace[1005084011] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"150.381621ms","start":"2026-07-30T23:50:18.772535Z","end":"2026-07-30T23:50:18.922916Z","steps":["trace[1005084011] 'process raft request' (duration: 150.219626ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:50:34.553722Z","caller":"traceutil/trace.go:171","msg":"trace[488099068] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"126.514674ms","start":"2026-07-30T23:50:34.427175Z","end":"2026-07-30T23:50:34.55369Z","steps":["trace[488099068] 'process raft request' (duration: 126.331878ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:50:35.547037Z","caller":"traceutil/trace.go:171","msg":"trace[710412428] linearizableReadLoop","detail":"{readStateIndex:1027; appliedIndex:1022; }","duration":"1.117416791s","start":"2026-07-30T23:50:34.429602Z","end":"2026-07-30T23:50:35.547019Z","steps":["trace[710412428] 'read index received' (duration: 123.942702ms)","trace[710412428] 'applied index is now lower than readState.Index' (duration: 993.473179ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:50:35.547056Z","caller":"traceutil/trace.go:171","msg":"trace[989949358] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"1.119301502s","start":"2026-07-30T23:50:34.427727Z","end":"2026-07-30T23:50:35.547028Z","steps":["trace[989949358] 'process raft request' (duration: 1.119093255s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:50:35.547164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.117543285s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:50:35.54719Z","caller":"traceutil/trace.go:171","msg":"trace[18433229] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:989; }","duration":"1.117611997s","start":"2026-07-30T23:50:34.42957Z","end":"2026-07-30T23:50:35.547182Z","steps":["trace[18433229] 'agreement among raft nodes before linearized reading' (duration: 1.117524335s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:50:35.547193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:50:34.427718Z","time spent":"1.119406815s","remote":"127.0.0.1:39442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:50:35.547214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:50:34.429556Z","time spent":"1.117653489s","remote":"127.0.0.1:39150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-30T23:50:35.547216Z","caller":"traceutil/trace.go:171","msg":"trace[630703757] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"1.119413635s","start":"2026-07-30T23:50:34.427779Z","end":"2026-07-30T23:50:35.547193Z","steps":["trace[630703757] 'process raft request' (duration: 1.119165297s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:50:35.547329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:50:34.427775Z","time spent":"1.119475047s","remote":"127.0.0.1:39442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:50:35.547067Z","caller":"traceutil/trace.go:171","msg":"trace[1304145432] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"1.119226629s","start":"2026-07-30T23:50:34.427813Z","end":"2026-07-30T23:50:35.54704Z","steps":["trace[1304145432] 'process raft request' (duration: 1.119155547s)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:50:35.547382Z","caller":"traceutil/trace.go:171","msg":"trace[1622762757] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"1.119454596s","start":"2026-07-30T23:50:34.427919Z","end":"2026-07-30T23:50:35.547374Z","steps":["trace[1622762757] 'process raft request' (duration: 1.119073054s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:50:35.547453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:50:34.427914Z","time spent":"1.119488257s","remote":"127.0.0.1:39442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:50:35.547607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:50:34.427808Z","time spent":"1.119600781s","remote":"127.0.0.1:39442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:50:35.597597Z","caller":"traceutil/trace.go:171","msg":"trace[1341090304] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"609.347112ms","start":"2026-07-30T23:50:34.988229Z","end":"2026-07-30T23:50:35.597576Z","steps":["trace[1341090304] 'process raft request' (duration: 582.750508ms)","trace[1341090304] 'compare' (duration: 26.274254ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:50:35.597697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:50:34.988207Z","time spent":"609.432405ms","remote":"127.0.0.1:39442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:50:59.8732Z","caller":"traceutil/trace.go:171","msg":"trace[1052736315] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"224.678618ms","start":"2026-07-30T23:50:59.648478Z","end":"2026-07-30T23:50:59.873157Z","steps":["trace[1052736315] 'process raft request' (duration: 224.537694ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:50:59.873231Z","caller":"traceutil/trace.go:171","msg":"trace[370940206] linearizableReadLoop","detail":"{readStateIndex:1109; appliedIndex:1109; }","duration":"219.582627ms","start":"2026-07-30T23:50:59.653614Z","end":"2026-07-30T23:50:59.873196Z","steps":["trace[370940206] 'read index received' (duration: 219.567006ms)","trace[370940206] 'applied index is now lower than readState.Index' (duration: 13.6µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:50:59.873425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.791973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-30T23:50:59.873469Z","caller":"traceutil/trace.go:171","msg":"trace[2078218648] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1066; }","duration":"219.883776ms","start":"2026-07-30T23:50:59.653577Z","end":"2026-07-30T23:50:59.87346Z","steps":["trace[2078218648] 'agreement among raft nodes before linearized reading' (duration: 219.70056ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:50:59.906573Z","caller":"traceutil/trace.go:171","msg":"trace[14962501] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"247.042587ms","start":"2026-07-30T23:50:59.6595Z","end":"2026-07-30T23:50:59.906543Z","steps":["trace[14962501] 'process raft request' (duration: 246.843651ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:50:59.906704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.771764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-30T23:50:59.90678Z","caller":"traceutil/trace.go:171","msg":"trace[1533549621] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1067; }","duration":"237.890227ms","start":"2026-07-30T23:50:59.668874Z","end":"2026-07-30T23:50:59.906764Z","steps":["trace[1533549621] 'agreement among raft nodes before linearized reading' (duration: 237.689221ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:50:59.906655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.660096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-30T23:50:59.906906Z","caller":"traceutil/trace.go:171","msg":"trace[994826213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1067; }","duration":"252.930884ms","start":"2026-07-30T23:50:59.653955Z","end":"2026-07-30T23:50:59.906886Z","steps":["trace[994826213] 'agreement among raft nodes before linearized reading' (duration: 252.532892ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:51:00.668609Z","caller":"traceutil/trace.go:171","msg":"trace[1571216129] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"791.103458ms","start":"2026-07-30T23:50:59.877457Z","end":"2026-07-30T23:51:00.66856Z","steps":["trace[1571216129] 'process raft request' (duration: 761.769257ms)","trace[1571216129] 'compare' (duration: 28.756042ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:51:00.668757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:50:59.877435Z","time spent":"791.248063ms","remote":"127.0.0.1:39442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:51:00.909165Z","caller":"traceutil/trace.go:171","msg":"trace[1735408511] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"998.063944ms","start":"2026-07-30T23:50:59.911072Z","end":"2026-07-30T23:51:00.909136Z","steps":["trace[1735408511] 'process raft request' (duration: 997.844857ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:51:00.909427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:50:59.911051Z","time spent":"998.195289ms","remote":"127.0.0.1:39442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:51:00.909704Z","caller":"traceutil/trace.go:171","msg":"trace[450527623] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"997.812406ms","start":"2026-07-30T23:50:59.911863Z","end":"2026-07-30T23:51:00.909675Z","steps":["trace[450527623] 'process raft request' (duration: 997.208347ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:51:00.909846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:50:59.911849Z","time spent":"997.92276ms","remote":"127.0.0.1:39442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:51:00.909707Z","caller":"traceutil/trace.go:171","msg":"trace[799760848] linearizableReadLoop","detail":"{readStateIndex:1113; appliedIndex:1110; }","duration":"608.561998ms","start":"2026-07-30T23:51:00.301118Z","end":"2026-07-30T23:51:00.90968Z","steps":["trace[799760848] 'read index received' (duration: 338.312214ms)","trace[799760848] 'applied index is now lower than readState.Index' (duration: 270.247424ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:51:00.910165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.793902ms","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-07-30T23:51:00.910351Z","caller":"traceutil/trace.go:171","msg":"trace[1120876003] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1070; }","duration":"179.018699ms","start":"2026-07-30T23:51:00.731317Z","end":"2026-07-30T23:51:00.910336Z","steps":["trace[1120876003] 'agreement among raft nodes before linearized reading' (duration: 178.72114ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:51:00.91028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.161656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:51:00.910474Z","caller":"traceutil/trace.go:171","msg":"trace[1439806108] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1070; }","duration":"609.390553ms","start":"2026-07-30T23:51:00.301069Z","end":"2026-07-30T23:51:00.91046Z","steps":["trace[1439806108] 'agreement among raft nodes before linearized reading' (duration: 608.685311ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:51:00.91052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:51:00.301048Z","time spent":"609.459456ms","remote":"127.0.0.1:39150","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-07-30T23:51:02.37746Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398342403163122241,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T23:51:02.390884Z","caller":"traceutil/trace.go:171","msg":"trace[1435061780] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"691.050494ms","start":"2026-07-30T23:51:01.699811Z","end":"2026-07-30T23:51:02.390861Z","steps":["trace[1435061780] 'process raft request' (duration: 690.857778ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:51:02.391017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:51:01.699794Z","time spent":"691.143457ms","remote":"127.0.0.1:39442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:51:02.395456Z","caller":"traceutil/trace.go:171","msg":"trace[979252230] linearizableReadLoop","detail":"{readStateIndex:1117; appliedIndex:1115; }","duration":"518.371266ms","start":"2026-07-30T23:51:01.877065Z","end":"2026-07-30T23:51:02.395437Z","steps":["trace[979252230] 'read index received' (duration: 513.627605ms)","trace[979252230] 'applied index is now lower than readState.Index' (duration: 4.742761ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:51:02.395819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.741838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-30T23:51:02.395894Z","caller":"traceutil/trace.go:171","msg":"trace[750982802] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1073; }","duration":"518.863442ms","start":"2026-07-30T23:51:01.877014Z","end":"2026-07-30T23:51:02.395877Z","steps":["trace[750982802] 'agreement among raft nodes before linearized reading' (duration: 518.655415ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:51:02.395936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:51:01.876995Z","time spent":"518.933123ms","remote":"127.0.0.1:39442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-30T23:51:02.395942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.923163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:51:02.395989Z","caller":"traceutil/trace.go:171","msg":"trace[471149756] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1073; }","duration":"344.986075ms","start":"2026-07-30T23:51:02.05099Z","end":"2026-07-30T23:51:02.395976Z","steps":["trace[471149756] 'agreement among raft nodes before linearized reading' (duration: 344.893792ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:51:02.396025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:51:02.050933Z","time spent":"345.082518ms","remote":"127.0.0.1:40564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"info","ts":"2026-07-30T23:51:02.396128Z","caller":"traceutil/trace.go:171","msg":"trace[1933816420] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"483.081746ms","start":"2026-07-30T23:51:01.913034Z","end":"2026-07-30T23:51:02.396116Z","steps":["trace[1933816420] 'process raft request' (duration: 482.307142ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:51:02.396215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:51:01.913012Z","time spent":"483.142018ms","remote":"127.0.0.1:39442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:51:02.574382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.164054ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T23:51:02.574497Z","caller":"traceutil/trace.go:171","msg":"trace[195270019] linearizableReadLoop","detail":"{readStateIndex:1118; appliedIndex:1117; }","duration":"102.845743ms","start":"2026-07-30T23:51:02.471633Z","end":"2026-07-30T23:51:02.574478Z","steps":["trace[195270019] 'read index received' (duration: 90.753µs)","trace[195270019] 'applied index is now lower than readState.Index' (duration: 102.75356ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:51:02.574561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.941886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T23:51:02.574595Z","caller":"traceutil/trace.go:171","msg":"trace[1322935918] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1074; }","duration":"103.025689ms","start":"2026-07-30T23:51:02.471558Z","end":"2026-07-30T23:51:02.574584Z","steps":["trace[1322935918] 'agreement among raft nodes before linearized reading' (duration: 102.967697ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:51:02.574687Z","caller":"traceutil/trace.go:171","msg":"trace[885798460] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"174.768915ms","start":"2026-07-30T23:51:02.399872Z","end":"2026-07-30T23:51:02.574641Z","steps":["trace[885798460] 'process raft request' (duration: 24.242069ms)","trace[885798460] 'compare' (duration: 150.012ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:51:03.164604Z","caller":"traceutil/trace.go:171","msg":"trace[1206058428] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"154.952916ms","start":"2026-07-30T23:51:03.009617Z","end":"2026-07-30T23:51:03.16457Z","steps":["trace[1206058428] 'process raft request' (duration: 154.773ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:51:03.350056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.081685ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T23:51:03.350482Z","caller":"traceutil/trace.go:171","msg":"trace[1841858117] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"340.494212ms","start":"2026-07-30T23:51:03.009965Z","end":"2026-07-30T23:51:03.35046Z","steps":["trace[1841858117] 'process raft request' (duration: 340.180072ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:51:03.350466Z","caller":"traceutil/trace.go:171","msg":"trace[2016071985] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"340.525094ms","start":"2026-07-30T23:51:03.009906Z","end":"2026-07-30T23:51:03.350431Z","steps":["trace[2016071985] 'process raft request' (duration: 210.958653ms)","trace[2016071985] 'compare' (duration: 128.92615ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:51:03.350618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:51:03.009896Z","time spent":"340.640557ms","remote":"127.0.0.1:39442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:51:03.35062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:51:03.009944Z","time spent":"340.591786ms","remote":"127.0.0.1:39442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:51:04.787206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.590108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-30T23:51:04.787318Z","caller":"traceutil/trace.go:171","msg":"trace[1730990846] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1079; }","duration":"208.719462ms","start":"2026-07-30T23:51:04.578576Z","end":"2026-07-30T23:51:04.787296Z","steps":["trace[1730990846] 'range keys from in-memory index tree' (duration: 208.408132ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:51:04.896702Z","caller":"traceutil/trace.go:171","msg":"trace[1040065039] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"105.00713ms","start":"2026-07-30T23:51:04.791676Z","end":"2026-07-30T23:51:04.896683Z","steps":["trace[1040065039] 'process raft request' (duration: 104.905097ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:51:05.287063Z","caller":"traceutil/trace.go:171","msg":"trace[864017887] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"115.151933ms","start":"2026-07-30T23:51:05.171879Z","end":"2026-07-30T23:51:05.287031Z","steps":["trace[864017887] 'process raft request' (duration: 115.006319ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:51:05.474581Z","caller":"traceutil/trace.go:171","msg":"trace[1678625901] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"115.39003ms","start":"2026-07-30T23:51:05.359149Z","end":"2026-07-30T23:51:05.474539Z","steps":["trace[1678625901] 'process raft request' (duration: 81.571808ms)","trace[1678625901] 'compare' (duration: 33.695278ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:51:21.191664Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398342403163122494,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T23:51:21.691938Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398342403163122494,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T23:51:21.887034Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.364600539s","expected-duration":"1s"} {"level":"info","ts":"2026-07-30T23:51:21.887389Z","caller":"traceutil/trace.go:171","msg":"trace[585501204] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"1.365005453s","start":"2026-07-30T23:51:20.522359Z","end":"2026-07-30T23:51:21.887364Z","steps":["trace[585501204] 'process raft request' (duration: 1.364861028s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:51:21.887537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:51:20.52234Z","time spent":"1.365103605s","remote":"127.0.0.1:39442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:51:21.889025Z","caller":"traceutil/trace.go:171","msg":"trace[135191429] linearizableReadLoop","detail":"{readStateIndex:1233; appliedIndex:1231; }","duration":"1.198116666s","start":"2026-07-30T23:51:20.690898Z","end":"2026-07-30T23:51:21.889015Z","steps":["trace[135191429] 'read index received' (duration: 1.196340249s)","trace[135191429] 'applied index is now lower than readState.Index' (duration: 1.775667ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:51:21.889099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.19819522s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:51:21.889129Z","caller":"traceutil/trace.go:171","msg":"trace[28879961] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1183; }","duration":"1.198252681s","start":"2026-07-30T23:51:20.690867Z","end":"2026-07-30T23:51:21.88912Z","steps":["trace[28879961] 'agreement among raft nodes before linearized reading' (duration: 1.198203329s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:51:21.889155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:51:20.690853Z","time spent":"1.198295283s","remote":"127.0.0.1:39306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-07-30T23:51:21.889194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.212709ms","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-07-30T23:51:21.889242Z","caller":"traceutil/trace.go:171","msg":"trace[232639235] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1183; }","duration":"537.27032ms","start":"2026-07-30T23:51:21.351962Z","end":"2026-07-30T23:51:21.889232Z","steps":["trace[232639235] 'agreement among raft nodes before linearized reading' (duration: 537.146366ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:51:21.889269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:51:21.351902Z","time spent":"537.361363ms","remote":"127.0.0.1:39442","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-07-30T23:51:21.889292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.59761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-30T23:51:21.889334Z","caller":"traceutil/trace.go:171","msg":"trace[1924990035] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1183; }","duration":"838.672572ms","start":"2026-07-30T23:51:21.050649Z","end":"2026-07-30T23:51:21.889322Z","steps":["trace[1924990035] 'agreement among raft nodes before linearized reading' (duration: 838.576429ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:51:21.88937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:51:21.050628Z","time spent":"838.735685ms","remote":"127.0.0.1:39442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-30T23:51:21.889196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.528708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-30T23:51:21.88945Z","caller":"traceutil/trace.go:171","msg":"trace[1353243730] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1183; }","duration":"838.808697ms","start":"2026-07-30T23:51:21.050618Z","end":"2026-07-30T23:51:21.889426Z","steps":["trace[1353243730] 'agreement among raft nodes before linearized reading' (duration: 838.472096ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:51:21.889402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.277654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-30T23:51:21.889507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:51:21.050598Z","time spent":"838.89423ms","remote":"127.0.0.1:39442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-30T23:51:21.889502Z","caller":"traceutil/trace.go:171","msg":"trace[183360944] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1183; }","duration":"347.408688ms","start":"2026-07-30T23:51:21.542083Z","end":"2026-07-30T23:51:21.889492Z","steps":["trace[183360944] 'agreement among raft nodes before linearized reading' (duration: 347.288274ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:51:21.88953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.441609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-30T23:51:21.889555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:51:21.54207Z","time spent":"347.47486ms","remote":"127.0.0.1:39442","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-07-30T23:51:21.889586Z","caller":"traceutil/trace.go:171","msg":"trace[1556352642] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1183; }","duration":"438.548292ms","start":"2026-07-30T23:51:21.451026Z","end":"2026-07-30T23:51:21.889574Z","steps":["trace[1556352642] 'agreement among raft nodes before linearized reading' (duration: 438.326635ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:51:21.889617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:51:21.451009Z","time spent":"438.602285ms","remote":"127.0.0.1:39442","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-07-30T23:51:21.889491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.536438ms","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-07-30T23:51:21.890263Z","caller":"traceutil/trace.go:171","msg":"trace[1522147140] 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:1183; }","duration":"839.429757ms","start":"2026-07-30T23:51:21.050752Z","end":"2026-07-30T23:51:21.890182Z","steps":["trace[1522147140] 'agreement among raft nodes before linearized reading' (duration: 838.408274ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:51:21.890383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:51:21.050742Z","time spent":"839.571481ms","remote":"127.0.0.1:40520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"info","ts":"2026-07-30T23:51:33.272264Z","caller":"traceutil/trace.go:171","msg":"trace[1324619951] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"159.274371ms","start":"2026-07-30T23:51:33.112965Z","end":"2026-07-30T23:51:33.272239Z","steps":["trace[1324619951] 'process raft request' (duration: 159.131606ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:51:43.460178Z","caller":"traceutil/trace.go:171","msg":"trace[1469578432] linearizableReadLoop","detail":"{readStateIndex:1345; appliedIndex:1344; }","duration":"413.863881ms","start":"2026-07-30T23:51:43.046291Z","end":"2026-07-30T23:51:43.460155Z","steps":["trace[1469578432] 'read index received' (duration: 413.802809ms)","trace[1469578432] 'applied index is now lower than readState.Index' (duration: 59.922µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:51:43.460212Z","caller":"traceutil/trace.go:171","msg":"trace[1442345316] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"527.28322ms","start":"2026-07-30T23:51:42.932893Z","end":"2026-07-30T23:51:43.460177Z","steps":["trace[1442345316] 'process raft request' (duration: 527.119885ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:51:43.460356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:51:42.93287Z","time spent":"527.419935ms","remote":"127.0.0.1:39220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T23:51:43.46035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.058877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:51:43.460473Z","caller":"traceutil/trace.go:171","msg":"trace[317984070] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1291; }","duration":"414.196601ms","start":"2026-07-30T23:51:43.04623Z","end":"2026-07-30T23:51:43.460427Z","steps":["trace[317984070] 'agreement among raft nodes before linearized reading' (duration: 414.025436ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:51:43.46052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:51:43.04619Z","time spent":"414.321225ms","remote":"127.0.0.1:39442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-30T23:51:44.200237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.981033ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T23:51:44.200381Z","caller":"traceutil/trace.go:171","msg":"trace[1250693175] linearizableReadLoop","detail":"{readStateIndex:1347; appliedIndex:1345; }","duration":"167.965999ms","start":"2026-07-30T23:51:44.032399Z","end":"2026-07-30T23:51:44.200365Z","steps":["trace[1250693175] 'read index received' (duration: 125.004362ms)","trace[1250693175] 'applied index is now lower than readState.Index' (duration: 42.960507ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:51:44.200551Z","caller":"traceutil/trace.go:171","msg":"trace[1157077607] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"733.92127ms","start":"2026-07-30T23:51:43.466601Z","end":"2026-07-30T23:51:44.200522Z","steps":["trace[1157077607] 'process raft request' (duration: 733.724654ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:51:44.200614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.207377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:51:44.200651Z","caller":"traceutil/trace.go:171","msg":"trace[405991156] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1293; }","duration":"168.279239ms","start":"2026-07-30T23:51:44.03236Z","end":"2026-07-30T23:51:44.200639Z","steps":["trace[405991156] 'agreement among raft nodes before linearized reading' (duration: 168.209237ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:51:44.200699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:51:43.466576Z","time spent":"734.057704ms","remote":"127.0.0.1:39442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T23:51:44.200855Z","caller":"traceutil/trace.go:171","msg":"trace[729417690] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"825.24325ms","start":"2026-07-30T23:51:43.37558Z","end":"2026-07-30T23:51:44.200824Z","steps":["trace[729417690] 'process raft request' (duration: 386.597485ms)","trace[729417690] 'compare' (duration: 437.891601ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:51:44.201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:51:43.375542Z","time spent":"825.384425ms","remote":"127.0.0.1:39442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:51:44.264706Z","caller":"traceutil/trace.go:171","msg":"trace[2131389862] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"211.62544ms","start":"2026-07-30T23:51:44.053061Z","end":"2026-07-30T23:51:44.264686Z","steps":["trace[2131389862] 'process raft request' (duration: 211.499376ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:51:44.49041Z","caller":"traceutil/trace.go:171","msg":"trace[1349885403] linearizableReadLoop","detail":"{readStateIndex:1349; appliedIndex:1347; }","duration":"289.980836ms","start":"2026-07-30T23:51:44.200406Z","end":"2026-07-30T23:51:44.490387Z","steps":["trace[1349885403] 'read index received' (duration: 64.208611ms)","trace[1349885403] 'applied index is now lower than readState.Index' (duration: 225.771135ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:51:44.490521Z","caller":"traceutil/trace.go:171","msg":"trace[104130006] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"284.365845ms","start":"2026-07-30T23:51:44.206103Z","end":"2026-07-30T23:51:44.490469Z","steps":["trace[104130006] 'process raft request' (duration: 284.247172ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:51:44.490559Z","caller":"traceutil/trace.go:171","msg":"trace[453802142] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"327.222299ms","start":"2026-07-30T23:51:44.163279Z","end":"2026-07-30T23:51:44.490501Z","steps":["trace[453802142] 'process raft request' (duration: 326.948571ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:51:44.490693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:51:44.163244Z","time spent":"327.383464ms","remote":"127.0.0.1:39220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T23:51:44.490759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.624765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-07-30T23:51:44.490799Z","caller":"traceutil/trace.go:171","msg":"trace[1162147402] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1296; }","duration":"285.705218ms","start":"2026-07-30T23:51:44.205084Z","end":"2026-07-30T23:51:44.490789Z","steps":["trace[1162147402] 'agreement among raft nodes before linearized reading' (duration: 285.577014ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:51:44.490848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.380639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:51:44.490883Z","caller":"traceutil/trace.go:171","msg":"trace[290356412] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1296; }","duration":"190.493982ms","start":"2026-07-30T23:51:44.300379Z","end":"2026-07-30T23:51:44.490873Z","steps":["trace[290356412] 'agreement among raft nodes before linearized reading' (duration: 190.440441ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:51:44.490947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.951933ms","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-07-30T23:51:44.490986Z","caller":"traceutil/trace.go:171","msg":"trace[1744682634] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1296; }","duration":"275.043596ms","start":"2026-07-30T23:51:44.215935Z","end":"2026-07-30T23:51:44.490978Z","steps":["trace[1744682634] 'agreement among raft nodes before linearized reading' (duration: 274.86686ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:51:44.491176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.824557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-30T23:51:44.491224Z","caller":"traceutil/trace.go:171","msg":"trace[1931470143] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1296; }","duration":"350.921699ms","start":"2026-07-30T23:51:44.140295Z","end":"2026-07-30T23:51:44.491216Z","steps":["trace[1931470143] 'agreement among raft nodes before linearized reading' (duration: 350.225377ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:51:44.491246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:51:44.14027Z","time spent":"350.970531ms","remote":"127.0.0.1:39442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-30T23:51:44.491245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.472631ms","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-07-30T23:51:44.49128Z","caller":"traceutil/trace.go:171","msg":"trace[947893878] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1296; }","duration":"329.541924ms","start":"2026-07-30T23:51:44.161728Z","end":"2026-07-30T23:51:44.49127Z","steps":["trace[947893878] 'agreement among raft nodes before linearized reading' (duration: 329.440451ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:51:44.491307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:51:44.161697Z","time spent":"329.604426ms","remote":"127.0.0.1:39442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-30T23:51:44.492123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.735966ms","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-07-30T23:51:44.492239Z","caller":"traceutil/trace.go:171","msg":"trace[1416190128] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1296; }","duration":"276.026638ms","start":"2026-07-30T23:51:44.216197Z","end":"2026-07-30T23:51:44.492223Z","steps":["trace[1416190128] 'agreement among raft nodes before linearized reading' (duration: 274.571411ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:51:44.49272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.316947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-30T23:51:44.492869Z","caller":"traceutil/trace.go:171","msg":"trace[16125721] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1296; }","duration":"276.484052ms","start":"2026-07-30T23:51:44.216359Z","end":"2026-07-30T23:51:44.492843Z","steps":["trace[16125721] 'agreement among raft nodes before linearized reading' (duration: 274.331412ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:51:44.645591Z","caller":"traceutil/trace.go:171","msg":"trace[916803799] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"148.95424ms","start":"2026-07-30T23:51:44.496612Z","end":"2026-07-30T23:51:44.645566Z","steps":["trace[916803799] 'process raft request' (duration: 148.908288ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:51:44.645721Z","caller":"traceutil/trace.go:171","msg":"trace[1298535843] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"149.115885ms","start":"2026-07-30T23:51:44.496599Z","end":"2026-07-30T23:51:44.645714Z","steps":["trace[1298535843] 'process raft request' (duration: 85.417241ms)","trace[1298535843] 'compare' (duration: 63.402725ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:51:44.64579Z","caller":"traceutil/trace.go:171","msg":"trace[844448778] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"149.115635ms","start":"2026-07-30T23:51:44.49667Z","end":"2026-07-30T23:51:44.645785Z","steps":["trace[844448778] 'process raft request' (duration: 148.871247ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:51:44.645885Z","caller":"traceutil/trace.go:171","msg":"trace[1568172578] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"148.098063ms","start":"2026-07-30T23:51:44.497783Z","end":"2026-07-30T23:51:44.645881Z","steps":["trace[1568172578] 'process raft request' (duration: 148.080562ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:51:44.645927Z","caller":"traceutil/trace.go:171","msg":"trace[748886108] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"149.225898ms","start":"2026-07-30T23:51:44.496698Z","end":"2026-07-30T23:51:44.645924Z","steps":["trace[748886108] 'process raft request' (duration: 149.068783ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:51:44.645962Z","caller":"traceutil/trace.go:171","msg":"trace[438353519] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"149.153526ms","start":"2026-07-30T23:51:44.496805Z","end":"2026-07-30T23:51:44.645959Z","steps":["trace[438353519] 'process raft request' (duration: 149.040443ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:51:45.527358Z","caller":"traceutil/trace.go:171","msg":"trace[950928941] linearizableReadLoop","detail":"{readStateIndex:1358; appliedIndex:1357; }","duration":"225.614639ms","start":"2026-07-30T23:51:45.301709Z","end":"2026-07-30T23:51:45.527324Z","steps":["trace[950928941] 'read index received' (duration: 225.309379ms)","trace[950928941] 'applied index is now lower than readState.Index' (duration: 303.83µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:51:45.527523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.778575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:51:45.527561Z","caller":"traceutil/trace.go:171","msg":"trace[338137476] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1304; }","duration":"225.9197ms","start":"2026-07-30T23:51:45.301629Z","end":"2026-07-30T23:51:45.527549Z","steps":["trace[338137476] 'agreement among raft nodes before linearized reading' (duration: 225.823456ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:51:45.527577Z","caller":"traceutil/trace.go:171","msg":"trace[1849215434] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"364.354462ms","start":"2026-07-30T23:51:45.163171Z","end":"2026-07-30T23:51:45.527525Z","steps":["trace[1849215434] 'process raft request' (duration: 363.944829ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:51:45.527902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:51:45.163153Z","time spent":"364.507906ms","remote":"127.0.0.1:39442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":492,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:51:46.17832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.279624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.156\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-30T23:51:46.178405Z","caller":"traceutil/trace.go:171","msg":"trace[1422893974] range","detail":"{range_begin:/registry/masterleases/199.204.45.156; range_end:; response_count:1; response_revision:1304; }","duration":"380.440779ms","start":"2026-07-30T23:51:45.797947Z","end":"2026-07-30T23:51:46.178388Z","steps":["trace[1422893974] 'range keys from in-memory index tree' (duration: 380.066117ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:51:46.17846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:51:45.797902Z","time spent":"380.544532ms","remote":"127.0.0.1:39186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.156\" "} {"level":"warn","ts":"2026-07-30T23:51:46.511603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.114518ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T23:51:46.511972Z","caller":"traceutil/trace.go:171","msg":"trace[1562066249] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"241.745638ms","start":"2026-07-30T23:51:46.270206Z","end":"2026-07-30T23:51:46.511952Z","steps":["trace[1562066249] 'process raft request' (duration: 241.634154ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:51:46.512019Z","caller":"traceutil/trace.go:171","msg":"trace[1772967215] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"285.158431ms","start":"2026-07-30T23:51:46.226831Z","end":"2026-07-30T23:51:46.511989Z","steps":["trace[1772967215] 'process raft request' (duration: 105.563487ms)","trace[1772967215] 'compare' (duration: 178.925902ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:51:50.249769Z","caller":"traceutil/trace.go:171","msg":"trace[896913242] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"383.872868ms","start":"2026-07-30T23:51:49.86587Z","end":"2026-07-30T23:51:50.249742Z","steps":["trace[896913242] 'process raft request' (duration: 383.706523ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:51:50.249905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:51:49.865851Z","time spent":"383.989642ms","remote":"127.0.0.1:39442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:51:51.184502Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398342403163122814,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T23:51:51.811472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.74711ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T23:51:51.811751Z","caller":"traceutil/trace.go:171","msg":"trace[1099648453] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"1.264601259s","start":"2026-07-30T23:51:50.547114Z","end":"2026-07-30T23:51:51.811716Z","steps":["trace[1099648453] 'process raft request' (duration: 642.530428ms)","trace[1099648453] 'compare' (duration: 621.398019ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:51:51.812506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:51:50.54708Z","time spent":"1.264743383s","remote":"127.0.0.1:39442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:51:52.045513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.920116ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-30T23:51:52.045629Z","caller":"traceutil/trace.go:171","msg":"trace[1994733021] linearizableReadLoop","detail":"{readStateIndex:1398; appliedIndex:1396; }","duration":"1.361575262s","start":"2026-07-30T23:51:50.68403Z","end":"2026-07-30T23:51:52.045606Z","steps":["trace[1994733021] 'read index received' (duration: 505.613085ms)","trace[1994733021] 'applied index is now lower than readState.Index' (duration: 855.959856ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:51:52.046004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.360415103s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-30T23:51:52.046015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.985575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:51:52.046132Z","caller":"traceutil/trace.go:171","msg":"trace[1404781771] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1342; }","duration":"745.158001ms","start":"2026-07-30T23:51:51.300954Z","end":"2026-07-30T23:51:52.046112Z","steps":["trace[1404781771] 'agreement among raft nodes before linearized reading' (duration: 744.989705ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:51:52.046137Z","caller":"traceutil/trace.go:171","msg":"trace[82523478] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1342; }","duration":"1.360575339s","start":"2026-07-30T23:51:50.685505Z","end":"2026-07-30T23:51:52.04608Z","steps":["trace[82523478] 'agreement among raft nodes before linearized reading' (duration: 1.360318481s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:51:52.046186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.358735s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-30T23:51:52.046227Z","caller":"traceutil/trace.go:171","msg":"trace[979897773] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1342; }","duration":"1.358794482s","start":"2026-07-30T23:51:50.687422Z","end":"2026-07-30T23:51:52.046216Z","steps":["trace[979897773] 'agreement among raft nodes before linearized reading' (duration: 1.358691499s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:51:52.046252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:51:50.687412Z","time spent":"1.358831623s","remote":"127.0.0.1:39442","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-07-30T23:51:52.046253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.393788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T23:51:52.04625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.359714352s","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-07-30T23:51:52.046268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:51:50.685494Z","time spent":"1.360728904s","remote":"127.0.0.1:39442","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-07-30T23:51:52.046287Z","caller":"traceutil/trace.go:171","msg":"trace[1152379984] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1342; }","duration":"285.46602ms","start":"2026-07-30T23:51:51.760812Z","end":"2026-07-30T23:51:52.046278Z","steps":["trace[1152379984] 'agreement among raft nodes before linearized reading' (duration: 285.413978ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:51:52.046301Z","caller":"traceutil/trace.go:171","msg":"trace[2022858691] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1342; }","duration":"1.359805054s","start":"2026-07-30T23:51:50.686482Z","end":"2026-07-30T23:51:52.046287Z","steps":["trace[2022858691] 'agreement among raft nodes before linearized reading' (duration: 1.359647339s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:51:52.046333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:51:50.686461Z","time spent":"1.359864737s","remote":"127.0.0.1:39442","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-07-30T23:51:52.046186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:51:51.300931Z","time spent":"745.245544ms","remote":"127.0.0.1:39150","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-07-30T23:51:52.046024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.361988234s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-30T23:51:52.046776Z","caller":"traceutil/trace.go:171","msg":"trace[534349020] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1342; }","duration":"1.362769849s","start":"2026-07-30T23:51:50.683985Z","end":"2026-07-30T23:51:52.046755Z","steps":["trace[534349020] 'agreement among raft nodes before linearized reading' (duration: 1.361721876s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:51:52.04698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:51:50.683966Z","time spent":"1.362996477s","remote":"127.0.0.1:39442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-30T23:51:52.046576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.35903143s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-30T23:51:52.047708Z","caller":"traceutil/trace.go:171","msg":"trace[70209532] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1342; }","duration":"1.360223688s","start":"2026-07-30T23:51:50.687474Z","end":"2026-07-30T23:51:52.047698Z","steps":["trace[70209532] 'agreement among raft nodes before linearized reading' (duration: 1.358831743s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:51:52.047737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:51:50.687453Z","time spent":"1.36027504s","remote":"127.0.0.1:39442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-30T23:53:58.137986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.127624ms","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:526"} {"level":"info","ts":"2026-07-30T23:53:58.138075Z","caller":"traceutil/trace.go:171","msg":"trace[507246379] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1924; }","duration":"139.263738ms","start":"2026-07-30T23:53:57.998791Z","end":"2026-07-30T23:53:58.138055Z","steps":["trace[507246379] 'range keys from in-memory index tree' (duration: 138.947858ms)"],"step_count":1}