{"level":"warn","ts":"2026-04-23T16:30:21.909634Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-23T16:30:21.909737Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.102:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.102:2380","--initial-cluster=instance=https://199.19.213.102:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.102:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.102:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-23T16:30:21.90981Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-23T16:30:21.909822Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.102:2380"]} {"level":"info","ts":"2026-04-23T16:30:21.909838Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-23T16:30:21.910624Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.102:2379"]} {"level":"info","ts":"2026-04-23T16:30:21.910699Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.102:2380"],"listen-peer-urls":["https://199.19.213.102:2380"],"advertise-client-urls":["https://199.19.213.102:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.102:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.102:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-23T16:30:21.915483Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.567917ms"} {"level":"info","ts":"2026-04-23T16:30:21.921896Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1ee87564e61d159f","cluster-id":"92a92aec402e97c3"} {"level":"info","ts":"2026-04-23T16:30:21.921985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f switched to configuration voters=()"} {"level":"info","ts":"2026-04-23T16:30:21.922018Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f became follower at term 0"} {"level":"info","ts":"2026-04-23T16:30:21.922036Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1ee87564e61d159f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-23T16:30:21.922048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f became follower at term 1"} {"level":"info","ts":"2026-04-23T16:30:21.922098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f switched to configuration voters=(2227159091952555423)"} {"level":"warn","ts":"2026-04-23T16:30:21.928238Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-23T16:30:21.930085Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-23T16:30:21.931296Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-23T16:30:21.933577Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1ee87564e61d159f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-23T16:30:21.937609Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1ee87564e61d159f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-23T16:30:21.937999Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-23T16:30:21.939816Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-23T16:30:21.939841Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-23T16:30:21.940073Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-23T16:30:21.940093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f switched to configuration voters=(2227159091952555423)"} {"level":"info","ts":"2026-04-23T16:30:21.940233Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"92a92aec402e97c3","local-member-id":"1ee87564e61d159f","added-peer-id":"1ee87564e61d159f","added-peer-peer-urls":["https://199.19.213.102:2380"]} {"level":"info","ts":"2026-04-23T16:30:21.940276Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-23T16:30:21.940233Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1ee87564e61d159f","initial-advertise-peer-urls":["https://199.19.213.102:2380"],"listen-peer-urls":["https://199.19.213.102:2380"],"advertise-client-urls":["https://199.19.213.102:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.102:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-23T16:30:21.940316Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.102:2380"} {"level":"info","ts":"2026-04-23T16:30:21.940334Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.102:2380"} {"level":"info","ts":"2026-04-23T16:30:22.522408Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f is starting a new election at term 1"} {"level":"info","ts":"2026-04-23T16:30:22.52247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f became pre-candidate at term 1"} {"level":"info","ts":"2026-04-23T16:30:22.522519Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f received MsgPreVoteResp from 1ee87564e61d159f at term 1"} {"level":"info","ts":"2026-04-23T16:30:22.522539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f became candidate at term 2"} {"level":"info","ts":"2026-04-23T16:30:22.52255Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f received MsgVoteResp from 1ee87564e61d159f at term 2"} {"level":"info","ts":"2026-04-23T16:30:22.522566Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f became leader at term 2"} {"level":"info","ts":"2026-04-23T16:30:22.522579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1ee87564e61d159f elected leader 1ee87564e61d159f at term 2"} {"level":"info","ts":"2026-04-23T16:30:22.52354Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1ee87564e61d159f","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.102:2379]}","request-path":"/0/members/1ee87564e61d159f/attributes","cluster-id":"92a92aec402e97c3","publish-timeout":"7s"} {"level":"info","ts":"2026-04-23T16:30:22.523703Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-23T16:30:22.523812Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-23T16:30:22.523858Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T16:30:22.524816Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"92a92aec402e97c3","local-member-id":"1ee87564e61d159f","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T16:30:22.524944Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T16:30:22.524976Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T16:30:22.525388Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-23T16:30:22.525409Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-23T16:30:22.52644Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.102:2379"} {"level":"info","ts":"2026-04-23T16:30:22.527666Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-23T16:30:24.412463Z","caller":"traceutil/trace.go:171","msg":"trace[1463560683] linearizableReadLoop","detail":"{readStateIndex:12; appliedIndex:5; }","duration":"455.888459ms","start":"2026-04-23T16:30:23.956551Z","end":"2026-04-23T16:30:24.41244Z","steps":["trace[1463560683] 'read index received' (duration: 403.495445ms)","trace[1463560683] 'applied index is now lower than readState.Index' (duration: 52.392094ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:30:24.41258Z","caller":"traceutil/trace.go:171","msg":"trace[1836961229] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"468.904892ms","start":"2026-04-23T16:30:23.943662Z","end":"2026-04-23T16:30:24.412567Z","steps":["trace[1836961229] 'process raft request' (duration: 468.599037ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:30:24.412567Z","caller":"traceutil/trace.go:171","msg":"trace[892279724] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"540.692216ms","start":"2026-04-23T16:30:23.871856Z","end":"2026-04-23T16:30:24.412548Z","steps":["trace[892279724] 'process raft request' (duration: 488.18405ms)","trace[892279724] 'compare' (duration: 52.005217ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:30:24.412758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.84555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-23T16:30:24.41282Z","caller":"traceutil/trace.go:171","msg":"trace[329482881] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:9; }","duration":"468.960821ms","start":"2026-04-23T16:30:23.943849Z","end":"2026-04-23T16:30:24.41281Z","steps":["trace[329482881] 'agreement among raft nodes before linearized reading' (duration: 468.800679ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:30:24.412849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:30:23.943828Z","time spent":"469.013763ms","remote":"127.0.0.1:56324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-23T16:30:24.413097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:30:23.943641Z","time spent":"468.964353ms","remote":"127.0.0.1:56678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T16:30:24.413147Z","caller":"traceutil/trace.go:171","msg":"trace[2130922832] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"467.544238ms","start":"2026-04-23T16:30:23.945592Z","end":"2026-04-23T16:30:24.413136Z","steps":["trace[2130922832] 'process raft request' (duration: 466.719704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:30:24.413216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:30:23.945577Z","time spent":"467.603179ms","remote":"127.0.0.1:56750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T16:30:24.413276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.520832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-23T16:30:24.413316Z","caller":"traceutil/trace.go:171","msg":"trace[1897184380] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:9; }","duration":"469.580482ms","start":"2026-04-23T16:30:23.943724Z","end":"2026-04-23T16:30:24.413305Z","steps":["trace[1897184380] 'agreement among raft nodes before linearized reading' (duration: 469.523382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:30:24.413366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:30:23.943712Z","time spent":"469.646904ms","remote":"127.0.0.1:56296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"info","ts":"2026-04-23T16:30:24.413377Z","caller":"traceutil/trace.go:171","msg":"trace[1507388288] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"467.259683ms","start":"2026-04-23T16:30:23.946104Z","end":"2026-04-23T16:30:24.413364Z","steps":["trace[1507388288] 'process raft request' (duration: 466.285677ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:30:24.413494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:30:23.946085Z","time spent":"467.385635ms","remote":"127.0.0.1:56750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T16:30:24.413514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.120875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.102\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-23T16:30:24.413541Z","caller":"traceutil/trace.go:171","msg":"trace[737195845] range","detail":"{range_begin:/registry/masterleases/199.19.213.102; range_end:; response_count:0; response_revision:9; }","duration":"472.171956ms","start":"2026-04-23T16:30:23.941361Z","end":"2026-04-23T16:30:24.413533Z","steps":["trace[737195845] 'agreement among raft nodes before linearized reading' (duration: 472.134516ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:30:24.41358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:30:23.941349Z","time spent":"472.225107ms","remote":"127.0.0.1:56254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.19.213.102\" "} {"level":"info","ts":"2026-04-23T16:30:24.413712Z","caller":"traceutil/trace.go:171","msg":"trace[873227605] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"467.758801ms","start":"2026-04-23T16:30:23.945944Z","end":"2026-04-23T16:30:24.413702Z","steps":["trace[873227605] 'process raft request' (duration: 466.401688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:30:24.413754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.138484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-23T16:30:24.413795Z","caller":"traceutil/trace.go:171","msg":"trace[2000339640] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:9; }","duration":"490.190895ms","start":"2026-04-23T16:30:23.923597Z","end":"2026-04-23T16:30:24.413788Z","steps":["trace[2000339640] 'agreement among raft nodes before linearized reading' (duration: 490.139454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:30:24.413801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:30:23.945927Z","time spent":"467.844013ms","remote":"127.0.0.1:56750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T16:30:24.413825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:30:23.92359Z","time spent":"490.230215ms","remote":"127.0.0.1:56324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-23T16:30:24.414791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:30:23.871847Z","time spent":"540.764327ms","remote":"127.0.0.1:56290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:30:24.415462Z","caller":"traceutil/trace.go:171","msg":"trace[774084606] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"469.358799ms","start":"2026-04-23T16:30:23.946078Z","end":"2026-04-23T16:30:24.415437Z","steps":["trace[774084606] 'process raft request' (duration: 466.288987ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:30:24.415417Z","caller":"traceutil/trace.go:171","msg":"trace[2048372367] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"469.206697ms","start":"2026-04-23T16:30:23.946159Z","end":"2026-04-23T16:30:24.415365Z","steps":["trace[2048372367] 'process raft request' (duration: 466.249397ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:30:24.415552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:30:23.946062Z","time spent":"469.45609ms","remote":"127.0.0.1:56750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T16:30:24.415865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.151315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-23T16:30:24.415928Z","caller":"traceutil/trace.go:171","msg":"trace[927507374] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:9; }","duration":"544.207656ms","start":"2026-04-23T16:30:23.871693Z","end":"2026-04-23T16:30:24.4159Z","steps":["trace[927507374] 'agreement among raft nodes before linearized reading' (duration: 543.967572ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:30:24.415973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:30:23.871688Z","time spent":"544.269507ms","remote":"127.0.0.1:56382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":0,"response size":27,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-23T16:30:24.415996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:30:23.946142Z","time spent":"469.42338ms","remote":"127.0.0.1:56750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T16:30:24.41372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.840518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-23T16:30:24.430283Z","caller":"traceutil/trace.go:171","msg":"trace[1862889962] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:9; }","duration":"506.417682ms","start":"2026-04-23T16:30:23.923854Z","end":"2026-04-23T16:30:24.430271Z","steps":["trace[1862889962] 'agreement among raft nodes before linearized reading' (duration: 489.836209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:30:24.430321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:30:23.923846Z","time spent":"506.467542ms","remote":"127.0.0.1:56324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-23T16:30:24.430479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.51574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-23T16:30:24.430517Z","caller":"traceutil/trace.go:171","msg":"trace[97870088] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:9; }","duration":"558.549541ms","start":"2026-04-23T16:30:23.871954Z","end":"2026-04-23T16:30:24.430503Z","steps":["trace[97870088] 'agreement among raft nodes before linearized reading' (duration: 558.345997ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:30:24.430535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:30:23.871939Z","time spent":"558.591651ms","remote":"127.0.0.1:56616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-04-23T16:30:24.430663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.449329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-23T16:30:24.430741Z","caller":"traceutil/trace.go:171","msg":"trace[686548302] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:9; }","duration":"507.55458ms","start":"2026-04-23T16:30:23.923156Z","end":"2026-04-23T16:30:24.43071Z","steps":["trace[686548302] 'agreement among raft nodes before linearized reading' (duration: 507.319506ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:30:24.430773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:30:23.923144Z","time spent":"507.623631ms","remote":"127.0.0.1:56324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-04-23T16:30:24.468598Z","caller":"traceutil/trace.go:171","msg":"trace[22182175] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"507.00074ms","start":"2026-04-23T16:30:23.96156Z","end":"2026-04-23T16:30:24.468561Z","steps":["trace[22182175] 'process raft request' (duration: 506.94386ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:30:24.468677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.724991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-23T16:30:24.468733Z","caller":"traceutil/trace.go:171","msg":"trace[1518848069] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"474.827052ms","start":"2026-04-23T16:30:23.993892Z","end":"2026-04-23T16:30:24.468719Z","steps":["trace[1518848069] 'agreement among raft nodes before linearized reading' (duration: 474.68248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:30:24.468771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:30:23.993882Z","time spent":"474.881473ms","remote":"127.0.0.1:56324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-23T16:30:24.46881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:30:23.961549Z","time spent":"507.125823ms","remote":"127.0.0.1:56468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T16:30:24.468878Z","caller":"traceutil/trace.go:171","msg":"trace[458504429] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"509.469413ms","start":"2026-04-23T16:30:23.959372Z","end":"2026-04-23T16:30:24.468842Z","steps":["trace[458504429] 'process raft request' (duration: 508.971255ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:30:24.470361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:30:23.95936Z","time spent":"510.933977ms","remote":"127.0.0.1:56382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T16:30:28.338936Z","caller":"traceutil/trace.go:171","msg":"trace[1595305459] transaction","detail":"{read_only:false; response_revision:270; number_of_response:1; }","duration":"101.605854ms","start":"2026-04-23T16:30:28.237297Z","end":"2026-04-23T16:30:28.338903Z","steps":["trace[1595305459] 'compare' (duration: 91.397479ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:30:34.024645Z","caller":"traceutil/trace.go:171","msg":"trace[1143826624] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"329.90779ms","start":"2026-04-23T16:30:33.694705Z","end":"2026-04-23T16:30:34.024613Z","steps":["trace[1143826624] 'process raft request' (duration: 329.658186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:30:34.024849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:30:33.694676Z","time spent":"330.074522ms","remote":"127.0.0.1:56690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:30:34.164683Z","caller":"traceutil/trace.go:171","msg":"trace[1905158385] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"134.403343ms","start":"2026-04-23T16:30:34.029778Z","end":"2026-04-23T16:30:34.164181Z","steps":["trace[1905158385] 'process raft request' (duration: 125.505652ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:30:34.457378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.310546ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-23T16:30:34.458155Z","caller":"traceutil/trace.go:171","msg":"trace[90330177] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"427.622387ms","start":"2026-04-23T16:30:34.030439Z","end":"2026-04-23T16:30:34.458062Z","steps":["trace[90330177] 'process raft request' (duration: 272.282336ms)","trace[90330177] 'compare' (duration: 153.176744ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:30:34.458434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:30:34.03042Z","time spent":"427.896111ms","remote":"127.0.0.1:56724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3678,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T16:30:34.640857Z","caller":"traceutil/trace.go:171","msg":"trace[1213794331] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"176.882928ms","start":"2026-04-23T16:30:34.463943Z","end":"2026-04-23T16:30:34.640826Z","steps":["trace[1213794331] 'process raft request' (duration: 176.786967ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:30:34.641231Z","caller":"traceutil/trace.go:171","msg":"trace[567420402] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"172.784719ms","start":"2026-04-23T16:30:34.468428Z","end":"2026-04-23T16:30:34.641213Z","steps":["trace[567420402] 'process raft request' (duration: 172.380892ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:30:34.641252Z","caller":"traceutil/trace.go:171","msg":"trace[195126136] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"175.82442ms","start":"2026-04-23T16:30:34.465389Z","end":"2026-04-23T16:30:34.641213Z","steps":["trace[195126136] 'process raft request' (duration: 175.382102ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:30:34.641568Z","caller":"traceutil/trace.go:171","msg":"trace[2084108630] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"182.026457ms","start":"2026-04-23T16:30:34.459531Z","end":"2026-04-23T16:30:34.641557Z","steps":["trace[2084108630] 'process raft request' (duration: 115.624564ms)","trace[2084108630] 'compare' (duration: 65.405596ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:30:38.832367Z","caller":"traceutil/trace.go:171","msg":"trace[2107400784] linearizableReadLoop","detail":"{readStateIndex:415; appliedIndex:414; }","duration":"110.22218ms","start":"2026-04-23T16:30:38.722126Z","end":"2026-04-23T16:30:38.832348Z","steps":["trace[2107400784] 'read index received' (duration: 29.3491ms)","trace[2107400784] 'applied index is now lower than readState.Index' (duration: 80.87236ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:30:38.832459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.324502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-23T16:30:38.832481Z","caller":"traceutil/trace.go:171","msg":"trace[263552861] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:401; }","duration":"110.389843ms","start":"2026-04-23T16:30:38.722083Z","end":"2026-04-23T16:30:38.832473Z","steps":["trace[263552861] 'agreement among raft nodes before linearized reading' (duration: 110.333202ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:30:38.832452Z","caller":"traceutil/trace.go:171","msg":"trace[67918625] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"117.886882ms","start":"2026-04-23T16:30:38.714535Z","end":"2026-04-23T16:30:38.832422Z","steps":["trace[67918625] 'process raft request' (duration: 37.005161ms)","trace[67918625] 'compare' (duration: 80.627436ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:30:47.751654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.706405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:30:47.751741Z","caller":"traceutil/trace.go:171","msg":"trace[1373694970] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:0; response_revision:424; }","duration":"234.862328ms","start":"2026-04-23T16:30:47.516864Z","end":"2026-04-23T16:30:47.751726Z","steps":["trace[1373694970] 'range keys from in-memory index tree' (duration: 234.636094ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:30:48.766486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.963602ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-23T16:30:48.766594Z","caller":"traceutil/trace.go:171","msg":"trace[1629853725] linearizableReadLoop","detail":"{readStateIndex:443; appliedIndex:442; }","duration":"475.537274ms","start":"2026-04-23T16:30:48.291037Z","end":"2026-04-23T16:30:48.766574Z","steps":["trace[1629853725] 'read index received' (duration: 38.791µs)","trace[1629853725] 'applied index is now lower than readState.Index' (duration: 475.497163ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:30:48.766724Z","caller":"traceutil/trace.go:171","msg":"trace[946735245] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"872.825034ms","start":"2026-04-23T16:30:47.893847Z","end":"2026-04-23T16:30:48.766672Z","steps":["trace[946735245] 'process raft request' (duration: 117.596637ms)","trace[946735245] 'compare' (duration: 754.545894ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:30:48.766982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:30:47.893826Z","time spent":"873.050157ms","remote":"127.0.0.1:56414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":505,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T16:30:48.767131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.088064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T16:30:48.767152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.185014ms","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-04-23T16:30:48.767188Z","caller":"traceutil/trace.go:171","msg":"trace[3905398] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:427; }","duration":"476.151125ms","start":"2026-04-23T16:30:48.291021Z","end":"2026-04-23T16:30:48.767172Z","steps":["trace[3905398] 'agreement among raft nodes before linearized reading' (duration: 476.017693ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:30:48.767197Z","caller":"traceutil/trace.go:171","msg":"trace[652812524] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:427; }","duration":"337.232415ms","start":"2026-04-23T16:30:48.429952Z","end":"2026-04-23T16:30:48.767184Z","steps":["trace[652812524] 'agreement among raft nodes before linearized reading' (duration: 337.123503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:30:48.76723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:30:48.429906Z","time spent":"337.316957ms","remote":"127.0.0.1:56482","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-04-23T16:30:48.767239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:30:48.290965Z","time spent":"476.258347ms","remote":"127.0.0.1:56216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-23T16:30:48.934427Z","caller":"traceutil/trace.go:171","msg":"trace[1899745212] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"161.865881ms","start":"2026-04-23T16:30:48.772512Z","end":"2026-04-23T16:30:48.934378Z","steps":["trace[1899745212] 'process raft request' (duration: 161.717749ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:30:48.934487Z","caller":"traceutil/trace.go:171","msg":"trace[799743946] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"162.241018ms","start":"2026-04-23T16:30:48.772214Z","end":"2026-04-23T16:30:48.934455Z","steps":["trace[799743946] 'process raft request' (duration: 156.231895ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:30:48.93476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.966104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-23T16:30:48.934828Z","caller":"traceutil/trace.go:171","msg":"trace[940300855] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:429; }","duration":"114.22306ms","start":"2026-04-23T16:30:48.820589Z","end":"2026-04-23T16:30:48.934812Z","steps":["trace[940300855] 'agreement among raft nodes before linearized reading' (duration: 113.958134ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:30:48.934339Z","caller":"traceutil/trace.go:171","msg":"trace[322665287] linearizableReadLoop","detail":"{readStateIndex:445; appliedIndex:443; }","duration":"113.660599ms","start":"2026-04-23T16:30:48.820642Z","end":"2026-04-23T16:30:48.934303Z","steps":["trace[322665287] 'read index received' (duration: 107.753008ms)","trace[322665287] 'applied index is now lower than readState.Index' (duration: 5.906321ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:30:50.578329Z","caller":"traceutil/trace.go:171","msg":"trace[1534537444] linearizableReadLoop","detail":"{readStateIndex:478; appliedIndex:477; }","duration":"165.91975ms","start":"2026-04-23T16:30:50.412383Z","end":"2026-04-23T16:30:50.578303Z","steps":["trace[1534537444] 'read index received' (duration: 150.240754ms)","trace[1534537444] 'applied index is now lower than readState.Index' (duration: 15.678106ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:30:50.578484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.072014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:30:50.578519Z","caller":"traceutil/trace.go:171","msg":"trace[1638689216] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:462; }","duration":"166.159175ms","start":"2026-04-23T16:30:50.412348Z","end":"2026-04-23T16:30:50.578507Z","steps":["trace[1638689216] 'agreement among raft nodes before linearized reading' (duration: 166.055923ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:30:50.578862Z","caller":"traceutil/trace.go:171","msg":"trace[1061214826] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"236.725359ms","start":"2026-04-23T16:30:50.342122Z","end":"2026-04-23T16:30:50.578847Z","steps":["trace[1061214826] 'process raft request' (duration: 220.537353ms)","trace[1061214826] 'compare' (duration: 15.515634ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:30:51.272451Z","caller":"traceutil/trace.go:171","msg":"trace[647424802] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"197.642663ms","start":"2026-04-23T16:30:51.074782Z","end":"2026-04-23T16:30:51.272425Z","steps":["trace[647424802] 'process raft request' (duration: 190.697215ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:30:51.272966Z","caller":"traceutil/trace.go:171","msg":"trace[372129849] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"189.736328ms","start":"2026-04-23T16:30:51.083209Z","end":"2026-04-23T16:30:51.272946Z","steps":["trace[372129849] 'process raft request' (duration: 189.146218ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:30:51.273088Z","caller":"traceutil/trace.go:171","msg":"trace[2061864056] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"139.765776ms","start":"2026-04-23T16:30:51.133296Z","end":"2026-04-23T16:30:51.273062Z","steps":["trace[2061864056] 'process raft request' (duration: 139.43769ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:30:52.398338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.064209ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T16:30:52.39843Z","caller":"traceutil/trace.go:171","msg":"trace[791938034] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"1.051972851s","start":"2026-04-23T16:30:51.346443Z","end":"2026-04-23T16:30:52.398416Z","steps":["trace[791938034] 'process raft request' (duration: 325.75925ms)","trace[791938034] 'compare' (duration: 725.962347ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:30:52.398476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:30:51.346424Z","time spent":"1.052031502s","remote":"127.0.0.1:56276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23066,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:30:52.400577Z","caller":"traceutil/trace.go:171","msg":"trace[1458003797] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"1.0519158s","start":"2026-04-23T16:30:51.348652Z","end":"2026-04-23T16:30:52.400567Z","steps":["trace[1458003797] 'process raft request' (duration: 1.051814189s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:30:52.400638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:30:51.348643Z","time spent":"1.051965221s","remote":"127.0.0.1:56276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7684,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:30:52.401155Z","caller":"traceutil/trace.go:171","msg":"trace[201944245] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"1.046554418s","start":"2026-04-23T16:30:51.354553Z","end":"2026-04-23T16:30:52.401107Z","steps":["trace[201944245] 'process raft request' (duration: 1.045985439s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:30:52.401338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:30:51.354516Z","time spent":"1.046734822s","remote":"127.0.0.1:56276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T16:30:52.403404Z","caller":"traceutil/trace.go:171","msg":"trace[235097203] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"1.004553722s","start":"2026-04-23T16:30:51.39884Z","end":"2026-04-23T16:30:52.403394Z","steps":["trace[235097203] 'process raft request' (duration: 1.002087519s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:30:52.403546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:30:51.398812Z","time spent":"1.004672344s","remote":"127.0.0.1:56276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:30:53.735902Z","caller":"traceutil/trace.go:171","msg":"trace[2062645826] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"362.390123ms","start":"2026-04-23T16:30:53.373482Z","end":"2026-04-23T16:30:53.735872Z","steps":["trace[2062645826] 'process raft request' (duration: 360.856128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:30:53.736139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:30:53.373446Z","time spent":"362.557266ms","remote":"127.0.0.1:56276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:30:53.767893Z","caller":"traceutil/trace.go:171","msg":"trace[1183689232] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"335.854281ms","start":"2026-04-23T16:30:53.432017Z","end":"2026-04-23T16:30:53.767871Z","steps":["trace[1183689232] 'process raft request' (duration: 335.741219ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:30:53.768017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:30:53.431989Z","time spent":"335.970833ms","remote":"127.0.0.1:56276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T16:30:54.037623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.716912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gateways.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:59631"} {"level":"info","ts":"2026-04-23T16:30:54.037749Z","caller":"traceutil/trace.go:171","msg":"trace[664382720] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gateways.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:542; }","duration":"209.096138ms","start":"2026-04-23T16:30:53.828623Z","end":"2026-04-23T16:30:54.03772Z","steps":["trace[664382720] 'range keys from in-memory index tree' (duration: 208.417736ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:30:55.19603Z","caller":"traceutil/trace.go:171","msg":"trace[1670870798] linearizableReadLoop","detail":"{readStateIndex:565; appliedIndex:564; }","duration":"193.681726ms","start":"2026-04-23T16:30:55.002321Z","end":"2026-04-23T16:30:55.196003Z","steps":["trace[1670870798] 'read index received' (duration: 193.433892ms)","trace[1670870798] 'applied index is now lower than readState.Index' (duration: 246.684µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:30:55.196115Z","caller":"traceutil/trace.go:171","msg":"trace[865777061] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"202.815521ms","start":"2026-04-23T16:30:54.99326Z","end":"2026-04-23T16:30:55.196075Z","steps":["trace[865777061] 'process raft request' (duration: 202.589647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:30:55.196239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.890519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-23T16:30:55.196456Z","caller":"traceutil/trace.go:171","msg":"trace[1041232720] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:547; }","duration":"194.110973ms","start":"2026-04-23T16:30:55.002308Z","end":"2026-04-23T16:30:55.196419Z","steps":["trace[1041232720] 'agreement among raft nodes before linearized reading' (duration: 193.847898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:30:55.196513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.310383ms","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-04-23T16:30:55.196551Z","caller":"traceutil/trace.go:171","msg":"trace[2029837103] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:547; }","duration":"120.343184ms","start":"2026-04-23T16:30:55.076192Z","end":"2026-04-23T16:30:55.196536Z","steps":["trace[2029837103] 'agreement among raft nodes before linearized reading' (duration: 120.221612ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:30:55.196761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.522051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-23T16:30:55.196795Z","caller":"traceutil/trace.go:171","msg":"trace[1535381383] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:547; }","duration":"115.592272ms","start":"2026-04-23T16:30:55.081193Z","end":"2026-04-23T16:30:55.196785Z","steps":["trace[1535381383] 'agreement among raft nodes before linearized reading' (duration: 115.47596ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:30:55.381446Z","caller":"traceutil/trace.go:171","msg":"trace[781001576] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"180.601352ms","start":"2026-04-23T16:30:55.200807Z","end":"2026-04-23T16:30:55.381409Z","steps":["trace[781001576] 'process raft request' (duration: 167.064291ms)","trace[781001576] 'compare' (duration: 13.271257ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:30:55.381587Z","caller":"traceutil/trace.go:171","msg":"trace[661884518] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"179.961151ms","start":"2026-04-23T16:30:55.20162Z","end":"2026-04-23T16:30:55.381581Z","steps":["trace[661884518] 'process raft request' (duration: 179.728177ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:30:55.381535Z","caller":"traceutil/trace.go:171","msg":"trace[8397840] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"180.525761ms","start":"2026-04-23T16:30:55.200989Z","end":"2026-04-23T16:30:55.381514Z","steps":["trace[8397840] 'process raft request' (duration: 180.311968ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:30:57.875781Z","caller":"traceutil/trace.go:171","msg":"trace[1470474298] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"150.716612ms","start":"2026-04-23T16:30:57.72505Z","end":"2026-04-23T16:30:57.875767Z","steps":["trace[1470474298] 'process raft request' (duration: 150.60377ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:30:57.897499Z","caller":"traceutil/trace.go:171","msg":"trace[387354434] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"169.949771ms","start":"2026-04-23T16:30:57.727537Z","end":"2026-04-23T16:30:57.897487Z","steps":["trace[387354434] 'process raft request' (duration: 169.795558ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:30:57.897443Z","caller":"traceutil/trace.go:171","msg":"trace[2003000892] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"169.803127ms","start":"2026-04-23T16:30:57.72761Z","end":"2026-04-23T16:30:57.897413Z","steps":["trace[2003000892] 'process raft request' (duration: 169.759747ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:30:57.897821Z","caller":"traceutil/trace.go:171","msg":"trace[923493277] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"171.182501ms","start":"2026-04-23T16:30:57.726618Z","end":"2026-04-23T16:30:57.897801Z","steps":["trace[923493277] 'process raft request' (duration: 170.607071ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:30:59.588315Z","caller":"traceutil/trace.go:171","msg":"trace[1205306729] linearizableReadLoop","detail":"{readStateIndex:589; appliedIndex:588; }","duration":"378.728314ms","start":"2026-04-23T16:30:59.209553Z","end":"2026-04-23T16:30:59.588281Z","steps":["trace[1205306729] 'read index received' (duration: 377.687675ms)","trace[1205306729] 'applied index is now lower than readState.Index' (duration: 1.039659ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:30:59.58838Z","caller":"traceutil/trace.go:171","msg":"trace[849850544] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"639.011654ms","start":"2026-04-23T16:30:58.949341Z","end":"2026-04-23T16:30:59.588353Z","steps":["trace[849850544] 'process raft request' (duration: 638.182889ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:30:59.588546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.990228ms","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-04-23T16:30:59.588589Z","caller":"traceutil/trace.go:171","msg":"trace[857783685] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:570; }","duration":"379.08806ms","start":"2026-04-23T16:30:59.209489Z","end":"2026-04-23T16:30:59.588577Z","steps":["trace[857783685] 'agreement among raft nodes before linearized reading' (duration: 378.945077ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:30:59.588603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:30:58.949311Z","time spent":"639.129716ms","remote":"127.0.0.1:56304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T16:30:59.588691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.918877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T16:30:59.588622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:30:59.209465Z","time spent":"379.14843ms","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-23T16:30:59.588867Z","caller":"traceutil/trace.go:171","msg":"trace[1507952579] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:570; }","duration":"297.151931ms","start":"2026-04-23T16:30:59.291663Z","end":"2026-04-23T16:30:59.588815Z","steps":["trace[1507952579] 'agreement among raft nodes before linearized reading' (duration: 296.865016ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:31:00.556418Z","caller":"traceutil/trace.go:171","msg":"trace[375637892] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"367.550291ms","start":"2026-04-23T16:31:00.188827Z","end":"2026-04-23T16:31:00.556377Z","steps":["trace[375637892] 'process raft request' (duration: 366.329131ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:31:00.55722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:31:00.188804Z","time spent":"368.335275ms","remote":"127.0.0.1:56742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T16:31:01.723145Z","caller":"traceutil/trace.go:171","msg":"trace[354537340] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"119.815905ms","start":"2026-04-23T16:31:01.603299Z","end":"2026-04-23T16:31:01.723115Z","steps":["trace[354537340] 'process raft request' (duration: 119.507949ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:31:02.034437Z","caller":"traceutil/trace.go:171","msg":"trace[89785843] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"113.377015ms","start":"2026-04-23T16:31:01.921027Z","end":"2026-04-23T16:31:02.034404Z","steps":["trace[89785843] 'process raft request' (duration: 105.007572ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:31:02.034675Z","caller":"traceutil/trace.go:171","msg":"trace[2024934997] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"113.268243ms","start":"2026-04-23T16:31:01.921382Z","end":"2026-04-23T16:31:02.03465Z","steps":["trace[2024934997] 'process raft request' (duration: 112.962918ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:31:20.550412Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1558137423343183931,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-23T16:31:20.584147Z","caller":"traceutil/trace.go:171","msg":"trace[1442293549] linearizableReadLoop","detail":"{readStateIndex:766; appliedIndex:765; }","duration":"534.058115ms","start":"2026-04-23T16:31:20.050063Z","end":"2026-04-23T16:31:20.584121Z","steps":["trace[1442293549] 'read index received' (duration: 533.82579ms)","trace[1442293549] 'applied index is now lower than readState.Index' (duration: 230.965µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:31:20.58443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.35741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-23T16:31:20.584191Z","caller":"traceutil/trace.go:171","msg":"trace[425858380] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"731.476032ms","start":"2026-04-23T16:31:19.852694Z","end":"2026-04-23T16:31:20.58417Z","steps":["trace[425858380] 'process raft request' (duration: 731.26259ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:31:20.584584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.54424ms","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-04-23T16:31:20.584606Z","caller":"traceutil/trace.go:171","msg":"trace[2135654241] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:742; }","duration":"448.580095ms","start":"2026-04-23T16:31:20.136021Z","end":"2026-04-23T16:31:20.584601Z","steps":["trace[2135654241] 'agreement among raft nodes before linearized reading' (duration: 448.350691ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:31:20.584666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:31:20.136014Z","time spent":"448.642816ms","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-23T16:31:20.584668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.121564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-23T16:31:20.584511Z","caller":"traceutil/trace.go:171","msg":"trace[794151331] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:742; }","duration":"534.49007ms","start":"2026-04-23T16:31:20.050006Z","end":"2026-04-23T16:31:20.584496Z","steps":["trace[794151331] 'agreement among raft nodes before linearized reading' (duration: 534.23139ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:31:20.584789Z","caller":"traceutil/trace.go:171","msg":"trace[1873528444] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:742; }","duration":"453.455493ms","start":"2026-04-23T16:31:20.13131Z","end":"2026-04-23T16:31:20.584766Z","steps":["trace[1873528444] 'agreement among raft nodes before linearized reading' (duration: 452.947927ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:31:20.585652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:31:20.131288Z","time spent":"454.291761ms","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-23T16:31:20.584867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:31:20.04998Z","time spent":"534.837892ms","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-23T16:31:20.586974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:31:19.852668Z","time spent":"731.932951ms","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:31:28.403128Z","caller":"traceutil/trace.go:171","msg":"trace[1861624919] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"104.088043ms","start":"2026-04-23T16:31:28.299014Z","end":"2026-04-23T16:31:28.403102Z","steps":["trace[1861624919] 'process raft request' (duration: 103.921359ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:31:29.222378Z","caller":"traceutil/trace.go:171","msg":"trace[1044250334] linearizableReadLoop","detail":"{readStateIndex:861; appliedIndex:860; }","duration":"107.13485ms","start":"2026-04-23T16:31:29.115214Z","end":"2026-04-23T16:31:29.222349Z","steps":["trace[1044250334] 'read index received' (duration: 106.67484ms)","trace[1044250334] 'applied index is now lower than readState.Index' (duration: 458.97µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:31:29.222396Z","caller":"traceutil/trace.go:171","msg":"trace[2143113354] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"251.910704ms","start":"2026-04-23T16:31:28.970463Z","end":"2026-04-23T16:31:29.222374Z","steps":["trace[2143113354] 'process raft request' (duration: 251.410773ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:31:29.222732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.486038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-23T16:31:29.222863Z","caller":"traceutil/trace.go:171","msg":"trace[224203938] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:834; }","duration":"107.668162ms","start":"2026-04-23T16:31:29.115169Z","end":"2026-04-23T16:31:29.222837Z","steps":["trace[224203938] 'agreement among raft nodes before linearized reading' (duration: 107.260313ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:31:30.504746Z","caller":"traceutil/trace.go:171","msg":"trace[447351528] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"112.821794ms","start":"2026-04-23T16:31:30.391883Z","end":"2026-04-23T16:31:30.504705Z","steps":["trace[447351528] 'process raft request' (duration: 112.567979ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:31:30.689883Z","caller":"traceutil/trace.go:171","msg":"trace[1471172679] linearizableReadLoop","detail":"{readStateIndex:869; appliedIndex:867; }","duration":"171.706066ms","start":"2026-04-23T16:31:30.518144Z","end":"2026-04-23T16:31:30.68985Z","steps":["trace[1471172679] 'read index received' (duration: 24.673432ms)","trace[1471172679] 'applied index is now lower than readState.Index' (duration: 147.031254ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:31:30.689994Z","caller":"traceutil/trace.go:171","msg":"trace[115903885] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"173.619888ms","start":"2026-04-23T16:31:30.516344Z","end":"2026-04-23T16:31:30.689964Z","steps":["trace[115903885] 'process raft request' (duration: 173.29523ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:31:30.690335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.978282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T16:31:30.690401Z","caller":"traceutil/trace.go:171","msg":"trace[1315600747] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:842; }","duration":"172.304669ms","start":"2026-04-23T16:31:30.518081Z","end":"2026-04-23T16:31:30.690386Z","steps":["trace[1315600747] 'agreement among raft nodes before linearized reading' (duration: 171.953571ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:31:30.969392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.212854ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-23T16:31:30.97041Z","caller":"traceutil/trace.go:171","msg":"trace[1167043397] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"175.962649ms","start":"2026-04-23T16:31:30.794413Z","end":"2026-04-23T16:31:30.970376Z","steps":["trace[1167043397] 'process raft request' (duration: 175.685733ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:31:31.371051Z","caller":"traceutil/trace.go:171","msg":"trace[621992910] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"100.844441ms","start":"2026-04-23T16:31:31.270178Z","end":"2026-04-23T16:31:31.371022Z","steps":["trace[621992910] 'process raft request' (duration: 100.641107ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:31:33.709882Z","caller":"traceutil/trace.go:171","msg":"trace[500776477] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"288.985018ms","start":"2026-04-23T16:31:33.420871Z","end":"2026-04-23T16:31:33.709856Z","steps":["trace[500776477] 'process raft request' (duration: 288.785634ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:34:22.416581Z","caller":"traceutil/trace.go:171","msg":"trace[1347552046] linearizableReadLoop","detail":"{readStateIndex:1513; appliedIndex:1512; }","duration":"124.025927ms","start":"2026-04-23T16:34:22.292529Z","end":"2026-04-23T16:34:22.416555Z","steps":["trace[1347552046] 'read index received' (duration: 123.785492ms)","trace[1347552046] 'applied index is now lower than readState.Index' (duration: 239.585µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:34:22.416738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.191001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:34:22.41677Z","caller":"traceutil/trace.go:171","msg":"trace[536465727] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1449; }","duration":"124.291514ms","start":"2026-04-23T16:34:22.292468Z","end":"2026-04-23T16:34:22.41676Z","steps":["trace[536465727] 'agreement among raft nodes before linearized reading' (duration: 124.188331ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:34:22.417155Z","caller":"traceutil/trace.go:171","msg":"trace[1590305512] transaction","detail":"{read_only:false; response_revision:1449; number_of_response:1; }","duration":"358.187603ms","start":"2026-04-23T16:34:22.058956Z","end":"2026-04-23T16:34:22.417143Z","steps":["trace[1590305512] 'process raft request' (duration: 357.454196ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:34:22.417252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:34:22.058918Z","time spent":"358.271415ms","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T16:34:23.448669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.475957ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T16:34:23.4488Z","caller":"traceutil/trace.go:171","msg":"trace[1226737877] linearizableReadLoop","detail":"{readStateIndex:1514; appliedIndex:1513; }","duration":"805.239694ms","start":"2026-04-23T16:34:22.64354Z","end":"2026-04-23T16:34:23.44878Z","steps":["trace[1226737877] 'read index received' (duration: 340.45698ms)","trace[1226737877] 'applied index is now lower than readState.Index' (duration: 464.781234ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:34:23.449065Z","caller":"traceutil/trace.go:171","msg":"trace[2050956257] transaction","detail":"{read_only:false; response_revision:1450; number_of_response:1; }","duration":"898.674035ms","start":"2026-04-23T16:34:22.550376Z","end":"2026-04-23T16:34:23.44905Z","steps":["trace[2050956257] 'process raft request' (duration: 433.689306ms)","trace[2050956257] 'compare' (duration: 463.544917ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:34:23.449136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:34:22.550348Z","time spent":"898.751366ms","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T16:34:23.449271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.856968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:34:23.449379Z","caller":"traceutil/trace.go:171","msg":"trace[1658954716] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1450; }","duration":"150.018002ms","start":"2026-04-23T16:34:23.299334Z","end":"2026-04-23T16:34:23.449352Z","steps":["trace[1658954716] 'agreement among raft nodes before linearized reading' (duration: 149.808688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:34:23.449607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"806.064192ms","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-04-23T16:34:23.449653Z","caller":"traceutil/trace.go:171","msg":"trace[379520475] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1450; }","duration":"806.164496ms","start":"2026-04-23T16:34:22.643477Z","end":"2026-04-23T16:34:23.449642Z","steps":["trace[379520475] 'agreement among raft nodes before linearized reading' (duration: 805.995912ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:34:23.449686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:34:22.643454Z","time spent":"806.224826ms","remote":"127.0.0.1:56482","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-04-23T16:34:23.983666Z","caller":"traceutil/trace.go:171","msg":"trace[1532052553] transaction","detail":"{read_only:false; response_revision:1451; number_of_response:1; }","duration":"503.970812ms","start":"2026-04-23T16:34:23.479644Z","end":"2026-04-23T16:34:23.983614Z","steps":["trace[1532052553] 'process raft request' (duration: 500.265571ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:34:23.983994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:34:23.479615Z","time spent":"504.175397ms","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:34:24.304074Z","caller":"traceutil/trace.go:171","msg":"trace[967982158] transaction","detail":"{read_only:false; response_revision:1452; number_of_response:1; }","duration":"313.564464ms","start":"2026-04-23T16:34:23.990237Z","end":"2026-04-23T16:34:24.303801Z","steps":["trace[967982158] 'process raft request' (duration: 256.068702ms)","trace[967982158] 'compare' (duration: 57.39036ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:34:24.304178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:34:23.990212Z","time spent":"313.910242ms","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:34:24.307261Z","caller":"traceutil/trace.go:171","msg":"trace[1622762348] transaction","detail":"{read_only:false; response_revision:1454; number_of_response:1; }","duration":"309.289819ms","start":"2026-04-23T16:34:23.997946Z","end":"2026-04-23T16:34:24.307236Z","steps":["trace[1622762348] 'process raft request' (duration: 309.241808ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:34:24.307305Z","caller":"traceutil/trace.go:171","msg":"trace[1529562631] transaction","detail":"{read_only:false; response_revision:1453; number_of_response:1; }","duration":"309.570946ms","start":"2026-04-23T16:34:23.99769Z","end":"2026-04-23T16:34:24.307261Z","steps":["trace[1529562631] 'process raft request' (duration: 309.393472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:34:24.307394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:34:23.997937Z","time spent":"309.379801ms","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:34:24.307457Z","caller":"traceutil/trace.go:171","msg":"trace[494218552] transaction","detail":"{read_only:false; response_revision:1455; number_of_response:1; }","duration":"307.854787ms","start":"2026-04-23T16:34:23.999569Z","end":"2026-04-23T16:34:24.307424Z","steps":["trace[494218552] 'process raft request' (duration: 307.639573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:34:24.307532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:34:23.997669Z","time spent":"309.749919ms","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T16:34:24.307646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:34:23.999557Z","time spent":"307.998081ms","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T16:34:50.590663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.904198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-23T16:34:50.590745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.212663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-23T16:34:50.590794Z","caller":"traceutil/trace.go:171","msg":"trace[1585198077] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1535; }","duration":"460.264094ms","start":"2026-04-23T16:34:50.130518Z","end":"2026-04-23T16:34:50.590782Z","steps":["trace[1585198077] 'range keys from in-memory index tree' (duration: 460.032557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:34:50.590844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:34:50.130469Z","time spent":"460.353156ms","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-23T16:34:50.590738Z","caller":"traceutil/trace.go:171","msg":"trace[1138568936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1535; }","duration":"154.00378ms","start":"2026-04-23T16:34:50.436717Z","end":"2026-04-23T16:34:50.59072Z","steps":["trace[1138568936] 'range keys from in-memory index tree' (duration: 153.750625ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:34:50.591767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.555474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-23T16:34:50.59181Z","caller":"traceutil/trace.go:171","msg":"trace[1245121983] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1535; }","duration":"150.628696ms","start":"2026-04-23T16:34:50.441171Z","end":"2026-04-23T16:34:50.5918Z","steps":["trace[1245121983] 'range keys from in-memory index tree' (duration: 150.458542ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:34:50.591851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.81916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-23T16:34:50.591897Z","caller":"traceutil/trace.go:171","msg":"trace[59452390] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1535; }","duration":"150.882322ms","start":"2026-04-23T16:34:50.441001Z","end":"2026-04-23T16:34:50.591883Z","steps":["trace[59452390] 'range keys from in-memory index tree' (duration: 150.737038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:34:50.591812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.312231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-23T16:34:50.592067Z","caller":"traceutil/trace.go:171","msg":"trace[132725756] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1535; }","duration":"151.605378ms","start":"2026-04-23T16:34:50.440443Z","end":"2026-04-23T16:34:50.592048Z","steps":["trace[132725756] 'range keys from in-memory index tree' (duration: 150.988274ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:34:50.81161Z","caller":"traceutil/trace.go:171","msg":"trace[851988569] transaction","detail":"{read_only:false; response_revision:1536; number_of_response:1; }","duration":"214.514048ms","start":"2026-04-23T16:34:50.597064Z","end":"2026-04-23T16:34:50.811578Z","steps":["trace[851988569] 'process raft request' (duration: 214.322334ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:34:51.1272Z","caller":"traceutil/trace.go:171","msg":"trace[190515201] transaction","detail":"{read_only:false; response_revision:1537; number_of_response:1; }","duration":"529.913094ms","start":"2026-04-23T16:34:50.59726Z","end":"2026-04-23T16:34:51.127173Z","steps":["trace[190515201] 'process raft request' (duration: 529.686089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:34:51.127371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:34:50.597225Z","time spent":"530.052998ms","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:34:51.127679Z","caller":"traceutil/trace.go:171","msg":"trace[1135370986] transaction","detail":"{read_only:false; response_revision:1538; number_of_response:1; }","duration":"530.253422ms","start":"2026-04-23T16:34:50.597402Z","end":"2026-04-23T16:34:51.127656Z","steps":["trace[1135370986] 'process raft request' (duration: 529.7296ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:34:51.128023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:34:50.597397Z","time spent":"530.352154ms","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:34:51.128176Z","caller":"traceutil/trace.go:171","msg":"trace[844802610] transaction","detail":"{read_only:false; response_revision:1540; number_of_response:1; }","duration":"529.995698ms","start":"2026-04-23T16:34:50.59815Z","end":"2026-04-23T16:34:51.128146Z","steps":["trace[844802610] 'process raft request' (duration: 529.720062ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:34:51.12826Z","caller":"traceutil/trace.go:171","msg":"trace[412429136] transaction","detail":"{read_only:false; response_revision:1539; number_of_response:1; }","duration":"530.12168ms","start":"2026-04-23T16:34:50.598115Z","end":"2026-04-23T16:34:51.128237Z","steps":["trace[412429136] 'process raft request' (duration: 529.459024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:34:51.129015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:34:50.598034Z","time spent":"530.688202ms","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T16:34:51.128437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:34:50.598131Z","time spent":"530.204372ms","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:34:51.128642Z","caller":"traceutil/trace.go:171","msg":"trace[1293083715] transaction","detail":"{read_only:false; response_revision:1541; number_of_response:1; }","duration":"332.243082ms","start":"2026-04-23T16:34:50.796359Z","end":"2026-04-23T16:34:51.128602Z","steps":["trace[1293083715] 'process raft request' (duration: 331.740851ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:34:51.129474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:34:50.796323Z","time spent":"333.106421ms","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:34:53.833369Z","caller":"traceutil/trace.go:171","msg":"trace[395079481] transaction","detail":"{read_only:false; response_revision:1549; number_of_response:1; }","duration":"169.71513ms","start":"2026-04-23T16:34:53.663624Z","end":"2026-04-23T16:34:53.833339Z","steps":["trace[395079481] 'process raft request' (duration: 169.444594ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:35:21.221235Z","caller":"traceutil/trace.go:171","msg":"trace[966399428] transaction","detail":"{read_only:false; response_revision:1686; number_of_response:1; }","duration":"105.984211ms","start":"2026-04-23T16:35:21.115228Z","end":"2026-04-23T16:35:21.221212Z","steps":["trace[966399428] 'process raft request' (duration: 105.814247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:35:21.628789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.32287ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T16:35:21.629165Z","caller":"traceutil/trace.go:171","msg":"trace[66976094] transaction","detail":"{read_only:false; response_revision:1689; number_of_response:1; }","duration":"181.897492ms","start":"2026-04-23T16:35:21.447028Z","end":"2026-04-23T16:35:21.628925Z","steps":["trace[66976094] 'process raft request' (duration: 38.902627ms)","trace[66976094] 'compare' (duration: 142.177667ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:35:21.988771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.026762ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T16:35:21.989063Z","caller":"traceutil/trace.go:171","msg":"trace[1155892681] transaction","detail":"{read_only:false; response_revision:1691; number_of_response:1; }","duration":"219.237204ms","start":"2026-04-23T16:35:21.76981Z","end":"2026-04-23T16:35:21.989048Z","steps":["trace[1155892681] 'process raft request' (duration: 219.03676ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:35:21.989883Z","caller":"traceutil/trace.go:171","msg":"trace[1281326998] transaction","detail":"{read_only:false; response_revision:1690; number_of_response:1; }","duration":"540.575262ms","start":"2026-04-23T16:35:21.448475Z","end":"2026-04-23T16:35:21.989051Z","steps":["trace[1281326998] 'process raft request' (duration: 342.201753ms)","trace[1281326998] 'compare' (duration: 197.841338ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:35:21.990281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:35:21.44845Z","time spent":"541.543314ms","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:35:23.183045Z","caller":"traceutil/trace.go:171","msg":"trace[169422394] transaction","detail":"{read_only:false; response_revision:1693; number_of_response:1; }","duration":"646.345219ms","start":"2026-04-23T16:35:22.536672Z","end":"2026-04-23T16:35:23.183017Z","steps":["trace[169422394] 'process raft request' (duration: 646.234297ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:35:23.183187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:35:22.536648Z","time spent":"646.471512ms","remote":"127.0.0.1:56232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:35:26.577672Z","caller":"traceutil/trace.go:171","msg":"trace[1418181658] linearizableReadLoop","detail":"{readStateIndex:1814; appliedIndex:1813; }","duration":"286.073463ms","start":"2026-04-23T16:35:26.291578Z","end":"2026-04-23T16:35:26.577651Z","steps":["trace[1418181658] 'read index received' (duration: 285.890949ms)","trace[1418181658] 'applied index is now lower than readState.Index' (duration: 181.574µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:35:26.577714Z","caller":"traceutil/trace.go:171","msg":"trace[365402709] transaction","detail":"{read_only:false; response_revision:1735; number_of_response:1; }","duration":"422.676336ms","start":"2026-04-23T16:35:26.155012Z","end":"2026-04-23T16:35:26.577688Z","steps":["trace[365402709] 'process raft request' (duration: 422.524192ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:35:26.577791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.203545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:35:26.577828Z","caller":"traceutil/trace.go:171","msg":"trace[1648217310] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1735; }","duration":"286.294137ms","start":"2026-04-23T16:35:26.291525Z","end":"2026-04-23T16:35:26.57782Z","steps":["trace[1648217310] 'agreement among raft nodes before linearized reading' (duration: 286.205256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:35:26.577847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:35:26.154999Z","time spent":"422.781469ms","remote":"127.0.0.1:56364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T16:35:27.109557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.975864ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T16:35:27.109654Z","caller":"traceutil/trace.go:171","msg":"trace[786412983] transaction","detail":"{read_only:false; response_revision:1736; number_of_response:1; }","duration":"518.95193ms","start":"2026-04-23T16:35:26.590683Z","end":"2026-04-23T16:35:27.109635Z","steps":["trace[786412983] 'process raft request' (duration: 393.829642ms)","trace[786412983] 'compare' (duration: 124.875562ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:35:27.109714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:35:26.590674Z","time spent":"519.009652ms","remote":"127.0.0.1:56364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":804,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:35:27.348753Z","caller":"traceutil/trace.go:171","msg":"trace[1027346815] transaction","detail":"{read_only:false; response_revision:1738; number_of_response:1; }","duration":"234.818101ms","start":"2026-04-23T16:35:27.113917Z","end":"2026-04-23T16:35:27.348735Z","steps":["trace[1027346815] 'process raft request' (duration: 144.443518ms)","trace[1027346815] 'compare' (duration: 89.822881ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:35:28.116362Z","caller":"traceutil/trace.go:171","msg":"trace[1084292111] transaction","detail":"{read_only:false; response_revision:1746; number_of_response:1; }","duration":"100.195312ms","start":"2026-04-23T16:35:28.016135Z","end":"2026-04-23T16:35:28.116331Z","steps":["trace[1084292111] 'process raft request' (duration: 99.945677ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:35:29.038254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.346136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:35:29.038353Z","caller":"traceutil/trace.go:171","msg":"trace[450078244] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1747; }","duration":"746.492489ms","start":"2026-04-23T16:35:28.291839Z","end":"2026-04-23T16:35:29.038331Z","steps":["trace[450078244] 'range keys from in-memory index tree' (duration: 746.261034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:35:29.038401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:35:28.291817Z","time spent":"746.57312ms","remote":"127.0.0.1:56216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T16:35:30.497059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.99229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T16:35:30.497122Z","caller":"traceutil/trace.go:171","msg":"trace[503584362] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1752; }","duration":"571.110053ms","start":"2026-04-23T16:35:29.925998Z","end":"2026-04-23T16:35:30.497108Z","steps":["trace[503584362] 'count revisions from in-memory index tree' (duration: 570.893278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:35:30.497152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:35:29.925977Z","time spent":"571.167474ms","remote":"127.0.0.1:56724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":5,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"info","ts":"2026-04-23T16:35:30.537186Z","caller":"traceutil/trace.go:171","msg":"trace[660490814] linearizableReadLoop","detail":"{readStateIndex:1832; appliedIndex:1831; }","duration":"343.034241ms","start":"2026-04-23T16:35:30.194125Z","end":"2026-04-23T16:35:30.537159Z","steps":["trace[660490814] 'read index received' (duration: 342.813606ms)","trace[660490814] 'applied index is now lower than readState.Index' (duration: 219.495µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:35:30.537452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.299668ms","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-04-23T16:35:30.537505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.913297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:35:30.53751Z","caller":"traceutil/trace.go:171","msg":"trace[728956349] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1753; }","duration":"343.414499ms","start":"2026-04-23T16:35:30.19408Z","end":"2026-04-23T16:35:30.537495Z","steps":["trace[728956349] 'agreement among raft nodes before linearized reading' (duration: 343.212145ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:35:30.537544Z","caller":"traceutil/trace.go:171","msg":"trace[164342151] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1753; }","duration":"245.98173ms","start":"2026-04-23T16:35:30.291551Z","end":"2026-04-23T16:35:30.537532Z","steps":["trace[164342151] 'agreement among raft nodes before linearized reading' (duration: 245.937108ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:35:30.537559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:35:30.19406Z","time spent":"343.488692ms","remote":"127.0.0.1:56482","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-04-23T16:35:30.537444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.223584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T16:35:30.53775Z","caller":"traceutil/trace.go:171","msg":"trace[1674848682] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1753; }","duration":"290.566763ms","start":"2026-04-23T16:35:30.247151Z","end":"2026-04-23T16:35:30.537718Z","steps":["trace[1674848682] 'agreement among raft nodes before linearized reading' (duration: 290.207704ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:35:30.537936Z","caller":"traceutil/trace.go:171","msg":"trace[655475552] transaction","detail":"{read_only:false; response_revision:1753; number_of_response:1; }","duration":"415.197918ms","start":"2026-04-23T16:35:30.122029Z","end":"2026-04-23T16:35:30.537227Z","steps":["trace[655475552] 'process raft request' (duration: 414.975283ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:35:30.5387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:35:30.122003Z","time spent":"416.635101ms","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:35:30.757867Z","caller":"traceutil/trace.go:171","msg":"trace[305233314] transaction","detail":"{read_only:false; response_revision:1754; number_of_response:1; }","duration":"215.802727ms","start":"2026-04-23T16:35:30.54203Z","end":"2026-04-23T16:35:30.757832Z","steps":["trace[305233314] 'process raft request' (duration: 177.912524ms)","trace[305233314] 'compare' (duration: 37.570986ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:35:31.105077Z","caller":"traceutil/trace.go:171","msg":"trace[1176548753] linearizableReadLoop","detail":"{readStateIndex:1835; appliedIndex:1834; }","duration":"103.786632ms","start":"2026-04-23T16:35:31.001266Z","end":"2026-04-23T16:35:31.105053Z","steps":["trace[1176548753] 'read index received' (duration: 103.605098ms)","trace[1176548753] 'applied index is now lower than readState.Index' (duration: 180.564µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:35:31.105111Z","caller":"traceutil/trace.go:171","msg":"trace[1868974291] transaction","detail":"{read_only:false; response_revision:1756; number_of_response:1; }","duration":"293.11171ms","start":"2026-04-23T16:35:30.811977Z","end":"2026-04-23T16:35:31.105088Z","steps":["trace[1868974291] 'process raft request' (duration: 292.942896ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:35:31.10535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.016107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T16:35:31.105425Z","caller":"traceutil/trace.go:171","msg":"trace[1694241659] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1756; }","duration":"104.191771ms","start":"2026-04-23T16:35:31.001222Z","end":"2026-04-23T16:35:31.105414Z","steps":["trace[1694241659] 'agreement among raft nodes before linearized reading' (duration: 103.938575ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:35:31.444407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.970505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:35:31.444524Z","caller":"traceutil/trace.go:171","msg":"trace[390443407] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1757; }","duration":"143.111348ms","start":"2026-04-23T16:35:31.301369Z","end":"2026-04-23T16:35:31.44448Z","steps":["trace[390443407] 'range keys from in-memory index tree' (duration: 142.881953ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:35:54.187398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.393865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-23T16:35:54.187481Z","caller":"traceutil/trace.go:171","msg":"trace[1320245057] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1909; }","duration":"609.520767ms","start":"2026-04-23T16:35:53.577939Z","end":"2026-04-23T16:35:54.18746Z","steps":["trace[1320245057] 'range keys from in-memory index tree' (duration: 596.854186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:35:54.187557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:35:53.577865Z","time spent":"609.681301ms","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-23T16:35:54.187636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.174431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-23T16:35:54.187689Z","caller":"traceutil/trace.go:171","msg":"trace[342948884] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1909; }","duration":"566.271974ms","start":"2026-04-23T16:35:53.621402Z","end":"2026-04-23T16:35:54.187674Z","steps":["trace[342948884] 'range keys from in-memory index tree' (duration: 553.282356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:35:54.187714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:35:53.621391Z","time spent":"566.317205ms","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-23T16:35:54.302633Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1558137423343186734,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T16:35:54.803152Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1558137423343186734,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T16:35:55.071065Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.328658547s","expected-duration":"1s"} {"level":"info","ts":"2026-04-23T16:35:55.0718Z","caller":"traceutil/trace.go:171","msg":"trace[1995901434] transaction","detail":"{read_only:false; response_revision:1910; number_of_response:1; }","duration":"1.329505006s","start":"2026-04-23T16:35:53.742274Z","end":"2026-04-23T16:35:55.071779Z","steps":["trace[1995901434] 'process raft request' (duration: 1.329370183s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:35:55.071897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:35:53.742246Z","time spent":"1.329591598s","remote":"127.0.0.1:56482","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-04-23T16:35:55.151213Z","caller":"traceutil/trace.go:171","msg":"trace[213177766] transaction","detail":"{read_only:false; response_revision:1911; number_of_response:1; }","duration":"960.326094ms","start":"2026-04-23T16:35:54.190852Z","end":"2026-04-23T16:35:55.151178Z","steps":["trace[213177766] 'process raft request' (duration: 890.615261ms)","trace[213177766] 'compare' (duration: 69.488498ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:35:55.151337Z","caller":"traceutil/trace.go:171","msg":"trace[918073801] transaction","detail":"{read_only:false; response_revision:1913; number_of_response:1; }","duration":"529.392243ms","start":"2026-04-23T16:35:54.621906Z","end":"2026-04-23T16:35:55.151298Z","steps":["trace[918073801] 'process raft request' (duration: 529.2517ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:35:55.151417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.349272338s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-23T16:35:55.151462Z","caller":"traceutil/trace.go:171","msg":"trace[1412747781] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1913; }","duration":"1.34937396s","start":"2026-04-23T16:35:53.802077Z","end":"2026-04-23T16:35:55.151451Z","steps":["trace[1412747781] 'agreement among raft nodes before linearized reading' (duration: 1.349213336s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:35:55.151501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:35:53.802057Z","time spent":"1.349435872s","remote":"127.0.0.1:56482","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-04-23T16:35:55.151489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:35:54.190837Z","time spent":"960.57824ms","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:35:55.15124Z","caller":"traceutil/trace.go:171","msg":"trace[1636827701] transaction","detail":"{read_only:false; response_revision:1912; number_of_response:1; }","duration":"957.811748ms","start":"2026-04-23T16:35:54.193405Z","end":"2026-04-23T16:35:55.151217Z","steps":["trace[1636827701] 'process raft request' (duration: 957.698655ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:35:55.151236Z","caller":"traceutil/trace.go:171","msg":"trace[1921087193] linearizableReadLoop","detail":"{readStateIndex:1997; appliedIndex:1993; }","duration":"1.349090563s","start":"2026-04-23T16:35:53.802121Z","end":"2026-04-23T16:35:55.151212Z","steps":["trace[1921087193] 'read index received' (duration: 1.26951428s)","trace[1921087193] 'applied index is now lower than readState.Index' (duration: 79.575243ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:35:55.151536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:35:54.621886Z","time spent":"529.538166ms","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T16:35:55.15195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.203148681s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-23T16:35:55.151997Z","caller":"traceutil/trace.go:171","msg":"trace[229501454] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1913; }","duration":"1.203234383s","start":"2026-04-23T16:35:53.948753Z","end":"2026-04-23T16:35:55.151988Z","steps":["trace[229501454] 'agreement among raft nodes before linearized reading' (duration: 1.203127001s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:35:55.152089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:35:53.948732Z","time spent":"1.203348916s","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-23T16:35:55.151852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:35:54.193398Z","time spent":"958.195446ms","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T16:35:55.152338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.836307ms","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-04-23T16:35:55.152429Z","caller":"traceutil/trace.go:171","msg":"trace[483664996] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1913; }","duration":"443.96286ms","start":"2026-04-23T16:35:54.708448Z","end":"2026-04-23T16:35:55.152411Z","steps":["trace[483664996] 'agreement among raft nodes before linearized reading' (duration: 443.754385ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:35:55.152487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:35:54.708429Z","time spent":"444.041111ms","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-04-23T16:35:55.152568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.555604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T16:35:55.1526Z","caller":"traceutil/trace.go:171","msg":"trace[858433780] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1913; }","duration":"207.639936ms","start":"2026-04-23T16:35:54.944953Z","end":"2026-04-23T16:35:55.152593Z","steps":["trace[858433780] 'agreement among raft nodes before linearized reading' (duration: 207.533503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:35:55.15263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.129591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-23T16:35:55.152658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"953.359388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:35:55.152684Z","caller":"traceutil/trace.go:171","msg":"trace[702141320] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1913; }","duration":"399.231893ms","start":"2026-04-23T16:35:54.753438Z","end":"2026-04-23T16:35:55.15267Z","steps":["trace[702141320] 'agreement among raft nodes before linearized reading' (duration: 399.149512ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:35:55.152719Z","caller":"traceutil/trace.go:171","msg":"trace[827531441] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1913; }","duration":"953.44324ms","start":"2026-04-23T16:35:54.199263Z","end":"2026-04-23T16:35:55.152706Z","steps":["trace[827531441] 'agreement among raft nodes before linearized reading' (duration: 953.338738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:35:55.152767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:35:54.199245Z","time spent":"953.514141ms","remote":"127.0.0.1:56362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-04-23T16:35:55.15277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:35:54.753414Z","time spent":"399.339975ms","remote":"127.0.0.1:53080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-23T16:35:55.152398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.141584ms","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":"warn","ts":"2026-04-23T16:35:55.152843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.399424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:35:55.153207Z","caller":"traceutil/trace.go:171","msg":"trace[1134613196] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1913; }","duration":"863.788643ms","start":"2026-04-23T16:35:54.289409Z","end":"2026-04-23T16:35:55.153197Z","steps":["trace[1134613196] 'agreement among raft nodes before linearized reading' (duration: 863.423364ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:35:55.153247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:35:54.289391Z","time spent":"863.848385ms","remote":"127.0.0.1:56216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-23T16:35:55.152961Z","caller":"traceutil/trace.go:171","msg":"trace[1823082884] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1913; }","duration":"286.662496ms","start":"2026-04-23T16:35:54.866183Z","end":"2026-04-23T16:35:55.152846Z","steps":["trace[1823082884] 'agreement among raft nodes before linearized reading' (duration: 286.102394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:35:55.15281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.782759ms","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-04-23T16:35:55.153462Z","caller":"traceutil/trace.go:171","msg":"trace[1234312980] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1913; }","duration":"608.461955ms","start":"2026-04-23T16:35:54.544987Z","end":"2026-04-23T16:35:55.153449Z","steps":["trace[1234312980] 'agreement among raft nodes before linearized reading' (duration: 607.77931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:35:55.153491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:35:54.544969Z","time spent":"608.515916ms","remote":"127.0.0.1:56482","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-04-23T16:35:55.297136Z","caller":"traceutil/trace.go:171","msg":"trace[88785436] linearizableReadLoop","detail":"{readStateIndex:1999; appliedIndex:1997; }","duration":"137.979953ms","start":"2026-04-23T16:35:55.159133Z","end":"2026-04-23T16:35:55.297112Z","steps":["trace[88785436] 'read index received' (duration: 27.148744ms)","trace[88785436] 'applied index is now lower than readState.Index' (duration: 110.830319ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:35:55.297874Z","caller":"traceutil/trace.go:171","msg":"trace[356698367] transaction","detail":"{read_only:false; response_revision:1915; number_of_response:1; }","duration":"139.690962ms","start":"2026-04-23T16:35:55.158164Z","end":"2026-04-23T16:35:55.297855Z","steps":["trace[356698367] 'process raft request' (duration: 138.464605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:35:55.298333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.180029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:35:55.300076Z","caller":"traceutil/trace.go:171","msg":"trace[1448135527] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1918; }","duration":"140.925309ms","start":"2026-04-23T16:35:55.159101Z","end":"2026-04-23T16:35:55.300026Z","steps":["trace[1448135527] 'agreement among raft nodes before linearized reading' (duration: 139.17796ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:35:55.298382Z","caller":"traceutil/trace.go:171","msg":"trace[960699807] transaction","detail":"{read_only:false; response_revision:1916; number_of_response:1; }","duration":"138.666009ms","start":"2026-04-23T16:35:55.159703Z","end":"2026-04-23T16:35:55.298369Z","steps":["trace[960699807] 'process raft request' (duration: 137.056344ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:35:55.298416Z","caller":"traceutil/trace.go:171","msg":"trace[1186260791] transaction","detail":"{read_only:false; response_revision:1917; number_of_response:1; }","duration":"138.658209ms","start":"2026-04-23T16:35:55.15975Z","end":"2026-04-23T16:35:55.298408Z","steps":["trace[1186260791] 'process raft request' (duration: 137.050513ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:35:55.298443Z","caller":"traceutil/trace.go:171","msg":"trace[860302540] transaction","detail":"{read_only:false; response_revision:1918; number_of_response:1; }","duration":"138.521385ms","start":"2026-04-23T16:35:55.159915Z","end":"2026-04-23T16:35:55.298437Z","steps":["trace[860302540] 'process raft request' (duration: 136.973481ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:36:11.477048Z","caller":"traceutil/trace.go:171","msg":"trace[1324851094] linearizableReadLoop","detail":"{readStateIndex:2071; appliedIndex:2070; }","duration":"333.42714ms","start":"2026-04-23T16:36:11.143591Z","end":"2026-04-23T16:36:11.477019Z","steps":["trace[1324851094] 'read index received' (duration: 333.194014ms)","trace[1324851094] 'applied index is now lower than readState.Index' (duration: 231.835µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:36:11.477108Z","caller":"traceutil/trace.go:171","msg":"trace[1309251379] transaction","detail":"{read_only:false; response_revision:1983; number_of_response:1; }","duration":"333.643094ms","start":"2026-04-23T16:36:11.143426Z","end":"2026-04-23T16:36:11.477069Z","steps":["trace[1309251379] 'process raft request' (duration: 333.326606ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:11.477264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.650744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-23T16:36:11.477294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:11.143344Z","time spent":"333.85855ms","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:36:11.477326Z","caller":"traceutil/trace.go:171","msg":"trace[1424168625] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1983; }","duration":"333.724196ms","start":"2026-04-23T16:36:11.143588Z","end":"2026-04-23T16:36:11.477312Z","steps":["trace[1424168625] 'agreement among raft nodes before linearized reading' (duration: 333.556102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:11.477368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:11.143572Z","time spent":"333.785457ms","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-23T16:36:12.272482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.225377ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-04-23T16:36:12.273075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:11.869414Z","time spent":"403.062209ms","remote":"127.0.0.1:49526","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-23T16:36:12.273096Z","caller":"traceutil/trace.go:171","msg":"trace[834629084] transaction","detail":"{read_only:false; response_revision:1984; number_of_response:1; }","duration":"1.124653736s","start":"2026-04-23T16:36:11.148418Z","end":"2026-04-23T16:36:12.273072Z","steps":["trace[834629084] 'process raft request' (duration: 1.124165365s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:12.273229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:11.148402Z","time spent":"1.124759558s","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:36:12.291185Z","caller":"traceutil/trace.go:171","msg":"trace[34318212] transaction","detail":"{read_only:false; response_revision:1985; number_of_response:1; }","duration":"1.142668676s","start":"2026-04-23T16:36:11.148492Z","end":"2026-04-23T16:36:12.29116Z","steps":["trace[34318212] 'process raft request' (duration: 1.124568403s)","trace[34318212] 'compare' (duration: 17.890149ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:36:12.291349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:11.148488Z","time spent":"1.142779819s","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:36:12.291417Z","caller":"traceutil/trace.go:171","msg":"trace[294452292] transaction","detail":"{read_only:false; response_revision:1986; number_of_response:1; }","duration":"1.14283563s","start":"2026-04-23T16:36:11.148556Z","end":"2026-04-23T16:36:12.291392Z","steps":["trace[294452292] 'process raft request' (duration: 1.142561984s)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:36:12.291479Z","caller":"traceutil/trace.go:171","msg":"trace[1250109572] transaction","detail":"{read_only:false; response_revision:1989; number_of_response:1; }","duration":"809.047302ms","start":"2026-04-23T16:36:11.482413Z","end":"2026-04-23T16:36:12.29146Z","steps":["trace[1250109572] 'process raft request' (duration: 809.001081ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:36:12.291474Z","caller":"traceutil/trace.go:171","msg":"trace[1294536423] transaction","detail":"{read_only:false; response_revision:1987; number_of_response:1; }","duration":"1.142859151s","start":"2026-04-23T16:36:11.148582Z","end":"2026-04-23T16:36:12.291441Z","steps":["trace[1294536423] 'process raft request' (duration: 1.142708977s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:12.291553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:11.148552Z","time spent":"1.142932423s","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T16:36:12.29158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:11.4824Z","time spent":"809.133804ms","remote":"127.0.0.1:56482","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-04-23T16:36:12.291626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:11.148579Z","time spent":"1.142970923s","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:36:12.29167Z","caller":"traceutil/trace.go:171","msg":"trace[146597301] linearizableReadLoop","detail":"{readStateIndex:2077; appliedIndex:2071; }","duration":"814.519624ms","start":"2026-04-23T16:36:11.47714Z","end":"2026-04-23T16:36:12.29166Z","steps":["trace[146597301] 'read index received' (duration: 43.255933ms)","trace[146597301] 'applied index is now lower than readState.Index' (duration: 771.262741ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:36:12.291712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.090614505s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:ttl-controller\" ","response":"range_response_count:1 size:663"} {"level":"info","ts":"2026-04-23T16:36:12.291751Z","caller":"traceutil/trace.go:171","msg":"trace[1057368127] range","detail":"{range_begin:/registry/clusterroles/system:controller:ttl-controller; range_end:; response_count:1; response_revision:1989; }","duration":"1.090679647s","start":"2026-04-23T16:36:11.201062Z","end":"2026-04-23T16:36:12.291742Z","steps":["trace[1057368127] 'agreement among raft nodes before linearized reading' (duration: 1.090599555s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:12.291769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.115479471s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:basic-user\" ","response":"range_response_count:1 size:697"} {"level":"warn","ts":"2026-04-23T16:36:12.29179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:11.201046Z","time spent":"1.090734238s","remote":"127.0.0.1:56568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":686,"request content":"key:\"/registry/clusterroles/system:controller:ttl-controller\" "} {"level":"info","ts":"2026-04-23T16:36:12.291797Z","caller":"traceutil/trace.go:171","msg":"trace[83477479] range","detail":"{range_begin:/registry/clusterrolebindings/system:basic-user; range_end:; response_count:1; response_revision:1989; }","duration":"1.115535231s","start":"2026-04-23T16:36:11.176253Z","end":"2026-04-23T16:36:12.291789Z","steps":["trace[83477479] 'agreement among raft nodes before linearized reading' (duration: 1.11547955s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:12.291825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:11.176239Z","time spent":"1.115580093s","remote":"127.0.0.1:56584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":720,"request content":"key:\"/registry/clusterrolebindings/system:basic-user\" "} {"level":"warn","ts":"2026-04-23T16:36:12.291865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.14825163s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-23T16:36:12.291481Z","caller":"traceutil/trace.go:171","msg":"trace[1250357070] transaction","detail":"{read_only:false; response_revision:1988; number_of_response:1; }","duration":"1.142862151s","start":"2026-04-23T16:36:11.148608Z","end":"2026-04-23T16:36:12.29147Z","steps":["trace[1250357070] 'process raft request' (duration: 1.142762359s)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:36:12.291905Z","caller":"traceutil/trace.go:171","msg":"trace[1989148452] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1989; }","duration":"1.148303702s","start":"2026-04-23T16:36:11.143591Z","end":"2026-04-23T16:36:12.291895Z","steps":["trace[1989148452] 'agreement among raft nodes before linearized reading' (duration: 1.148172099s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:12.291938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:11.143584Z","time spent":"1.148347392s","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-23T16:36:12.291988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:11.148605Z","time spent":"1.143327411s","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T16:36:12.292027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.130375933s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T16:36:12.292038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.143415ms","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-04-23T16:36:12.29208Z","caller":"traceutil/trace.go:171","msg":"trace[1702543582] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1989; }","duration":"831.225667ms","start":"2026-04-23T16:36:11.460845Z","end":"2026-04-23T16:36:12.292071Z","steps":["trace[1702543582] 'agreement among raft nodes before linearized reading' (duration: 831.098194ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:12.292114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:11.460824Z","time spent":"831.283389ms","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-23T16:36:12.292128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.144810075s","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":"warn","ts":"2026-04-23T16:36:12.292126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"885.618199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T16:36:12.292157Z","caller":"traceutil/trace.go:171","msg":"trace[1492724121] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1989; }","duration":"1.144854715s","start":"2026-04-23T16:36:11.147294Z","end":"2026-04-23T16:36:12.292149Z","steps":["trace[1492724121] 'agreement among raft nodes before linearized reading' (duration: 1.144776323s)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:36:12.292165Z","caller":"traceutil/trace.go:171","msg":"trace[715168793] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1989; }","duration":"885.703291ms","start":"2026-04-23T16:36:11.406451Z","end":"2026-04-23T16:36:12.292154Z","steps":["trace[715168793] 'agreement among raft nodes before linearized reading' (duration: 885.6289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:12.292046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.684879ms","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":"warn","ts":"2026-04-23T16:36:12.292188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:11.147284Z","time spent":"1.144898657s","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-04-23T16:36:12.292196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:11.406428Z","time spent":"885.760873ms","remote":"127.0.0.1:56636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-04-23T16:36:12.292214Z","caller":"traceutil/trace.go:171","msg":"trace[1841037666] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1989; }","duration":"748.890653ms","start":"2026-04-23T16:36:11.543308Z","end":"2026-04-23T16:36:12.292199Z","steps":["trace[1841037666] 'agreement among raft nodes before linearized reading' (duration: 748.667528ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:12.292257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:11.543286Z","time spent":"748.961235ms","remote":"127.0.0.1:56482","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":"info","ts":"2026-04-23T16:36:12.292051Z","caller":"traceutil/trace.go:171","msg":"trace[2047255573] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1989; }","duration":"1.130423544s","start":"2026-04-23T16:36:11.161619Z","end":"2026-04-23T16:36:12.292043Z","steps":["trace[2047255573] 'agreement among raft nodes before linearized reading' (duration: 1.130384193s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:12.292294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:11.161607Z","time spent":"1.13068148s","remote":"127.0.0.1:56216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-23T16:36:17.991058Z","caller":"traceutil/trace.go:171","msg":"trace[1656684139] linearizableReadLoop","detail":"{readStateIndex:2105; appliedIndex:2104; }","duration":"436.979315ms","start":"2026-04-23T16:36:17.554053Z","end":"2026-04-23T16:36:17.991032Z","steps":["trace[1656684139] 'read index received' (duration: 436.727519ms)","trace[1656684139] 'applied index is now lower than readState.Index' (duration: 250.526µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:36:17.991199Z","caller":"traceutil/trace.go:171","msg":"trace[1961626032] transaction","detail":"{read_only:false; response_revision:2015; number_of_response:1; }","duration":"494.68741ms","start":"2026-04-23T16:36:17.496467Z","end":"2026-04-23T16:36:17.991154Z","steps":["trace[1961626032] 'process raft request' (duration: 494.402044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:17.991434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.281131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-23T16:36:17.99151Z","caller":"traceutil/trace.go:171","msg":"trace[521610043] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:2015; }","duration":"415.389504ms","start":"2026-04-23T16:36:17.576109Z","end":"2026-04-23T16:36:17.991498Z","steps":["trace[521610043] 'agreement among raft nodes before linearized reading' (duration: 415.304062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:17.991562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:17.576089Z","time spent":"415.459115ms","remote":"127.0.0.1:56324","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-04-23T16:36:17.991801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:17.496455Z","time spent":"495.002677ms","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T16:36:17.991367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.290331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-public\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-23T16:36:17.99201Z","caller":"traceutil/trace.go:171","msg":"trace[922123036] range","detail":"{range_begin:/registry/namespaces/kube-public; range_end:; response_count:1; response_revision:2015; }","duration":"437.961566ms","start":"2026-04-23T16:36:17.554008Z","end":"2026-04-23T16:36:17.99197Z","steps":["trace[922123036] 'agreement among raft nodes before linearized reading' (duration: 437.254881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:17.99214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:17.553988Z","time spent":"438.105119ms","remote":"127.0.0.1:56324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-public\" "} {"level":"warn","ts":"2026-04-23T16:36:18.630141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.904048ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T16:36:18.630277Z","caller":"traceutil/trace.go:171","msg":"trace[683762463] transaction","detail":"{read_only:false; response_revision:2017; number_of_response:1; }","duration":"276.98533ms","start":"2026-04-23T16:36:18.353274Z","end":"2026-04-23T16:36:18.630259Z","steps":["trace[683762463] 'process raft request' (duration: 74.882448ms)","trace[683762463] 'compare' (duration: 201.671533ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:36:18.67786Z","caller":"traceutil/trace.go:171","msg":"trace[1733290057] transaction","detail":"{read_only:false; response_revision:2018; number_of_response:1; }","duration":"324.175552ms","start":"2026-04-23T16:36:18.353659Z","end":"2026-04-23T16:36:18.677834Z","steps":["trace[1733290057] 'process raft request' (duration: 323.977558ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:18.678018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:18.353651Z","time spent":"324.287464ms","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:36:18.67814Z","caller":"traceutil/trace.go:171","msg":"trace[1925285052] transaction","detail":"{read_only:false; response_revision:2019; number_of_response:1; }","duration":"324.296474ms","start":"2026-04-23T16:36:18.353815Z","end":"2026-04-23T16:36:18.678111Z","steps":["trace[1925285052] 'process raft request' (duration: 323.979067ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:18.678326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:18.35379Z","time spent":"324.438288ms","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:36:18.678876Z","caller":"traceutil/trace.go:171","msg":"trace[1266630953] transaction","detail":"{read_only:false; response_revision:2020; number_of_response:1; }","duration":"324.439497ms","start":"2026-04-23T16:36:18.354417Z","end":"2026-04-23T16:36:18.678856Z","steps":["trace[1266630953] 'process raft request' (duration: 323.596118ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:18.679118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:18.354392Z","time spent":"324.666932ms","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:36:18.67956Z","caller":"traceutil/trace.go:171","msg":"trace[1256502779] transaction","detail":"{read_only:false; response_revision:2021; number_of_response:1; }","duration":"323.822294ms","start":"2026-04-23T16:36:18.354996Z","end":"2026-04-23T16:36:18.678818Z","steps":["trace[1256502779] 'process raft request' (duration: 323.734502ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:18.679814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:18.354981Z","time spent":"324.777525ms","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T16:36:20.549339Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1558137423343187475,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T16:36:21.049519Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1558137423343187475,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T16:36:21.550089Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1558137423343187475,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T16:36:22.050878Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1558137423343187475,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T16:36:22.292274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000565656s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-23T16:36:22.29238Z","caller":"traceutil/trace.go:171","msg":"trace[679756679] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000720339s","start":"2026-04-23T16:36:20.291635Z","end":"2026-04-23T16:36:22.292355Z","steps":["trace[679756679] 'agreement among raft nodes before linearized reading' (duration: 2.000599096s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:22.292441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:20.291612Z","time spent":"2.000814312s","remote":"127.0.0.1:56216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T16:36:22.551662Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1558137423343187475,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T16:36:23.052708Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1558137423343187475,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T16:36:23.508987Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.511871485s","expected-duration":"1s"} {"level":"info","ts":"2026-04-23T16:36:23.509485Z","caller":"traceutil/trace.go:171","msg":"trace[1824318765] transaction","detail":"{read_only:false; response_revision:2022; number_of_response:1; }","duration":"3.512422916s","start":"2026-04-23T16:36:19.99704Z","end":"2026-04-23T16:36:23.509463Z","steps":["trace[1824318765] 'process raft request' (duration: 3.512286803s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:23.509627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:19.997026Z","time spent":"3.512527349s","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T16:36:23.984391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.591165ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-23T16:36:23.984808Z","caller":"traceutil/trace.go:171","msg":"trace[1661952417] transaction","detail":"{read_only:false; response_revision:2023; number_of_response:1; }","duration":"704.291909ms","start":"2026-04-23T16:36:23.280479Z","end":"2026-04-23T16:36:23.984771Z","steps":["trace[1661952417] 'process raft request' (duration: 704.003953ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:36:23.984879Z","caller":"traceutil/trace.go:171","msg":"trace[1116048605] linearizableReadLoop","detail":"{readStateIndex:2113; appliedIndex:2112; }","duration":"3.935680384s","start":"2026-04-23T16:36:20.049179Z","end":"2026-04-23T16:36:23.984859Z","steps":["trace[1116048605] 'read index received' (duration: 3.460368156s)","trace[1116048605] 'applied index is now lower than readState.Index' (duration: 475.061782ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:36:23.985014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:23.280452Z","time spent":"704.477615ms","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T16:36:23.985043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.914781841s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/default/\" range_end:\"/registry/roles/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T16:36:23.985023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.935837148s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1243"} {"level":"info","ts":"2026-04-23T16:36:23.985075Z","caller":"traceutil/trace.go:171","msg":"trace[1460005933] range","detail":"{range_begin:/registry/roles/default/; range_end:/registry/roles/default0; response_count:0; response_revision:2023; }","duration":"3.914876473s","start":"2026-04-23T16:36:20.07019Z","end":"2026-04-23T16:36:23.985066Z","steps":["trace[1460005933] 'agreement among raft nodes before linearized reading' (duration: 3.914809331s)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:36:23.985091Z","caller":"traceutil/trace.go:171","msg":"trace[1389695868] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:2023; }","duration":"3.935949351s","start":"2026-04-23T16:36:20.049126Z","end":"2026-04-23T16:36:23.985075Z","steps":["trace[1389695868] 'agreement among raft nodes before linearized reading' (duration: 3.935750905s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:23.985111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:20.070169Z","time spent":"3.914933013s","remote":"127.0.0.1:56542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":0,"response size":28,"request content":"key:\"/registry/roles/default/\" range_end:\"/registry/roles/default0\" limit:500 "} {"level":"warn","ts":"2026-04-23T16:36:23.985128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:20.049106Z","time spent":"3.936013953s","remote":"127.0.0.1:56364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":1266,"request content":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" "} {"level":"warn","ts":"2026-04-23T16:36:23.985485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.300799812s","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":"warn","ts":"2026-04-23T16:36:23.985518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.668803506s","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-04-23T16:36:23.98553Z","caller":"traceutil/trace.go:171","msg":"trace[892365427] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2023; }","duration":"3.300884114s","start":"2026-04-23T16:36:20.684634Z","end":"2026-04-23T16:36:23.985518Z","steps":["trace[892365427] 'agreement among raft nodes before linearized reading' (duration: 3.300728951s)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:36:23.985558Z","caller":"traceutil/trace.go:171","msg":"trace[1914775756] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2023; }","duration":"1.668864218s","start":"2026-04-23T16:36:22.316682Z","end":"2026-04-23T16:36:23.985546Z","steps":["trace[1914775756] 'agreement among raft nodes before linearized reading' (duration: 1.668739815s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:23.985561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.3025139s","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":"warn","ts":"2026-04-23T16:36:23.985563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:20.684613Z","time spent":"3.300943625s","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-04-23T16:36:23.985586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:22.316671Z","time spent":"1.668908839s","remote":"127.0.0.1:56482","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":"info","ts":"2026-04-23T16:36:23.985592Z","caller":"traceutil/trace.go:171","msg":"trace[1653248517] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2023; }","duration":"3.302564071s","start":"2026-04-23T16:36:20.683019Z","end":"2026-04-23T16:36:23.985583Z","steps":["trace[1653248517] 'agreement among raft nodes before linearized reading' (duration: 3.302491979s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:23.98562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:20.683014Z","time spent":"3.302600272s","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-23T16:36:23.985688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.631799486s","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-04-23T16:36:23.98573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.302726685s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-23T16:36:23.985734Z","caller":"traceutil/trace.go:171","msg":"trace[1475214308] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2023; }","duration":"3.631887678s","start":"2026-04-23T16:36:20.353833Z","end":"2026-04-23T16:36:23.98572Z","steps":["trace[1475214308] 'agreement among raft nodes before linearized reading' (duration: 3.631736865s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:23.985745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.123846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/default/\" range_end:\"/registry/secrets/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:36:23.985755Z","caller":"traceutil/trace.go:171","msg":"trace[1642453135] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2023; }","duration":"3.302769835s","start":"2026-04-23T16:36:20.682979Z","end":"2026-04-23T16:36:23.985749Z","steps":["trace[1642453135] 'agreement among raft nodes before linearized reading' (duration: 3.302703474s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:23.985767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:20.353812Z","time spent":"3.63194738s","remote":"127.0.0.1:56482","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-04-23T16:36:23.985776Z","caller":"traceutil/trace.go:171","msg":"trace[1237042258] range","detail":"{range_begin:/registry/secrets/default/; range_end:/registry/secrets/default0; response_count:0; response_revision:2023; }","duration":"414.185127ms","start":"2026-04-23T16:36:23.571582Z","end":"2026-04-23T16:36:23.985768Z","steps":["trace[1237042258] 'agreement among raft nodes before linearized reading' (duration: 414.134846ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:23.985781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:20.68297Z","time spent":"3.302804646s","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-23T16:36:23.985867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.672774755s","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":"info","ts":"2026-04-23T16:36:23.985901Z","caller":"traceutil/trace.go:171","msg":"trace[1251004079] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2023; }","duration":"1.672813686s","start":"2026-04-23T16:36:22.313078Z","end":"2026-04-23T16:36:23.985891Z","steps":["trace[1251004079] 'agreement among raft nodes before linearized reading' (duration: 1.672716433s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:23.985892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.350911778s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-23T16:36:23.985931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:22.313038Z","time spent":"1.672886847s","remote":"127.0.0.1:56482","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":"info","ts":"2026-04-23T16:36:23.985933Z","caller":"traceutil/trace.go:171","msg":"trace[791949058] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2023; }","duration":"3.35098955s","start":"2026-04-23T16:36:20.634935Z","end":"2026-04-23T16:36:23.985924Z","steps":["trace[791949058] 'agreement among raft nodes before linearized reading' (duration: 3.350900158s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:23.985963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:20.634911Z","time spent":"3.351046381s","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-23T16:36:23.985805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:23.571566Z","time spent":"414.232449ms","remote":"127.0.0.1:56304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/default/\" range_end:\"/registry/secrets/default0\" limit:500 "} {"level":"warn","ts":"2026-04-23T16:36:23.986057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.678222066s","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:535"} {"level":"info","ts":"2026-04-23T16:36:23.986085Z","caller":"traceutil/trace.go:171","msg":"trace[158261622] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2023; }","duration":"1.678287977s","start":"2026-04-23T16:36:22.307787Z","end":"2026-04-23T16:36:23.986075Z","steps":["trace[158261622] 'agreement among raft nodes before linearized reading' (duration: 1.678207635s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:23.98611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:22.307769Z","time spent":"1.678335458s","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-23T16:36:23.986134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.693286352s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:36:23.986162Z","caller":"traceutil/trace.go:171","msg":"trace[1927379881] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2023; }","duration":"1.693349853s","start":"2026-04-23T16:36:22.292803Z","end":"2026-04-23T16:36:23.986153Z","steps":["trace[1927379881] 'agreement among raft nodes before linearized reading' (duration: 1.693307942s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:23.986191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:22.292783Z","time spent":"1.693400335s","remote":"127.0.0.1:56230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T16:36:23.986234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.668758596s","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-04-23T16:36:23.986269Z","caller":"traceutil/trace.go:171","msg":"trace[46224311] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2023; }","duration":"1.668820057s","start":"2026-04-23T16:36:22.317439Z","end":"2026-04-23T16:36:23.986259Z","steps":["trace[46224311] 'agreement among raft nodes before linearized reading' (duration: 1.668727225s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:23.985491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.303569735s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-23T16:36:23.986296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:22.317427Z","time spent":"1.668862738s","remote":"127.0.0.1:56482","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-04-23T16:36:23.986315Z","caller":"traceutil/trace.go:171","msg":"trace[1484344119] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2023; }","duration":"3.304422783s","start":"2026-04-23T16:36:20.681883Z","end":"2026-04-23T16:36:23.986306Z","steps":["trace[1484344119] 'agreement among raft nodes before linearized reading' (duration: 3.303532323s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:23.986345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:20.68187Z","time spent":"3.304467264s","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-23T16:36:24.191089Z","caller":"traceutil/trace.go:171","msg":"trace[1046635702] linearizableReadLoop","detail":"{readStateIndex:2115; appliedIndex:2114; }","duration":"198.38667ms","start":"2026-04-23T16:36:23.992671Z","end":"2026-04-23T16:36:24.191058Z","steps":["trace[1046635702] 'read index received' (duration: 148.679922ms)","trace[1046635702] 'applied index is now lower than readState.Index' (duration: 49.705188ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:36:24.19138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.684476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1243"} {"level":"info","ts":"2026-04-23T16:36:24.191417Z","caller":"traceutil/trace.go:171","msg":"trace[812766002] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:2023; }","duration":"198.762668ms","start":"2026-04-23T16:36:23.992643Z","end":"2026-04-23T16:36:24.191406Z","steps":["trace[812766002] 'agreement among raft nodes before linearized reading' (duration: 198.589844ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:36:24.223747Z","caller":"traceutil/trace.go:171","msg":"trace[1538854194] transaction","detail":"{read_only:false; response_revision:2028; number_of_response:1; }","duration":"230.609418ms","start":"2026-04-23T16:36:23.993119Z","end":"2026-04-23T16:36:24.223728Z","steps":["trace[1538854194] 'process raft request' (duration: 230.376513ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:36:24.223757Z","caller":"traceutil/trace.go:171","msg":"trace[928216307] transaction","detail":"{read_only:false; response_revision:2027; number_of_response:1; }","duration":"230.612198ms","start":"2026-04-23T16:36:23.993108Z","end":"2026-04-23T16:36:24.22372Z","steps":["trace[928216307] 'process raft request' (duration: 230.356083ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:36:24.22403Z","caller":"traceutil/trace.go:171","msg":"trace[959248895] transaction","detail":"{read_only:false; response_revision:2029; number_of_response:1; }","duration":"230.875424ms","start":"2026-04-23T16:36:23.993143Z","end":"2026-04-23T16:36:24.224018Z","steps":["trace[959248895] 'process raft request' (duration: 230.410114ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:36:24.224107Z","caller":"traceutil/trace.go:171","msg":"trace[711901174] transaction","detail":"{read_only:false; response_revision:2030; number_of_response:1; }","duration":"230.894874ms","start":"2026-04-23T16:36:23.993205Z","end":"2026-04-23T16:36:24.2241Z","steps":["trace[711901174] 'process raft request' (duration: 230.380002ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:36:24.224181Z","caller":"traceutil/trace.go:171","msg":"trace[1325143100] transaction","detail":"{read_only:false; response_revision:2031; number_of_response:1; }","duration":"230.964955ms","start":"2026-04-23T16:36:23.993208Z","end":"2026-04-23T16:36:24.224173Z","steps":["trace[1325143100] 'process raft request' (duration: 230.404553ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:36:24.224246Z","caller":"traceutil/trace.go:171","msg":"trace[80430786] transaction","detail":"{read_only:false; response_revision:2032; number_of_response:1; }","duration":"229.013802ms","start":"2026-04-23T16:36:23.995225Z","end":"2026-04-23T16:36:24.224239Z","steps":["trace[80430786] 'process raft request' (duration: 228.413029ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:36:24.224307Z","caller":"traceutil/trace.go:171","msg":"trace[1021732444] transaction","detail":"{read_only:false; response_revision:2033; number_of_response:1; }","duration":"228.878959ms","start":"2026-04-23T16:36:23.995421Z","end":"2026-04-23T16:36:24.2243Z","steps":["trace[1021732444] 'process raft request' (duration: 228.244715ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:36:24.224358Z","caller":"traceutil/trace.go:171","msg":"trace[262954671] transaction","detail":"{read_only:false; response_revision:2024; number_of_response:1; }","duration":"231.402945ms","start":"2026-04-23T16:36:23.992942Z","end":"2026-04-23T16:36:24.224344Z","steps":["trace[262954671] 'process raft request' (duration: 230.306331ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:24.224456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.0943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/envoy-gateway-system/\" range_end:\"/registry/persistentvolumeclaims/envoy-gateway-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:36:24.224515Z","caller":"traceutil/trace.go:171","msg":"trace[117954475] range","detail":"{range_begin:/registry/persistentvolumeclaims/envoy-gateway-system/; range_end:/registry/persistentvolumeclaims/envoy-gateway-system0; response_count:0; response_revision:2033; }","duration":"157.205642ms","start":"2026-04-23T16:36:24.067299Z","end":"2026-04-23T16:36:24.224504Z","steps":["trace[117954475] 'agreement among raft nodes before linearized reading' (duration: 157.107169ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:36:24.2245Z","caller":"traceutil/trace.go:171","msg":"trace[1669459005] transaction","detail":"{read_only:false; response_revision:2025; number_of_response:1; }","duration":"231.413116ms","start":"2026-04-23T16:36:23.99306Z","end":"2026-04-23T16:36:24.224473Z","steps":["trace[1669459005] 'process raft request' (duration: 230.339512ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:24.224684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.494828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/default/\" range_end:\"/registry/serviceaccounts/default0\" limit:500 ","response":"range_response_count:1 size:171"} {"level":"info","ts":"2026-04-23T16:36:24.224673Z","caller":"traceutil/trace.go:171","msg":"trace[34096211] transaction","detail":"{read_only:false; response_revision:2026; number_of_response:1; }","duration":"231.58321ms","start":"2026-04-23T16:36:23.993059Z","end":"2026-04-23T16:36:24.224642Z","steps":["trace[34096211] 'process raft request' (duration: 230.377563ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:36:24.224724Z","caller":"traceutil/trace.go:171","msg":"trace[1018505034] range","detail":"{range_begin:/registry/serviceaccounts/default/; range_end:/registry/serviceaccounts/default0; response_count:1; response_revision:2033; }","duration":"161.560219ms","start":"2026-04-23T16:36:24.063155Z","end":"2026-04-23T16:36:24.224715Z","steps":["trace[1018505034] 'agreement among raft nodes before linearized reading' (duration: 161.485808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:24.224842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.017113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:36:24.224888Z","caller":"traceutil/trace.go:171","msg":"trace[1926036753] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2033; }","duration":"230.082996ms","start":"2026-04-23T16:36:23.994792Z","end":"2026-04-23T16:36:24.224875Z","steps":["trace[1926036753] 'agreement among raft nodes before linearized reading' (duration: 230.003754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:24.225007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.879543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:110702"} {"level":"info","ts":"2026-04-23T16:36:24.225048Z","caller":"traceutil/trace.go:171","msg":"trace[1820283837] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2033; }","duration":"230.944864ms","start":"2026-04-23T16:36:23.994092Z","end":"2026-04-23T16:36:24.225037Z","steps":["trace[1820283837] 'agreement among raft nodes before linearized reading' (duration: 230.726809ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:36:24.428421Z","caller":"traceutil/trace.go:171","msg":"trace[705726527] linearizableReadLoop","detail":"{readStateIndex:2126; appliedIndex:2125; }","duration":"204.69009ms","start":"2026-04-23T16:36:24.223702Z","end":"2026-04-23T16:36:24.428392Z","steps":["trace[705726527] 'read index received' (duration: 204.459085ms)","trace[705726527] 'applied index is now lower than readState.Index' (duration: 229.475µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:36:24.42844Z","caller":"traceutil/trace.go:171","msg":"trace[1660329436] transaction","detail":"{read_only:false; response_revision:2034; number_of_response:1; }","duration":"236.000097ms","start":"2026-04-23T16:36:24.192414Z","end":"2026-04-23T16:36:24.428414Z","steps":["trace[1660329436] 'process raft request' (duration: 235.808223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:24.42861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.158534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/\" range_end:\"/registry/pods/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:36:24.428661Z","caller":"traceutil/trace.go:171","msg":"trace[798852040] range","detail":"{range_begin:/registry/pods/default/; range_end:/registry/pods/default0; response_count:0; response_revision:2034; }","duration":"233.273156ms","start":"2026-04-23T16:36:24.195378Z","end":"2026-04-23T16:36:24.428651Z","steps":["trace[798852040] 'agreement among raft nodes before linearized reading' (duration: 233.121333ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:24.429028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.363548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:36:24.429094Z","caller":"traceutil/trace.go:171","msg":"trace[1195092865] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2034; }","duration":"202.477351ms","start":"2026-04-23T16:36:24.2266Z","end":"2026-04-23T16:36:24.429077Z","steps":["trace[1195092865] 'agreement among raft nodes before linearized reading' (duration: 202.357228ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:24.42915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.268234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/default/\" range_end:\"/registry/services/specs/default0\" limit:500 ","response":"range_response_count:1 size:704"} {"level":"warn","ts":"2026-04-23T16:36:24.429182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.704897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/\" range_end:\"/registry/rolebindings/envoy-gateway-system0\" limit:500 ","response":"range_response_count:3 size:3302"} {"level":"info","ts":"2026-04-23T16:36:24.429203Z","caller":"traceutil/trace.go:171","msg":"trace[786047852] range","detail":"{range_begin:/registry/services/specs/default/; range_end:/registry/services/specs/default0; response_count:1; response_revision:2034; }","duration":"131.355656ms","start":"2026-04-23T16:36:24.297835Z","end":"2026-04-23T16:36:24.42919Z","steps":["trace[786047852] 'agreement among raft nodes before linearized reading' (duration: 131.247434ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:24.429172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.497039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/default/default\" ","response":"range_response_count:1 size:171"} {"level":"info","ts":"2026-04-23T16:36:24.42924Z","caller":"traceutil/trace.go:171","msg":"trace[251893818] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/; range_end:/registry/rolebindings/envoy-gateway-system0; response_count:3; response_revision:2034; }","duration":"119.807279ms","start":"2026-04-23T16:36:24.309416Z","end":"2026-04-23T16:36:24.429223Z","steps":["trace[251893818] 'agreement among raft nodes before linearized reading' (duration: 119.595684ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:36:24.429281Z","caller":"traceutil/trace.go:171","msg":"trace[1117822238] range","detail":"{range_begin:/registry/serviceaccounts/default/default; range_end:; response_count:1; response_revision:2034; }","duration":"118.639903ms","start":"2026-04-23T16:36:24.310615Z","end":"2026-04-23T16:36:24.429255Z","steps":["trace[1117822238] 'agreement among raft nodes before linearized reading' (duration: 118.403577ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:24.789026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.250197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/default/kubernetes\" ","response":"range_response_count:1 size:704"} {"level":"info","ts":"2026-04-23T16:36:24.789285Z","caller":"traceutil/trace.go:171","msg":"trace[1627828567] range","detail":"{range_begin:/registry/services/specs/default/kubernetes; range_end:; response_count:1; response_revision:2034; }","duration":"189.546523ms","start":"2026-04-23T16:36:24.599719Z","end":"2026-04-23T16:36:24.789266Z","steps":["trace[1627828567] 'range keys from in-memory index tree' (duration: 189.158674ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:24.789095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.760468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/default/default\" ","response":"range_response_count:1 size:171"} {"level":"info","ts":"2026-04-23T16:36:24.789484Z","caller":"traceutil/trace.go:171","msg":"trace[1281582926] range","detail":"{range_begin:/registry/serviceaccounts/default/default; range_end:; response_count:1; response_revision:2034; }","duration":"265.164348ms","start":"2026-04-23T16:36:24.524298Z","end":"2026-04-23T16:36:24.789463Z","steps":["trace[1281582926] 'range keys from in-memory index tree' (duration: 264.704438ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:24.789142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.434301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/envoy-gateway-system/\" range_end:\"/registry/roles/envoy-gateway-system0\" limit:500 ","response":"range_response_count:3 size:3333"} {"level":"info","ts":"2026-04-23T16:36:24.789652Z","caller":"traceutil/trace.go:171","msg":"trace[1017332483] range","detail":"{range_begin:/registry/roles/envoy-gateway-system/; range_end:/registry/roles/envoy-gateway-system0; response_count:3; response_revision:2034; }","duration":"255.959313ms","start":"2026-04-23T16:36:24.533656Z","end":"2026-04-23T16:36:24.789615Z","steps":["trace[1017332483] 'range keys from in-memory index tree' (duration: 255.315348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:24.789762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.651136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:36:24.789825Z","caller":"traceutil/trace.go:171","msg":"trace[11672879] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2034; }","duration":"256.551386ms","start":"2026-04-23T16:36:24.533257Z","end":"2026-04-23T16:36:24.789809Z","steps":["trace[11672879] 'count revisions from in-memory index tree' (duration: 255.514682ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:24.791201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.535408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" limit:500 ","response":"range_response_count:5 size:1050276"} {"level":"info","ts":"2026-04-23T16:36:24.791292Z","caller":"traceutil/trace.go:171","msg":"trace[254646251] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:2034; }","duration":"206.492369ms","start":"2026-04-23T16:36:24.584778Z","end":"2026-04-23T16:36:24.79127Z","steps":["trace[254646251] 'range keys from in-memory index tree' (duration: 203.876641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:25.743192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.209387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T16:36:25.743312Z","caller":"traceutil/trace.go:171","msg":"trace[157538668] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:2035; }","duration":"102.422071ms","start":"2026-04-23T16:36:25.640865Z","end":"2026-04-23T16:36:25.743287Z","steps":["trace[157538668] 'count revisions from in-memory index tree' (duration: 102.122824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:25.743599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.714641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:846"} {"level":"warn","ts":"2026-04-23T16:36:25.743642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.725263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.102\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-23T16:36:25.743681Z","caller":"traceutil/trace.go:171","msg":"trace[991231761] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:2035; }","duration":"143.834303ms","start":"2026-04-23T16:36:25.599827Z","end":"2026-04-23T16:36:25.743661Z","steps":["trace[991231761] 'range keys from in-memory index tree' (duration: 143.369654ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:36:25.743749Z","caller":"traceutil/trace.go:171","msg":"trace[728617312] range","detail":"{range_begin:/registry/masterleases/199.19.213.102; range_end:; response_count:1; response_revision:2035; }","duration":"139.880616ms","start":"2026-04-23T16:36:25.603823Z","end":"2026-04-23T16:36:25.743704Z","steps":["trace[728617312] 'range keys from in-memory index tree' (duration: 139.578979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:25.98199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.833671ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T16:36:25.98218Z","caller":"traceutil/trace.go:171","msg":"trace[1866870623] linearizableReadLoop","detail":"{readStateIndex:2130; appliedIndex:2129; }","duration":"177.313099ms","start":"2026-04-23T16:36:25.80485Z","end":"2026-04-23T16:36:25.982163Z","steps":["trace[1866870623] 'read index received' (duration: 43.175492ms)","trace[1866870623] 'applied index is now lower than readState.Index' (duration: 134.136257ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:36:25.982252Z","caller":"traceutil/trace.go:171","msg":"trace[1016390463] transaction","detail":"{read_only:false; response_revision:2037; number_of_response:1; }","duration":"180.618103ms","start":"2026-04-23T16:36:25.801602Z","end":"2026-04-23T16:36:25.982221Z","steps":["trace[1016390463] 'process raft request' (duration: 46.480605ms)","trace[1016390463] 'compare' (duration: 133.724408ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:36:25.982713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.90294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:846"} {"level":"warn","ts":"2026-04-23T16:36:25.982781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.529708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/kube-public/\" range_end:\"/registry/ingress/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T16:36:25.98277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.943402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-node-lease/\" range_end:\"/registry/pods/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:36:25.982899Z","caller":"traceutil/trace.go:171","msg":"trace[835619400] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:2037; }","duration":"136.970851ms","start":"2026-04-23T16:36:25.845782Z","end":"2026-04-23T16:36:25.982753Z","steps":["trace[835619400] 'agreement among raft nodes before linearized reading' (duration: 136.841578ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:36:25.982901Z","caller":"traceutil/trace.go:171","msg":"trace[843266387] range","detail":"{range_begin:/registry/pods/kube-node-lease/; range_end:/registry/pods/kube-node-lease0; response_count:0; response_revision:2037; }","duration":"142.119907ms","start":"2026-04-23T16:36:25.840746Z","end":"2026-04-23T16:36:25.982866Z","steps":["trace[843266387] 'agreement among raft nodes before linearized reading' (duration: 141.882292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:25.983195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.339174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" limit:500 ","response":"range_response_count:5 size:1050276"} {"level":"info","ts":"2026-04-23T16:36:25.983273Z","caller":"traceutil/trace.go:171","msg":"trace[1443484258] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:2037; }","duration":"178.449145ms","start":"2026-04-23T16:36:25.80481Z","end":"2026-04-23T16:36:25.983259Z","steps":["trace[1443484258] 'agreement among raft nodes before linearized reading' (duration: 177.462054ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:36:25.982826Z","caller":"traceutil/trace.go:171","msg":"trace[946144127] range","detail":"{range_begin:/registry/ingress/kube-public/; range_end:/registry/ingress/kube-public0; response_count:0; response_revision:2037; }","duration":"139.623251ms","start":"2026-04-23T16:36:25.843192Z","end":"2026-04-23T16:36:25.982815Z","steps":["trace[946144127] 'agreement among raft nodes before linearized reading' (duration: 139.530229ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:36:26.503567Z","caller":"traceutil/trace.go:171","msg":"trace[1467166295] linearizableReadLoop","detail":"{readStateIndex:2131; appliedIndex:2130; }","duration":"271.655202ms","start":"2026-04-23T16:36:26.231891Z","end":"2026-04-23T16:36:26.503546Z","steps":["trace[1467166295] 'read index received' (duration: 271.53941ms)","trace[1467166295] 'applied index is now lower than readState.Index' (duration: 114.612µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:36:26.503612Z","caller":"traceutil/trace.go:171","msg":"trace[118993764] transaction","detail":"{read_only:false; response_revision:2038; number_of_response:1; }","duration":"271.969158ms","start":"2026-04-23T16:36:26.231622Z","end":"2026-04-23T16:36:26.503591Z","steps":["trace[118993764] 'process raft request' (duration: 271.788404ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:26.504031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.106002ms","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-04-23T16:36:26.504223Z","caller":"traceutil/trace.go:171","msg":"trace[370102392] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2038; }","duration":"272.258205ms","start":"2026-04-23T16:36:26.231876Z","end":"2026-04-23T16:36:26.504134Z","steps":["trace[370102392] 'agreement among raft nodes before linearized reading' (duration: 271.940538ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:27.004543Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1558137423343187585,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-23T16:36:27.332539Z","caller":"traceutil/trace.go:171","msg":"trace[1347552867] transaction","detail":"{read_only:false; response_revision:2039; number_of_response:1; }","duration":"1.100579087s","start":"2026-04-23T16:36:26.231922Z","end":"2026-04-23T16:36:27.332501Z","steps":["trace[1347552867] 'process raft request' (duration: 1.100061746s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:27.332763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:26.231917Z","time spent":"1.100752462s","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:36:27.332951Z","caller":"traceutil/trace.go:171","msg":"trace[1128903397] transaction","detail":"{read_only:false; response_revision:2040; number_of_response:1; }","duration":"1.100751231s","start":"2026-04-23T16:36:26.232176Z","end":"2026-04-23T16:36:27.332927Z","steps":["trace[1128903397] 'process raft request' (duration: 1.100062765s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:27.333071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:26.232168Z","time spent":"1.100834883s","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:36:27.333286Z","caller":"traceutil/trace.go:171","msg":"trace[703525250] transaction","detail":"{read_only:false; response_revision:2041; number_of_response:1; }","duration":"1.100118908s","start":"2026-04-23T16:36:26.233154Z","end":"2026-04-23T16:36:27.333273Z","steps":["trace[703525250] 'process raft request' (duration: 1.099487604s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:27.333356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:26.233146Z","time spent":"1.100174289s","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:36:27.333359Z","caller":"traceutil/trace.go:171","msg":"trace[1608299637] transaction","detail":"{read_only:false; response_revision:2042; number_of_response:1; }","duration":"1.099855922s","start":"2026-04-23T16:36:26.233491Z","end":"2026-04-23T16:36:27.333347Z","steps":["trace[1608299637] 'process raft request' (duration: 1.099382381s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:27.333421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:26.233483Z","time spent":"1.099906554s","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T16:36:27.441535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.135410454s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/kube-node-lease/\" range_end:\"/registry/persistentvolumeclaims/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:36:27.441609Z","caller":"traceutil/trace.go:171","msg":"trace[1670025488] range","detail":"{range_begin:/registry/persistentvolumeclaims/kube-node-lease/; range_end:/registry/persistentvolumeclaims/kube-node-lease0; response_count:0; response_revision:2043; }","duration":"1.135533946s","start":"2026-04-23T16:36:26.306058Z","end":"2026-04-23T16:36:27.441592Z","steps":["trace[1670025488] 'agreement among raft nodes before linearized reading' (duration: 1.135420804s)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:36:27.441638Z","caller":"traceutil/trace.go:171","msg":"trace[125982290] transaction","detail":"{read_only:false; response_revision:2043; number_of_response:1; }","duration":"932.619946ms","start":"2026-04-23T16:36:26.508976Z","end":"2026-04-23T16:36:27.441596Z","steps":["trace[125982290] 'process raft request' (duration: 888.967284ms)","trace[125982290] 'compare' (duration: 43.223042ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:36:27.441678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:26.306038Z","time spent":"1.135618558s","remote":"127.0.0.1:56364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/kube-node-lease/\" range_end:\"/registry/persistentvolumeclaims/kube-node-lease0\" limit:500 "} {"level":"warn","ts":"2026-04-23T16:36:27.441791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.185429378s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:1910"} {"level":"warn","ts":"2026-04-23T16:36:27.441817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.195649086s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:1 size:833"} {"level":"warn","ts":"2026-04-23T16:36:27.441832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:26.508952Z","time spent":"932.767448ms","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:36:27.441871Z","caller":"traceutil/trace.go:171","msg":"trace[718278655] range","detail":"{range_begin:/registry/services/specs/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:2043; }","duration":"1.185552112s","start":"2026-04-23T16:36:26.256298Z","end":"2026-04-23T16:36:27.44185Z","steps":["trace[718278655] 'agreement among raft nodes before linearized reading' (duration: 1.185266815s)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:36:27.44188Z","caller":"traceutil/trace.go:171","msg":"trace[1349634314] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:1; response_revision:2043; }","duration":"1.195724568s","start":"2026-04-23T16:36:26.246135Z","end":"2026-04-23T16:36:27.441859Z","steps":["trace[1349634314] 'agreement among raft nodes before linearized reading' (duration: 1.195543553s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:27.441919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:26.256281Z","time spent":"1.185626793s","remote":"127.0.0.1:56398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":1933,"request content":"key:\"/registry/services/specs/envoy-gateway-system/envoy-gateway\" "} {"level":"warn","ts":"2026-04-23T16:36:27.441933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:26.246123Z","time spent":"1.19579494s","remote":"127.0.0.1:56414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":856,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-04-23T16:36:27.44128Z","caller":"traceutil/trace.go:171","msg":"trace[315210440] linearizableReadLoop","detail":"{readStateIndex:2136; appliedIndex:2131; }","duration":"937.631807ms","start":"2026-04-23T16:36:26.503627Z","end":"2026-04-23T16:36:27.441258Z","steps":["trace[315210440] 'read index received' (duration: 828.434974ms)","trace[315210440] 'applied index is now lower than readState.Index' (duration: 109.195893ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:36:27.442215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.151917773s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:36:27.442331Z","caller":"traceutil/trace.go:171","msg":"trace[153707281] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2043; }","duration":"1.152066855s","start":"2026-04-23T16:36:26.290251Z","end":"2026-04-23T16:36:27.442317Z","steps":["trace[153707281] 'agreement among raft nodes before linearized reading' (duration: 1.151235676s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:27.442364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:26.290231Z","time spent":"1.152125747s","remote":"127.0.0.1:56216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T16:36:27.442549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.586776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/kube-public/\" range_end:\"/registry/persistentvolumeclaims/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:36:27.442606Z","caller":"traceutil/trace.go:171","msg":"trace[1094792727] range","detail":"{range_begin:/registry/persistentvolumeclaims/kube-public/; range_end:/registry/persistentvolumeclaims/kube-public0; response_count:0; response_revision:2043; }","duration":"765.677808ms","start":"2026-04-23T16:36:26.676912Z","end":"2026-04-23T16:36:27.44259Z","steps":["trace[1094792727] 'agreement among raft nodes before linearized reading' (duration: 765.583406ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:27.442648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:26.676874Z","time spent":"765.761529ms","remote":"127.0.0.1:56364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/kube-public/\" range_end:\"/registry/persistentvolumeclaims/kube-public0\" limit:500 "} {"level":"warn","ts":"2026-04-23T16:36:27.442746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.509218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:36:27.442804Z","caller":"traceutil/trace.go:171","msg":"trace[1244597597] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:2043; }","duration":"653.607041ms","start":"2026-04-23T16:36:26.789184Z","end":"2026-04-23T16:36:27.442791Z","steps":["trace[1244597597] 'agreement among raft nodes before linearized reading' (duration: 653.510069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:27.442839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:26.789165Z","time spent":"653.662032ms","remote":"127.0.0.1:56296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-04-23T16:36:27.443397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.059845131s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:36:27.443488Z","caller":"traceutil/trace.go:171","msg":"trace[1158779697] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:2043; }","duration":"1.061702443s","start":"2026-04-23T16:36:26.381764Z","end":"2026-04-23T16:36:27.443466Z","steps":["trace[1158779697] 'agreement among raft nodes before linearized reading' (duration: 1.059853261s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:27.443562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:26.38174Z","time spent":"1.061800604s","remote":"127.0.0.1:60058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-04-23T16:36:28.183951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.061457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/envoy\" ","response":"range_response_count:1 size:4434"} {"level":"warn","ts":"2026-04-23T16:36:28.18402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.406393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-node-lease/\" range_end:\"/registry/rolebindings/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:36:28.184069Z","caller":"traceutil/trace.go:171","msg":"trace[1454581071] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/envoy; range_end:; response_count:1; response_revision:2043; }","duration":"626.22152ms","start":"2026-04-23T16:36:27.557822Z","end":"2026-04-23T16:36:28.184043Z","steps":["trace[1454581071] 'range keys from in-memory index tree' (duration: 625.877622ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:36:28.184114Z","caller":"traceutil/trace.go:171","msg":"trace[1907878690] range","detail":"{range_begin:/registry/rolebindings/kube-node-lease/; range_end:/registry/rolebindings/kube-node-lease0; response_count:0; response_revision:2043; }","duration":"630.538777ms","start":"2026-04-23T16:36:27.553547Z","end":"2026-04-23T16:36:28.184085Z","steps":["trace[1907878690] 'range keys from in-memory index tree' (duration: 630.340532ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:28.184163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:27.553528Z","time spent":"630.626459ms","remote":"127.0.0.1:56554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/rolebindings/kube-node-lease/\" range_end:\"/registry/rolebindings/kube-node-lease0\" limit:500 "} {"level":"warn","ts":"2026-04-23T16:36:28.18414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:27.557814Z","time spent":"626.300391ms","remote":"127.0.0.1:56304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":4457,"request content":"key:\"/registry/secrets/envoy-gateway-system/envoy\" "} {"level":"warn","ts":"2026-04-23T16:36:28.185296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.149895ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T16:36:28.185393Z","caller":"traceutil/trace.go:171","msg":"trace[1851505716] linearizableReadLoop","detail":"{readStateIndex:2137; appliedIndex:2136; }","duration":"579.737075ms","start":"2026-04-23T16:36:27.605639Z","end":"2026-04-23T16:36:28.185377Z","steps":["trace[1851505716] 'read index received' (duration: 464.299004ms)","trace[1851505716] 'applied index is now lower than readState.Index' (duration: 115.436841ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:36:28.185489Z","caller":"traceutil/trace.go:171","msg":"trace[498774097] transaction","detail":"{read_only:false; response_revision:2044; number_of_response:1; }","duration":"601.810416ms","start":"2026-04-23T16:36:27.583664Z","end":"2026-04-23T16:36:28.185474Z","steps":["trace[498774097] 'process raft request' (duration: 486.387765ms)","trace[498774097] 'compare' (duration: 114.06048ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:36:28.185547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:27.583651Z","time spent":"601.867108ms","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T16:36:28.185635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.99692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-node-lease/\" range_end:\"/registry/roles/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:36:28.185686Z","caller":"traceutil/trace.go:171","msg":"trace[1900381558] range","detail":"{range_begin:/registry/roles/kube-node-lease/; range_end:/registry/roles/kube-node-lease0; response_count:0; response_revision:2044; }","duration":"580.078833ms","start":"2026-04-23T16:36:27.605598Z","end":"2026-04-23T16:36:28.185677Z","steps":["trace[1900381558] 'agreement among raft nodes before linearized reading' (duration: 579.97417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:28.185719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:27.605583Z","time spent":"580.130413ms","remote":"127.0.0.1:56542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":28,"request content":"key:\"/registry/roles/kube-node-lease/\" range_end:\"/registry/roles/kube-node-lease0\" limit:500 "} {"level":"warn","ts":"2026-04-23T16:36:28.18561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.616433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T16:36:28.185773Z","caller":"traceutil/trace.go:171","msg":"trace[1734347990] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2044; }","duration":"439.807277ms","start":"2026-04-23T16:36:27.745955Z","end":"2026-04-23T16:36:28.185762Z","steps":["trace[1734347990] 'agreement among raft nodes before linearized reading' (duration: 439.550732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:28.185779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.373253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:4641"} {"level":"warn","ts":"2026-04-23T16:36:28.185815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:27.745889Z","time spent":"439.91357ms","remote":"127.0.0.1:56678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-04-23T16:36:28.185827Z","caller":"traceutil/trace.go:171","msg":"trace[1077536446] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:2044; }","duration":"388.444963ms","start":"2026-04-23T16:36:27.797365Z","end":"2026-04-23T16:36:28.18581Z","steps":["trace[1077536446] 'agreement among raft nodes before linearized reading' (duration: 388.2923ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:28.185869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:27.797347Z","time spent":"388.514276ms","remote":"127.0.0.1:56304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":4664,"request content":"key:\"/registry/secrets/envoy-gateway-system/envoy-gateway\" "} {"level":"warn","ts":"2026-04-23T16:36:28.584071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.522622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-public/\" range_end:\"/registry/secrets/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:36:28.584162Z","caller":"traceutil/trace.go:171","msg":"trace[1730094021] range","detail":"{range_begin:/registry/secrets/kube-public/; range_end:/registry/secrets/kube-public0; response_count:0; response_revision:2044; }","duration":"220.656325ms","start":"2026-04-23T16:36:28.363481Z","end":"2026-04-23T16:36:28.584137Z","steps":["trace[1730094021] 'range keys from in-memory index tree' (duration: 220.44499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:28.584281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.945793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/envoy\" ","response":"range_response_count:1 size:4434"} {"level":"info","ts":"2026-04-23T16:36:28.584359Z","caller":"traceutil/trace.go:171","msg":"trace[733461098] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/envoy; range_end:; response_count:1; response_revision:2044; }","duration":"283.081966ms","start":"2026-04-23T16:36:28.301258Z","end":"2026-04-23T16:36:28.58434Z","steps":["trace[733461098] 'range keys from in-memory index tree' (duration: 282.690298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:28.58447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.197291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-node-lease/\" range_end:\"/registry/serviceaccounts/kube-node-lease0\" limit:500 ","response":"range_response_count:1 size:187"} {"level":"warn","ts":"2026-04-23T16:36:28.584547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.245782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:4641"} {"level":"info","ts":"2026-04-23T16:36:28.584577Z","caller":"traceutil/trace.go:171","msg":"trace[729892280] range","detail":"{range_begin:/registry/serviceaccounts/kube-node-lease/; range_end:/registry/serviceaccounts/kube-node-lease0; response_count:1; response_revision:2044; }","duration":"214.362335ms","start":"2026-04-23T16:36:28.37019Z","end":"2026-04-23T16:36:28.584553Z","steps":["trace[729892280] 'range keys from in-memory index tree' (duration: 214.075299ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:36:28.584604Z","caller":"traceutil/trace.go:171","msg":"trace[78675458] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:2044; }","duration":"293.335584ms","start":"2026-04-23T16:36:28.291255Z","end":"2026-04-23T16:36:28.58459Z","steps":["trace[78675458] 'range keys from in-memory index tree' (duration: 293.092689ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:28.851523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.809578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/kube-public/\" range_end:\"/registry/services/specs/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:36:28.851622Z","caller":"traceutil/trace.go:171","msg":"trace[326424757] range","detail":"{range_begin:/registry/services/specs/kube-public/; range_end:/registry/services/specs/kube-public0; response_count:0; response_revision:2045; }","duration":"158.996072ms","start":"2026-04-23T16:36:28.692604Z","end":"2026-04-23T16:36:28.8516Z","steps":["trace[326424757] 'range keys from in-memory index tree' (duration: 158.706406ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:28.851873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.928424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/envoy-oidc-hmac\" ","response":"range_response_count:1 size:452"} {"level":"info","ts":"2026-04-23T16:36:28.851951Z","caller":"traceutil/trace.go:171","msg":"trace[396583157] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/envoy-oidc-hmac; range_end:; response_count:1; response_revision:2045; }","duration":"165.047487ms","start":"2026-04-23T16:36:28.686884Z","end":"2026-04-23T16:36:28.851932Z","steps":["trace[396583157] 'range keys from in-memory index tree' (duration: 164.331641ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:36:29.475209Z","caller":"traceutil/trace.go:171","msg":"trace[1925606208] linearizableReadLoop","detail":"{readStateIndex:2141; appliedIndex:2139; }","duration":"238.533544ms","start":"2026-04-23T16:36:29.236652Z","end":"2026-04-23T16:36:29.475185Z","steps":["trace[1925606208] 'read index received' (duration: 95.476857ms)","trace[1925606208] 'applied index is now lower than readState.Index' (duration: 143.055407ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:36:29.475497Z","caller":"traceutil/trace.go:171","msg":"trace[872711641] transaction","detail":"{read_only:false; response_revision:2048; number_of_response:1; }","duration":"240.273832ms","start":"2026-04-23T16:36:29.235197Z","end":"2026-04-23T16:36:29.475471Z","steps":["trace[872711641] 'process raft request' (duration: 239.930274ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:36:29.475544Z","caller":"traceutil/trace.go:171","msg":"trace[1339894296] transaction","detail":"{read_only:false; response_revision:2047; number_of_response:1; }","duration":"241.368827ms","start":"2026-04-23T16:36:29.234154Z","end":"2026-04-23T16:36:29.475523Z","steps":["trace[1339894296] 'process raft request' (duration: 149.161274ms)","trace[1339894296] 'compare' (duration: 91.686861ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:36:29.475805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.010335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-23T16:36:29.475835Z","caller":"traceutil/trace.go:171","msg":"trace[1954308668] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2048; }","duration":"138.109937ms","start":"2026-04-23T16:36:29.337718Z","end":"2026-04-23T16:36:29.475828Z","steps":["trace[1954308668] 'agreement among raft nodes before linearized reading' (duration: 137.966224ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:29.4759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.850532ms","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":"warn","ts":"2026-04-23T16:36:29.475968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.782122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/kube-system/\" range_end:\"/registry/ingress/kube-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:36:29.475993Z","caller":"traceutil/trace.go:171","msg":"trace[1770579800] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2048; }","duration":"238.980865ms","start":"2026-04-23T16:36:29.236991Z","end":"2026-04-23T16:36:29.475972Z","steps":["trace[1770579800] 'agreement among raft nodes before linearized reading' (duration: 238.714339ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:29.476577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.819352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-public/cluster-info\" ","response":"range_response_count:1 size:2178"} {"level":"info","ts":"2026-04-23T16:36:29.476699Z","caller":"traceutil/trace.go:171","msg":"trace[2104584023] range","detail":"{range_begin:/registry/configmaps/kube-public/cluster-info; range_end:; response_count:1; response_revision:2048; }","duration":"164.945754ms","start":"2026-04-23T16:36:29.31171Z","end":"2026-04-23T16:36:29.476656Z","steps":["trace[2104584023] 'agreement among raft nodes before linearized reading' (duration: 164.76256ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:36:29.477513Z","caller":"traceutil/trace.go:171","msg":"trace[1290017668] range","detail":"{range_begin:/registry/ingress/kube-system/; range_end:/registry/ingress/kube-system0; response_count:0; response_revision:2048; }","duration":"131.312485ms","start":"2026-04-23T16:36:29.346159Z","end":"2026-04-23T16:36:29.477472Z","steps":["trace[1290017668] 'agreement among raft nodes before linearized reading' (duration: 129.782872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:29.477658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.982602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:36:29.477927Z","caller":"traceutil/trace.go:171","msg":"trace[1765988346] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2048; }","duration":"186.274969ms","start":"2026-04-23T16:36:29.291538Z","end":"2026-04-23T16:36:29.477813Z","steps":["trace[1765988346] 'agreement among raft nodes before linearized reading' (duration: 185.923321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:29.47842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.289208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-23T16:36:29.478488Z","caller":"traceutil/trace.go:171","msg":"trace[473271493] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2048; }","duration":"141.37804ms","start":"2026-04-23T16:36:29.337087Z","end":"2026-04-23T16:36:29.478465Z","steps":["trace[473271493] 'agreement among raft nodes before linearized reading' (duration: 141.197805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:29.478941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.554339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/kube-system/\" range_end:\"/registry/services/endpoints/kube-system0\" limit:500 ","response":"range_response_count:3 size:2612"} {"level":"warn","ts":"2026-04-23T16:36:29.477988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.64647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"warn","ts":"2026-04-23T16:36:29.478243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.672464ms","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-04-23T16:36:29.479278Z","caller":"traceutil/trace.go:171","msg":"trace[1215655556] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2048; }","duration":"141.631295ms","start":"2026-04-23T16:36:29.337528Z","end":"2026-04-23T16:36:29.479159Z","steps":["trace[1215655556] 'agreement among raft nodes before linearized reading' (duration: 140.557431ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:36:29.479535Z","caller":"traceutil/trace.go:171","msg":"trace[1143094457] range","detail":"{range_begin:/registry/services/endpoints/kube-system/; range_end:/registry/services/endpoints/kube-system0; response_count:3; response_revision:2048; }","duration":"219.234183ms","start":"2026-04-23T16:36:29.260272Z","end":"2026-04-23T16:36:29.479506Z","steps":["trace[1143094457] 'agreement among raft nodes before linearized reading' (duration: 218.211181ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:36:29.479893Z","caller":"traceutil/trace.go:171","msg":"trace[1046285746] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2048; }","duration":"141.557834ms","start":"2026-04-23T16:36:29.338312Z","end":"2026-04-23T16:36:29.47987Z","steps":["trace[1046285746] 'agreement among raft nodes before linearized reading' (duration: 139.5819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:29.487297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.621814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" ","response":"range_response_count:1 size:1036317"} {"level":"info","ts":"2026-04-23T16:36:29.487367Z","caller":"traceutil/trace.go:171","msg":"trace[380434343] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1; range_end:; response_count:1; response_revision:2048; }","duration":"250.715516ms","start":"2026-04-23T16:36:29.236636Z","end":"2026-04-23T16:36:29.487352Z","steps":["trace[380434343] 'agreement among raft nodes before linearized reading' (duration: 239.260741ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:36:29.727042Z","caller":"traceutil/trace.go:171","msg":"trace[801464825] transaction","detail":"{read_only:false; response_revision:2050; number_of_response:1; }","duration":"240.919276ms","start":"2026-04-23T16:36:29.486095Z","end":"2026-04-23T16:36:29.727015Z","steps":["trace[801464825] 'process raft request' (duration: 240.703922ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:36:29.727346Z","caller":"traceutil/trace.go:171","msg":"trace[1305199070] transaction","detail":"{read_only:false; response_revision:2051; number_of_response:1; }","duration":"239.612397ms","start":"2026-04-23T16:36:29.487706Z","end":"2026-04-23T16:36:29.727319Z","steps":["trace[1305199070] 'process raft request' (duration: 239.267049ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:36:29.727422Z","caller":"traceutil/trace.go:171","msg":"trace[1221986660] transaction","detail":"{read_only:false; response_revision:2053; number_of_response:1; }","duration":"237.099911ms","start":"2026-04-23T16:36:29.490283Z","end":"2026-04-23T16:36:29.727383Z","steps":["trace[1221986660] 'process raft request' (duration: 237.03172ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:36:29.727441Z","caller":"traceutil/trace.go:171","msg":"trace[2100024760] linearizableReadLoop","detail":"{readStateIndex:2145; appliedIndex:2141; }","duration":"239.584486ms","start":"2026-04-23T16:36:29.487844Z","end":"2026-04-23T16:36:29.727429Z","steps":["trace[2100024760] 'read index received' (duration: 44.516892ms)","trace[2100024760] 'applied index is now lower than readState.Index' (duration: 195.066834ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:36:29.727527Z","caller":"traceutil/trace.go:171","msg":"trace[1480504888] transaction","detail":"{read_only:false; response_revision:2054; number_of_response:1; }","duration":"237.235844ms","start":"2026-04-23T16:36:29.490277Z","end":"2026-04-23T16:36:29.727513Z","steps":["trace[1480504888] 'process raft request' (duration: 237.070291ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:36:29.727546Z","caller":"traceutil/trace.go:171","msg":"trace[518848486] transaction","detail":"{read_only:false; response_revision:2052; number_of_response:1; }","duration":"239.765231ms","start":"2026-04-23T16:36:29.487771Z","end":"2026-04-23T16:36:29.727536Z","steps":["trace[518848486] 'process raft request' (duration: 239.455704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:29.727589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.721839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-public/cluster-info\" ","response":"range_response_count:1 size:2178"} {"level":"info","ts":"2026-04-23T16:36:29.727635Z","caller":"traceutil/trace.go:171","msg":"trace[1665120180] range","detail":"{range_begin:/registry/configmaps/kube-public/cluster-info; range_end:; response_count:1; response_revision:2054; }","duration":"239.794291ms","start":"2026-04-23T16:36:29.48783Z","end":"2026-04-23T16:36:29.727624Z","steps":["trace[1665120180] 'agreement among raft nodes before linearized reading' (duration: 239.683168ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:29.727717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.044223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/kube-system/\" range_end:\"/registry/networkpolicies/kube-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:36:29.727762Z","caller":"traceutil/trace.go:171","msg":"trace[563694308] range","detail":"{range_begin:/registry/networkpolicies/kube-system/; range_end:/registry/networkpolicies/kube-system0; response_count:0; response_revision:2054; }","duration":"146.135957ms","start":"2026-04-23T16:36:29.581614Z","end":"2026-04-23T16:36:29.72775Z","steps":["trace[563694308] 'agreement among raft nodes before linearized reading' (duration: 146.055274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:29.727893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.499208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/kube-system/\" range_end:\"/registry/jobs/kube-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T16:36:29.727964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.957753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" ","response":"range_response_count:1 size:1036317"} {"level":"info","ts":"2026-04-23T16:36:29.72802Z","caller":"traceutil/trace.go:171","msg":"trace[652173232] range","detail":"{range_begin:/registry/jobs/kube-system/; range_end:/registry/jobs/kube-system0; response_count:0; response_revision:2054; }","duration":"161.61252ms","start":"2026-04-23T16:36:29.566359Z","end":"2026-04-23T16:36:29.727972Z","steps":["trace[652173232] 'agreement among raft nodes before linearized reading' (duration: 161.465687ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:36:29.728033Z","caller":"traceutil/trace.go:171","msg":"trace[1701979412] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1; range_end:; response_count:1; response_revision:2054; }","duration":"177.062795ms","start":"2026-04-23T16:36:29.550957Z","end":"2026-04-23T16:36:29.72802Z","steps":["trace[1701979412] 'agreement among raft nodes before linearized reading' (duration: 176.676466ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:36:30.294562Z","caller":"traceutil/trace.go:171","msg":"trace[1098815752] transaction","detail":"{read_only:false; response_revision:2055; number_of_response:1; }","duration":"104.061809ms","start":"2026-04-23T16:36:30.190486Z","end":"2026-04-23T16:36:30.294547Z","steps":["trace[1098815752] 'process raft request' (duration: 103.735311ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:30.505069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.819381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-node-lease/default\" ","response":"range_response_count:1 size:187"} {"level":"info","ts":"2026-04-23T16:36:30.505131Z","caller":"traceutil/trace.go:171","msg":"trace[730860106] range","detail":"{range_begin:/registry/serviceaccounts/kube-node-lease/default; range_end:; response_count:1; response_revision:2055; }","duration":"120.939044ms","start":"2026-04-23T16:36:30.384178Z","end":"2026-04-23T16:36:30.505117Z","steps":["trace[730860106] 'range keys from in-memory index tree' (duration: 120.455714ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:30.504883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.345776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-public/system:controller:bootstrap-signer\" ","response":"range_response_count:1 size:709"} {"level":"info","ts":"2026-04-23T16:36:30.505599Z","caller":"traceutil/trace.go:171","msg":"trace[778621287] range","detail":"{range_begin:/registry/roles/kube-public/system:controller:bootstrap-signer; range_end:; response_count:1; response_revision:2055; }","duration":"114.141423ms","start":"2026-04-23T16:36:30.391434Z","end":"2026-04-23T16:36:30.505575Z","steps":["trace[778621287] 'range keys from in-memory index tree' (duration: 113.129751ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:31.251553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.074484ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-23T16:36:31.251655Z","caller":"traceutil/trace.go:171","msg":"trace[885038861] linearizableReadLoop","detail":"{readStateIndex:2150; appliedIndex:2149; }","duration":"440.188486ms","start":"2026-04-23T16:36:30.811448Z","end":"2026-04-23T16:36:31.251637Z","steps":["trace[885038861] 'read index received' (duration: 83.02048ms)","trace[885038861] 'applied index is now lower than readState.Index' (duration: 357.166315ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:36:31.251933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.462952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/ceph-csi-config\" ","response":"range_response_count:1 size:919"} {"level":"info","ts":"2026-04-23T16:36:31.252011Z","caller":"traceutil/trace.go:171","msg":"trace[82845336] range","detail":"{range_begin:/registry/configmaps/kube-system/ceph-csi-config; range_end:; response_count:1; response_revision:2056; }","duration":"440.587275ms","start":"2026-04-23T16:36:30.811407Z","end":"2026-04-23T16:36:31.251994Z","steps":["trace[82845336] 'agreement among raft nodes before linearized reading' (duration: 440.35819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:31.252053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:30.811389Z","time spent":"440.655206ms","remote":"127.0.0.1:56318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":942,"request content":"key:\"/registry/configmaps/kube-system/ceph-csi-config\" "} {"level":"warn","ts":"2026-04-23T16:36:31.25195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.10683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/ceph-config\" ","response":"range_response_count:1 size:899"} {"level":"info","ts":"2026-04-23T16:36:31.252142Z","caller":"traceutil/trace.go:171","msg":"trace[1407844181] range","detail":"{range_begin:/registry/configmaps/kube-system/ceph-config; range_end:; response_count:1; response_revision:2056; }","duration":"438.308755ms","start":"2026-04-23T16:36:30.813809Z","end":"2026-04-23T16:36:31.252117Z","steps":["trace[1407844181] 'agreement among raft nodes before linearized reading' (duration: 437.977218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:36:31.252211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:36:30.813802Z","time spent":"438.389277ms","remote":"127.0.0.1:56318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":922,"request content":"key:\"/registry/configmaps/kube-system/ceph-config\" "}