{"level":"warn","ts":"2026-06-09T13:45:38.283386Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-09T13:45:38.283516Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.180: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.180:2380","--initial-cluster=instance=https://199.19.213.180:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.180:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.180: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-06-09T13:45:38.28365Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-09T13:45:38.283673Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.180:2380"]} {"level":"info","ts":"2026-06-09T13:45:38.283702Z","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-06-09T13:45:38.285559Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.180:2379"]} {"level":"info","ts":"2026-06-09T13:45:38.285877Z","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.180:2380"],"listen-peer-urls":["https://199.19.213.180:2380"],"advertise-client-urls":["https://199.19.213.180:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.180:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.180: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-06-09T13:45:38.288831Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.567912ms"} {"level":"info","ts":"2026-06-09T13:45:38.302196Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5d6864bfaf2cb565","cluster-id":"32ff95319ee9cd99"} {"level":"info","ts":"2026-06-09T13:45:38.302275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 switched to configuration voters=()"} {"level":"info","ts":"2026-06-09T13:45:38.302295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 became follower at term 0"} {"level":"info","ts":"2026-06-09T13:45:38.302304Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5d6864bfaf2cb565 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-09T13:45:38.302312Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 became follower at term 1"} {"level":"info","ts":"2026-06-09T13:45:38.302388Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 switched to configuration voters=(6730740417545680229)"} {"level":"warn","ts":"2026-06-09T13:45:38.309951Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-09T13:45:38.311262Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-09T13:45:38.31229Z","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-06-09T13:45:38.314457Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5d6864bfaf2cb565","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-09T13:45:38.314572Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5d6864bfaf2cb565","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-09T13:45:38.314556Z","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-06-09T13:45:38.314936Z","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-06-09T13:45:38.31527Z","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-06-09T13:45:38.315992Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 switched to configuration voters=(6730740417545680229)"} {"level":"info","ts":"2026-06-09T13:45:38.316157Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"32ff95319ee9cd99","local-member-id":"5d6864bfaf2cb565","added-peer-id":"5d6864bfaf2cb565","added-peer-peer-urls":["https://199.19.213.180:2380"]} {"level":"info","ts":"2026-06-09T13:45:38.322328Z","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-06-09T13:45:38.322452Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.180:2380"} {"level":"info","ts":"2026-06-09T13:45:38.32249Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.180:2380"} {"level":"info","ts":"2026-06-09T13:45:38.322625Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5d6864bfaf2cb565","initial-advertise-peer-urls":["https://199.19.213.180:2380"],"listen-peer-urls":["https://199.19.213.180:2380"],"advertise-client-urls":["https://199.19.213.180:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.180:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-09T13:45:38.322655Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-09T13:45:39.003668Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 is starting a new election at term 1"} {"level":"info","ts":"2026-06-09T13:45:39.003749Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-09T13:45:39.003791Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 received MsgPreVoteResp from 5d6864bfaf2cb565 at term 1"} {"level":"info","ts":"2026-06-09T13:45:39.003817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 became candidate at term 2"} {"level":"info","ts":"2026-06-09T13:45:39.003833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 received MsgVoteResp from 5d6864bfaf2cb565 at term 2"} {"level":"info","ts":"2026-06-09T13:45:39.00385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 became leader at term 2"} {"level":"info","ts":"2026-06-09T13:45:39.003868Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5d6864bfaf2cb565 elected leader 5d6864bfaf2cb565 at term 2"} {"level":"info","ts":"2026-06-09T13:45:39.004912Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T13:45:39.005801Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5d6864bfaf2cb565","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.180:2379]}","request-path":"/0/members/5d6864bfaf2cb565/attributes","cluster-id":"32ff95319ee9cd99","publish-timeout":"7s"} {"level":"info","ts":"2026-06-09T13:45:39.005883Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-09T13:45:39.005929Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-09T13:45:39.006148Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-09T13:45:39.006174Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-09T13:45:39.007763Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"32ff95319ee9cd99","local-member-id":"5d6864bfaf2cb565","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T13:45:39.007886Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T13:45:39.007934Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T13:45:39.009389Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.180:2379"} {"level":"info","ts":"2026-06-09T13:45:39.010351Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-09T13:45:41.393361Z","caller":"traceutil/trace.go:171","msg":"trace[1132799331] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"185.273027ms","start":"2026-06-09T13:45:41.20807Z","end":"2026-06-09T13:45:41.393343Z","steps":["trace[1132799331] 'read index received' (duration: 185.131234ms)","trace[1132799331] 'applied index is now lower than readState.Index' (duration: 141.123µs)"],"step_count":2} {"level":"warn","ts":"2026-06-09T13:45:41.393494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.40542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-09T13:45:41.393534Z","caller":"traceutil/trace.go:171","msg":"trace[335536061] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:72; }","duration":"185.525293ms","start":"2026-06-09T13:45:41.208001Z","end":"2026-06-09T13:45:41.393526Z","steps":["trace[335536061] 'agreement among raft nodes before linearized reading' (duration: 185.430521ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:45:41.393437Z","caller":"traceutil/trace.go:171","msg":"trace[1288309854] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"186.285401ms","start":"2026-06-09T13:45:41.207128Z","end":"2026-06-09T13:45:41.393414Z","steps":["trace[1288309854] 'process raft request' (duration: 186.117617ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:45:42.048274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.704437ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-06-09T13:45:42.048409Z","caller":"traceutil/trace.go:171","msg":"trace[1456641143] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:76; }","duration":"648.439584ms","start":"2026-06-09T13:45:41.399951Z","end":"2026-06-09T13:45:42.04839Z","steps":["trace[1456641143] 'read index received' (duration: 303.035921ms)","trace[1456641143] 'applied index is now lower than readState.Index' (duration: 345.402563ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T13:45:42.048523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.570168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-09T13:45:42.048559Z","caller":"traceutil/trace.go:171","msg":"trace[1916357204] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:0; response_revision:73; }","duration":"648.641639ms","start":"2026-06-09T13:45:41.399907Z","end":"2026-06-09T13:45:42.048549Z","steps":["trace[1916357204] 'agreement among raft nodes before linearized reading' (duration: 648.548847ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:45:42.04855Z","caller":"traceutil/trace.go:171","msg":"trace[390194157] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"648.554827ms","start":"2026-06-09T13:45:41.399881Z","end":"2026-06-09T13:45:42.048436Z","steps":["trace[390194157] 'process raft request' (duration: 303.155114ms)","trace[390194157] 'compare' (duration: 344.544113ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T13:45:42.048593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:45:41.399889Z","time spent":"648.696761ms","remote":"127.0.0.1:54460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/view\" "} {"level":"warn","ts":"2026-06-09T13:45:42.048798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:45:41.399866Z","time spent":"648.769852ms","remote":"127.0.0.1:54476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T13:46:14.736209Z","caller":"traceutil/trace.go:171","msg":"trace[178817239] linearizableReadLoop","detail":"{readStateIndex:507; appliedIndex:506; }","duration":"147.407913ms","start":"2026-06-09T13:46:14.588778Z","end":"2026-06-09T13:46:14.736186Z","steps":["trace[178817239] 'read index received' (duration: 146.980023ms)","trace[178817239] 'applied index is now lower than readState.Index' (duration: 427.08µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T13:46:14.736496Z","caller":"traceutil/trace.go:171","msg":"trace[1424876136] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"210.120371ms","start":"2026-06-09T13:46:14.526338Z","end":"2026-06-09T13:46:14.736458Z","steps":["trace[1424876136] 'process raft request' (duration: 209.609069ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:46:14.736603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.804222ms","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-06-09T13:46:14.736693Z","caller":"traceutil/trace.go:171","msg":"trace[307515657] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:490; }","duration":"147.949856ms","start":"2026-06-09T13:46:14.588724Z","end":"2026-06-09T13:46:14.736674Z","steps":["trace[307515657] 'agreement among raft nodes before linearized reading' (duration: 147.70803ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:46:16.457212Z","caller":"traceutil/trace.go:171","msg":"trace[481531097] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"127.927577ms","start":"2026-06-09T13:46:16.329259Z","end":"2026-06-09T13:46:16.457186Z","steps":["trace[481531097] 'process raft request' (duration: 127.781054ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:46:16.464397Z","caller":"traceutil/trace.go:171","msg":"trace[725510765] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"126.36756ms","start":"2026-06-09T13:46:16.338005Z","end":"2026-06-09T13:46:16.464373Z","steps":["trace[725510765] 'process raft request' (duration: 126.120964ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:46:16.464465Z","caller":"traceutil/trace.go:171","msg":"trace[713410010] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"134.720259ms","start":"2026-06-09T13:46:16.329724Z","end":"2026-06-09T13:46:16.464444Z","steps":["trace[713410010] 'process raft request' (duration: 134.229116ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:46:16.464611Z","caller":"traceutil/trace.go:171","msg":"trace[2076158070] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"126.119924ms","start":"2026-06-09T13:46:16.338288Z","end":"2026-06-09T13:46:16.464408Z","steps":["trace[2076158070] 'process raft request' (duration: 125.907759ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:46:16.464739Z","caller":"traceutil/trace.go:171","msg":"trace[1721441828] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"109.246941ms","start":"2026-06-09T13:46:16.355483Z","end":"2026-06-09T13:46:16.46473Z","steps":["trace[1721441828] 'process raft request' (duration: 109.151358ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:46:16.464671Z","caller":"traceutil/trace.go:171","msg":"trace[747712165] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"115.969022ms","start":"2026-06-09T13:46:16.348692Z","end":"2026-06-09T13:46:16.464661Z","steps":["trace[747712165] 'process raft request' (duration: 115.565172ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:46:16.464818Z","caller":"traceutil/trace.go:171","msg":"trace[391441301] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"109.23536ms","start":"2026-06-09T13:46:16.355576Z","end":"2026-06-09T13:46:16.464811Z","steps":["trace[391441301] 'process raft request' (duration: 109.100227ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:46:16.464595Z","caller":"traceutil/trace.go:171","msg":"trace[1143192368] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"115.91464ms","start":"2026-06-09T13:46:16.348635Z","end":"2026-06-09T13:46:16.464549Z","steps":["trace[1143192368] 'process raft request' (duration: 115.590723ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:46:16.464709Z","caller":"traceutil/trace.go:171","msg":"trace[2142174512] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"109.269341ms","start":"2026-06-09T13:46:16.355432Z","end":"2026-06-09T13:46:16.464702Z","steps":["trace[2142174512] 'process raft request' (duration: 109.164368ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:46:22.639656Z","caller":"traceutil/trace.go:171","msg":"trace[1667883800] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"417.519887ms","start":"2026-06-09T13:46:22.222106Z","end":"2026-06-09T13:46:22.639626Z","steps":["trace[1667883800] 'process raft request' (duration: 416.927553ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:46:22.639984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:46:22.222077Z","time spent":"417.66185ms","remote":"127.0.0.1:54232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T13:46:22.998294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.551185ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T13:46:22.998434Z","caller":"traceutil/trace.go:171","msg":"trace[538235515] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:634; }","duration":"219.701149ms","start":"2026-06-09T13:46:22.778711Z","end":"2026-06-09T13:46:22.998412Z","steps":["trace[538235515] 'range keys from in-memory index tree' (duration: 219.530675ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:46:23.091069Z","caller":"traceutil/trace.go:171","msg":"trace[1005970378] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"394.899346ms","start":"2026-06-09T13:46:22.696119Z","end":"2026-06-09T13:46:23.091019Z","steps":["trace[1005970378] 'process raft request' (duration: 394.63766ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:46:23.091287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:46:22.696085Z","time spent":"395.084811ms","remote":"127.0.0.1:54292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T13:46:23.19517Z","caller":"traceutil/trace.go:171","msg":"trace[1157636416] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:655; }","duration":"486.577667ms","start":"2026-06-09T13:46:22.708562Z","end":"2026-06-09T13:46:23.19514Z","steps":["trace[1157636416] 'read index received' (duration: 382.126991ms)","trace[1157636416] 'applied index is now lower than readState.Index' (duration: 104.449636ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T13:46:23.195321Z","caller":"traceutil/trace.go:171","msg":"trace[1785829071] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"490.47576ms","start":"2026-06-09T13:46:22.704809Z","end":"2026-06-09T13:46:23.195285Z","steps":["trace[1785829071] 'process raft request' (duration: 405.162112ms)","trace[1785829071] 'compare' (duration: 85.003331ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T13:46:23.19548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:46:22.704784Z","time spent":"490.614993ms","remote":"127.0.0.1:54624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-09T13:46:23.195514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.803593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-09T13:46:23.195393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.811092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T13:46:23.195509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.956904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.180\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-09T13:46:23.19556Z","caller":"traceutil/trace.go:171","msg":"trace[377555247] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:636; }","duration":"399.901386ms","start":"2026-06-09T13:46:22.795645Z","end":"2026-06-09T13:46:23.195547Z","steps":["trace[377555247] 'agreement among raft nodes before linearized reading' (duration: 399.749732ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:46:23.195597Z","caller":"traceutil/trace.go:171","msg":"trace[968010353] range","detail":"{range_begin:/registry/masterleases/199.19.213.180; range_end:; response_count:1; response_revision:636; }","duration":"484.074246ms","start":"2026-06-09T13:46:22.711498Z","end":"2026-06-09T13:46:23.195572Z","steps":["trace[968010353] 'agreement among raft nodes before linearized reading' (duration: 483.882762ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:46:23.195603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:46:22.795623Z","time spent":"399.972437ms","remote":"127.0.0.1:54364","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-06-09T13:46:23.195652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:46:22.711488Z","time spent":"484.154789ms","remote":"127.0.0.1:54168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.180\" "} {"level":"info","ts":"2026-06-09T13:46:23.195566Z","caller":"traceutil/trace.go:171","msg":"trace[742265040] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:636; }","duration":"487.016407ms","start":"2026-06-09T13:46:22.708537Z","end":"2026-06-09T13:46:23.195553Z","steps":["trace[742265040] 'agreement among raft nodes before linearized reading' (duration: 486.766861ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:46:23.195749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:46:22.708528Z","time spent":"487.207731ms","remote":"127.0.0.1:54142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-09T13:46:23.195914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.619479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-09T13:46:23.195958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.917022ms","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-06-09T13:46:23.195964Z","caller":"traceutil/trace.go:171","msg":"trace[1321269478] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:636; }","duration":"368.68876ms","start":"2026-06-09T13:46:22.827262Z","end":"2026-06-09T13:46:23.195951Z","steps":["trace[1321269478] 'agreement among raft nodes before linearized reading' (duration: 368.521695ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:46:23.195991Z","caller":"traceutil/trace.go:171","msg":"trace[910055611] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:636; }","duration":"372.970763ms","start":"2026-06-09T13:46:22.823012Z","end":"2026-06-09T13:46:23.195983Z","steps":["trace[910055611] 'agreement among raft nodes before linearized reading' (duration: 372.887091ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:46:23.195999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:46:22.827254Z","time spent":"368.738232ms","remote":"127.0.0.1:54364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-09T13:46:23.196013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:46:22.822998Z","time spent":"373.010714ms","remote":"127.0.0.1:54364","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-06-09T13:46:23.706404Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13071027957797028730,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-09T13:46:24.439409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.465565ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-06-09T13:46:24.439615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:46:23.197038Z","time spent":"1.242565341s","remote":"127.0.0.1:54168","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-06-09T13:46:24.439732Z","caller":"traceutil/trace.go:171","msg":"trace[800400510] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:657; }","duration":"1.233437885s","start":"2026-06-09T13:46:23.206277Z","end":"2026-06-09T13:46:24.439715Z","steps":["trace[800400510] 'read index received' (duration: 738.829305ms)","trace[800400510] 'applied index is now lower than readState.Index' (duration: 494.60741ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T13:46:24.439753Z","caller":"traceutil/trace.go:171","msg":"trace[121025766] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"1.240280438s","start":"2026-06-09T13:46:23.199445Z","end":"2026-06-09T13:46:24.439725Z","steps":["trace[121025766] 'process raft request' (duration: 1.240057722s)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:46:24.439823Z","caller":"traceutil/trace.go:171","msg":"trace[1493739075] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"1.239733965s","start":"2026-06-09T13:46:23.200079Z","end":"2026-06-09T13:46:24.439813Z","steps":["trace[1493739075] 'process raft request' (duration: 1.239563211s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:46:24.439923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:46:23.19943Z","time spent":"1.240394881s","remote":"127.0.0.1:54364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T13:46:24.439966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:46:23.20007Z","time spent":"1.239780326s","remote":"127.0.0.1:54364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T13:46:24.44012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.233822764s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T13:46:24.440171Z","caller":"traceutil/trace.go:171","msg":"trace[167697590] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:639; }","duration":"1.233890345s","start":"2026-06-09T13:46:23.206259Z","end":"2026-06-09T13:46:24.440149Z","steps":["trace[167697590] 'agreement among raft nodes before linearized reading' (duration: 1.233741902s)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:46:24.440188Z","caller":"traceutil/trace.go:171","msg":"trace[1438254267] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"1.23909397s","start":"2026-06-09T13:46:23.201083Z","end":"2026-06-09T13:46:24.440177Z","steps":["trace[1438254267] 'process raft request' (duration: 1.238599908s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:46:24.440208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:46:23.206251Z","time spent":"1.233948436s","remote":"127.0.0.1:54338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-06-09T13:46:24.440248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:46:23.201073Z","time spent":"1.239142871s","remote":"127.0.0.1:54364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T13:46:24.440299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.320548ms","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-06-09T13:46:24.44034Z","caller":"traceutil/trace.go:171","msg":"trace[951352371] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:639; }","duration":"937.39353ms","start":"2026-06-09T13:46:23.502935Z","end":"2026-06-09T13:46:24.440328Z","steps":["trace[951352371] 'agreement among raft nodes before linearized reading' (duration: 937.314139ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:46:24.440372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:46:23.502917Z","time spent":"937.448951ms","remote":"127.0.0.1:54364","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-06-09T13:46:24.440491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.291279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T13:46:24.440517Z","caller":"traceutil/trace.go:171","msg":"trace[737851447] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"732.346471ms","start":"2026-06-09T13:46:23.708162Z","end":"2026-06-09T13:46:24.440509Z","steps":["trace[737851447] 'agreement among raft nodes before linearized reading' (duration: 732.30422ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:46:24.440562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:46:23.708141Z","time spent":"732.416203ms","remote":"127.0.0.1:54138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-09T13:46:26.078631Z","caller":"traceutil/trace.go:171","msg":"trace[2141425133] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"114.363313ms","start":"2026-06-09T13:46:25.964246Z","end":"2026-06-09T13:46:26.078609Z","steps":["trace[2141425133] 'process raft request' (duration: 114.2422ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:46:38.770921Z","caller":"traceutil/trace.go:171","msg":"trace[1784580383] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"264.095601ms","start":"2026-06-09T13:46:38.506803Z","end":"2026-06-09T13:46:38.770898Z","steps":["trace[1784580383] 'process raft request' (duration: 263.974198ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:46:38.796213Z","caller":"traceutil/trace.go:171","msg":"trace[1333397955] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:821; }","duration":"198.427872ms","start":"2026-06-09T13:46:38.597762Z","end":"2026-06-09T13:46:38.79619Z","steps":["trace[1333397955] 'read index received' (duration: 173.092047ms)","trace[1333397955] 'applied index is now lower than readState.Index' (duration: 25.334695ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T13:46:38.796483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.713568ms","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-06-09T13:46:38.796545Z","caller":"traceutil/trace.go:171","msg":"trace[185332638] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:799; }","duration":"198.817941ms","start":"2026-06-09T13:46:38.597711Z","end":"2026-06-09T13:46:38.796529Z","steps":["trace[185332638] 'agreement among raft nodes before linearized reading' (duration: 198.609056ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:46:38.796578Z","caller":"traceutil/trace.go:171","msg":"trace[650018488] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"289.703413ms","start":"2026-06-09T13:46:38.506852Z","end":"2026-06-09T13:46:38.796555Z","steps":["trace[650018488] 'process raft request' (duration: 289.096658ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:46:38.796667Z","caller":"traceutil/trace.go:171","msg":"trace[663188196] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"287.473109ms","start":"2026-06-09T13:46:38.509172Z","end":"2026-06-09T13:46:38.796646Z","steps":["trace[663188196] 'process raft request' (duration: 286.956667ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:07.115209Z","caller":"traceutil/trace.go:171","msg":"trace[541945512] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"147.875411ms","start":"2026-06-09T13:47:06.967297Z","end":"2026-06-09T13:47:07.115172Z","steps":["trace[541945512] 'process raft request' (duration: 147.608954ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:07.115496Z","caller":"traceutil/trace.go:171","msg":"trace[144976853] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"147.435119ms","start":"2026-06-09T13:47:06.968036Z","end":"2026-06-09T13:47:07.115471Z","steps":["trace[144976853] 'process raft request' (duration: 147.079811ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:07.115523Z","caller":"traceutil/trace.go:171","msg":"trace[1769151392] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"146.912066ms","start":"2026-06-09T13:47:06.968589Z","end":"2026-06-09T13:47:07.115501Z","steps":["trace[1769151392] 'process raft request' (duration: 146.782423ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:12.988914Z","caller":"traceutil/trace.go:171","msg":"trace[961412687] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"158.818936ms","start":"2026-06-09T13:47:12.830076Z","end":"2026-06-09T13:47:12.988895Z","steps":["trace[961412687] 'process raft request' (duration: 158.682983ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:13.503066Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13071027957797029498,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-09T13:47:13.554549Z","caller":"traceutil/trace.go:171","msg":"trace[460184942] linearizableReadLoop","detail":"{readStateIndex:1052; appliedIndex:1050; }","duration":"551.546035ms","start":"2026-06-09T13:47:13.00297Z","end":"2026-06-09T13:47:13.554516Z","steps":["trace[460184942] 'read index received' (duration: 551.108734ms)","trace[460184942] 'applied index is now lower than readState.Index' (duration: 436.111µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T13:47:13.554708Z","caller":"traceutil/trace.go:171","msg":"trace[1650735003] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"616.470599ms","start":"2026-06-09T13:47:12.938226Z","end":"2026-06-09T13:47:13.554696Z","steps":["trace[1650735003] 'process raft request' (duration: 615.937476ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:13.554882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:47:12.938211Z","time spent":"616.540301ms","remote":"127.0.0.1:54168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T13:47:13.55548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.790481ms","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-06-09T13:47:13.55553Z","caller":"traceutil/trace.go:171","msg":"trace[1343621216] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1016; }","duration":"418.869753ms","start":"2026-06-09T13:47:13.136648Z","end":"2026-06-09T13:47:13.555518Z","steps":["trace[1343621216] 'agreement among raft nodes before linearized reading' (duration: 418.692949ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:13.555565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:47:13.136634Z","time spent":"418.923454ms","remote":"127.0.0.1:54364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-09T13:47:13.555534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.512368ms","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-06-09T13:47:13.555725Z","caller":"traceutil/trace.go:171","msg":"trace[483799901] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"574.495339ms","start":"2026-06-09T13:47:12.981198Z","end":"2026-06-09T13:47:13.555693Z","steps":["trace[483799901] 'process raft request' (duration: 573.255968ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:13.555781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.10364ms","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-06-09T13:47:13.555798Z","caller":"traceutil/trace.go:171","msg":"trace[1996172840] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1016; }","duration":"552.842117ms","start":"2026-06-09T13:47:13.002928Z","end":"2026-06-09T13:47:13.55577Z","steps":["trace[1996172840] 'agreement among raft nodes before linearized reading' (duration: 552.257212ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:13.555815Z","caller":"traceutil/trace.go:171","msg":"trace[291559467] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1016; }","duration":"415.156841ms","start":"2026-06-09T13:47:13.140649Z","end":"2026-06-09T13:47:13.555806Z","steps":["trace[291559467] 'agreement among raft nodes before linearized reading' (duration: 415.068398ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:13.555849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:47:13.140639Z","time spent":"415.200732ms","remote":"127.0.0.1:54364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-09T13:47:13.555865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:47:13.002911Z","time spent":"552.939549ms","remote":"127.0.0.1:54364","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-06-09T13:47:13.555895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:47:12.98118Z","time spent":"574.618121ms","remote":"127.0.0.1:54364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T13:47:13.556033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.73754ms","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-06-09T13:47:13.556069Z","caller":"traceutil/trace.go:171","msg":"trace[178308108] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1016; }","duration":"416.803622ms","start":"2026-06-09T13:47:13.139256Z","end":"2026-06-09T13:47:13.55606Z","steps":["trace[178308108] 'agreement among raft nodes before linearized reading' (duration: 416.71429ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:13.556102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:47:13.139242Z","time spent":"416.854283ms","remote":"127.0.0.1:54364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-09T13:47:13.70629Z","caller":"traceutil/trace.go:171","msg":"trace[1447921628] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"146.173188ms","start":"2026-06-09T13:47:13.560088Z","end":"2026-06-09T13:47:13.706261Z","steps":["trace[1447921628] 'process raft request' (duration: 146.006984ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:13.70714Z","caller":"traceutil/trace.go:171","msg":"trace[1963117248] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"145.86644ms","start":"2026-06-09T13:47:13.561253Z","end":"2026-06-09T13:47:13.70712Z","steps":["trace[1963117248] 'process raft request' (duration: 145.688626ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:13.707142Z","caller":"traceutil/trace.go:171","msg":"trace[1887183601] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"145.8622ms","start":"2026-06-09T13:47:13.561254Z","end":"2026-06-09T13:47:13.707116Z","steps":["trace[1887183601] 'process raft request' (duration: 145.775068ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:13.707195Z","caller":"traceutil/trace.go:171","msg":"trace[1413268303] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"145.47403ms","start":"2026-06-09T13:47:13.561683Z","end":"2026-06-09T13:47:13.707157Z","steps":["trace[1413268303] 'process raft request' (duration: 145.380108ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:34.610999Z","caller":"traceutil/trace.go:171","msg":"trace[2098485030] linearizableReadLoop","detail":"{readStateIndex:1127; appliedIndex:1126; }","duration":"179.517873ms","start":"2026-06-09T13:47:34.431455Z","end":"2026-06-09T13:47:34.610973Z","steps":["trace[2098485030] 'read index received' (duration: 179.279777ms)","trace[2098485030] 'applied index is now lower than readState.Index' (duration: 237.286µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T13:47:34.611037Z","caller":"traceutil/trace.go:171","msg":"trace[1459967000] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"453.640764ms","start":"2026-06-09T13:47:34.157366Z","end":"2026-06-09T13:47:34.611007Z","steps":["trace[1459967000] 'process raft request' (duration: 453.46377ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:34.611129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.654957ms","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-06-09T13:47:34.611166Z","caller":"traceutil/trace.go:171","msg":"trace[1565836341] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1087; }","duration":"179.715079ms","start":"2026-06-09T13:47:34.431438Z","end":"2026-06-09T13:47:34.611153Z","steps":["trace[1565836341] 'agreement among raft nodes before linearized reading' (duration: 179.633517ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:34.611178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:47:34.157354Z","time spent":"453.750106ms","remote":"127.0.0.1:54364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T13:47:35.783001Z","caller":"traceutil/trace.go:171","msg":"trace[1200244094] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"161.073932ms","start":"2026-06-09T13:47:35.621893Z","end":"2026-06-09T13:47:35.782967Z","steps":["trace[1200244094] 'process raft request' (duration: 160.862996ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:36.294408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.443773ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T13:47:36.294514Z","caller":"traceutil/trace.go:171","msg":"trace[1225619580] linearizableReadLoop","detail":"{readStateIndex:1129; appliedIndex:1128; }","duration":"472.551747ms","start":"2026-06-09T13:47:35.821946Z","end":"2026-06-09T13:47:36.294498Z","steps":["trace[1225619580] 'read index received' (duration: 218.95959ms)","trace[1225619580] 'applied index is now lower than readState.Index' (duration: 253.591007ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T13:47:36.294598Z","caller":"traceutil/trace.go:171","msg":"trace[1482329198] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"472.998567ms","start":"2026-06-09T13:47:35.821589Z","end":"2026-06-09T13:47:36.294588Z","steps":["trace[1482329198] 'process raft request' (duration: 219.294438ms)","trace[1482329198] 'compare' (duration: 253.31002ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T13:47:36.294654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:47:35.821561Z","time spent":"473.064079ms","remote":"127.0.0.1:54364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T13:47:36.294855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.538946ms","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-06-09T13:47:36.294939Z","caller":"traceutil/trace.go:171","msg":"trace[687385742] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1089; }","duration":"472.65982ms","start":"2026-06-09T13:47:35.822262Z","end":"2026-06-09T13:47:36.294922Z","steps":["trace[687385742] 'agreement among raft nodes before linearized reading' (duration: 472.427604ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:36.294987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:47:35.82225Z","time spent":"472.729751ms","remote":"127.0.0.1:54364","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-06-09T13:47:36.295026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.069641ms","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-06-09T13:47:36.295125Z","caller":"traceutil/trace.go:171","msg":"trace[1247250521] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1089; }","duration":"473.215314ms","start":"2026-06-09T13:47:35.82189Z","end":"2026-06-09T13:47:36.295105Z","steps":["trace[1247250521] 'agreement among raft nodes before linearized reading' (duration: 473.007308ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:36.295168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:47:35.821861Z","time spent":"473.297476ms","remote":"127.0.0.1:54364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-09T13:47:36.295134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.207224ms","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-06-09T13:47:36.295225Z","caller":"traceutil/trace.go:171","msg":"trace[93670696] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1089; }","duration":"471.349988ms","start":"2026-06-09T13:47:35.823855Z","end":"2026-06-09T13:47:36.295205Z","steps":["trace[93670696] 'agreement among raft nodes before linearized reading' (duration: 471.07251ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:36.295267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:47:35.823848Z","time spent":"471.41069ms","remote":"127.0.0.1:54364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-09T13:47:38.73318Z","caller":"traceutil/trace.go:171","msg":"trace[1166966601] linearizableReadLoop","detail":"{readStateIndex:1140; appliedIndex:1138; }","duration":"107.824469ms","start":"2026-06-09T13:47:38.62532Z","end":"2026-06-09T13:47:38.733145Z","steps":["trace[1166966601] 'read index received' (duration: 40.027452ms)","trace[1166966601] 'applied index is now lower than readState.Index' (duration: 67.795907ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T13:47:38.733396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.055966ms","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-06-09T13:47:38.733435Z","caller":"traceutil/trace.go:171","msg":"trace[54471622] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1099; }","duration":"108.167948ms","start":"2026-06-09T13:47:38.625258Z","end":"2026-06-09T13:47:38.733426Z","steps":["trace[54471622] 'agreement among raft nodes before linearized reading' (duration: 107.989044ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:38.733636Z","caller":"traceutil/trace.go:171","msg":"trace[1029867769] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"302.083021ms","start":"2026-06-09T13:47:38.43149Z","end":"2026-06-09T13:47:38.733573Z","steps":["trace[1029867769] 'process raft request' (duration: 233.809662ms)","trace[1029867769] 'compare' (duration: 67.421008ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T13:47:38.733723Z","caller":"traceutil/trace.go:171","msg":"trace[2011889076] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"301.506657ms","start":"2026-06-09T13:47:38.432188Z","end":"2026-06-09T13:47:38.733695Z","steps":["trace[2011889076] 'process raft request' (duration: 300.911972ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:38.733783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:47:38.431466Z","time spent":"302.243935ms","remote":"127.0.0.1:54364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T13:47:38.733884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:47:38.432174Z","time spent":"301.62552ms","remote":"127.0.0.1:54364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T13:47:39.225308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.16375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T13:47:39.225396Z","caller":"traceutil/trace.go:171","msg":"trace[338788952] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1100; }","duration":"351.327434ms","start":"2026-06-09T13:47:38.874047Z","end":"2026-06-09T13:47:39.225374Z","steps":["trace[338788952] 'count revisions from in-memory index tree' (duration: 351.081427ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:39.22544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:47:38.87403Z","time spent":"351.398565ms","remote":"127.0.0.1:37652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-09T13:47:52.859804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.756558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T13:47:52.859898Z","caller":"traceutil/trace.go:171","msg":"trace[1774267612] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1193; }","duration":"151.894981ms","start":"2026-06-09T13:47:52.707983Z","end":"2026-06-09T13:47:52.859878Z","steps":["trace[1774267612] 'range keys from in-memory index tree' (duration: 151.680826ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:52.860383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.011565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.180\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-09T13:47:52.860464Z","caller":"traceutil/trace.go:171","msg":"trace[433945292] range","detail":"{range_begin:/registry/masterleases/199.19.213.180; range_end:; response_count:1; response_revision:1193; }","duration":"144.109507ms","start":"2026-06-09T13:47:52.716335Z","end":"2026-06-09T13:47:52.860444Z","steps":["trace[433945292] 'range keys from in-memory index tree' (duration: 143.915453ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:52.860648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.06133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T13:47:52.860691Z","caller":"traceutil/trace.go:171","msg":"trace[418562787] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1193; }","duration":"104.112771ms","start":"2026-06-09T13:47:52.756566Z","end":"2026-06-09T13:47:52.860679Z","steps":["trace[418562787] 'count revisions from in-memory index tree' (duration: 104.009819ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:53.9078Z","caller":"traceutil/trace.go:171","msg":"trace[201942565] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"346.955446ms","start":"2026-06-09T13:47:53.560818Z","end":"2026-06-09T13:47:53.907773Z","steps":["trace[201942565] 'process raft request' (duration: 346.759681ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:53.90801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:47:53.560799Z","time spent":"347.084978ms","remote":"127.0.0.1:54364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T13:47:54.433408Z","caller":"traceutil/trace.go:171","msg":"trace[1141442084] linearizableReadLoop","detail":"{readStateIndex:1245; appliedIndex:1243; }","duration":"553.375922ms","start":"2026-06-09T13:47:53.880004Z","end":"2026-06-09T13:47:54.43338Z","steps":["trace[1141442084] 'read index received' (duration: 27.651568ms)","trace[1141442084] 'applied index is now lower than readState.Index' (duration: 525.722684ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T13:47:54.433571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.530857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T13:47:54.433622Z","caller":"traceutil/trace.go:171","msg":"trace[178196966] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"563.737021ms","start":"2026-06-09T13:47:53.869845Z","end":"2026-06-09T13:47:54.433582Z","steps":["trace[178196966] 'process raft request' (duration: 562.235214ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:47:54.433635Z","caller":"traceutil/trace.go:171","msg":"trace[1596232257] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1199; }","duration":"553.618478ms","start":"2026-06-09T13:47:53.879978Z","end":"2026-06-09T13:47:54.433596Z","steps":["trace[1596232257] 'agreement among raft nodes before linearized reading' (duration: 553.503925ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:47:54.433706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:47:53.879971Z","time spent":"553.721751ms","remote":"127.0.0.1:37758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-06-09T13:47:54.433738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:47:53.869827Z","time spent":"563.851043ms","remote":"127.0.0.1:54364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T13:48:11.001283Z","caller":"traceutil/trace.go:171","msg":"trace[1282722421] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"265.560339ms","start":"2026-06-09T13:48:10.7357Z","end":"2026-06-09T13:48:11.00126Z","steps":["trace[1282722421] 'process raft request' (duration: 265.416995ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:48:11.203898Z","caller":"traceutil/trace.go:171","msg":"trace[1123279056] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"182.03085ms","start":"2026-06-09T13:48:11.021843Z","end":"2026-06-09T13:48:11.203874Z","steps":["trace[1123279056] 'process raft request' (duration: 117.807152ms)","trace[1123279056] 'compare' (duration: 63.88835ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T13:48:11.204201Z","caller":"traceutil/trace.go:171","msg":"trace[846091280] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"181.507488ms","start":"2026-06-09T13:48:11.022667Z","end":"2026-06-09T13:48:11.204175Z","steps":["trace[846091280] 'process raft request' (duration: 181.374404ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:48:11.204312Z","caller":"traceutil/trace.go:171","msg":"trace[886875441] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"179.789455ms","start":"2026-06-09T13:48:11.024512Z","end":"2026-06-09T13:48:11.204301Z","steps":["trace[886875441] 'process raft request' (duration: 179.60405ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:48:12.064383Z","caller":"traceutil/trace.go:171","msg":"trace[1232627617] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"189.762073ms","start":"2026-06-09T13:48:11.874591Z","end":"2026-06-09T13:48:12.064353Z","steps":["trace[1232627617] 'process raft request' (duration: 186.233115ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:48:12.316593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.133317ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-09T13:48:12.316923Z","caller":"traceutil/trace.go:171","msg":"trace[1244236908] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"246.816073ms","start":"2026-06-09T13:48:12.070086Z","end":"2026-06-09T13:48:12.316903Z","steps":["trace[1244236908] 'process raft request' (duration: 96.321317ms)","trace[1244236908] 'compare' (duration: 150.014064ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T13:48:12.646507Z","caller":"traceutil/trace.go:171","msg":"trace[1504780242] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"570.037058ms","start":"2026-06-09T13:48:12.076443Z","end":"2026-06-09T13:48:12.64648Z","steps":["trace[1504780242] 'process raft request' (duration: 569.911715ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:48:12.646667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:48:12.076432Z","time spent":"570.157821ms","remote":"127.0.0.1:54364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T13:48:12.646737Z","caller":"traceutil/trace.go:171","msg":"trace[128275718] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"571.314579ms","start":"2026-06-09T13:48:12.075385Z","end":"2026-06-09T13:48:12.646699Z","steps":["trace[128275718] 'process raft request' (duration: 570.840147ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:48:12.646872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:48:12.075367Z","time spent":"571.447633ms","remote":"127.0.0.1:54218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T13:48:12.672581Z","caller":"traceutil/trace.go:171","msg":"trace[992839292] linearizableReadLoop","detail":"{readStateIndex:1333; appliedIndex:1332; }","duration":"156.319511ms","start":"2026-06-09T13:48:12.516238Z","end":"2026-06-09T13:48:12.672557Z","steps":["trace[992839292] 'read index received' (duration: 130.432807ms)","trace[992839292] 'applied index is now lower than readState.Index' (duration: 25.885754ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T13:48:12.672685Z","caller":"traceutil/trace.go:171","msg":"trace[1092972169] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"168.150915ms","start":"2026-06-09T13:48:12.504523Z","end":"2026-06-09T13:48:12.672674Z","steps":["trace[1092972169] 'process raft request' (duration: 167.902949ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:48:12.672895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.640199ms","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-06-09T13:48:12.672926Z","caller":"traceutil/trace.go:171","msg":"trace[96179057] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1284; }","duration":"156.720631ms","start":"2026-06-09T13:48:12.516193Z","end":"2026-06-09T13:48:12.672914Z","steps":["trace[96179057] 'agreement among raft nodes before linearized reading' (duration: 156.453394ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:48:13.173922Z","caller":"traceutil/trace.go:171","msg":"trace[176956173] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"166.909745ms","start":"2026-06-09T13:48:13.006982Z","end":"2026-06-09T13:48:13.173892Z","steps":["trace[176956173] 'process raft request' (duration: 166.733051ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:48:13.454475Z","caller":"traceutil/trace.go:171","msg":"trace[737331866] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"243.266975ms","start":"2026-06-09T13:48:13.211176Z","end":"2026-06-09T13:48:13.454443Z","steps":["trace[737331866] 'process raft request' (duration: 182.298288ms)","trace[737331866] 'compare' (duration: 60.845204ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T13:48:13.487995Z","caller":"traceutil/trace.go:171","msg":"trace[1471970703] linearizableReadLoop","detail":"{readStateIndex:1340; appliedIndex:1337; }","duration":"190.421479ms","start":"2026-06-09T13:48:13.297549Z","end":"2026-06-09T13:48:13.487971Z","steps":["trace[1471970703] 'read index received' (duration: 95.951577ms)","trace[1471970703] 'applied index is now lower than readState.Index' (duration: 94.468812ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T13:48:13.488049Z","caller":"traceutil/trace.go:171","msg":"trace[579371551] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"276.494012ms","start":"2026-06-09T13:48:13.211523Z","end":"2026-06-09T13:48:13.488017Z","steps":["trace[579371551] 'process raft request' (duration: 276.236526ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:48:13.488159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.596694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T13:48:13.488214Z","caller":"traceutil/trace.go:171","msg":"trace[1247486327] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1290; }","duration":"190.695306ms","start":"2026-06-09T13:48:13.297503Z","end":"2026-06-09T13:48:13.488198Z","steps":["trace[1247486327] 'agreement among raft nodes before linearized reading' (duration: 190.571133ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:48:13.488379Z","caller":"traceutil/trace.go:171","msg":"trace[582826252] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"276.680505ms","start":"2026-06-09T13:48:13.21167Z","end":"2026-06-09T13:48:13.48835Z","steps":["trace[582826252] 'process raft request' (duration: 276.244845ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:48:22.990697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.461342ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T13:48:22.990804Z","caller":"traceutil/trace.go:171","msg":"trace[96382197] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1357; }","duration":"211.573415ms","start":"2026-06-09T13:48:22.779212Z","end":"2026-06-09T13:48:22.990786Z","steps":["trace[96382197] 'range keys from in-memory index tree' (duration: 211.446702ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:48:23.099186Z","caller":"traceutil/trace.go:171","msg":"trace[1575435912] linearizableReadLoop","detail":"{readStateIndex:1409; appliedIndex:1408; }","duration":"382.301235ms","start":"2026-06-09T13:48:22.716864Z","end":"2026-06-09T13:48:23.099165Z","steps":["trace[1575435912] 'read index received' (duration: 382.210342ms)","trace[1575435912] 'applied index is now lower than readState.Index' (duration: 90.003µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T13:48:23.09924Z","caller":"traceutil/trace.go:171","msg":"trace[1039370084] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"398.5966ms","start":"2026-06-09T13:48:22.700614Z","end":"2026-06-09T13:48:23.09921Z","steps":["trace[1039370084] 'process raft request' (duration: 398.416935ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:48:23.09941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:48:22.700596Z","time spent":"398.722063ms","remote":"127.0.0.1:54364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T13:48:23.09942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.53992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.180\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-09T13:48:23.099469Z","caller":"traceutil/trace.go:171","msg":"trace[70730960] range","detail":"{range_begin:/registry/masterleases/199.19.213.180; range_end:; response_count:1; response_revision:1358; }","duration":"382.632773ms","start":"2026-06-09T13:48:22.716822Z","end":"2026-06-09T13:48:23.099454Z","steps":["trace[70730960] 'agreement among raft nodes before linearized reading' (duration: 382.480639ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:48:23.099504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:48:22.716804Z","time spent":"382.691774ms","remote":"127.0.0.1:54168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.180\" "} {"level":"warn","ts":"2026-06-09T13:48:23.099531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.221109ms","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-06-09T13:48:23.099617Z","caller":"traceutil/trace.go:171","msg":"trace[556450119] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1358; }","duration":"330.363072ms","start":"2026-06-09T13:48:22.769236Z","end":"2026-06-09T13:48:23.099599Z","steps":["trace[556450119] 'agreement among raft nodes before linearized reading' (duration: 330.121656ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:48:23.099674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:48:22.769212Z","time spent":"330.448105ms","remote":"127.0.0.1:54364","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-06-09T13:48:23.706994Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13071027957797030282,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-09T13:48:23.947648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.580267ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T13:48:23.94842Z","caller":"traceutil/trace.go:171","msg":"trace[1808212152] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"844.216381ms","start":"2026-06-09T13:48:23.10418Z","end":"2026-06-09T13:48:23.948396Z","steps":["trace[1808212152] 'process raft request' (duration: 721.782223ms)","trace[1808212152] 'compare' (duration: 120.805058ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T13:48:23.948516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:48:23.10416Z","time spent":"844.307494ms","remote":"127.0.0.1:54364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T13:48:23.985898Z","caller":"traceutil/trace.go:171","msg":"trace[2127104257] linearizableReadLoop","detail":"{readStateIndex:1412; appliedIndex:1410; }","duration":"779.020159ms","start":"2026-06-09T13:48:23.206858Z","end":"2026-06-09T13:48:23.985878Z","steps":["trace[2127104257] 'read index received' (duration: 619.056527ms)","trace[2127104257] 'applied index is now lower than readState.Index' (duration: 159.962422ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T13:48:23.985996Z","caller":"traceutil/trace.go:171","msg":"trace[379348793] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"822.133392ms","start":"2026-06-09T13:48:23.163854Z","end":"2026-06-09T13:48:23.985988Z","steps":["trace[379348793] 'process raft request' (duration: 821.913607ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:48:23.986112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:48:23.163828Z","time spent":"822.187424ms","remote":"127.0.0.1:54168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T13:48:23.986413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.559352ms","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-06-09T13:48:23.986441Z","caller":"traceutil/trace.go:171","msg":"trace[1199191206] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1360; }","duration":"779.623354ms","start":"2026-06-09T13:48:23.20681Z","end":"2026-06-09T13:48:23.986433Z","steps":["trace[1199191206] 'agreement among raft nodes before linearized reading' (duration: 779.489001ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:48:23.986487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:48:23.20679Z","time spent":"779.689926ms","remote":"127.0.0.1:54364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-09T13:48:23.986676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.152117ms","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-06-09T13:48:23.986699Z","caller":"traceutil/trace.go:171","msg":"trace[849859656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1360; }","duration":"465.206518ms","start":"2026-06-09T13:48:23.521486Z","end":"2026-06-09T13:48:23.986693Z","steps":["trace[849859656] 'agreement among raft nodes before linearized reading' (duration: 465.121946ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:48:23.986739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:48:23.521468Z","time spent":"465.246379ms","remote":"127.0.0.1:54364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-09T13:48:23.986952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.729127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T13:48:23.986977Z","caller":"traceutil/trace.go:171","msg":"trace[446703212] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1360; }","duration":"280.796028ms","start":"2026-06-09T13:48:23.706174Z","end":"2026-06-09T13:48:23.98697Z","steps":["trace[446703212] 'agreement among raft nodes before linearized reading' (duration: 280.754267ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:48:23.987219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.826294ms","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-06-09T13:48:23.987268Z","caller":"traceutil/trace.go:171","msg":"trace[740888712] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1360; }","duration":"463.893386ms","start":"2026-06-09T13:48:23.523348Z","end":"2026-06-09T13:48:23.987241Z","steps":["trace[740888712] 'agreement among raft nodes before linearized reading' (duration: 463.815584ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:48:23.987296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:48:23.523327Z","time spent":"463.963558ms","remote":"127.0.0.1:54364","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-06-09T13:48:23.987478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.023921ms","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-06-09T13:48:23.987939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.134067ms","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-06-09T13:48:23.98802Z","caller":"traceutil/trace.go:171","msg":"trace[1617931744] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1360; }","duration":"463.290941ms","start":"2026-06-09T13:48:23.52471Z","end":"2026-06-09T13:48:23.988001Z","steps":["trace[1617931744] 'agreement among raft nodes before linearized reading' (duration: 463.023024ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:48:23.988072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:48:23.524679Z","time spent":"463.382853ms","remote":"127.0.0.1:54364","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-06-09T13:48:23.987504Z","caller":"traceutil/trace.go:171","msg":"trace[1921275785] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1360; }","duration":"307.094532ms","start":"2026-06-09T13:48:23.680403Z","end":"2026-06-09T13:48:23.987497Z","steps":["trace[1921275785] 'agreement among raft nodes before linearized reading' (duration: 307.029021ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:48:23.989324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:48:23.680368Z","time spent":"308.518268ms","remote":"127.0.0.1:54364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-06-09T13:49:05.488359Z","caller":"traceutil/trace.go:171","msg":"trace[368622053] transaction","detail":"{read_only:false; response_revision:1553; number_of_response:1; }","duration":"172.949454ms","start":"2026-06-09T13:49:05.315376Z","end":"2026-06-09T13:49:05.488325Z","steps":["trace[368622053] 'process raft request' (duration: 139.698729ms)","trace[368622053] 'compare' (duration: 33.129072ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T13:50:06.683378Z","caller":"traceutil/trace.go:171","msg":"trace[1372030786] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"372.964567ms","start":"2026-06-09T13:50:06.310391Z","end":"2026-06-09T13:50:06.683356Z","steps":["trace[1372030786] 'process raft request' (duration: 372.777992ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:50:06.68353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T13:50:06.310379Z","time spent":"373.075209ms","remote":"127.0.0.1:54364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T13:50:06.685572Z","caller":"traceutil/trace.go:171","msg":"trace[60927275] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"256.509933ms","start":"2026-06-09T13:50:06.429042Z","end":"2026-06-09T13:50:06.685552Z","steps":["trace[60927275] 'process raft request' (duration: 256.150834ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:51:53.343131Z","caller":"traceutil/trace.go:171","msg":"trace[816801972] linearizableReadLoop","detail":"{readStateIndex:2414; appliedIndex:2413; }","duration":"114.467239ms","start":"2026-06-09T13:51:53.228608Z","end":"2026-06-09T13:51:53.343076Z","steps":["trace[816801972] 'read index received' (duration: 114.343696ms)","trace[816801972] 'applied index is now lower than readState.Index' (duration: 122.533µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T13:51:53.343305Z","caller":"traceutil/trace.go:171","msg":"trace[1026140210] transaction","detail":"{read_only:false; response_revision:2320; number_of_response:1; }","duration":"189.875909ms","start":"2026-06-09T13:51:53.153379Z","end":"2026-06-09T13:51:53.343255Z","steps":["trace[1026140210] 'process raft request' (duration: 189.551881ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:51:53.343461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.883233ms","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":"info","ts":"2026-06-09T13:51:53.343525Z","caller":"traceutil/trace.go:171","msg":"trace[806707511] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2320; }","duration":"105.994356ms","start":"2026-06-09T13:51:53.237517Z","end":"2026-06-09T13:51:53.343511Z","steps":["trace[806707511] 'agreement among raft nodes before linearized reading' (duration: 105.836152ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:51:53.343599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.297869ms","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-06-09T13:51:53.343655Z","caller":"traceutil/trace.go:171","msg":"trace[1213276067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2320; }","duration":"107.388281ms","start":"2026-06-09T13:51:53.236251Z","end":"2026-06-09T13:51:53.343639Z","steps":["trace[1213276067] 'agreement among raft nodes before linearized reading' (duration: 107.265888ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:51:53.343648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.654943ms","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-06-09T13:51:53.343769Z","caller":"traceutil/trace.go:171","msg":"trace[1795032089] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2320; }","duration":"106.757616ms","start":"2026-06-09T13:51:53.236966Z","end":"2026-06-09T13:51:53.343724Z","steps":["trace[1795032089] 'agreement among raft nodes before linearized reading' (duration: 106.405697ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:51:53.344282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.746025ms","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-06-09T13:51:53.344329Z","caller":"traceutil/trace.go:171","msg":"trace[988305893] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2320; }","duration":"115.768752ms","start":"2026-06-09T13:51:53.228547Z","end":"2026-06-09T13:51:53.344316Z","steps":["trace[988305893] 'agreement among raft nodes before linearized reading' (duration: 114.704535ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:51:53.344613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.903849ms","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-06-09T13:51:53.344732Z","caller":"traceutil/trace.go:171","msg":"trace[1524959160] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2320; }","duration":"105.037243ms","start":"2026-06-09T13:51:53.239675Z","end":"2026-06-09T13:51:53.344713Z","steps":["trace[1524959160] 'agreement among raft nodes before linearized reading' (duration: 103.670899ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:52:12.831703Z","caller":"traceutil/trace.go:171","msg":"trace[252366198] linearizableReadLoop","detail":"{readStateIndex:2534; appliedIndex:2533; }","duration":"126.205996ms","start":"2026-06-09T13:52:12.705476Z","end":"2026-06-09T13:52:12.831682Z","steps":["trace[252366198] 'read index received' (duration: 106.859616ms)","trace[252366198] 'applied index is now lower than readState.Index' (duration: 19.34533ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T13:52:12.831823Z","caller":"traceutil/trace.go:171","msg":"trace[854983284] transaction","detail":"{read_only:false; response_revision:2436; number_of_response:1; }","duration":"217.611869ms","start":"2026-06-09T13:52:12.614197Z","end":"2026-06-09T13:52:12.831808Z","steps":["trace[854983284] 'process raft request' (duration: 198.170217ms)","trace[854983284] 'compare' (duration: 18.614852ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T13:52:12.832123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.514664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.180\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-09T13:52:12.8322Z","caller":"traceutil/trace.go:171","msg":"trace[404633986] range","detail":"{range_begin:/registry/masterleases/199.19.213.180; range_end:; response_count:1; response_revision:2436; }","duration":"101.623086ms","start":"2026-06-09T13:52:12.730557Z","end":"2026-06-09T13:52:12.83218Z","steps":["trace[404633986] 'agreement among raft nodes before linearized reading' (duration: 101.440061ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:52:12.832223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.719488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T13:52:12.83233Z","caller":"traceutil/trace.go:171","msg":"trace[1827161850] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2436; }","duration":"126.872811ms","start":"2026-06-09T13:52:12.705436Z","end":"2026-06-09T13:52:12.832309Z","steps":["trace[1827161850] 'agreement among raft nodes before linearized reading' (duration: 126.696817ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:52:13.620699Z","caller":"traceutil/trace.go:171","msg":"trace[1582434301] linearizableReadLoop","detail":"{readStateIndex:2538; appliedIndex:2537; }","duration":"185.013852ms","start":"2026-06-09T13:52:13.435662Z","end":"2026-06-09T13:52:13.620676Z","steps":["trace[1582434301] 'read index received' (duration: 184.835187ms)","trace[1582434301] 'applied index is now lower than readState.Index' (duration: 177.134µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T13:52:13.620753Z","caller":"traceutil/trace.go:171","msg":"trace[2140377961] transaction","detail":"{read_only:false; response_revision:2439; number_of_response:1; }","duration":"221.31411ms","start":"2026-06-09T13:52:13.399412Z","end":"2026-06-09T13:52:13.620726Z","steps":["trace[2140377961] 'process raft request' (duration: 221.099094ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:52:13.620926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.253157ms","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-06-09T13:52:13.620968Z","caller":"traceutil/trace.go:171","msg":"trace[1013287431] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2439; }","duration":"185.336319ms","start":"2026-06-09T13:52:13.435622Z","end":"2026-06-09T13:52:13.620958Z","steps":["trace[1013287431] 'agreement among raft nodes before linearized reading' (duration: 185.183236ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:52:13.621125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.786545ms","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-06-09T13:52:13.621163Z","caller":"traceutil/trace.go:171","msg":"trace[81847843] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2439; }","duration":"127.838216ms","start":"2026-06-09T13:52:13.493314Z","end":"2026-06-09T13:52:13.621153Z","steps":["trace[81847843] 'agreement among raft nodes before linearized reading' (duration: 127.686132ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:52:13.621192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.248325ms","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-06-09T13:52:13.621243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.917498ms","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-06-09T13:52:13.621273Z","caller":"traceutil/trace.go:171","msg":"trace[250748931] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2439; }","duration":"130.377489ms","start":"2026-06-09T13:52:13.490873Z","end":"2026-06-09T13:52:13.621251Z","steps":["trace[250748931] 'agreement among raft nodes before linearized reading' (duration: 130.123412ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T13:52:13.621324Z","caller":"traceutil/trace.go:171","msg":"trace[851871791] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2439; }","duration":"128.0187ms","start":"2026-06-09T13:52:13.493285Z","end":"2026-06-09T13:52:13.621304Z","steps":["trace[851871791] 'agreement among raft nodes before linearized reading' (duration: 127.800315ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:52:13.621344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.41303ms","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-06-09T13:52:13.621378Z","caller":"traceutil/trace.go:171","msg":"trace[1334995558] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2439; }","duration":"128.485311ms","start":"2026-06-09T13:52:13.492884Z","end":"2026-06-09T13:52:13.621369Z","steps":["trace[1334995558] 'agreement among raft nodes before linearized reading' (duration: 128.396299ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T13:52:13.621418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.443096ms","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-06-09T13:52:13.621452Z","caller":"traceutil/trace.go:171","msg":"trace[1601816376] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2439; }","duration":"127.494477ms","start":"2026-06-09T13:52:13.493948Z","end":"2026-06-09T13:52:13.621443Z","steps":["trace[1601816376] 'agreement among raft nodes before linearized reading' (duration: 127.409215ms)"],"step_count":1}