{"level":"warn","ts":"2026-05-11T05:35:31.244733Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-11T05:35:31.244815Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.233: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.233:2380","--initial-cluster=instance=https://199.204.45.233:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.233:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.233:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-11T05:35:31.244873Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-11T05:35:31.244884Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.233:2380"]} {"level":"info","ts":"2026-05-11T05:35:31.2449Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-11T05:35:31.245479Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"]} {"level":"info","ts":"2026-05-11T05:35:31.245546Z","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.233:2380"],"listen-peer-urls":["https://199.204.45.233:2380"],"advertise-client-urls":["https://199.204.45.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.233:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-11T05:35:31.251119Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.392519ms"} {"level":"info","ts":"2026-05-11T05:35:31.259173Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"90a0cb2044183f95","cluster-id":"782fef34d5ea94f9"} {"level":"info","ts":"2026-05-11T05:35:31.259253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=()"} {"level":"info","ts":"2026-05-11T05:35:31.259299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became follower at term 0"} {"level":"info","ts":"2026-05-11T05:35:31.259325Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 90a0cb2044183f95 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-11T05:35:31.25934Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became follower at term 1"} {"level":"info","ts":"2026-05-11T05:35:31.259385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=(10421552877177159573)"} {"level":"warn","ts":"2026-05-11T05:35:31.267075Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-11T05:35:31.269117Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-11T05:35:31.271097Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-11T05:35:31.273938Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"90a0cb2044183f95","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-11T05:35:31.274391Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"90a0cb2044183f95","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-11T05:35:31.274487Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-11T05:35:31.274672Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-11T05:35:31.27468Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-11T05:35:31.275189Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=(10421552877177159573)"} {"level":"info","ts":"2026-05-11T05:35:31.277035Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"782fef34d5ea94f9","local-member-id":"90a0cb2044183f95","added-peer-id":"90a0cb2044183f95","added-peer-peer-urls":["https://199.204.45.233:2380"]} {"level":"info","ts":"2026-05-11T05:35:31.279631Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-11T05:35:31.279735Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.233:2380"} {"level":"info","ts":"2026-05-11T05:35:31.279775Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.233:2380"} {"level":"info","ts":"2026-05-11T05:35:31.279794Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"90a0cb2044183f95","initial-advertise-peer-urls":["https://199.204.45.233:2380"],"listen-peer-urls":["https://199.204.45.233:2380"],"advertise-client-urls":["https://199.204.45.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-11T05:35:31.279822Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-11T05:35:31.360596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 is starting a new election at term 1"} {"level":"info","ts":"2026-05-11T05:35:31.360634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-11T05:35:31.360649Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 received MsgPreVoteResp from 90a0cb2044183f95 at term 1"} {"level":"info","ts":"2026-05-11T05:35:31.36066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became candidate at term 2"} {"level":"info","ts":"2026-05-11T05:35:31.360665Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 received MsgVoteResp from 90a0cb2044183f95 at term 2"} {"level":"info","ts":"2026-05-11T05:35:31.360673Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became leader at term 2"} {"level":"info","ts":"2026-05-11T05:35:31.36068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 90a0cb2044183f95 elected leader 90a0cb2044183f95 at term 2"} {"level":"info","ts":"2026-05-11T05:35:31.36135Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T05:35:31.362137Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T05:35:31.362164Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T05:35:31.362143Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"90a0cb2044183f95","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.233:2379]}","request-path":"/0/members/90a0cb2044183f95/attributes","cluster-id":"782fef34d5ea94f9","publish-timeout":"7s"} {"level":"info","ts":"2026-05-11T05:35:31.362454Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-11T05:35:31.362511Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-11T05:35:31.36413Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"782fef34d5ea94f9","local-member-id":"90a0cb2044183f95","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T05:35:31.364245Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T05:35:31.364288Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T05:35:31.365485Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-11T05:35:31.366208Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.233:2379"} {"level":"warn","ts":"2026-05-11T05:35:36.475282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.362712ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-11T05:35:36.475505Z","caller":"traceutil/trace.go:171","msg":"trace[1063772750] transaction","detail":"{read_only:false; response_revision:238; number_of_response:1; }","duration":"377.78392ms","start":"2026-05-11T05:35:36.097679Z","end":"2026-05-11T05:35:36.475463Z","steps":["trace[1063772750] 'process raft request' (duration: 179.725672ms)","trace[1063772750] 'compare' (duration: 197.2468ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:35:36.475696Z","caller":"traceutil/trace.go:171","msg":"trace[1453846222] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"228.776994ms","start":"2026-05-11T05:35:36.246886Z","end":"2026-05-11T05:35:36.475663Z","steps":["trace[1453846222] 'process raft request' (duration: 228.737393ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:35:36.475659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:35:36.097667Z","time spent":"377.926493ms","remote":"127.0.0.1:52494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":597,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T05:35:36.475785Z","caller":"traceutil/trace.go:171","msg":"trace[577501961] transaction","detail":"{read_only:false; number_of_response:0; response_revision:239; }","duration":"230.958082ms","start":"2026-05-11T05:35:36.24477Z","end":"2026-05-11T05:35:36.475728Z","steps":["trace[577501961] 'process raft request' (duration: 230.717917ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:35:36.475797Z","caller":"traceutil/trace.go:171","msg":"trace[1756339358] transaction","detail":"{read_only:false; response_revision:239; number_of_response:1; }","duration":"343.30551ms","start":"2026-05-11T05:35:36.132461Z","end":"2026-05-11T05:35:36.475766Z","steps":["trace[1756339358] 'process raft request' (duration: 342.930372ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:35:36.47588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:35:36.13245Z","time spent":"343.387142ms","remote":"127.0.0.1:52474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T05:35:36.475903Z","caller":"traceutil/trace.go:171","msg":"trace[1263390414] linearizableReadLoop","detail":"{readStateIndex:244; appliedIndex:243; }","duration":"344.409104ms","start":"2026-05-11T05:35:36.131455Z","end":"2026-05-11T05:35:36.475864Z","steps":["trace[1263390414] 'read index received' (duration: 145.963569ms)","trace[1263390414] 'applied index is now lower than readState.Index' (duration: 198.440185ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:35:36.475946Z","caller":"traceutil/trace.go:171","msg":"trace[1312271877] transaction","detail":"{read_only:false; number_of_response:0; response_revision:240; }","duration":"229.94208ms","start":"2026-05-11T05:35:36.245994Z","end":"2026-05-11T05:35:36.475936Z","steps":["trace[1312271877] 'process raft request' (duration: 229.563682ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:35:36.47595Z","caller":"traceutil/trace.go:171","msg":"trace[970635726] transaction","detail":"{read_only:false; response_revision:241; number_of_response:1; }","duration":"229.915079ms","start":"2026-05-11T05:35:36.24602Z","end":"2026-05-11T05:35:36.475935Z","steps":["trace[970635726] 'process raft request' (duration: 229.565291ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:35:36.476016Z","caller":"traceutil/trace.go:171","msg":"trace[1679781248] transaction","detail":"{read_only:false; response_revision:240; number_of_response:1; }","duration":"230.074852ms","start":"2026-05-11T05:35:36.245925Z","end":"2026-05-11T05:35:36.475999Z","steps":["trace[1679781248] 'process raft request' (duration: 229.585492ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:35:36.47815Z","caller":"traceutil/trace.go:171","msg":"trace[1533810839] transaction","detail":"{read_only:false; number_of_response:0; response_revision:239; }","duration":"239.242095ms","start":"2026-05-11T05:35:36.236665Z","end":"2026-05-11T05:35:36.475908Z","steps":["trace[1533810839] 'process raft request' (duration: 238.773845ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:35:36.479049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.432679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:35:36.479143Z","caller":"traceutil/trace.go:171","msg":"trace[1190560531] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:242; }","duration":"346.556562ms","start":"2026-05-11T05:35:36.132553Z","end":"2026-05-11T05:35:36.47911Z","steps":["trace[1190560531] 'agreement among raft nodes before linearized reading' (duration: 344.406695ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:35:36.479181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:35:36.132531Z","time spent":"346.640893ms","remote":"127.0.0.1:52640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-05-11T05:35:36.479451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.970443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-05-11T05:35:36.479517Z","caller":"traceutil/trace.go:171","msg":"trace[1000399367] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:242; }","duration":"348.068655ms","start":"2026-05-11T05:35:36.131421Z","end":"2026-05-11T05:35:36.47949Z","steps":["trace[1000399367] 'agreement among raft nodes before linearized reading' (duration: 344.6401ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:35:36.479557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:35:36.131403Z","time spent":"348.143427ms","remote":"127.0.0.1:52764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-05-11T05:35:36.479678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.959634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-11T05:35:36.479797Z","caller":"traceutil/trace.go:171","msg":"trace[1350120374] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:242; }","duration":"339.070807ms","start":"2026-05-11T05:35:36.140696Z","end":"2026-05-11T05:35:36.479766Z","steps":["trace[1350120374] 'agreement among raft nodes before linearized reading' (duration: 336.472259ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:35:36.479863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:35:36.140669Z","time spent":"339.181689ms","remote":"127.0.0.1:52498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-11T05:35:36.479872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.617509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:35:36.479944Z","caller":"traceutil/trace.go:171","msg":"trace[224372941] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:242; }","duration":"320.707262ms","start":"2026-05-11T05:35:36.15921Z","end":"2026-05-11T05:35:36.479917Z","steps":["trace[224372941] 'agreement among raft nodes before linearized reading' (duration: 317.91869ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:35:36.479989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:35:36.159193Z","time spent":"320.784893ms","remote":"127.0.0.1:52640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-11T05:35:36.805656Z","caller":"traceutil/trace.go:171","msg":"trace[895510125] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"280.020665ms","start":"2026-05-11T05:35:36.525608Z","end":"2026-05-11T05:35:36.805628Z","steps":["trace[895510125] 'process raft request' (duration: 279.979944ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:35:36.805667Z","caller":"traceutil/trace.go:171","msg":"trace[231315204] transaction","detail":"{read_only:false; response_revision:244; number_of_response:1; }","duration":"322.566922ms","start":"2026-05-11T05:35:36.483034Z","end":"2026-05-11T05:35:36.805601Z","steps":["trace[231315204] 'process raft request' (duration: 227.099367ms)","trace[231315204] 'compare' (duration: 95.195819ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:35:36.805857Z","caller":"traceutil/trace.go:171","msg":"trace[1858637877] linearizableReadLoop","detail":"{readStateIndex:255; appliedIndex:252; }","duration":"284.215037ms","start":"2026-05-11T05:35:36.521618Z","end":"2026-05-11T05:35:36.805834Z","steps":["trace[1858637877] 'read index received' (duration: 188.65594ms)","trace[1858637877] 'applied index is now lower than readState.Index' (duration: 95.556707ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:35:36.805914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:35:36.483016Z","time spent":"322.750577ms","remote":"127.0.0.1:52586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":180,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T05:35:36.806153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.206912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"warn","ts":"2026-05-11T05:35:36.806208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.752205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"warn","ts":"2026-05-11T05:35:36.806245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.758615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-05-11T05:35:36.806273Z","caller":"traceutil/trace.go:171","msg":"trace[436588752] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:247; }","duration":"321.381036ms","start":"2026-05-11T05:35:36.48485Z","end":"2026-05-11T05:35:36.806231Z","steps":["trace[436588752] 'agreement among raft nodes before linearized reading' (duration: 321.11318ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:35:36.806306Z","caller":"traceutil/trace.go:171","msg":"trace[1603124603] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:247; }","duration":"321.829046ms","start":"2026-05-11T05:35:36.484463Z","end":"2026-05-11T05:35:36.806292Z","steps":["trace[1603124603] 'agreement among raft nodes before linearized reading' (duration: 321.724584ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:35:36.806308Z","caller":"traceutil/trace.go:171","msg":"trace[208980054] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:247; }","duration":"321.867297ms","start":"2026-05-11T05:35:36.484412Z","end":"2026-05-11T05:35:36.806279Z","steps":["trace[208980054] 'agreement among raft nodes before linearized reading' (duration: 321.5473ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:35:36.806352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:35:36.484823Z","time spent":"321.502059ms","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4040,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-05-11T05:35:36.806398Z","caller":"traceutil/trace.go:171","msg":"trace[1927411511] transaction","detail":"{read_only:false; response_revision:245; number_of_response:1; }","duration":"321.892428ms","start":"2026-05-11T05:35:36.484497Z","end":"2026-05-11T05:35:36.806389Z","steps":["trace[1927411511] 'process raft request' (duration: 320.992508ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:35:36.806339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:35:36.484432Z","time spent":"321.899738ms","remote":"127.0.0.1:52764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-05-11T05:35:36.806478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:35:36.484485Z","time spent":"321.938708ms","remote":"127.0.0.1:52640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T05:35:36.807117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:35:36.484404Z","time spent":"322.00244ms","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4040,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-05-11T05:35:36.806558Z","caller":"traceutil/trace.go:171","msg":"trace[701867684] transaction","detail":"{read_only:false; response_revision:246; number_of_response:1; }","duration":"319.947905ms","start":"2026-05-11T05:35:36.48574Z","end":"2026-05-11T05:35:36.805688Z","steps":["trace[701867684] 'process raft request' (duration: 319.797792ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:35:36.80811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:35:36.485698Z","time spent":"321.624122ms","remote":"127.0.0.1:52474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T05:35:59.196134Z","caller":"traceutil/trace.go:171","msg":"trace[109431927] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"251.915027ms","start":"2026-05-11T05:35:58.944196Z","end":"2026-05-11T05:35:59.196111Z","steps":["trace[109431927] 'process raft request' (duration: 157.756671ms)","trace[109431927] 'compare' (duration: 94.069884ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:35:59.325179Z","caller":"traceutil/trace.go:171","msg":"trace[1517296365] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"380.695963ms","start":"2026-05-11T05:35:58.944437Z","end":"2026-05-11T05:35:59.325133Z","steps":["trace[1517296365] 'process raft request' (duration: 380.424407ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:35:59.325199Z","caller":"traceutil/trace.go:171","msg":"trace[1043242078] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"362.978489ms","start":"2026-05-11T05:35:58.962181Z","end":"2026-05-11T05:35:59.325159Z","steps":["trace[1043242078] 'process raft request' (duration: 362.811545ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:35:59.325294Z","caller":"traceutil/trace.go:171","msg":"trace[1730504915] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"362.994659ms","start":"2026-05-11T05:35:58.962269Z","end":"2026-05-11T05:35:59.325264Z","steps":["trace[1730504915] 'process raft request' (duration: 362.787755ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:35:59.325368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:35:58.962264Z","time spent":"363.068051ms","remote":"127.0.0.1:52444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T05:35:59.325389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:35:58.962162Z","time spent":"363.136103ms","remote":"127.0.0.1:52444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T05:35:59.325569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:35:58.944429Z","time spent":"380.870707ms","remote":"127.0.0.1:52444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T05:35:59.325818Z","caller":"traceutil/trace.go:171","msg":"trace[1529302118] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"359.486681ms","start":"2026-05-11T05:35:58.966317Z","end":"2026-05-11T05:35:59.325804Z","steps":["trace[1529302118] 'process raft request' (duration: 359.318517ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:35:59.325781Z","caller":"traceutil/trace.go:171","msg":"trace[1083243140] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"363.406058ms","start":"2026-05-11T05:35:58.962356Z","end":"2026-05-11T05:35:59.325762Z","steps":["trace[1083243140] 'process raft request' (duration: 363.196704ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:35:59.325886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:35:58.966299Z","time spent":"359.555272ms","remote":"127.0.0.1:52444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T05:35:59.325969Z","caller":"traceutil/trace.go:171","msg":"trace[1965883532] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"343.403833ms","start":"2026-05-11T05:35:58.982554Z","end":"2026-05-11T05:35:59.325958Z","steps":["trace[1965883532] 'process raft request' (duration: 343.206218ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:35:59.32595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:35:58.962351Z","time spent":"363.516631ms","remote":"127.0.0.1:52444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T05:35:59.326043Z","caller":"traceutil/trace.go:171","msg":"trace[657626880] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"343.905194ms","start":"2026-05-11T05:35:58.98213Z","end":"2026-05-11T05:35:59.326035Z","steps":["trace[657626880] 'process raft request' (duration: 343.562247ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:35:59.326117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:35:58.969655Z","time spent":"356.414173ms","remote":"127.0.0.1:52444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T05:35:59.326046Z","caller":"traceutil/trace.go:171","msg":"trace[444916678] linearizableReadLoop","detail":"{readStateIndex:491; appliedIndex:480; }","duration":"251.691951ms","start":"2026-05-11T05:35:59.074333Z","end":"2026-05-11T05:35:59.326025Z","steps":["trace[444916678] 'read index received' (duration: 27.643215ms)","trace[444916678] 'applied index is now lower than readState.Index' (duration: 224.047696ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:35:59.326203Z","caller":"traceutil/trace.go:171","msg":"trace[400622116] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"331.11882ms","start":"2026-05-11T05:35:58.995076Z","end":"2026-05-11T05:35:59.326195Z","steps":["trace[400622116] 'process raft request' (duration: 330.883745ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:35:59.32621Z","caller":"traceutil/trace.go:171","msg":"trace[495729923] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"343.582127ms","start":"2026-05-11T05:35:58.982612Z","end":"2026-05-11T05:35:59.326194Z","steps":["trace[495729923] 'process raft request' (duration: 343.219129ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:35:59.326246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:35:58.995048Z","time spent":"331.180691ms","remote":"127.0.0.1:52444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T05:35:59.326012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:35:58.982544Z","time spent":"343.449254ms","remote":"127.0.0.1:52444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T05:35:59.326292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:35:58.982606Z","time spent":"343.640188ms","remote":"127.0.0.1:52444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T05:35:59.326318Z","caller":"traceutil/trace.go:171","msg":"trace[362968939] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"341.788267ms","start":"2026-05-11T05:35:58.984523Z","end":"2026-05-11T05:35:59.326311Z","steps":["trace[362968939] 'process raft request' (duration: 341.367038ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:35:59.326356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:35:58.984503Z","time spent":"341.836208ms","remote":"127.0.0.1:52444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T05:35:59.326465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.120292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:35:59.326522Z","caller":"traceutil/trace.go:171","msg":"trace[258259131] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:474; }","duration":"252.201053ms","start":"2026-05-11T05:35:59.074305Z","end":"2026-05-11T05:35:59.326506Z","steps":["trace[258259131] 'agreement among raft nodes before linearized reading' (duration: 252.09401ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:35:59.334443Z","caller":"traceutil/trace.go:171","msg":"trace[1939339902] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"178.174315ms","start":"2026-05-11T05:35:59.156256Z","end":"2026-05-11T05:35:59.33443Z","steps":["trace[1939339902] 'process raft request' (duration: 177.907589ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:35:59.334576Z","caller":"traceutil/trace.go:171","msg":"trace[2014537960] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"133.45991ms","start":"2026-05-11T05:35:59.201109Z","end":"2026-05-11T05:35:59.334569Z","steps":["trace[2014537960] 'process raft request' (duration: 133.128163ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:35:59.33465Z","caller":"traceutil/trace.go:171","msg":"trace[1385422318] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"131.578488ms","start":"2026-05-11T05:35:59.203066Z","end":"2026-05-11T05:35:59.334644Z","steps":["trace[1385422318] 'process raft request' (duration: 131.195219ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:35:59.33471Z","caller":"traceutil/trace.go:171","msg":"trace[1708266976] transaction","detail":"{read_only:false; number_of_response:1; response_revision:477; }","duration":"131.381443ms","start":"2026-05-11T05:35:59.203324Z","end":"2026-05-11T05:35:59.334705Z","steps":["trace[1708266976] 'process raft request' (duration: 130.972194ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:35:59.334831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.433394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-11T05:35:59.334856Z","caller":"traceutil/trace.go:171","msg":"trace[1181725950] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:478; }","duration":"148.494334ms","start":"2026-05-11T05:35:59.186356Z","end":"2026-05-11T05:35:59.334851Z","steps":["trace[1181725950] 'agreement among raft nodes before linearized reading' (duration: 148.424063ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:36:05.331497Z","caller":"traceutil/trace.go:171","msg":"trace[60600735] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:593; }","duration":"102.665725ms","start":"2026-05-11T05:36:05.22881Z","end":"2026-05-11T05:36:05.331475Z","steps":["trace[60600735] 'read index received' (duration: 102.00614ms)","trace[60600735] 'applied index is now lower than readState.Index' (duration: 658.945µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:36:05.331573Z","caller":"traceutil/trace.go:171","msg":"trace[100537067] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"227.830562ms","start":"2026-05-11T05:36:05.10371Z","end":"2026-05-11T05:36:05.33154Z","steps":["trace[100537067] 'process raft request' (duration: 227.32886ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:36:05.33164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.823628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rqqls\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-11T05:36:05.33167Z","caller":"traceutil/trace.go:171","msg":"trace[1232804773] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rqqls; range_end:; response_count:1; response_revision:570; }","duration":"102.90792ms","start":"2026-05-11T05:36:05.228754Z","end":"2026-05-11T05:36:05.331662Z","steps":["trace[1232804773] 'agreement among raft nodes before linearized reading' (duration: 102.798048ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:36:05.587571Z","caller":"traceutil/trace.go:171","msg":"trace[162638501] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:594; }","duration":"203.029459ms","start":"2026-05-11T05:36:05.384517Z","end":"2026-05-11T05:36:05.587547Z","steps":["trace[162638501] 'read index received' (duration: 202.848785ms)","trace[162638501] 'applied index is now lower than readState.Index' (duration: 179.704µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:36:05.587624Z","caller":"traceutil/trace.go:171","msg":"trace[540556397] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"229.085929ms","start":"2026-05-11T05:36:05.358496Z","end":"2026-05-11T05:36:05.587582Z","steps":["trace[540556397] 'process raft request' (duration: 228.888405ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:36:05.587719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.178803ms","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-05-11T05:36:05.587792Z","caller":"traceutil/trace.go:171","msg":"trace[270128002] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:571; }","duration":"203.284525ms","start":"2026-05-11T05:36:05.384488Z","end":"2026-05-11T05:36:05.587773Z","steps":["trace[270128002] 'agreement among raft nodes before linearized reading' (duration: 203.175493ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:36:05.587919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.369457ms","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-05-11T05:36:05.588035Z","caller":"traceutil/trace.go:171","msg":"trace[61033511] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:571; }","duration":"203.526701ms","start":"2026-05-11T05:36:05.384488Z","end":"2026-05-11T05:36:05.588015Z","steps":["trace[61033511] 'agreement among raft nodes before linearized reading' (duration: 203.262695ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:36:05.607478Z","caller":"traceutil/trace.go:171","msg":"trace[1309735043] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"206.498657ms","start":"2026-05-11T05:36:05.400956Z","end":"2026-05-11T05:36:05.607454Z","steps":["trace[1309735043] 'process raft request' (duration: 206.298622ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:36:06.286826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.685031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:36:06.287698Z","caller":"traceutil/trace.go:171","msg":"trace[1235290752] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:575; }","duration":"410.801394ms","start":"2026-05-11T05:36:05.876074Z","end":"2026-05-11T05:36:06.286875Z","steps":["trace[1235290752] 'range keys from in-memory index tree' (duration: 410.61902ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:36:06.287833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:36:05.876053Z","time spent":"411.743434ms","remote":"127.0.0.1:52396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-11T05:36:06.292263Z","caller":"traceutil/trace.go:171","msg":"trace[1203105058] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"268.931926ms","start":"2026-05-11T05:36:06.023299Z","end":"2026-05-11T05:36:06.292231Z","steps":["trace[1203105058] 'process raft request' (duration: 261.981521ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:36:21.622739Z","caller":"traceutil/trace.go:171","msg":"trace[1701139534] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"286.922457ms","start":"2026-05-11T05:36:21.335777Z","end":"2026-05-11T05:36:21.622699Z","steps":["trace[1701139534] 'process raft request' (duration: 278.51913ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:36:21.778731Z","caller":"traceutil/trace.go:171","msg":"trace[1732038467] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"151.697787ms","start":"2026-05-11T05:36:21.627005Z","end":"2026-05-11T05:36:21.778703Z","steps":["trace[1732038467] 'process raft request' (duration: 149.565389ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:36:21.779248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.922216ms","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-05-11T05:36:21.779316Z","caller":"traceutil/trace.go:171","msg":"trace[1074616014] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:736; }","duration":"101.06645ms","start":"2026-05-11T05:36:21.678237Z","end":"2026-05-11T05:36:21.779303Z","steps":["trace[1074616014] 'agreement among raft nodes before linearized reading' (duration: 100.60097ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:36:21.778745Z","caller":"traceutil/trace.go:171","msg":"trace[1600011678] linearizableReadLoop","detail":"{readStateIndex:764; appliedIndex:763; }","duration":"100.403795ms","start":"2026-05-11T05:36:21.67831Z","end":"2026-05-11T05:36:21.778714Z","steps":["trace[1600011678] 'read index received' (duration: 98.183165ms)","trace[1600011678] 'applied index is now lower than readState.Index' (duration: 2.21898ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:37:20.162041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.917213ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-11T05:37:20.162247Z","caller":"traceutil/trace.go:171","msg":"trace[1708338513] linearizableReadLoop","detail":"{readStateIndex:1066; appliedIndex:1065; }","duration":"103.020148ms","start":"2026-05-11T05:37:20.059207Z","end":"2026-05-11T05:37:20.162227Z","steps":["trace[1708338513] 'read index received' (duration: 52.672µs)","trace[1708338513] 'applied index is now lower than readState.Index' (duration: 102.965686ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:37:20.162465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.246003ms","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-05-11T05:37:20.162538Z","caller":"traceutil/trace.go:171","msg":"trace[1963135987] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1025; }","duration":"103.367866ms","start":"2026-05-11T05:37:20.05915Z","end":"2026-05-11T05:37:20.162518Z","steps":["trace[1963135987] 'agreement among raft nodes before linearized reading' (duration: 103.158781ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:37:20.268012Z","caller":"traceutil/trace.go:171","msg":"trace[2004373509] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"102.000855ms","start":"2026-05-11T05:37:20.165989Z","end":"2026-05-11T05:37:20.26799Z","steps":["trace[2004373509] 'process raft request' (duration: 101.861822ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:37:20.277431Z","caller":"traceutil/trace.go:171","msg":"trace[1958887168] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"111.149721ms","start":"2026-05-11T05:37:20.166271Z","end":"2026-05-11T05:37:20.277421Z","steps":["trace[1958887168] 'process raft request' (duration: 111.046939ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:37:20.277554Z","caller":"traceutil/trace.go:171","msg":"trace[585385425] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"110.668231ms","start":"2026-05-11T05:37:20.166879Z","end":"2026-05-11T05:37:20.277547Z","steps":["trace[585385425] 'process raft request' (duration: 110.512818ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:37:20.277743Z","caller":"traceutil/trace.go:171","msg":"trace[1580660188] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"109.074534ms","start":"2026-05-11T05:37:20.168652Z","end":"2026-05-11T05:37:20.277726Z","steps":["trace[1580660188] 'process raft request' (duration: 108.87247ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:37:32.786962Z","caller":"traceutil/trace.go:171","msg":"trace[83845914] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"455.37663ms","start":"2026-05-11T05:37:32.331563Z","end":"2026-05-11T05:37:32.78694Z","steps":["trace[83845914] 'process raft request' (duration: 455.246857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:37:32.787302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:37:32.331547Z","time spent":"455.488512ms","remote":"127.0.0.1:52640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T05:37:32.858928Z","caller":"traceutil/trace.go:171","msg":"trace[387025831] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"526.865775ms","start":"2026-05-11T05:37:32.332025Z","end":"2026-05-11T05:37:32.858891Z","steps":["trace[387025831] 'process raft request' (duration: 526.66976ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:37:32.858998Z","caller":"traceutil/trace.go:171","msg":"trace[1614112351] linearizableReadLoop","detail":"{readStateIndex:1162; appliedIndex:1160; }","duration":"526.894446ms","start":"2026-05-11T05:37:32.33208Z","end":"2026-05-11T05:37:32.858975Z","steps":["trace[1614112351] 'read index received' (duration: 454.691624ms)","trace[1614112351] 'applied index is now lower than readState.Index' (duration: 72.201372ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:37:32.859031Z","caller":"traceutil/trace.go:171","msg":"trace[2004667067] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"525.730839ms","start":"2026-05-11T05:37:32.333281Z","end":"2026-05-11T05:37:32.859011Z","steps":["trace[2004667067] 'process raft request' (duration: 525.568986ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:37:32.859119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:37:32.333267Z","time spent":"525.806381ms","remote":"127.0.0.1:52640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:37:32.859144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.931677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T05:37:32.859154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:37:32.332016Z","time spent":"527.019698ms","remote":"127.0.0.1:52640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T05:37:32.859185Z","caller":"traceutil/trace.go:171","msg":"trace[1357967713] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1118; }","duration":"142.008259ms","start":"2026-05-11T05:37:32.717163Z","end":"2026-05-11T05:37:32.859171Z","steps":["trace[1357967713] 'agreement among raft nodes before linearized reading' (duration: 141.931867ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:37:32.859288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.165102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-11T05:37:32.859367Z","caller":"traceutil/trace.go:171","msg":"trace[1396985544] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1118; }","duration":"527.284934ms","start":"2026-05-11T05:37:32.332064Z","end":"2026-05-11T05:37:32.859349Z","steps":["trace[1396985544] 'agreement among raft nodes before linearized reading' (duration: 527.030118ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:37:32.859417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:37:32.332058Z","time spent":"527.350805ms","remote":"127.0.0.1:52640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-11T05:37:45.092011Z","caller":"traceutil/trace.go:171","msg":"trace[925113842] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"140.249899ms","start":"2026-05-11T05:37:44.951738Z","end":"2026-05-11T05:37:45.091988Z","steps":["trace[925113842] 'process raft request' (duration: 140.175827ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:37:45.092027Z","caller":"traceutil/trace.go:171","msg":"trace[1714200857] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"141.172ms","start":"2026-05-11T05:37:44.950816Z","end":"2026-05-11T05:37:45.091988Z","steps":["trace[1714200857] 'process raft request' (duration: 139.506832ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:37:51.669355Z","caller":"traceutil/trace.go:171","msg":"trace[1630661542] linearizableReadLoop","detail":"{readStateIndex:1265; appliedIndex:1264; }","duration":"155.982505ms","start":"2026-05-11T05:37:51.513344Z","end":"2026-05-11T05:37:51.669327Z","steps":["trace[1630661542] 'read index received' (duration: 60.267152ms)","trace[1630661542] 'applied index is now lower than readState.Index' (duration: 95.714223ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:37:51.669417Z","caller":"traceutil/trace.go:171","msg":"trace[1321018462] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"181.639664ms","start":"2026-05-11T05:37:51.487733Z","end":"2026-05-11T05:37:51.669373Z","steps":["trace[1321018462] 'process raft request' (duration: 85.8686ms)","trace[1321018462] 'compare' (duration: 95.57403ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:37:51.669698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.326453ms","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-05-11T05:37:51.6698Z","caller":"traceutil/trace.go:171","msg":"trace[1729552271] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1216; }","duration":"156.471225ms","start":"2026-05-11T05:37:51.513308Z","end":"2026-05-11T05:37:51.669779Z","steps":["trace[1729552271] 'agreement among raft nodes before linearized reading' (duration: 156.136138ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:37:51.721364Z","caller":"traceutil/trace.go:171","msg":"trace[926827278] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"156.899805ms","start":"2026-05-11T05:37:51.564215Z","end":"2026-05-11T05:37:51.721115Z","steps":["trace[926827278] 'process raft request' (duration: 156.712661ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:37:51.730126Z","caller":"traceutil/trace.go:171","msg":"trace[1157193640] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"155.79215ms","start":"2026-05-11T05:37:51.574317Z","end":"2026-05-11T05:37:51.730109Z","steps":["trace[1157193640] 'process raft request' (duration: 155.558415ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:38:03.961463Z","caller":"traceutil/trace.go:171","msg":"trace[436091270] linearizableReadLoop","detail":"{readStateIndex:1342; appliedIndex:1341; }","duration":"184.14299ms","start":"2026-05-11T05:38:03.777296Z","end":"2026-05-11T05:38:03.961439Z","steps":["trace[436091270] 'read index received' (duration: 183.950645ms)","trace[436091270] 'applied index is now lower than readState.Index' (duration: 191.385µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:38:03.961586Z","caller":"traceutil/trace.go:171","msg":"trace[1747247745] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"200.884778ms","start":"2026-05-11T05:38:03.76069Z","end":"2026-05-11T05:38:03.961575Z","steps":["trace[1747247745] 'process raft request' (duration: 200.607691ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:38:03.962045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.730643ms","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-05-11T05:38:03.962127Z","caller":"traceutil/trace.go:171","msg":"trace[245504745] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1291; }","duration":"184.843876ms","start":"2026-05-11T05:38:03.777272Z","end":"2026-05-11T05:38:03.962116Z","steps":["trace[245504745] 'agreement among raft nodes before linearized reading' (duration: 184.662342ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:40:04.078205Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581742011241843524,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-11T05:40:04.268373Z","caller":"traceutil/trace.go:171","msg":"trace[1662796548] linearizableReadLoop","detail":"{readStateIndex:1913; appliedIndex:1912; }","duration":"690.591886ms","start":"2026-05-11T05:40:03.577758Z","end":"2026-05-11T05:40:04.26835Z","steps":["trace[1662796548] 'read index received' (duration: 690.36364ms)","trace[1662796548] 'applied index is now lower than readState.Index' (duration: 227.316µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:40:04.268499Z","caller":"traceutil/trace.go:171","msg":"trace[532516231] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"798.024914ms","start":"2026-05-11T05:40:03.470373Z","end":"2026-05-11T05:40:04.268398Z","steps":["trace[532516231] 'process raft request' (duration: 797.84496ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:40:04.268664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.781864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T05:40:04.268695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:03.470346Z","time spent":"798.249668ms","remote":"127.0.0.1:52640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:40:04.268712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.852161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-11T05:40:04.268699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.640218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:40:04.26876Z","caller":"traceutil/trace.go:171","msg":"trace[1275207497] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1838; }","duration":"284.936238ms","start":"2026-05-11T05:40:03.983805Z","end":"2026-05-11T05:40:04.268742Z","steps":["trace[1275207497] 'agreement among raft nodes before linearized reading' (duration: 284.739013ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:40:04.26877Z","caller":"traceutil/trace.go:171","msg":"trace[357576478] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1838; }","duration":"533.942223ms","start":"2026-05-11T05:40:03.734813Z","end":"2026-05-11T05:40:04.268755Z","steps":["trace[357576478] 'agreement among raft nodes before linearized reading' (duration: 533.771289ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:40:04.268795Z","caller":"traceutil/trace.go:171","msg":"trace[1791942006] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1838; }","duration":"391.825662ms","start":"2026-05-11T05:40:03.876946Z","end":"2026-05-11T05:40:04.268772Z","steps":["trace[1791942006] 'agreement among raft nodes before linearized reading' (duration: 391.649408ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:40:04.268808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:03.734804Z","time spent":"533.995554ms","remote":"127.0.0.1:52640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-11T05:40:04.268814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.052737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"warn","ts":"2026-05-11T05:40:04.268857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:03.876928Z","time spent":"391.920964ms","remote":"127.0.0.1:52386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-11T05:40:04.268905Z","caller":"traceutil/trace.go:171","msg":"trace[1710631453] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1838; }","duration":"691.18271ms","start":"2026-05-11T05:40:03.577705Z","end":"2026-05-11T05:40:04.268887Z","steps":["trace[1710631453] 'agreement among raft nodes before linearized reading' (duration: 690.76531ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:40:04.268956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:03.577675Z","time spent":"691.267091ms","remote":"127.0.0.1:52640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-11T05:40:04.268938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.40393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-05-11T05:40:04.268977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.038384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-11T05:40:04.26904Z","caller":"traceutil/trace.go:171","msg":"trace[464825261] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1838; }","duration":"596.120465ms","start":"2026-05-11T05:40:03.672909Z","end":"2026-05-11T05:40:04.26903Z","steps":["trace[464825261] 'agreement among raft nodes before linearized reading' (duration: 595.574603ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:40:04.269043Z","caller":"traceutil/trace.go:171","msg":"trace[950647925] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1838; }","duration":"536.570093ms","start":"2026-05-11T05:40:03.732443Z","end":"2026-05-11T05:40:04.269013Z","steps":["trace[950647925] 'agreement among raft nodes before linearized reading' (duration: 536.190584ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:40:04.269083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:03.732422Z","time spent":"536.656225ms","remote":"127.0.0.1:52640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-11T05:40:04.269094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:03.672898Z","time spent":"596.163816ms","remote":"127.0.0.1:52640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-11T05:40:04.856452Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581742011241843528,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T05:40:05.357095Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581742011241843528,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T05:40:05.858111Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581742011241843528,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T05:40:06.359158Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581742011241843528,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T05:40:06.860049Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581742011241843528,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T05:40:06.877516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999807931s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-11T05:40:06.877618Z","caller":"traceutil/trace.go:171","msg":"trace[2023810069] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999926554s","start":"2026-05-11T05:40:04.877667Z","end":"2026-05-11T05:40:06.877594Z","steps":["trace[2023810069] 'agreement among raft nodes before linearized reading' (duration: 1.999803591s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:40:06.877683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:04.877639Z","time spent":"2.000030706s","remote":"127.0.0.1:52386","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-05-11T05:40:07.360923Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581742011241843528,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T05:40:07.862052Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581742011241843528,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T05:40:08.362586Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581742011241843528,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T05:40:08.863197Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581742011241843528,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T05:40:08.878772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000114799s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-11T05:40:08.87887Z","caller":"traceutil/trace.go:171","msg":"trace[373979070] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000268833s","start":"2026-05-11T05:40:06.878583Z","end":"2026-05-11T05:40:08.878852Z","steps":["trace[373979070] 'agreement among raft nodes before linearized reading' (duration: 2.00014743s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:40:08.878933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:06.878551Z","time spent":"2.000367534s","remote":"127.0.0.1:52396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/11 05:40:08 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-11T05:40:09.272715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:04.275598Z","time spent":"4.997103858s","remote":"127.0.0.1:52640","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/05/11 05:40:09 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-11T05:40:09.27342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:04.27529Z","time spent":"4.998120761s","remote":"127.0.0.1:52640","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/05/11 05:40:09 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-11T05:40:09.328109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:04.329906Z","time spent":"4.998199483s","remote":"127.0.0.1:52640","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-05-11T05:40:09.36512Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581742011241843528,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T05:40:09.533819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999824262s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-11T05:40:09.533915Z","caller":"traceutil/trace.go:171","msg":"trace[164417648] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999986325s","start":"2026-05-11T05:40:07.533905Z","end":"2026-05-11T05:40:09.533891Z","steps":["trace[164417648] 'agreement among raft nodes before linearized reading' (duration: 1.999855512s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:40:09.533974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:07.533873Z","time spent":"2.000090658s","remote":"127.0.0.1:52386","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-05-11T05:40:09.865833Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581742011241843528,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T05:40:10.36703Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581742011241843528,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T05:40:10.867788Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581742011241843528,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T05:40:10.887313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000135618s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-11T05:40:10.887396Z","caller":"traceutil/trace.go:171","msg":"trace[1412659038] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000264152s","start":"2026-05-11T05:40:08.887107Z","end":"2026-05-11T05:40:10.887371Z","steps":["trace[1412659038] 'agreement among raft nodes before linearized reading' (duration: 2.000158119s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:40:10.887461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:08.887091Z","time spent":"2.000356444s","remote":"127.0.0.1:52386","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-05-11T05:40:11.272334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999277428s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-11T05:40:11.27253Z","caller":"traceutil/trace.go:171","msg":"trace[708816620] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.999533164s","start":"2026-05-11T05:40:06.272976Z","end":"2026-05-11T05:40:11.27251Z","steps":["trace[708816620] 'agreement among raft nodes before linearized reading' (duration: 4.999316599s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:40:11.272728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:06.272952Z","time spent":"4.999616565s","remote":"127.0.0.1:52640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/05/11 05:40:11 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-11T05:40:11.275814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:04.275599Z","time spent":"7.000195024s","remote":"127.0.0.1:52640","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-05-11T05:40:11.277652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:04.276964Z","time spent":"7.000678385s","remote":"127.0.0.1:52640","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-05-11T05:40:11.356216Z","caller":"etcdserver/v3_server.go:909","msg":"timed out waiting for read index response (local node might have slow network)","timeout":"7s"} {"level":"warn","ts":"2026-05-11T05:40:11.35651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.000331077s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-05-11T05:40:11.356575Z","caller":"traceutil/trace.go:171","msg":"trace[883235769] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; }","duration":"7.000458659s","start":"2026-05-11T05:40:04.356103Z","end":"2026-05-11T05:40:11.356562Z","steps":["trace[883235769] 'agreement among raft nodes before linearized reading' (duration: 7.000377097s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:40:11.356613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:04.356078Z","time spent":"7.000525271s","remote":"127.0.0.1:45498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":0,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-05-11T05:40:11.856512Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581742011241843539,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T05:40:12.356888Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581742011241843539,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T05:40:12.857885Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581742011241843539,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T05:40:12.888557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999965485s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-11T05:40:12.888631Z","caller":"traceutil/trace.go:171","msg":"trace[855601121] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000115688s","start":"2026-05-11T05:40:10.888496Z","end":"2026-05-11T05:40:12.888612Z","steps":["trace[855601121] 'agreement among raft nodes before linearized reading' (duration: 1.999996105s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:40:12.88868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:10.888475Z","time spent":"2.00019561s","remote":"127.0.0.1:52396","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-05-11T05:40:13.202456Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"8.927053646s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-11T05:40:13.258275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.977436854s","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-05-11T05:40:13.258375Z","caller":"traceutil/trace.go:171","msg":"trace[764282991] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1839; }","duration":"6.977546716s","start":"2026-05-11T05:40:06.280812Z","end":"2026-05-11T05:40:13.258359Z","steps":["trace[764282991] 'range keys from in-memory index tree' (duration: 6.977424524s)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:40:13.32766Z","caller":"traceutil/trace.go:171","msg":"trace[1576695402] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"4.755285557s","start":"2026-05-11T05:40:08.57235Z","end":"2026-05-11T05:40:13.327636Z","steps":["trace[1576695402] 'process raft request' (duration: 4.754861647s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:40:13.3278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:08.572331Z","time spent":"4.755387849s","remote":"127.0.0.1:52640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:40:13.328012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:08.882675Z","time spent":"4.445334167s","remote":"127.0.0.1:52474","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-11T05:40:13.335256Z","caller":"traceutil/trace.go:171","msg":"trace[478230741] linearizableReadLoop","detail":"{readStateIndex:1923; appliedIndex:1914; }","duration":"1.978806782s","start":"2026-05-11T05:40:11.356431Z","end":"2026-05-11T05:40:13.335237Z","steps":["trace[478230741] 'read index received' (duration: 1.846868584s)","trace[478230741] 'applied index is now lower than readState.Index' (duration: 131.937448ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:40:13.335388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.06004707s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-11T05:40:13.335388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.639512643s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T05:40:13.335396Z","caller":"traceutil/trace.go:171","msg":"trace[147076112] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"507.424137ms","start":"2026-05-11T05:40:12.827945Z","end":"2026-05-11T05:40:13.335369Z","steps":["trace[147076112] 'process raft request' (duration: 507.176931ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:40:13.33542Z","caller":"traceutil/trace.go:171","msg":"trace[1293400825] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1848; }","duration":"2.060114251s","start":"2026-05-11T05:40:11.275298Z","end":"2026-05-11T05:40:13.335412Z","steps":["trace[1293400825] 'agreement among raft nodes before linearized reading' (duration: 2.059972107s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:40:13.335453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:11.275281Z","time spent":"2.060166162s","remote":"127.0.0.1:52640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-11T05:40:13.33545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.625566571s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-11T05:40:13.335481Z","caller":"traceutil/trace.go:171","msg":"trace[156345949] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1848; }","duration":"8.625644673s","start":"2026-05-11T05:40:04.709828Z","end":"2026-05-11T05:40:13.335473Z","steps":["trace[156345949] 'agreement among raft nodes before linearized reading' (duration: 8.62551898s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:40:13.335499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.905159331s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T05:40:13.335512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:12.827924Z","time spent":"507.524168ms","remote":"127.0.0.1:52640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T05:40:13.335534Z","caller":"traceutil/trace.go:171","msg":"trace[756481605] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1848; }","duration":"6.905245063s","start":"2026-05-11T05:40:06.430278Z","end":"2026-05-11T05:40:13.335523Z","steps":["trace[756481605] 'agreement among raft nodes before linearized reading' (duration: 6.904970127s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:40:13.335541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.637170826s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:40:13.335561Z","caller":"traceutil/trace.go:171","msg":"trace[1371859290] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1848; }","duration":"8.637223688s","start":"2026-05-11T05:40:04.698331Z","end":"2026-05-11T05:40:13.335555Z","steps":["trace[1371859290] 'agreement among raft nodes before linearized reading' (duration: 8.637188367s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:40:13.335567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:06.430256Z","time spent":"6.905302534s","remote":"127.0.0.1:45296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-11T05:40:13.335578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:04.698317Z","time spent":"8.637257248s","remote":"127.0.0.1:45402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-05-11T05:40:13.335594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.059850415s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-11T05:40:13.335616Z","caller":"traceutil/trace.go:171","msg":"trace[43376195] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1848; }","duration":"2.059932587s","start":"2026-05-11T05:40:11.275677Z","end":"2026-05-11T05:40:13.33561Z","steps":["trace[43376195] 'agreement among raft nodes before linearized reading' (duration: 2.059872656s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:40:13.335585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.056604975s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-05-11T05:40:13.335689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.829945736s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-11T05:40:13.335679Z","caller":"traceutil/trace.go:171","msg":"trace[1294540809] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1848; }","duration":"7.056760718s","start":"2026-05-11T05:40:06.278898Z","end":"2026-05-11T05:40:13.335659Z","steps":["trace[1294540809] 'agreement among raft nodes before linearized reading' (duration: 7.056498162s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:40:13.335702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.278154439s","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:534"} {"level":"warn","ts":"2026-05-11T05:40:13.335734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:06.278879Z","time spent":"7.056848591s","remote":"127.0.0.1:52640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-11T05:40:13.335739Z","caller":"traceutil/trace.go:171","msg":"trace[245187219] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1848; }","duration":"6.278235871s","start":"2026-05-11T05:40:07.057491Z","end":"2026-05-11T05:40:13.335727Z","steps":["trace[245187219] 'agreement among raft nodes before linearized reading' (duration: 6.278158439s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:40:13.335633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:11.275642Z","time spent":"2.059987748s","remote":"127.0.0.1:52640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-11T05:40:13.335765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.670601591s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.233\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:40:13.335781Z","caller":"traceutil/trace.go:171","msg":"trace[119392674] range","detail":"{range_begin:/registry/masterleases/199.204.45.233; range_end:; response_count:0; response_revision:1848; }","duration":"8.670667983s","start":"2026-05-11T05:40:04.665108Z","end":"2026-05-11T05:40:13.335776Z","steps":["trace[119392674] 'agreement among raft nodes before linearized reading' (duration: 8.670645652s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:40:13.335506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:04.709816Z","time spent":"8.625684334s","remote":"127.0.0.1:52640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-11T05:40:13.335796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:04.665089Z","time spent":"8.670703763s","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/199.204.45.233\" "} {"level":"warn","ts":"2026-05-11T05:40:13.335774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:07.057466Z","time spent":"6.278296253s","remote":"127.0.0.1:52640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-11T05:40:13.335526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.066770436s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T05:40:13.335866Z","caller":"traceutil/trace.go:171","msg":"trace[379873295] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1848; }","duration":"3.067166675s","start":"2026-05-11T05:40:10.268689Z","end":"2026-05-11T05:40:13.335855Z","steps":["trace[379873295] 'agreement among raft nodes before linearized reading' (duration: 3.066784206s)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:40:13.33571Z","caller":"traceutil/trace.go:171","msg":"trace[1016439390] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1848; }","duration":"8.830013677s","start":"2026-05-11T05:40:04.50569Z","end":"2026-05-11T05:40:13.335704Z","steps":["trace[1016439390] 'agreement among raft nodes before linearized reading' (duration: 8.829963146s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:40:13.335897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:10.268657Z","time spent":"3.067227006s","remote":"127.0.0.1:52444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-05-11T05:40:13.335896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.032898402s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-11T05:40:13.335906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:04.505664Z","time spent":"8.830231483s","remote":"127.0.0.1:52640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-11T05:40:13.335822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.703657348s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:40:13.335937Z","caller":"traceutil/trace.go:171","msg":"trace[1673503978] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1848; }","duration":"7.032975994s","start":"2026-05-11T05:40:06.302945Z","end":"2026-05-11T05:40:13.335921Z","steps":["trace[1673503978] 'agreement among raft nodes before linearized reading' (duration: 7.032907213s)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:40:13.335957Z","caller":"traceutil/trace.go:171","msg":"trace[1508096603] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1848; }","duration":"1.703849272s","start":"2026-05-11T05:40:11.632097Z","end":"2026-05-11T05:40:13.335946Z","steps":["trace[1508096603] 'agreement among raft nodes before linearized reading' (duration: 1.703699988s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:40:13.335983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:11.632063Z","time spent":"1.703911814s","remote":"127.0.0.1:45458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-05-11T05:40:13.335976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:06.302924Z","time spent":"7.033042346s","remote":"127.0.0.1:52634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-05-11T05:40:13.335741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.662374923s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T05:40:13.335631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.005387314s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:550"} {"level":"info","ts":"2026-05-11T05:40:13.336057Z","caller":"traceutil/trace.go:171","msg":"trace[1387011754] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1848; }","duration":"8.662735551s","start":"2026-05-11T05:40:04.673307Z","end":"2026-05-11T05:40:13.336043Z","steps":["trace[1387011754] 'agreement among raft nodes before linearized reading' (duration: 8.662416754s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:40:13.336081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.969433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T05:40:13.336092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:04.67328Z","time spent":"8.662800503s","remote":"127.0.0.1:45508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-11T05:40:13.336103Z","caller":"traceutil/trace.go:171","msg":"trace[427450969] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1848; }","duration":"440.022544ms","start":"2026-05-11T05:40:12.896074Z","end":"2026-05-11T05:40:13.336097Z","steps":["trace[427450969] 'agreement among raft nodes before linearized reading' (duration: 439.990654ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:40:13.336125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:12.896056Z","time spent":"440.064386ms","remote":"127.0.0.1:52386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-11T05:40:13.336057Z","caller":"traceutil/trace.go:171","msg":"trace[1156140079] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:1848; }","duration":"4.005854255s","start":"2026-05-11T05:40:09.330192Z","end":"2026-05-11T05:40:13.336046Z","steps":["trace[1156140079] 'agreement among raft nodes before linearized reading' (duration: 4.005354024s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:40:13.33615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:09.330166Z","time spent":"4.005977197s","remote":"127.0.0.1:52640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":573,"request content":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" "} {"level":"info","ts":"2026-05-11T05:40:13.335446Z","caller":"traceutil/trace.go:171","msg":"trace[1056488717] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1848; }","duration":"2.639632946s","start":"2026-05-11T05:40:10.695801Z","end":"2026-05-11T05:40:13.335434Z","steps":["trace[1056488717] 'agreement among raft nodes before linearized reading' (duration: 2.639465892s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:40:13.336236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.932566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-11T05:40:13.336236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:10.695762Z","time spent":"2.640459325s","remote":"127.0.0.1:52640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":13,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"info","ts":"2026-05-11T05:40:13.336254Z","caller":"traceutil/trace.go:171","msg":"trace[2030205367] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1848; }","duration":"691.983828ms","start":"2026-05-11T05:40:12.644265Z","end":"2026-05-11T05:40:13.336249Z","steps":["trace[2030205367] 'agreement among raft nodes before linearized reading' (duration: 691.947377ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:40:13.336273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:12.644248Z","time spent":"692.020408ms","remote":"127.0.0.1:52648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":5,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-05-11T05:40:13.336365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"916.713828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-11T05:40:13.336382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.922752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T05:40:13.336403Z","caller":"traceutil/trace.go:171","msg":"trace[1271170748] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1848; }","duration":"916.777749ms","start":"2026-05-11T05:40:12.419613Z","end":"2026-05-11T05:40:13.336391Z","steps":["trace[1271170748] 'agreement among raft nodes before linearized reading' (duration: 916.721398ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:40:13.336412Z","caller":"traceutil/trace.go:171","msg":"trace[1770073691] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1848; }","duration":"933.955182ms","start":"2026-05-11T05:40:12.402448Z","end":"2026-05-11T05:40:13.336404Z","steps":["trace[1770073691] 'agreement among raft nodes before linearized reading' (duration: 933.901611ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:40:13.336431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:12.4196Z","time spent":"916.822051ms","remote":"127.0.0.1:45476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-11T05:40:13.336438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:12.402397Z","time spent":"934.033844ms","remote":"127.0.0.1:45494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":4,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-05-11T05:40:13.336457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.508673554s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T05:40:13.336466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.235938307s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:40:13.336478Z","caller":"traceutil/trace.go:171","msg":"trace[812542970] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1848; }","duration":"7.508755396s","start":"2026-05-11T05:40:05.827716Z","end":"2026-05-11T05:40:13.336472Z","steps":["trace[812542970] 'agreement among raft nodes before linearized reading' (duration: 7.508721575s)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:40:13.336494Z","caller":"traceutil/trace.go:171","msg":"trace[847730798] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1848; }","duration":"7.236003287s","start":"2026-05-11T05:40:06.100482Z","end":"2026-05-11T05:40:13.336485Z","steps":["trace[847730798] 'agreement among raft nodes before linearized reading' (duration: 7.235954306s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:40:13.336499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:05.827683Z","time spent":"7.508811107s","remote":"127.0.0.1:45276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-05-11T05:40:13.336519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:06.100462Z","time spent":"7.236050099s","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-11T05:40:13.336772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.613372189s","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-05-11T05:40:13.336806Z","caller":"traceutil/trace.go:171","msg":"trace[905500855] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1848; }","duration":"7.61342736s","start":"2026-05-11T05:40:05.723363Z","end":"2026-05-11T05:40:13.33679Z","steps":["trace[905500855] 'agreement among raft nodes before linearized reading' (duration: 7.611964977s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:40:13.336824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:05.723346Z","time spent":"7.613474341s","remote":"127.0.0.1:52640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-11T05:41:05.890493Z","caller":"traceutil/trace.go:171","msg":"trace[7715079] transaction","detail":"{read_only:false; response_revision:2111; number_of_response:1; }","duration":"128.115183ms","start":"2026-05-11T05:41:05.762346Z","end":"2026-05-11T05:41:05.890461Z","steps":["trace[7715079] 'process raft request' (duration: 51.597005ms)","trace[7715079] 'compare' (duration: 76.314813ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:41:05.890691Z","caller":"traceutil/trace.go:171","msg":"trace[772031392] linearizableReadLoop","detail":"{readStateIndex:2201; appliedIndex:2198; }","duration":"125.471352ms","start":"2026-05-11T05:41:05.765205Z","end":"2026-05-11T05:41:05.890676Z","steps":["trace[772031392] 'read index received' (duration: 48.806441ms)","trace[772031392] 'applied index is now lower than readState.Index' (duration: 76.663971ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:41:05.890955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.736929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-11T05:41:05.890932Z","caller":"traceutil/trace.go:171","msg":"trace[206801825] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"127.205662ms","start":"2026-05-11T05:41:05.763564Z","end":"2026-05-11T05:41:05.89077Z","steps":["trace[206801825] 'process raft request' (duration: 126.855264ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:41:05.891Z","caller":"traceutil/trace.go:171","msg":"trace[1163420785] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2113; }","duration":"125.850591ms","start":"2026-05-11T05:41:05.765138Z","end":"2026-05-11T05:41:05.890989Z","steps":["trace[1163420785] 'agreement among raft nodes before linearized reading' (duration: 125.659167ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:41:05.891034Z","caller":"traceutil/trace.go:171","msg":"trace[1813363042] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"125.78529ms","start":"2026-05-11T05:41:05.765008Z","end":"2026-05-11T05:41:05.890794Z","steps":["trace[1813363042] 'process raft request' (duration: 125.606726ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:05.891076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.091863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-11T05:41:05.891174Z","caller":"traceutil/trace.go:171","msg":"trace[426253112] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2113; }","duration":"125.250337ms","start":"2026-05-11T05:41:05.765902Z","end":"2026-05-11T05:41:05.891152Z","steps":["trace[426253112] 'agreement among raft nodes before linearized reading' (duration: 124.980771ms)"],"step_count":1}