{"level":"warn","ts":"2026-06-09T15:06:16.40146Z","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-09T15:06:16.401543Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.78:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.78:2380","--initial-cluster=instance=https://199.204.45.78:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.78:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.78: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-09T15:06:16.401608Z","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-09T15:06:16.401621Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.78:2380"]} {"level":"info","ts":"2026-06-09T15:06:16.401642Z","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-09T15:06:16.402357Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"]} {"level":"info","ts":"2026-06-09T15:06:16.402454Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.78:2380"],"listen-peer-urls":["https://199.204.45.78:2380"],"advertise-client-urls":["https://199.204.45.78:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.78: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-09T15:06:16.406316Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.615785ms"} {"level":"info","ts":"2026-06-09T15:06:16.412399Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6eb8766b1ec86e92","cluster-id":"9bbfe9e5d45d3718"} {"level":"info","ts":"2026-06-09T15:06:16.412602Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=()"} {"level":"info","ts":"2026-06-09T15:06:16.412657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became follower at term 0"} {"level":"info","ts":"2026-06-09T15:06:16.412698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6eb8766b1ec86e92 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-09T15:06:16.412718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became follower at term 1"} {"level":"info","ts":"2026-06-09T15:06:16.412769Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=(7978256942336863890)"} {"level":"warn","ts":"2026-06-09T15:06:16.419456Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-09T15:06:16.42082Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-09T15:06:16.422249Z","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-09T15:06:16.424644Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6eb8766b1ec86e92","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-09T15:06:16.426125Z","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-09T15:06:16.427453Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6eb8766b1ec86e92","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-09T15:06:16.42753Z","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-09T15:06:16.427549Z","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-09T15:06:16.428059Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=(7978256942336863890)"} {"level":"info","ts":"2026-06-09T15:06:16.428456Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9bbfe9e5d45d3718","local-member-id":"6eb8766b1ec86e92","added-peer-id":"6eb8766b1ec86e92","added-peer-peer-urls":["https://199.204.45.78:2380"]} {"level":"info","ts":"2026-06-09T15:06:16.430767Z","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-09T15:06:16.430931Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.78:2380"} {"level":"info","ts":"2026-06-09T15:06:16.430967Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.78:2380"} {"level":"info","ts":"2026-06-09T15:06:16.431161Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6eb8766b1ec86e92","initial-advertise-peer-urls":["https://199.204.45.78:2380"],"listen-peer-urls":["https://199.204.45.78:2380"],"advertise-client-urls":["https://199.204.45.78:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-09T15:06:16.431216Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-09T15:06:16.513856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 is starting a new election at term 1"} {"level":"info","ts":"2026-06-09T15:06:16.514039Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-09T15:06:16.514128Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 received MsgPreVoteResp from 6eb8766b1ec86e92 at term 1"} {"level":"info","ts":"2026-06-09T15:06:16.514198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became candidate at term 2"} {"level":"info","ts":"2026-06-09T15:06:16.514244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 received MsgVoteResp from 6eb8766b1ec86e92 at term 2"} {"level":"info","ts":"2026-06-09T15:06:16.514289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became leader at term 2"} {"level":"info","ts":"2026-06-09T15:06:16.514379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6eb8766b1ec86e92 elected leader 6eb8766b1ec86e92 at term 2"} {"level":"info","ts":"2026-06-09T15:06:16.515842Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6eb8766b1ec86e92","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.78:2379]}","request-path":"/0/members/6eb8766b1ec86e92/attributes","cluster-id":"9bbfe9e5d45d3718","publish-timeout":"7s"} {"level":"info","ts":"2026-06-09T15:06:16.515948Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-09T15:06:16.515879Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-09T15:06:16.516088Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T15:06:16.516853Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-09T15:06:16.516892Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-09T15:06:16.518092Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-09T15:06:16.518734Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9bbfe9e5d45d3718","local-member-id":"6eb8766b1ec86e92","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T15:06:16.518981Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T15:06:16.519139Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T15:06:16.521651Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.78:2379"} {"level":"warn","ts":"2026-06-09T15:06:20.15814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.469925ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-09T15:06:20.158259Z","caller":"traceutil/trace.go:171","msg":"trace[1822315212] transaction","detail":"{read_only:false; response_revision:144; number_of_response:1; }","duration":"456.999345ms","start":"2026-06-09T15:06:19.701242Z","end":"2026-06-09T15:06:20.158241Z","steps":["trace[1822315212] 'process raft request' (duration: 97.892065ms)","trace[1822315212] 'compare' (duration: 358.336522ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:06:20.15832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:06:19.701224Z","time spent":"457.074448ms","remote":"127.0.0.1:60220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":694,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-09T15:06:20.634909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.517223ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-09T15:06:20.635037Z","caller":"traceutil/trace.go:171","msg":"trace[1208130217] linearizableReadLoop","detail":"{readStateIndex:149; appliedIndex:148; }","duration":"160.948644ms","start":"2026-06-09T15:06:20.474067Z","end":"2026-06-09T15:06:20.635016Z","steps":["trace[1208130217] 'read index received' (duration: 46.291µs)","trace[1208130217] 'applied index is now lower than readState.Index' (duration: 160.900793ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:06:20.635138Z","caller":"traceutil/trace.go:171","msg":"trace[11596301] transaction","detail":"{read_only:false; response_revision:145; number_of_response:1; }","duration":"471.937366ms","start":"2026-06-09T15:06:20.163173Z","end":"2026-06-09T15:06:20.63511Z","steps":["trace[11596301] 'process raft request' (duration: 195.142526ms)","trace[11596301] 'compare' (duration: 276.37173ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:06:20.635256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.153868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T15:06:20.635278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:06:20.163152Z","time spent":"472.049138ms","remote":"127.0.0.1:60220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":700,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T15:06:20.63533Z","caller":"traceutil/trace.go:171","msg":"trace[519562756] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:145; }","duration":"161.303083ms","start":"2026-06-09T15:06:20.474008Z","end":"2026-06-09T15:06:20.635312Z","steps":["trace[519562756] 'agreement among raft nodes before linearized reading' (duration: 161.113228ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:06:20.635349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.320533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:06:20.63545Z","caller":"traceutil/trace.go:171","msg":"trace[1142621642] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:145; }","duration":"158.473416ms","start":"2026-06-09T15:06:20.476959Z","end":"2026-06-09T15:06:20.635433Z","steps":["trace[1142621642] 'agreement among raft nodes before linearized reading' (duration: 158.18081ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:06:20.839164Z","caller":"traceutil/trace.go:171","msg":"trace[477273717] transaction","detail":"{read_only:false; response_revision:146; number_of_response:1; }","duration":"200.479311ms","start":"2026-06-09T15:06:20.63863Z","end":"2026-06-09T15:06:20.83911Z","steps":["trace[477273717] 'process raft request' (duration: 199.422066ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:06:20.846553Z","caller":"traceutil/trace.go:171","msg":"trace[2095449026] transaction","detail":"{read_only:false; response_revision:147; number_of_response:1; }","duration":"206.091322ms","start":"2026-06-09T15:06:20.640442Z","end":"2026-06-09T15:06:20.846534Z","steps":["trace[2095449026] 'process raft request' (duration: 205.847096ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:06:22.034939Z","caller":"traceutil/trace.go:171","msg":"trace[1024419526] linearizableReadLoop","detail":"{readStateIndex:214; appliedIndex:212; }","duration":"171.976612ms","start":"2026-06-09T15:06:21.862933Z","end":"2026-06-09T15:06:22.034909Z","steps":["trace[1024419526] 'read index received' (duration: 26.01919ms)","trace[1024419526] 'applied index is now lower than readState.Index' (duration: 145.956422ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:06:22.035097Z","caller":"traceutil/trace.go:171","msg":"trace[1161854449] transaction","detail":"{read_only:false; response_revision:209; number_of_response:1; }","duration":"188.397918ms","start":"2026-06-09T15:06:21.846656Z","end":"2026-06-09T15:06:22.035053Z","steps":["trace[1161854449] 'process raft request' (duration: 117.111396ms)","trace[1161854449] 'compare' (duration: 70.965624ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:06:22.035209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.840935ms","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-09T15:06:22.0353Z","caller":"traceutil/trace.go:171","msg":"trace[1893500126] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:209; }","duration":"142.01304ms","start":"2026-06-09T15:06:21.893274Z","end":"2026-06-09T15:06:22.035287Z","steps":["trace[1893500126] 'agreement among raft nodes before linearized reading' (duration: 141.805555ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:06:22.035515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.544366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:06:22.035696Z","caller":"traceutil/trace.go:171","msg":"trace[1268166380] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:209; }","duration":"172.73365ms","start":"2026-06-09T15:06:21.862879Z","end":"2026-06-09T15:06:22.035612Z","steps":["trace[1268166380] 'agreement among raft nodes before linearized reading' (duration: 172.193567ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:06:22.208702Z","caller":"traceutil/trace.go:171","msg":"trace[1346330352] transaction","detail":"{read_only:false; response_revision:212; number_of_response:1; }","duration":"167.432386ms","start":"2026-06-09T15:06:22.041256Z","end":"2026-06-09T15:06:22.208689Z","steps":["trace[1346330352] 'process raft request' (duration: 167.385315ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:06:22.208788Z","caller":"traceutil/trace.go:171","msg":"trace[193054303] transaction","detail":"{read_only:false; response_revision:211; number_of_response:1; }","duration":"168.097952ms","start":"2026-06-09T15:06:22.040677Z","end":"2026-06-09T15:06:22.208775Z","steps":["trace[193054303] 'process raft request' (duration: 167.916748ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:06:22.208702Z","caller":"traceutil/trace.go:171","msg":"trace[1102914668] transaction","detail":"{read_only:false; response_revision:210; number_of_response:1; }","duration":"168.939261ms","start":"2026-06-09T15:06:22.039715Z","end":"2026-06-09T15:06:22.208654Z","steps":["trace[1102914668] 'process raft request' (duration: 141.536359ms)","trace[1102914668] 'compare' (duration: 27.059614ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:06:22.421673Z","caller":"traceutil/trace.go:171","msg":"trace[1417089615] transaction","detail":"{read_only:false; response_revision:213; number_of_response:1; }","duration":"208.855627ms","start":"2026-06-09T15:06:22.212778Z","end":"2026-06-09T15:06:22.421633Z","steps":["trace[1417089615] 'process raft request' (duration: 136.773397ms)","trace[1417089615] 'compare' (duration: 71.901885ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:06:22.421734Z","caller":"traceutil/trace.go:171","msg":"trace[910065825] linearizableReadLoop","detail":"{readStateIndex:218; appliedIndex:217; }","duration":"208.674822ms","start":"2026-06-09T15:06:22.213034Z","end":"2026-06-09T15:06:22.421708Z","steps":["trace[910065825] 'read index received' (duration: 136.628693ms)","trace[910065825] 'applied index is now lower than readState.Index' (duration: 72.044539ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:06:22.421899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.846496ms","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-09T15:06:22.421941Z","caller":"traceutil/trace.go:171","msg":"trace[676209766] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:213; }","duration":"208.927909ms","start":"2026-06-09T15:06:22.213002Z","end":"2026-06-09T15:06:22.42193Z","steps":["trace[676209766] 'agreement among raft nodes before linearized reading' (duration: 208.835676ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:06:22.422057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.878497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:06:22.422097Z","caller":"traceutil/trace.go:171","msg":"trace[1653118116] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:213; }","duration":"208.945359ms","start":"2026-06-09T15:06:22.213139Z","end":"2026-06-09T15:06:22.422084Z","steps":["trace[1653118116] 'agreement among raft nodes before linearized reading' (duration: 208.869977ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:06:54.822439Z","caller":"traceutil/trace.go:171","msg":"trace[341913851] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"504.322975ms","start":"2026-06-09T15:06:54.318092Z","end":"2026-06-09T15:06:54.822415Z","steps":["trace[341913851] 'process raft request' (duration: 504.223333ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:06:54.822589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:06:54.318067Z","time spent":"504.466979ms","remote":"127.0.0.1:59916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T15:06:54.832945Z","caller":"traceutil/trace.go:171","msg":"trace[252046634] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"509.553339ms","start":"2026-06-09T15:06:54.323374Z","end":"2026-06-09T15:06:54.832928Z","steps":["trace[252046634] 'process raft request' (duration: 509.366775ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:06:54.832974Z","caller":"traceutil/trace.go:171","msg":"trace[733663218] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"509.524869ms","start":"2026-06-09T15:06:54.32343Z","end":"2026-06-09T15:06:54.832955Z","steps":["trace[733663218] 'process raft request' (duration: 509.428537ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:06:54.833031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:06:54.323352Z","time spent":"509.639412ms","remote":"127.0.0.1:59916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-09T15:06:54.833043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:06:54.323426Z","time spent":"509.58398ms","remote":"127.0.0.1:59916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T15:06:54.833122Z","caller":"traceutil/trace.go:171","msg":"trace[1452796203] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"500.041762ms","start":"2026-06-09T15:06:54.333073Z","end":"2026-06-09T15:06:54.833114Z","steps":["trace[1452796203] 'process raft request' (duration: 499.877888ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:06:54.833127Z","caller":"traceutil/trace.go:171","msg":"trace[1543010729] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"501.812344ms","start":"2026-06-09T15:06:54.331308Z","end":"2026-06-09T15:06:54.83312Z","steps":["trace[1543010729] 'process raft request' (duration: 501.602629ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:06:54.833154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:06:54.331291Z","time spent":"501.851634ms","remote":"127.0.0.1:59916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-09T15:06:54.833156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:06:54.333054Z","time spent":"500.086212ms","remote":"127.0.0.1:59916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T15:06:54.833195Z","caller":"traceutil/trace.go:171","msg":"trace[416884978] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"499.844037ms","start":"2026-06-09T15:06:54.333346Z","end":"2026-06-09T15:06:54.833191Z","steps":["trace[416884978] 'process raft request' (duration: 499.682083ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:06:54.833209Z","caller":"traceutil/trace.go:171","msg":"trace[1331684665] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"500.056662ms","start":"2026-06-09T15:06:54.333146Z","end":"2026-06-09T15:06:54.833203Z","steps":["trace[1331684665] 'process raft request' (duration: 499.841877ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:06:54.83322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:06:54.333339Z","time spent":"499.869557ms","remote":"127.0.0.1:59916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-09T15:06:54.833243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:06:54.333143Z","time spent":"500.079262ms","remote":"127.0.0.1:59916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T15:06:54.833261Z","caller":"traceutil/trace.go:171","msg":"trace[452609534] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"498.939735ms","start":"2026-06-09T15:06:54.334317Z","end":"2026-06-09T15:06:54.833257Z","steps":["trace[452609534] 'process raft request' (duration: 498.748281ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:06:54.83328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:06:54.334307Z","time spent":"498.963696ms","remote":"127.0.0.1:59916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T15:06:54.833296Z","caller":"traceutil/trace.go:171","msg":"trace[109772887] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"495.702607ms","start":"2026-06-09T15:06:54.337589Z","end":"2026-06-09T15:06:54.833291Z","steps":["trace[109772887] 'process raft request' (duration: 495.527543ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:06:54.833315Z","caller":"traceutil/trace.go:171","msg":"trace[21610315] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"489.461307ms","start":"2026-06-09T15:06:54.34385Z","end":"2026-06-09T15:06:54.833311Z","steps":["trace[21610315] 'process raft request' (duration: 489.322904ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:06:54.833325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:06:54.33758Z","time spent":"495.734728ms","remote":"127.0.0.1:59916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-09T15:06:54.83334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:06:54.343839Z","time spent":"489.493228ms","remote":"127.0.0.1:59916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T15:06:54.833459Z","caller":"traceutil/trace.go:171","msg":"trace[1007818111] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"361.948577ms","start":"2026-06-09T15:06:54.471503Z","end":"2026-06-09T15:06:54.833452Z","steps":["trace[1007818111] 'process raft request' (duration: 361.788763ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:06:54.833437Z","caller":"traceutil/trace.go:171","msg":"trace[1581532839] linearizableReadLoop","detail":"{readStateIndex:511; appliedIndex:501; }","duration":"476.512836ms","start":"2026-06-09T15:06:54.3569Z","end":"2026-06-09T15:06:54.833413Z","steps":["trace[1581532839] 'read index received' (duration: 465.475392ms)","trace[1581532839] 'applied index is now lower than readState.Index' (duration: 11.036594ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:06:54.833463Z","caller":"traceutil/trace.go:171","msg":"trace[1582644249] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"475.438291ms","start":"2026-06-09T15:06:54.358013Z","end":"2026-06-09T15:06:54.833451Z","steps":["trace[1582644249] 'process raft request' (duration: 475.213495ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:06:54.833487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:06:54.471474Z","time spent":"362.001347ms","remote":"127.0.0.1:59916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-09T15:06:54.833515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:06:54.357985Z","time spent":"475.512802ms","remote":"127.0.0.1:59916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-09T15:06:54.833588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.675951ms","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-09T15:06:54.833629Z","caller":"traceutil/trace.go:171","msg":"trace[598218006] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:495; }","duration":"476.756672ms","start":"2026-06-09T15:06:54.356863Z","end":"2026-06-09T15:06:54.83362Z","steps":["trace[598218006] 'agreement among raft nodes before linearized reading' (duration: 476.647159ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:06:54.833666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:06:54.356851Z","time spent":"476.806844ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-09T15:06:54.842198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.50576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:06:54.842284Z","caller":"traceutil/trace.go:171","msg":"trace[134639359] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:497; }","duration":"337.632173ms","start":"2026-06-09T15:06:54.504636Z","end":"2026-06-09T15:06:54.842268Z","steps":["trace[134639359] 'agreement among raft nodes before linearized reading' (duration: 337.485209ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:06:54.842342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:06:54.50462Z","time spent":"337.712275ms","remote":"127.0.0.1:59888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-09T15:06:54.843144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.324867ms","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-09T15:06:54.84362Z","caller":"traceutil/trace.go:171","msg":"trace[1643234391] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:497; }","duration":"240.83062ms","start":"2026-06-09T15:06:54.602778Z","end":"2026-06-09T15:06:54.843608Z","steps":["trace[1643234391] 'agreement among raft nodes before linearized reading' (duration: 240.217335ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:06:54.843785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.459554ms","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-09T15:06:54.843836Z","caller":"traceutil/trace.go:171","msg":"trace[264491752] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:497; }","duration":"246.556377ms","start":"2026-06-09T15:06:54.597268Z","end":"2026-06-09T15:06:54.843825Z","steps":["trace[264491752] 'agreement among raft nodes before linearized reading' (duration: 246.426934ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:06:55.156109Z","caller":"traceutil/trace.go:171","msg":"trace[594011940] linearizableReadLoop","detail":"{readStateIndex:547; appliedIndex:546; }","duration":"135.476582ms","start":"2026-06-09T15:06:55.020613Z","end":"2026-06-09T15:06:55.15609Z","steps":["trace[594011940] 'read index received' (duration: 68.171157ms)","trace[594011940] 'applied index is now lower than readState.Index' (duration: 67.304915ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:06:55.156275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.643496ms","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-09T15:06:55.156298Z","caller":"traceutil/trace.go:171","msg":"trace[1749108865] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:524; }","duration":"135.714417ms","start":"2026-06-09T15:06:55.020577Z","end":"2026-06-09T15:06:55.156292Z","steps":["trace[1749108865] 'agreement among raft nodes before linearized reading' (duration: 135.600614ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:06:55.156261Z","caller":"traceutil/trace.go:171","msg":"trace[148687712] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"159.110509ms","start":"2026-06-09T15:06:54.997108Z","end":"2026-06-09T15:06:55.156219Z","steps":["trace[148687712] 'process raft request' (duration: 91.64071ms)","trace[148687712] 'compare' (duration: 67.240914ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:06:55.156919Z","caller":"traceutil/trace.go:171","msg":"trace[1710837070] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"123.442823ms","start":"2026-06-09T15:06:55.03345Z","end":"2026-06-09T15:06:55.156893Z","steps":["trace[1710837070] 'process raft request' (duration: 123.018583ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:06:55.34177Z","caller":"traceutil/trace.go:171","msg":"trace[2073963084] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"105.274437ms","start":"2026-06-09T15:06:55.236459Z","end":"2026-06-09T15:06:55.341734Z","steps":["trace[2073963084] 'process raft request' (duration: 61.402344ms)","trace[2073963084] 'compare' (duration: 43.71328ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:06:55.343478Z","caller":"traceutil/trace.go:171","msg":"trace[1373259920] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"102.967682ms","start":"2026-06-09T15:06:55.240479Z","end":"2026-06-09T15:06:55.343447Z","steps":["trace[1373259920] 'process raft request' (duration: 102.609553ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:06:55.344049Z","caller":"traceutil/trace.go:171","msg":"trace[286440269] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"102.225374ms","start":"2026-06-09T15:06:55.241689Z","end":"2026-06-09T15:06:55.343915Z","steps":["trace[286440269] 'process raft request' (duration: 102.08093ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:06:55.548036Z","caller":"traceutil/trace.go:171","msg":"trace[326891741] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"179.300084ms","start":"2026-06-09T15:06:55.368689Z","end":"2026-06-09T15:06:55.547989Z","steps":["trace[326891741] 'process raft request' (duration: 121.837695ms)","trace[326891741] 'compare' (duration: 56.826034ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:06:55.54844Z","caller":"traceutil/trace.go:171","msg":"trace[1205322185] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"168.496514ms","start":"2026-06-09T15:06:55.379917Z","end":"2026-06-09T15:06:55.548413Z","steps":["trace[1205322185] 'process raft request' (duration: 167.90659ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:06:55.549487Z","caller":"traceutil/trace.go:171","msg":"trace[836359433] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"165.978275ms","start":"2026-06-09T15:06:55.383493Z","end":"2026-06-09T15:06:55.549471Z","steps":["trace[836359433] 'process raft request' (duration: 165.38086ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:06:55.877878Z","caller":"traceutil/trace.go:171","msg":"trace[225917426] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"141.970877ms","start":"2026-06-09T15:06:55.735876Z","end":"2026-06-09T15:06:55.877847Z","steps":["trace[225917426] 'process raft request' (duration: 76.223669ms)","trace[225917426] 'compare' (duration: 65.593275ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:06:55.880086Z","caller":"traceutil/trace.go:171","msg":"trace[285359181] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"111.936897ms","start":"2026-06-09T15:06:55.768125Z","end":"2026-06-09T15:06:55.880061Z","steps":["trace[285359181] 'process raft request' (duration: 111.808164ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:06:56.143913Z","caller":"traceutil/trace.go:171","msg":"trace[1973377388] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"299.346515ms","start":"2026-06-09T15:06:55.844528Z","end":"2026-06-09T15:06:56.143874Z","steps":["trace[1973377388] 'process raft request' (duration: 215.048432ms)","trace[1973377388] 'compare' (duration: 83.099304ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:06:56.143968Z","caller":"traceutil/trace.go:171","msg":"trace[909366712] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"236.431945ms","start":"2026-06-09T15:06:55.907518Z","end":"2026-06-09T15:06:56.14395Z","steps":["trace[909366712] 'process raft request' (duration: 236.353843ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:06:56.14404Z","caller":"traceutil/trace.go:171","msg":"trace[22140572] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"241.387874ms","start":"2026-06-09T15:06:55.902613Z","end":"2026-06-09T15:06:56.144001Z","steps":["trace[22140572] 'process raft request' (duration: 241.181499ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:06:56.33222Z","caller":"traceutil/trace.go:171","msg":"trace[2017271587] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"176.426995ms","start":"2026-06-09T15:06:56.155759Z","end":"2026-06-09T15:06:56.332186Z","steps":["trace[2017271587] 'process raft request' (duration: 92.601603ms)","trace[2017271587] 'compare' (duration: 83.616007ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:06:56.366883Z","caller":"traceutil/trace.go:171","msg":"trace[2096536945] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"113.726209ms","start":"2026-06-09T15:06:56.253131Z","end":"2026-06-09T15:06:56.366857Z","steps":["trace[2096536945] 'process raft request' (duration: 113.592006ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:06:56.666884Z","caller":"traceutil/trace.go:171","msg":"trace[915485491] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"276.518298ms","start":"2026-06-09T15:06:56.390345Z","end":"2026-06-09T15:06:56.666864Z","steps":["trace[915485491] 'process raft request' (duration: 202.757827ms)","trace[915485491] 'compare' (duration: 73.670618ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:06:56.805259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.181331ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-09T15:06:56.806703Z","caller":"traceutil/trace.go:171","msg":"trace[581088095] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"329.014587ms","start":"2026-06-09T15:06:56.477664Z","end":"2026-06-09T15:06:56.806678Z","steps":["trace[581088095] 'process raft request' (duration: 327.856009ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:06:56.806762Z","caller":"traceutil/trace.go:171","msg":"trace[543688254] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:575; }","duration":"300.878792ms","start":"2026-06-09T15:06:56.505861Z","end":"2026-06-09T15:06:56.80674Z","steps":["trace[543688254] 'read index received' (duration: 87.289605ms)","trace[543688254] 'applied index is now lower than readState.Index' (duration: 213.588057ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:06:56.806812Z","caller":"traceutil/trace.go:171","msg":"trace[1304105544] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"305.883281ms","start":"2026-06-09T15:06:56.500887Z","end":"2026-06-09T15:06:56.80677Z","steps":["trace[1304105544] 'process raft request' (duration: 305.724598ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:06:56.806881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.016736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:06:56.806928Z","caller":"traceutil/trace.go:171","msg":"trace[1687997024] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:555; }","duration":"301.296962ms","start":"2026-06-09T15:06:56.505617Z","end":"2026-06-09T15:06:56.806914Z","steps":["trace[1687997024] 'agreement among raft nodes before linearized reading' (duration: 301.197709ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:06:56.806974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:06:56.505589Z","time spent":"301.374534ms","remote":"127.0.0.1:59884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-09T15:06:56.806987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:06:56.500868Z","time spent":"306.026055ms","remote":"127.0.0.1:59916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-09T15:06:56.807009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:06:56.477636Z","time spent":"329.12808ms","remote":"127.0.0.1:59916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T15:06:56.836791Z","caller":"traceutil/trace.go:171","msg":"trace[1937502941] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"141.097367ms","start":"2026-06-09T15:06:56.695673Z","end":"2026-06-09T15:06:56.83677Z","steps":["trace[1937502941] 'process raft request' (duration: 140.979464ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:06:58.010536Z","caller":"traceutil/trace.go:171","msg":"trace[1651968623] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"112.925951ms","start":"2026-06-09T15:06:57.897586Z","end":"2026-06-09T15:06:58.010512Z","steps":["trace[1651968623] 'process raft request' (duration: 112.756837ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:06:59.453254Z","caller":"traceutil/trace.go:171","msg":"trace[344753086] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"454.715385ms","start":"2026-06-09T15:06:58.998512Z","end":"2026-06-09T15:06:59.453227Z","steps":["trace[344753086] 'process raft request' (duration: 454.549401ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:06:59.453518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:06:58.998449Z","time spent":"454.882619ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T15:06:59.510911Z","caller":"traceutil/trace.go:171","msg":"trace[728274319] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"510.484573ms","start":"2026-06-09T15:06:59.000382Z","end":"2026-06-09T15:06:59.510867Z","steps":["trace[728274319] 'process raft request' (duration: 509.447868ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:06:59.511051Z","caller":"traceutil/trace.go:171","msg":"trace[1224958347] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"510.606476ms","start":"2026-06-09T15:06:59.000416Z","end":"2026-06-09T15:06:59.511023Z","steps":["trace[1224958347] 'process raft request' (duration: 510.38479ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:06:59.511088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:06:59.000363Z","time spent":"510.626987ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T15:06:59.511075Z","caller":"traceutil/trace.go:171","msg":"trace[1868426744] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:590; }","duration":"333.617768ms","start":"2026-06-09T15:06:59.177372Z","end":"2026-06-09T15:06:59.51099Z","steps":["trace[1868426744] 'read index received' (duration: 275.656867ms)","trace[1868426744] 'applied index is now lower than readState.Index' (duration: 57.958351ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:06:59.511628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.262723ms","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-09T15:06:59.511673Z","caller":"traceutil/trace.go:171","msg":"trace[623467790] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:569; }","duration":"334.308124ms","start":"2026-06-09T15:06:59.177355Z","end":"2026-06-09T15:06:59.511663Z","steps":["trace[623467790] 'agreement among raft nodes before linearized reading' (duration: 334.165261ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:06:59.511704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:06:59.177293Z","time spent":"334.403787ms","remote":"127.0.0.1:60138","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-09T15:06:59.511193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:06:59.000402Z","time spent":"510.713369ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T15:06:59.512009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.621574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-06-09T15:06:59.512096Z","caller":"traceutil/trace.go:171","msg":"trace[115848798] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:569; }","duration":"147.797797ms","start":"2026-06-09T15:06:59.364283Z","end":"2026-06-09T15:06:59.51208Z","steps":["trace[115848798] 'agreement among raft nodes before linearized reading' (duration: 147.426808ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:07:02.157042Z","caller":"traceutil/trace.go:171","msg":"trace[1224477581] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"202.446509ms","start":"2026-06-09T15:07:01.954569Z","end":"2026-06-09T15:07:02.157015Z","steps":["trace[1224477581] 'process raft request' (duration: 200.651006ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:07:02.680614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.432343ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-09T15:07:02.680723Z","caller":"traceutil/trace.go:171","msg":"trace[1971503182] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:609; }","duration":"177.234924ms","start":"2026-06-09T15:07:02.503471Z","end":"2026-06-09T15:07:02.680706Z","steps":["trace[1971503182] 'read index received' (duration: 54.761µs)","trace[1971503182] 'applied index is now lower than readState.Index' (duration: 177.178733ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:07:02.680807Z","caller":"traceutil/trace.go:171","msg":"trace[1250677304] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"470.874462ms","start":"2026-06-09T15:07:02.209921Z","end":"2026-06-09T15:07:02.680796Z","steps":["trace[1250677304] 'process raft request' (duration: 252.191723ms)","trace[1250677304] 'compare' (duration: 217.945981ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:07:02.680858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:07:02.209881Z","time spent":"470.951234ms","remote":"127.0.0.1:60084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-09T15:07:02.681073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.259403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-p4f4g\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-06-09T15:07:02.681164Z","caller":"traceutil/trace.go:171","msg":"trace[2092204439] range","detail":"{range_begin:/registry/pods/kube-system/cilium-p4f4g; range_end:; response_count:1; response_revision:585; }","duration":"154.387145ms","start":"2026-06-09T15:07:02.526752Z","end":"2026-06-09T15:07:02.681139Z","steps":["trace[2092204439] 'agreement among raft nodes before linearized reading' (duration: 154.126079ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:07:02.681196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.699345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:07:02.681282Z","caller":"traceutil/trace.go:171","msg":"trace[2053975079] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:585; }","duration":"177.823858ms","start":"2026-06-09T15:07:02.503431Z","end":"2026-06-09T15:07:02.681254Z","steps":["trace[2053975079] 'agreement among raft nodes before linearized reading' (duration: 177.674604ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:07:03.982046Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967605156294680890,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-09T15:07:04.31412Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.234139423s","expected-duration":"1s"} {"level":"info","ts":"2026-06-09T15:07:04.31523Z","caller":"traceutil/trace.go:171","msg":"trace[124797481] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:611; }","duration":"833.66434ms","start":"2026-06-09T15:07:03.481529Z","end":"2026-06-09T15:07:04.315194Z","steps":["trace[124797481] 'read index received' (duration: 832.81778ms)","trace[124797481] 'applied index is now lower than readState.Index' (duration: 845.38µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:07:04.315298Z","caller":"traceutil/trace.go:171","msg":"trace[1847519023] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"1.236777066s","start":"2026-06-09T15:07:03.07846Z","end":"2026-06-09T15:07:04.315237Z","steps":["trace[1847519023] 'process raft request' (duration: 1.23609712s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:07:04.315436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"833.894186ms","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-09T15:07:04.315471Z","caller":"traceutil/trace.go:171","msg":"trace[218075615] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:587; }","duration":"833.980628ms","start":"2026-06-09T15:07:03.481479Z","end":"2026-06-09T15:07:04.31546Z","steps":["trace[218075615] 'agreement among raft nodes before linearized reading' (duration: 833.846354ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:07:04.315505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:07:03.481461Z","time spent":"834.035969ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-09T15:07:04.315542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:07:03.078429Z","time spent":"1.236971951s","remote":"127.0.0.1:59970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036161,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T15:07:04.315715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.040803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:07:04.315745Z","caller":"traceutil/trace.go:171","msg":"trace[1531624317] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:587; }","duration":"810.103965ms","start":"2026-06-09T15:07:03.505632Z","end":"2026-06-09T15:07:04.315736Z","steps":["trace[1531624317] 'agreement among raft nodes before linearized reading' (duration: 810.061244ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:07:04.31577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:07:03.505611Z","time spent":"810.152636ms","remote":"127.0.0.1:59888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-09T15:07:04.316169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.294369ms","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-09T15:07:04.31621Z","caller":"traceutil/trace.go:171","msg":"trace[204380407] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:587; }","duration":"790.377641ms","start":"2026-06-09T15:07:03.525823Z","end":"2026-06-09T15:07:04.316201Z","steps":["trace[204380407] 'agreement among raft nodes before linearized reading' (duration: 790.271839ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:07:04.31624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:07:03.525794Z","time spent":"790.438953ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-09T15:07:04.316328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.452891ms","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-09T15:07:04.316384Z","caller":"traceutil/trace.go:171","msg":"trace[756872628] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:587; }","duration":"792.541213ms","start":"2026-06-09T15:07:03.523831Z","end":"2026-06-09T15:07:04.316372Z","steps":["trace[756872628] 'agreement among raft nodes before linearized reading' (duration: 792.138344ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:07:04.316417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:07:03.523812Z","time spent":"792.597914ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-09T15:07:04.316464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.621146ms","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-09T15:07:04.31656Z","caller":"traceutil/trace.go:171","msg":"trace[1155628461] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:587; }","duration":"787.693837ms","start":"2026-06-09T15:07:03.528814Z","end":"2026-06-09T15:07:04.316508Z","steps":["trace[1155628461] 'agreement among raft nodes before linearized reading' (duration: 787.107623ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:07:04.316589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:07:03.528803Z","time spent":"787.77916ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-09T15:07:05.970805Z","caller":"traceutil/trace.go:171","msg":"trace[903592438] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"270.79865ms","start":"2026-06-09T15:07:05.699978Z","end":"2026-06-09T15:07:05.970776Z","steps":["trace[903592438] 'process raft request' (duration: 269.081719ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:07:05.981348Z","caller":"traceutil/trace.go:171","msg":"trace[569065452] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"155.975894ms","start":"2026-06-09T15:07:05.825342Z","end":"2026-06-09T15:07:05.981318Z","steps":["trace[569065452] 'process raft request' (duration: 155.507103ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:07:16.784953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.097221ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T15:07:16.785085Z","caller":"traceutil/trace.go:171","msg":"trace[1416834491] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"186.6303ms","start":"2026-06-09T15:07:16.598435Z","end":"2026-06-09T15:07:16.785065Z","steps":["trace[1416834491] 'process raft request' (duration: 39.326565ms)","trace[1416834491] 'compare' (duration: 146.977887ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:07:16.803404Z","caller":"traceutil/trace.go:171","msg":"trace[2076585517] linearizableReadLoop","detail":"{readStateIndex:770; appliedIndex:768; }","duration":"192.48624ms","start":"2026-06-09T15:07:16.610898Z","end":"2026-06-09T15:07:16.803384Z","steps":["trace[2076585517] 'read index received' (duration: 26.872565ms)","trace[2076585517] 'applied index is now lower than readState.Index' (duration: 165.612755ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:07:16.803588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.675035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-sw5h9\" ","response":"range_response_count:1 size:5046"} {"level":"info","ts":"2026-06-09T15:07:16.803621Z","caller":"traceutil/trace.go:171","msg":"trace[2074702242] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-sw5h9; range_end:; response_count:1; response_revision:741; }","duration":"192.751126ms","start":"2026-06-09T15:07:16.61086Z","end":"2026-06-09T15:07:16.803611Z","steps":["trace[2074702242] 'agreement among raft nodes before linearized reading' (duration: 192.613283ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:07:16.803682Z","caller":"traceutil/trace.go:171","msg":"trace[395719335] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"203.196757ms","start":"2026-06-09T15:07:16.600463Z","end":"2026-06-09T15:07:16.80366Z","steps":["trace[395719335] 'process raft request' (duration: 202.795508ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:07:55.231328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.863717ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T15:07:55.231442Z","caller":"traceutil/trace.go:171","msg":"trace[456011883] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"544.035783ms","start":"2026-06-09T15:07:54.687383Z","end":"2026-06-09T15:07:55.231419Z","steps":["trace[456011883] 'process raft request' (duration: 302.995492ms)","trace[456011883] 'compare' (duration: 240.575951ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:07:55.231529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:07:54.687366Z","time spent":"544.124185ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T15:07:55.589615Z","caller":"traceutil/trace.go:171","msg":"trace[711487142] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"902.035658ms","start":"2026-06-09T15:07:54.687552Z","end":"2026-06-09T15:07:55.589588Z","steps":["trace[711487142] 'process raft request' (duration: 900.149778ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:07:55.589763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:07:54.687526Z","time spent":"902.158011ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T15:07:55.589796Z","caller":"traceutil/trace.go:171","msg":"trace[1314260377] linearizableReadLoop","detail":"{readStateIndex:1017; appliedIndex:1014; }","duration":"654.850966ms","start":"2026-06-09T15:07:54.934932Z","end":"2026-06-09T15:07:55.589783Z","steps":["trace[1314260377] 'read index received' (duration: 55.562275ms)","trace[1314260377] 'applied index is now lower than readState.Index' (duration: 599.287341ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:07:55.589616Z","caller":"traceutil/trace.go:171","msg":"trace[29231176] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"900.69003ms","start":"2026-06-09T15:07:54.688896Z","end":"2026-06-09T15:07:55.589586Z","steps":["trace[29231176] 'process raft request' (duration: 900.621749ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:07:55.589931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.99423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:07:55.58996Z","caller":"traceutil/trace.go:171","msg":"trace[724222860] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:980; }","duration":"655.089612ms","start":"2026-06-09T15:07:54.934862Z","end":"2026-06-09T15:07:55.589952Z","steps":["trace[724222860] 'agreement among raft nodes before linearized reading' (duration: 655.0095ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:07:55.589979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:07:54.688881Z","time spent":"901.069738ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T15:07:55.58999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:07:54.934832Z","time spent":"655.150692ms","remote":"127.0.0.1:59888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-09T15:07:55.590446Z","caller":"traceutil/trace.go:171","msg":"trace[64757576] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"595.048371ms","start":"2026-06-09T15:07:54.995384Z","end":"2026-06-09T15:07:55.590433Z","steps":["trace[64757576] 'process raft request' (duration: 594.332716ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:07:55.590455Z","caller":"traceutil/trace.go:171","msg":"trace[1052011649] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"173.530281ms","start":"2026-06-09T15:07:55.416891Z","end":"2026-06-09T15:07:55.590421Z","steps":["trace[1052011649] 'process raft request' (duration: 173.436309ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:07:55.590535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:07:54.99536Z","time spent":"595.139883ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T15:08:16.521207Z","caller":"traceutil/trace.go:171","msg":"trace[1095099847] linearizableReadLoop","detail":"{readStateIndex:1090; appliedIndex:1089; }","duration":"113.718285ms","start":"2026-06-09T15:08:16.407468Z","end":"2026-06-09T15:08:16.521187Z","steps":["trace[1095099847] 'read index received' (duration: 113.557492ms)","trace[1095099847] 'applied index is now lower than readState.Index' (duration: 160.143µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:08:16.521252Z","caller":"traceutil/trace.go:171","msg":"trace[1903788633] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"131.7741ms","start":"2026-06-09T15:08:16.38946Z","end":"2026-06-09T15:08:16.521234Z","steps":["trace[1903788633] 'process raft request' (duration: 131.633447ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:08:16.521351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.863178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:08:16.521401Z","caller":"traceutil/trace.go:171","msg":"trace[1292205812] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1048; }","duration":"113.9464ms","start":"2026-06-09T15:08:16.407439Z","end":"2026-06-09T15:08:16.521386Z","steps":["trace[1292205812] 'agreement among raft nodes before linearized reading' (duration: 113.868689ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:08:23.88805Z","caller":"traceutil/trace.go:171","msg":"trace[8336512] linearizableReadLoop","detail":"{readStateIndex:1115; appliedIndex:1114; }","duration":"143.313177ms","start":"2026-06-09T15:08:23.744712Z","end":"2026-06-09T15:08:23.888025Z","steps":["trace[8336512] 'read index received' (duration: 143.171244ms)","trace[8336512] 'applied index is now lower than readState.Index' (duration: 140.363µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:08:23.888198Z","caller":"traceutil/trace.go:171","msg":"trace[2039161335] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"189.247986ms","start":"2026-06-09T15:08:23.698935Z","end":"2026-06-09T15:08:23.888183Z","steps":["trace[2039161335] 'process raft request' (duration: 188.94655ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:08:23.888365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.423026ms","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-09T15:08:23.888438Z","caller":"traceutil/trace.go:171","msg":"trace[1821240103] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1071; }","duration":"118.536018ms","start":"2026-06-09T15:08:23.76987Z","end":"2026-06-09T15:08:23.888406Z","steps":["trace[1821240103] 'agreement among raft nodes before linearized reading' (duration: 118.380665ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:08:23.888727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.95334ms","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-09T15:08:23.888858Z","caller":"traceutil/trace.go:171","msg":"trace[1390243848] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1071; }","duration":"144.166945ms","start":"2026-06-09T15:08:23.744664Z","end":"2026-06-09T15:08:23.888831Z","steps":["trace[1390243848] 'agreement among raft nodes before linearized reading' (duration: 143.786437ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:08:24.73657Z","caller":"traceutil/trace.go:171","msg":"trace[221771107] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"181.593233ms","start":"2026-06-09T15:08:24.554948Z","end":"2026-06-09T15:08:24.736541Z","steps":["trace[221771107] 'process raft request' (duration: 181.43442ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:08:25.673752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.253654ms","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-09T15:08:25.673839Z","caller":"traceutil/trace.go:171","msg":"trace[172925183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1074; }","duration":"247.393717ms","start":"2026-06-09T15:08:25.426424Z","end":"2026-06-09T15:08:25.673818Z","steps":["trace[172925183] 'range keys from in-memory index tree' (duration: 247.031749ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:08:25.673827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.088375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:08:25.673905Z","caller":"traceutil/trace.go:171","msg":"trace[112020829] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1074; }","duration":"168.223368ms","start":"2026-06-09T15:08:25.50566Z","end":"2026-06-09T15:08:25.673884Z","steps":["trace[112020829] 'range keys from in-memory index tree' (duration: 167.994433ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:08:25.67405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.834397ms","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-09T15:08:25.674111Z","caller":"traceutil/trace.go:171","msg":"trace[995127847] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1074; }","duration":"767.942219ms","start":"2026-06-09T15:08:24.906154Z","end":"2026-06-09T15:08:25.674096Z","steps":["trace[995127847] 'range keys from in-memory index tree' (duration: 767.531249ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:08:25.674167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:08:24.906125Z","time spent":"768.03271ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-09T15:08:26.125169Z","caller":"traceutil/trace.go:171","msg":"trace[1395620396] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"162.495715ms","start":"2026-06-09T15:08:25.962644Z","end":"2026-06-09T15:08:26.125139Z","steps":["trace[1395620396] 'process raft request' (duration: 66.779784ms)","trace[1395620396] 'compare' (duration: 95.581078ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:08:27.125658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.875121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T15:08:27.125744Z","caller":"traceutil/trace.go:171","msg":"trace[117922306] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1079; }","duration":"725.003413ms","start":"2026-06-09T15:08:26.400723Z","end":"2026-06-09T15:08:27.125726Z","steps":["trace[117922306] 'count revisions from in-memory index tree' (duration: 724.755667ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:08:27.125788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:08:26.400702Z","time spent":"725.075505ms","remote":"127.0.0.1:60070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":11,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-06-09T15:08:27.125774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.194657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:08:27.125818Z","caller":"traceutil/trace.go:171","msg":"trace[1528331063] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"601.164542ms","start":"2026-06-09T15:08:26.524629Z","end":"2026-06-09T15:08:27.125794Z","steps":["trace[1528331063] 'process raft request' (duration: 566.162055ms)","trace[1528331063] 'compare' (duration: 34.474316ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:08:27.12587Z","caller":"traceutil/trace.go:171","msg":"trace[1469839673] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1079; }","duration":"619.332419ms","start":"2026-06-09T15:08:26.506514Z","end":"2026-06-09T15:08:27.125847Z","steps":["trace[1469839673] 'range keys from in-memory index tree' (duration: 619.069544ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:08:27.125934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:08:26.506496Z","time spent":"619.426572ms","remote":"127.0.0.1:59888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-09T15:08:27.125987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:08:26.524609Z","time spent":"601.301256ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T15:08:27.197842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.020936ms","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-09T15:08:27.197903Z","caller":"traceutil/trace.go:171","msg":"trace[1742724265] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1080; }","duration":"456.138969ms","start":"2026-06-09T15:08:26.741754Z","end":"2026-06-09T15:08:27.197893Z","steps":["trace[1742724265] 'agreement among raft nodes before linearized reading' (duration: 455.938535ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:08:27.197944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:08:26.741733Z","time spent":"456.20296ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-09T15:08:27.197646Z","caller":"traceutil/trace.go:171","msg":"trace[1412803563] linearizableReadLoop","detail":"{readStateIndex:1125; appliedIndex:1123; }","duration":"455.762011ms","start":"2026-06-09T15:08:26.741805Z","end":"2026-06-09T15:08:27.197568Z","steps":["trace[1412803563] 'read index received' (duration: 349.004424ms)","trace[1412803563] 'applied index is now lower than readState.Index' (duration: 106.756087ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:08:27.198732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.75881ms","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-09T15:08:27.198882Z","caller":"traceutil/trace.go:171","msg":"trace[602371165] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1080; }","duration":"205.957524ms","start":"2026-06-09T15:08:26.992867Z","end":"2026-06-09T15:08:27.198825Z","steps":["trace[602371165] 'agreement among raft nodes before linearized reading' (duration: 205.720239ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:08:28.277158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.57464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T15:08:28.277885Z","caller":"traceutil/trace.go:171","msg":"trace[833110122] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1083; }","duration":"430.370697ms","start":"2026-06-09T15:08:27.847489Z","end":"2026-06-09T15:08:28.27786Z","steps":["trace[833110122] 'count revisions from in-memory index tree' (duration: 429.449718ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:08:28.277525Z","caller":"traceutil/trace.go:171","msg":"trace[228281524] linearizableReadLoop","detail":"{readStateIndex:1129; appliedIndex:1128; }","duration":"290.834072ms","start":"2026-06-09T15:08:27.986662Z","end":"2026-06-09T15:08:28.277496Z","steps":["trace[228281524] 'read index received' (duration: 258.670866ms)","trace[228281524] 'applied index is now lower than readState.Index' (duration: 32.161786ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:08:28.277973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:08:27.847459Z","time spent":"430.48767ms","remote":"127.0.0.1:58166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-06-09T15:08:28.277692Z","caller":"traceutil/trace.go:171","msg":"trace[1714926969] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"312.807781ms","start":"2026-06-09T15:08:27.964857Z","end":"2026-06-09T15:08:28.277665Z","steps":["trace[1714926969] 'process raft request' (duration: 280.43444ms)","trace[1714926969] 'compare' (duration: 31.746107ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:08:28.278169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.973246ms","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-09T15:08:28.278242Z","caller":"traceutil/trace.go:171","msg":"trace[1388901458] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1084; }","duration":"148.075519ms","start":"2026-06-09T15:08:28.130147Z","end":"2026-06-09T15:08:28.278223Z","steps":["trace[1388901458] 'agreement among raft nodes before linearized reading' (duration: 147.869194ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:08:28.278324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:08:27.964828Z","time spent":"313.271471ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T15:08:28.277728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.053816ms","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-09T15:08:28.27861Z","caller":"traceutil/trace.go:171","msg":"trace[1302563360] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1084; }","duration":"291.970106ms","start":"2026-06-09T15:08:27.986611Z","end":"2026-06-09T15:08:28.278581Z","steps":["trace[1302563360] 'agreement among raft nodes before linearized reading' (duration: 290.964195ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:08:42.234126Z","caller":"traceutil/trace.go:171","msg":"trace[1233163403] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"210.763934ms","start":"2026-06-09T15:08:42.023336Z","end":"2026-06-09T15:08:42.2341Z","steps":["trace[1233163403] 'process raft request' (duration: 210.5792ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:08:42.473942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.496612ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-09T15:08:42.474105Z","caller":"traceutil/trace.go:171","msg":"trace[831083042] linearizableReadLoop","detail":"{readStateIndex:1182; appliedIndex:1180; }","duration":"207.107077ms","start":"2026-06-09T15:08:42.26698Z","end":"2026-06-09T15:08:42.474087Z","steps":["trace[831083042] 'read index received' (duration: 92.37376ms)","trace[831083042] 'applied index is now lower than readState.Index' (duration: 114.732457ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:08:42.474164Z","caller":"traceutil/trace.go:171","msg":"trace[1204806818] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"231.1068ms","start":"2026-06-09T15:08:42.243028Z","end":"2026-06-09T15:08:42.474135Z","steps":["trace[1204806818] 'process raft request' (duration: 231.013878ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:08:42.474259Z","caller":"traceutil/trace.go:171","msg":"trace[234686386] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"232.006168ms","start":"2026-06-09T15:08:42.24215Z","end":"2026-06-09T15:08:42.474157Z","steps":["trace[234686386] 'process raft request' (duration: 117.22152ms)","trace[234686386] 'compare' (duration: 114.351169ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:08:42.474318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.320702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:08:42.474392Z","caller":"traceutil/trace.go:171","msg":"trace[564952112] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1133; }","duration":"207.446365ms","start":"2026-06-09T15:08:42.266927Z","end":"2026-06-09T15:08:42.474373Z","steps":["trace[564952112] 'agreement among raft nodes before linearized reading' (duration: 207.290042ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:08:42.535258Z","caller":"traceutil/trace.go:171","msg":"trace[889846941] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"130.139215ms","start":"2026-06-09T15:08:42.405089Z","end":"2026-06-09T15:08:42.535229Z","steps":["trace[889846941] 'process raft request' (duration: 129.91329ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:08:42.704456Z","caller":"traceutil/trace.go:171","msg":"trace[67959785] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"163.745591ms","start":"2026-06-09T15:08:42.540689Z","end":"2026-06-09T15:08:42.704434Z","steps":["trace[67959785] 'process raft request' (duration: 163.702441ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:08:42.70517Z","caller":"traceutil/trace.go:171","msg":"trace[710976409] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"164.976128ms","start":"2026-06-09T15:08:42.54016Z","end":"2026-06-09T15:08:42.705136Z","steps":["trace[710976409] 'process raft request' (duration: 113.677865ms)","trace[710976409] 'compare' (duration: 50.444515ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:08:42.754968Z","caller":"traceutil/trace.go:171","msg":"trace[1151913912] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"172.586871ms","start":"2026-06-09T15:08:42.58236Z","end":"2026-06-09T15:08:42.754946Z","steps":["trace[1151913912] 'process raft request' (duration: 172.55775ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:08:42.754976Z","caller":"traceutil/trace.go:171","msg":"trace[319025998] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"173.317156ms","start":"2026-06-09T15:08:42.581637Z","end":"2026-06-09T15:08:42.754954Z","steps":["trace[319025998] 'process raft request' (duration: 173.155402ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:08:42.755104Z","caller":"traceutil/trace.go:171","msg":"trace[1512390180] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"173.06012ms","start":"2026-06-09T15:08:42.582017Z","end":"2026-06-09T15:08:42.755077Z","steps":["trace[1512390180] 'process raft request' (duration: 172.864476ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:08:42.892874Z","caller":"traceutil/trace.go:171","msg":"trace[373005559] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"115.968843ms","start":"2026-06-09T15:08:42.776883Z","end":"2026-06-09T15:08:42.892852Z","steps":["trace[373005559] 'process raft request' (duration: 115.894571ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:08:43.080095Z","caller":"traceutil/trace.go:171","msg":"trace[586891777] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"182.409711ms","start":"2026-06-09T15:08:42.89766Z","end":"2026-06-09T15:08:43.080069Z","steps":["trace[586891777] 'process raft request' (duration: 182.270978ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:08:43.253293Z","caller":"traceutil/trace.go:171","msg":"trace[350918773] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"134.807875ms","start":"2026-06-09T15:08:43.118443Z","end":"2026-06-09T15:08:43.253251Z","steps":["trace[350918773] 'process raft request' (duration: 131.994206ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:08:43.400489Z","caller":"traceutil/trace.go:171","msg":"trace[359460111] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"142.489749ms","start":"2026-06-09T15:08:43.25797Z","end":"2026-06-09T15:08:43.400459Z","steps":["trace[359460111] 'process raft request' (duration: 142.239373ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:08:43.407429Z","caller":"traceutil/trace.go:171","msg":"trace[244411778] transaction","detail":"{read_only:false; response_revision:1151; number_of_response:1; }","duration":"112.021859ms","start":"2026-06-09T15:08:43.295376Z","end":"2026-06-09T15:08:43.407398Z","steps":["trace[244411778] 'process raft request' (duration: 111.806004ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:08:43.407926Z","caller":"traceutil/trace.go:171","msg":"trace[293186639] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"112.212053ms","start":"2026-06-09T15:08:43.295609Z","end":"2026-06-09T15:08:43.407821Z","steps":["trace[293186639] 'process raft request' (duration: 111.676531ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:08:45.937869Z","caller":"traceutil/trace.go:171","msg":"trace[1520730882] linearizableReadLoop","detail":"{readStateIndex:1241; appliedIndex:1240; }","duration":"434.972166ms","start":"2026-06-09T15:08:45.502874Z","end":"2026-06-09T15:08:45.937846Z","steps":["trace[1520730882] 'read index received' (duration: 434.833673ms)","trace[1520730882] 'applied index is now lower than readState.Index' (duration: 137.443µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:08:45.937892Z","caller":"traceutil/trace.go:171","msg":"trace[131014415] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"481.741634ms","start":"2026-06-09T15:08:45.456125Z","end":"2026-06-09T15:08:45.937867Z","steps":["trace[131014415] 'process raft request' (duration: 481.57636ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:08:45.938007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:08:45.456097Z","time spent":"481.846855ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T15:08:45.938098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.722959ms","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-09T15:08:45.938072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.14969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:08:45.938188Z","caller":"traceutil/trace.go:171","msg":"trace[2123106077] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1191; }","duration":"435.329814ms","start":"2026-06-09T15:08:45.502831Z","end":"2026-06-09T15:08:45.938161Z","steps":["trace[2123106077] 'agreement among raft nodes before linearized reading' (duration: 435.101749ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:08:45.938242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:08:45.502814Z","time spent":"435.420186ms","remote":"127.0.0.1:59884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-09T15:08:45.938129Z","caller":"traceutil/trace.go:171","msg":"trace[250774018] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1191; }","duration":"108.79606ms","start":"2026-06-09T15:08:45.829323Z","end":"2026-06-09T15:08:45.938119Z","steps":["trace[250774018] 'agreement among raft nodes before linearized reading' (duration: 108.660157ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:08:46.48911Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967605156294682220,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-09T15:08:46.990303Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967605156294682220,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-09T15:08:47.490619Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967605156294682220,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-09T15:08:47.991466Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967605156294682220,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-09T15:08:48.168352Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.226200948s","expected-duration":"1s"} {"level":"info","ts":"2026-06-09T15:08:48.1691Z","caller":"traceutil/trace.go:171","msg":"trace[298135451] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"2.227058847s","start":"2026-06-09T15:08:45.942012Z","end":"2026-06-09T15:08:48.169071Z","steps":["trace[298135451] 'process raft request' (duration: 2.226894633s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:08:48.169286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:08:45.941986Z","time spent":"2.227207399s","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T15:08:48.171415Z","caller":"traceutil/trace.go:171","msg":"trace[1032850413] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"2.229032778s","start":"2026-06-09T15:08:45.942351Z","end":"2026-06-09T15:08:48.171384Z","steps":["trace[1032850413] 'process raft request' (duration: 2.228835914s)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:08:48.171618Z","caller":"traceutil/trace.go:171","msg":"trace[194116193] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"1.623027364s","start":"2026-06-09T15:08:46.548562Z","end":"2026-06-09T15:08:48.17159Z","steps":["trace[194116193] 'process raft request' (duration: 1.622781918s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:08:48.17176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:08:46.548542Z","time spent":"1.623143827s","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T15:08:48.171768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:08:45.942338Z","time spent":"2.229245873s","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T15:08:48.178885Z","caller":"traceutil/trace.go:171","msg":"trace[610021717] linearizableReadLoop","detail":"{readStateIndex:1246; appliedIndex:1241; }","duration":"2.19017812s","start":"2026-06-09T15:08:45.988695Z","end":"2026-06-09T15:08:48.178873Z","steps":["trace[610021717] 'read index received' (duration: 2.180276079s)","trace[610021717] 'applied index is now lower than readState.Index' (duration: 9.901271ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:08:48.178971Z","caller":"traceutil/trace.go:171","msg":"trace[1750998344] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"308.95354ms","start":"2026-06-09T15:08:47.869997Z","end":"2026-06-09T15:08:48.178951Z","steps":["trace[1750998344] 'process raft request' (duration: 308.775516ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:08:48.179098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:08:47.869961Z","time spent":"309.063031ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T15:08:48.179141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.672673102s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:08:48.179169Z","caller":"traceutil/trace.go:171","msg":"trace[75527050] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1195; }","duration":"1.672732534s","start":"2026-06-09T15:08:46.506427Z","end":"2026-06-09T15:08:48.17916Z","steps":["trace[75527050] 'agreement among raft nodes before linearized reading' (duration: 1.672685593s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:08:48.17919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:08:46.506403Z","time spent":"1.672782665s","remote":"127.0.0.1:59888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-09T15:08:48.179203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.190429035s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T15:08:48.179321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.229075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T15:08:48.179351Z","caller":"traceutil/trace.go:171","msg":"trace[217721712] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1195; }","duration":"234.261536ms","start":"2026-06-09T15:08:47.945082Z","end":"2026-06-09T15:08:48.179343Z","steps":["trace[217721712] 'agreement among raft nodes before linearized reading' (duration: 234.219585ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:08:48.179382Z","caller":"traceutil/trace.go:171","msg":"trace[1039309155] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1195; }","duration":"2.19067502s","start":"2026-06-09T15:08:45.988645Z","end":"2026-06-09T15:08:48.17932Z","steps":["trace[1039309155] 'agreement among raft nodes before linearized reading' (duration: 2.190424445s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:08:48.179455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"880.343365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T15:08:48.179507Z","caller":"traceutil/trace.go:171","msg":"trace[788216685] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1195; }","duration":"880.444797ms","start":"2026-06-09T15:08:47.299048Z","end":"2026-06-09T15:08:48.179493Z","steps":["trace[788216685] 'agreement among raft nodes before linearized reading' (duration: 880.318184ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:08:48.179515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.461143231s","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-09T15:08:48.17955Z","caller":"traceutil/trace.go:171","msg":"trace[1043332256] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1195; }","duration":"1.461236853s","start":"2026-06-09T15:08:46.718304Z","end":"2026-06-09T15:08:48.179541Z","steps":["trace[1043332256] 'agreement among raft nodes before linearized reading' (duration: 1.461136421s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:08:48.179565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:08:47.299029Z","time spent":"880.527149ms","remote":"127.0.0.1:60220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":55,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-06-09T15:08:48.179579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.462053581s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-09T15:08:48.179588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:08:46.718265Z","time spent":"1.461316465s","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-09T15:08:48.179612Z","caller":"traceutil/trace.go:171","msg":"trace[1252302607] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1195; }","duration":"1.462138832s","start":"2026-06-09T15:08:46.717463Z","end":"2026-06-09T15:08:48.179602Z","steps":["trace[1252302607] 'agreement among raft nodes before linearized reading' (duration: 1.46203074s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:08:48.179637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:08:46.717435Z","time spent":"1.462197984s","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-09T15:08:48.179484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:08:45.988623Z","time spent":"2.190838014s","remote":"127.0.0.1:42486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-09T15:08:48.1798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.653048ms","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-09T15:08:48.17985Z","caller":"traceutil/trace.go:171","msg":"trace[2059393630] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1195; }","duration":"236.735389ms","start":"2026-06-09T15:08:47.943096Z","end":"2026-06-09T15:08:48.179831Z","steps":["trace[2059393630] 'agreement among raft nodes before linearized reading' (duration: 236.613497ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:08:48.179912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.426576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:08:48.179935Z","caller":"traceutil/trace.go:171","msg":"trace[2016578859] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1195; }","duration":"488.452857ms","start":"2026-06-09T15:08:47.691477Z","end":"2026-06-09T15:08:48.17993Z","steps":["trace[2016578859] 'agreement among raft nodes before linearized reading' (duration: 488.410237ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:08:48.17995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:08:47.691454Z","time spent":"488.492698ms","remote":"127.0.0.1:60166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-06-09T15:08:50.549948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.010424ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T15:08:50.550215Z","caller":"traceutil/trace.go:171","msg":"trace[706604120] linearizableReadLoop","detail":"{readStateIndex:1252; appliedIndex:1250; }","duration":"354.740285ms","start":"2026-06-09T15:08:50.195453Z","end":"2026-06-09T15:08:50.550193Z","steps":["trace[706604120] 'read index received' (duration: 17.403573ms)","trace[706604120] 'applied index is now lower than readState.Index' (duration: 337.335502ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:08:50.550332Z","caller":"traceutil/trace.go:171","msg":"trace[135079345] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"363.344554ms","start":"2026-06-09T15:08:50.186973Z","end":"2026-06-09T15:08:50.550317Z","steps":["trace[135079345] 'process raft request' (duration: 363.088078ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:08:50.550366Z","caller":"traceutil/trace.go:171","msg":"trace[593074225] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"372.219979ms","start":"2026-06-09T15:08:50.178126Z","end":"2026-06-09T15:08:50.550346Z","steps":["trace[593074225] 'process raft request' (duration: 34.717664ms)","trace[593074225] 'compare' (duration: 336.896531ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:08:50.550414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:08:50.186918Z","time spent":"363.447147ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T15:08:50.550454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:08:50.178103Z","time spent":"372.307332ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T15:08:50.550792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.332465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-09T15:08:50.550848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.39428ms","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-09T15:08:50.550877Z","caller":"traceutil/trace.go:171","msg":"trace[572005373] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1201; }","duration":"354.451188ms","start":"2026-06-09T15:08:50.196401Z","end":"2026-06-09T15:08:50.550853Z","steps":["trace[572005373] 'agreement among raft nodes before linearized reading' (duration: 354.204202ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:08:50.550906Z","caller":"traceutil/trace.go:171","msg":"trace[1742939975] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1201; }","duration":"355.487022ms","start":"2026-06-09T15:08:50.195404Z","end":"2026-06-09T15:08:50.550891Z","steps":["trace[1742939975] 'agreement among raft nodes before linearized reading' (duration: 355.308658ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:08:50.550943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:08:50.196377Z","time spent":"354.552171ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-09T15:08:50.550949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:08:50.195386Z","time spent":"355.551454ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-09T15:08:50.551398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.13432ms","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-09T15:08:50.55146Z","caller":"traceutil/trace.go:171","msg":"trace[196735418] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1201; }","duration":"354.210233ms","start":"2026-06-09T15:08:50.197235Z","end":"2026-06-09T15:08:50.551445Z","steps":["trace[196735418] 'agreement among raft nodes before linearized reading' (duration: 353.819164ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:08:50.551501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:08:50.197228Z","time spent":"354.262404ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-09T15:08:50.751954Z","caller":"traceutil/trace.go:171","msg":"trace[651843821] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"197.146625ms","start":"2026-06-09T15:08:50.554776Z","end":"2026-06-09T15:08:50.751923Z","steps":["trace[651843821] 'process raft request' (duration: 112.323371ms)","trace[651843821] 'compare' (duration: 84.722582ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:08:50.962622Z","caller":"traceutil/trace.go:171","msg":"trace[1282998150] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"407.436315ms","start":"2026-06-09T15:08:50.555157Z","end":"2026-06-09T15:08:50.962593Z","steps":["trace[1282998150] 'process raft request' (duration: 341.713723ms)","trace[1282998150] 'compare' (duration: 65.537158ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:08:50.962773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:08:50.555139Z","time spent":"407.558908ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T15:08:50.962921Z","caller":"traceutil/trace.go:171","msg":"trace[1200437842] linearizableReadLoop","detail":"{readStateIndex:1255; appliedIndex:1253; }","duration":"189.173601ms","start":"2026-06-09T15:08:50.773738Z","end":"2026-06-09T15:08:50.962912Z","steps":["trace[1200437842] 'read index received' (duration: 123.222554ms)","trace[1200437842] 'applied index is now lower than readState.Index' (duration: 65.949867ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:08:50.963013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.277023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:08:50.963051Z","caller":"traceutil/trace.go:171","msg":"trace[14559967] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1204; }","duration":"189.356255ms","start":"2026-06-09T15:08:50.773685Z","end":"2026-06-09T15:08:50.963041Z","steps":["trace[14559967] 'agreement among raft nodes before linearized reading' (duration: 189.266603ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:08:50.963277Z","caller":"traceutil/trace.go:171","msg":"trace[1603886908] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"407.231441ms","start":"2026-06-09T15:08:50.556035Z","end":"2026-06-09T15:08:50.963267Z","steps":["trace[1603886908] 'process raft request' (duration: 406.515974ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:08:50.963345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:08:50.556019Z","time spent":"407.289852ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T15:09:01.112424Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967605156294682320,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-09T15:09:01.612854Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967605156294682320,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-09T15:09:01.861874Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.249864884s","expected-duration":"1s"} {"level":"info","ts":"2026-06-09T15:09:01.862165Z","caller":"traceutil/trace.go:171","msg":"trace[974490527] linearizableReadLoop","detail":"{readStateIndex:1286; appliedIndex:1285; }","duration":"1.249790732s","start":"2026-06-09T15:09:00.612357Z","end":"2026-06-09T15:09:01.862148Z","steps":["trace[974490527] 'read index received' (duration: 1.249644209s)","trace[974490527] 'applied index is now lower than readState.Index' (duration: 145.893µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:09:01.862426Z","caller":"traceutil/trace.go:171","msg":"trace[1822918627] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"1.250519789s","start":"2026-06-09T15:09:00.611898Z","end":"2026-06-09T15:09:01.862418Z","steps":["trace[1822918627] 'process raft request' (duration: 1.25015391s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:09:01.862532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:09:00.611875Z","time spent":"1.250585851s","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T15:09:01.862714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.250355695s","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-09T15:09:01.862743Z","caller":"traceutil/trace.go:171","msg":"trace[1792555693] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1233; }","duration":"1.250406216s","start":"2026-06-09T15:09:00.612329Z","end":"2026-06-09T15:09:01.862735Z","steps":["trace[1792555693] 'agreement among raft nodes before linearized reading' (duration: 1.250324284s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:09:01.862762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:09:00.612316Z","time spent":"1.250441627s","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-09T15:09:01.862878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.070191123s","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-09T15:09:01.8629Z","caller":"traceutil/trace.go:171","msg":"trace[1305583708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1233; }","duration":"1.070214734s","start":"2026-06-09T15:09:00.792681Z","end":"2026-06-09T15:09:01.862895Z","steps":["trace[1305583708] 'agreement among raft nodes before linearized reading' (duration: 1.070161302s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:09:01.862919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:09:00.79262Z","time spent":"1.070293075s","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-09T15:09:01.86303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.299759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.78\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-09T15:09:01.863051Z","caller":"traceutil/trace.go:171","msg":"trace[624059126] range","detail":"{range_begin:/registry/masterleases/199.204.45.78; range_end:; response_count:1; response_revision:1233; }","duration":"675.36602ms","start":"2026-06-09T15:09:01.187681Z","end":"2026-06-09T15:09:01.863047Z","steps":["trace[624059126] 'agreement among raft nodes before linearized reading' (duration: 675.314969ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:09:01.86307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:09:01.187657Z","time spent":"675.409731ms","remote":"127.0.0.1:59902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.78\" "} {"level":"warn","ts":"2026-06-09T15:09:01.863095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.624511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-09T15:09:01.863172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.086906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T15:09:01.863195Z","caller":"traceutil/trace.go:171","msg":"trace[1291727587] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1233; }","duration":"793.145927ms","start":"2026-06-09T15:09:01.070044Z","end":"2026-06-09T15:09:01.86319Z","steps":["trace[1291727587] 'agreement among raft nodes before linearized reading' (duration: 793.109177ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:09:01.863216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:09:01.070024Z","time spent":"793.188028ms","remote":"127.0.0.1:60350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-06-09T15:09:01.863331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.316145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-09T15:09:01.863366Z","caller":"traceutil/trace.go:171","msg":"trace[1093106879] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1233; }","duration":"851.338585ms","start":"2026-06-09T15:09:01.012009Z","end":"2026-06-09T15:09:01.863348Z","steps":["trace[1093106879] 'agreement among raft nodes before linearized reading' (duration: 851.284284ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:09:01.863386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:09:01.011999Z","time spent":"851.383146ms","remote":"127.0.0.1:60138","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-09T15:09:01.863166Z","caller":"traceutil/trace.go:171","msg":"trace[497780117] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1233; }","duration":"395.740194ms","start":"2026-06-09T15:09:01.46741Z","end":"2026-06-09T15:09:01.86315Z","steps":["trace[497780117] 'agreement among raft nodes before linearized reading' (duration: 395.5555ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:09:01.863508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"854.855907ms","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-09T15:09:01.863526Z","caller":"traceutil/trace.go:171","msg":"trace[1256569371] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1233; }","duration":"854.906627ms","start":"2026-06-09T15:09:01.008613Z","end":"2026-06-09T15:09:01.86352Z","steps":["trace[1256569371] 'agreement among raft nodes before linearized reading' (duration: 854.860896ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:09:01.863542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:09:01.008593Z","time spent":"854.945738ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-09T15:09:01.863532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:09:01.467391Z","time spent":"396.124813ms","remote":"127.0.0.1:59954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":100,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-06-09T15:09:02.007031Z","caller":"traceutil/trace.go:171","msg":"trace[154655334] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"140.153796ms","start":"2026-06-09T15:09:01.866865Z","end":"2026-06-09T15:09:02.007018Z","steps":["trace[154655334] 'process raft request' (duration: 139.836439ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:09:02.007204Z","caller":"traceutil/trace.go:171","msg":"trace[1325564476] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"140.095165ms","start":"2026-06-09T15:09:01.867084Z","end":"2026-06-09T15:09:02.007179Z","steps":["trace[1325564476] 'process raft request' (duration: 139.938521ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:09:02.006972Z","caller":"traceutil/trace.go:171","msg":"trace[1766548251] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"140.359361ms","start":"2026-06-09T15:09:01.866562Z","end":"2026-06-09T15:09:02.006921Z","steps":["trace[1766548251] 'process raft request' (duration: 139.955411ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:09:02.007554Z","caller":"traceutil/trace.go:171","msg":"trace[955562768] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"138.440826ms","start":"2026-06-09T15:09:01.869091Z","end":"2026-06-09T15:09:02.007532Z","steps":["trace[955562768] 'process raft request' (duration: 138.067097ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:09:07.295934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.136951ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T15:09:07.296067Z","caller":"traceutil/trace.go:171","msg":"trace[499732687] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"225.958961ms","start":"2026-06-09T15:09:07.070083Z","end":"2026-06-09T15:09:07.296041Z","steps":["trace[499732687] 'process raft request' (duration: 108.634146ms)","trace[499732687] 'compare' (duration: 115.491043ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:09:07.303484Z","caller":"traceutil/trace.go:171","msg":"trace[522549551] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"233.159198ms","start":"2026-06-09T15:09:07.070288Z","end":"2026-06-09T15:09:07.303448Z","steps":["trace[522549551] 'process raft request' (duration: 232.975324ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:09:07.303721Z","caller":"traceutil/trace.go:171","msg":"trace[330945948] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"228.151612ms","start":"2026-06-09T15:09:07.075559Z","end":"2026-06-09T15:09:07.30371Z","steps":["trace[330945948] 'process raft request' (duration: 227.825564ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:09:08.775124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.105974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-09T15:09:08.775175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.616005ms","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-09T15:09:08.775214Z","caller":"traceutil/trace.go:171","msg":"trace[976097712] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1272; }","duration":"707.234267ms","start":"2026-06-09T15:09:08.067957Z","end":"2026-06-09T15:09:08.775191Z","steps":["trace[976097712] 'range keys from in-memory index tree' (duration: 706.93499ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:09:08.775236Z","caller":"traceutil/trace.go:171","msg":"trace[116772590] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1272; }","duration":"881.717538ms","start":"2026-06-09T15:09:07.893504Z","end":"2026-06-09T15:09:08.775221Z","steps":["trace[116772590] 'range keys from in-memory index tree' (duration: 881.468622ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:09:08.775266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:09:07.893486Z","time spent":"881.773209ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-09T15:09:08.77527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:09:08.067938Z","time spent":"707.3218ms","remote":"127.0.0.1:60138","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-09T15:09:08.775193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.26915939s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:09:08.775405Z","caller":"traceutil/trace.go:171","msg":"trace[812573304] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1272; }","duration":"1.269430327s","start":"2026-06-09T15:09:07.505963Z","end":"2026-06-09T15:09:08.775393Z","steps":["trace[812573304] 'range keys from in-memory index tree' (duration: 1.269089289s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:09:08.775432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:09:07.505943Z","time spent":"1.269481968s","remote":"127.0.0.1:59888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-09T15:09:08.775515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.67949ms","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-09T15:09:08.775648Z","caller":"traceutil/trace.go:171","msg":"trace[597870687] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1272; }","duration":"676.829474ms","start":"2026-06-09T15:09:08.0988Z","end":"2026-06-09T15:09:08.775629Z","steps":["trace[597870687] 'range keys from in-memory index tree' (duration: 676.562088ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:09:08.775737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.523617ms","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-09T15:09:08.775772Z","caller":"traceutil/trace.go:171","msg":"trace[73158736] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1272; }","duration":"673.598909ms","start":"2026-06-09T15:09:08.102166Z","end":"2026-06-09T15:09:08.775765Z","steps":["trace[73158736] 'range keys from in-memory index tree' (duration: 673.406364ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:09:08.775796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:09:08.102137Z","time spent":"673.65015ms","remote":"127.0.0.1:60138","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-09T15:09:08.775726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.099302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T15:09:08.775737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:09:08.098783Z","time spent":"676.928665ms","remote":"127.0.0.1:60138","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":"info","ts":"2026-06-09T15:09:08.775907Z","caller":"traceutil/trace.go:171","msg":"trace[975776109] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1272; }","duration":"589.317257ms","start":"2026-06-09T15:09:08.186571Z","end":"2026-06-09T15:09:08.775888Z","steps":["trace[975776109] 'count revisions from in-memory index tree' (duration: 589.01129ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:09:08.775962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:09:08.186553Z","time spent":"589.390399ms","remote":"127.0.0.1:42484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-06-09T15:09:08.776423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.411128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-09T15:09:08.776551Z","caller":"traceutil/trace.go:171","msg":"trace[1049798161] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1272; }","duration":"674.558482ms","start":"2026-06-09T15:09:08.101976Z","end":"2026-06-09T15:09:08.776534Z","steps":["trace[1049798161] 'range keys from in-memory index tree' (duration: 672.79582ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:09:08.776597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:09:08.101968Z","time spent":"674.615113ms","remote":"127.0.0.1:60138","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-09T15:09:08.796652Z","caller":"traceutil/trace.go:171","msg":"trace[1687567528] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"557.50378ms","start":"2026-06-09T15:09:08.23908Z","end":"2026-06-09T15:09:08.796584Z","steps":["trace[1687567528] 'process raft request' (duration: 557.261734ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:09:08.797188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:09:08.239055Z","time spent":"557.786787ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T15:09:08.905046Z","caller":"traceutil/trace.go:171","msg":"trace[1078065787] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"123.434579ms","start":"2026-06-09T15:09:08.781572Z","end":"2026-06-09T15:09:08.905007Z","steps":["trace[1078065787] 'process raft request' (duration: 96.937485ms)","trace[1078065787] 'compare' (duration: 26.359081ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:09:08.905312Z","caller":"traceutil/trace.go:171","msg":"trace[611747025] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"123.604752ms","start":"2026-06-09T15:09:08.781684Z","end":"2026-06-09T15:09:08.905289Z","steps":["trace[611747025] 'process raft request' (duration: 123.289495ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:09:08.905358Z","caller":"traceutil/trace.go:171","msg":"trace[108502073] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"123.583121ms","start":"2026-06-09T15:09:08.781755Z","end":"2026-06-09T15:09:08.905338Z","steps":["trace[108502073] 'process raft request' (duration: 123.51198ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:09:08.905483Z","caller":"traceutil/trace.go:171","msg":"trace[1176379391] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"123.731565ms","start":"2026-06-09T15:09:08.781738Z","end":"2026-06-09T15:09:08.905469Z","steps":["trace[1176379391] 'process raft request' (duration: 123.449079ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:09:08.905509Z","caller":"traceutil/trace.go:171","msg":"trace[2078549291] linearizableReadLoop","detail":"{readStateIndex:1329; appliedIndex:1327; }","duration":"123.900659ms","start":"2026-06-09T15:09:08.781602Z","end":"2026-06-09T15:09:08.905502Z","steps":["trace[2078549291] 'read index received' (duration: 14.626459ms)","trace[2078549291] 'applied index is now lower than readState.Index' (duration: 109.2734ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:09:08.905559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.94488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:09:08.905577Z","caller":"traceutil/trace.go:171","msg":"trace[122115987] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1278; }","duration":"124.008512ms","start":"2026-06-09T15:09:08.781563Z","end":"2026-06-09T15:09:08.905572Z","steps":["trace[122115987] 'agreement among raft nodes before linearized reading' (duration: 123.960661ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:09:08.905612Z","caller":"traceutil/trace.go:171","msg":"trace[1344670528] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"123.798297ms","start":"2026-06-09T15:09:08.781786Z","end":"2026-06-09T15:09:08.905584Z","steps":["trace[1344670528] 'process raft request' (duration: 123.517661ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:09:10.484524Z","caller":"traceutil/trace.go:171","msg":"trace[1743794993] linearizableReadLoop","detail":"{readStateIndex:1335; appliedIndex:1334; }","duration":"195.345794ms","start":"2026-06-09T15:09:10.289155Z","end":"2026-06-09T15:09:10.484501Z","steps":["trace[1743794993] 'read index received' (duration: 195.18002ms)","trace[1743794993] 'applied index is now lower than readState.Index' (duration: 164.924µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:09:10.484594Z","caller":"traceutil/trace.go:171","msg":"trace[43901356] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"334.791943ms","start":"2026-06-09T15:09:10.149751Z","end":"2026-06-09T15:09:10.484543Z","steps":["trace[43901356] 'process raft request' (duration: 334.628049ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:09:10.484697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.510317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T15:09:10.484739Z","caller":"traceutil/trace.go:171","msg":"trace[1326522834] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1280; }","duration":"195.6ms","start":"2026-06-09T15:09:10.289127Z","end":"2026-06-09T15:09:10.484727Z","steps":["trace[1326522834] 'agreement among raft nodes before linearized reading' (duration: 195.462467ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:09:10.484757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:09:10.149732Z","time spent":"334.947407ms","remote":"127.0.0.1:59954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":892,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T15:09:10.911905Z","caller":"traceutil/trace.go:171","msg":"trace[2032660226] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"109.432564ms","start":"2026-06-09T15:09:10.802455Z","end":"2026-06-09T15:09:10.911888Z","steps":["trace[2032660226] 'process raft request' (duration: 109.27006ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:09:11.029378Z","caller":"traceutil/trace.go:171","msg":"trace[16452696] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"110.383696ms","start":"2026-06-09T15:09:10.918966Z","end":"2026-06-09T15:09:11.02935Z","steps":["trace[16452696] 'process raft request' (duration: 36.152027ms)","trace[16452696] 'compare' (duration: 74.049164ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:09:11.029559Z","caller":"traceutil/trace.go:171","msg":"trace[116010539] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"110.106529ms","start":"2026-06-09T15:09:10.919436Z","end":"2026-06-09T15:09:11.029543Z","steps":["trace[116010539] 'process raft request' (duration: 110.083469ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:09:11.029775Z","caller":"traceutil/trace.go:171","msg":"trace[1508582039] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"110.688273ms","start":"2026-06-09T15:09:10.919081Z","end":"2026-06-09T15:09:11.029769Z","steps":["trace[1508582039] 'process raft request' (duration: 110.234733ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:09:11.029804Z","caller":"traceutil/trace.go:171","msg":"trace[717732776] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"110.626942ms","start":"2026-06-09T15:09:10.919164Z","end":"2026-06-09T15:09:11.029791Z","steps":["trace[717732776] 'process raft request' (duration: 110.305444ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:09:11.029824Z","caller":"traceutil/trace.go:171","msg":"trace[293755368] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"110.615232ms","start":"2026-06-09T15:09:10.919203Z","end":"2026-06-09T15:09:11.029819Z","steps":["trace[293755368] 'process raft request' (duration: 110.299764ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:09:12.202428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"870.334004ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T15:09:12.202569Z","caller":"traceutil/trace.go:171","msg":"trace[1631458528] linearizableReadLoop","detail":"{readStateIndex:1343; appliedIndex:1342; }","duration":"697.366269ms","start":"2026-06-09T15:09:11.505186Z","end":"2026-06-09T15:09:12.202553Z","steps":["trace[1631458528] 'read index received' (duration: 42.121µs)","trace[1631458528] 'applied index is now lower than readState.Index' (duration: 697.322878ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:09:12.202651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.471632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:09:12.202676Z","caller":"traceutil/trace.go:171","msg":"trace[1903636045] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1287; }","duration":"697.528623ms","start":"2026-06-09T15:09:11.505139Z","end":"2026-06-09T15:09:12.202667Z","steps":["trace[1903636045] 'agreement among raft nodes before linearized reading' (duration: 697.458371ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:09:12.202664Z","caller":"traceutil/trace.go:171","msg":"trace[1409651151] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"952.036167ms","start":"2026-06-09T15:09:11.250598Z","end":"2026-06-09T15:09:12.202634Z","steps":["trace[1409651151] 'process raft request' (duration: 81.419506ms)","trace[1409651151] 'compare' (duration: 869.963256ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:09:12.202704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:09:11.505112Z","time spent":"697.586294ms","remote":"127.0.0.1:59888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-09T15:09:12.202819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:09:11.250581Z","time spent":"952.145329ms","remote":"127.0.0.1:59902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T15:09:12.704724Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967605156294682434,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-09T15:09:12.758424Z","caller":"traceutil/trace.go:171","msg":"trace[611343145] linearizableReadLoop","detail":"{readStateIndex:1344; appliedIndex:1343; }","duration":"554.685865ms","start":"2026-06-09T15:09:12.203672Z","end":"2026-06-09T15:09:12.758357Z","steps":["trace[611343145] 'read index received' (duration: 554.512261ms)","trace[611343145] 'applied index is now lower than readState.Index' (duration: 171.964µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:09:12.758534Z","caller":"traceutil/trace.go:171","msg":"trace[1465285477] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"642.509199ms","start":"2026-06-09T15:09:12.115931Z","end":"2026-06-09T15:09:12.758441Z","steps":["trace[1465285477] 'process raft request' (duration: 642.238672ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:09:12.758631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.827255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-06-09T15:09:12.758674Z","caller":"traceutil/trace.go:171","msg":"trace[10052196] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1288; }","duration":"553.918267ms","start":"2026-06-09T15:09:12.204744Z","end":"2026-06-09T15:09:12.758663Z","steps":["trace[10052196] 'agreement among raft nodes before linearized reading' (duration: 553.802144ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:09:12.758705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:09:12.204727Z","time spent":"553.970279ms","remote":"127.0.0.1:60044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-06-09T15:09:12.758457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.771477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T15:09:12.758882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:09:12.115903Z","time spent":"642.715774ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T15:09:12.759131Z","caller":"traceutil/trace.go:171","msg":"trace[1252370967] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1288; }","duration":"555.36384ms","start":"2026-06-09T15:09:12.203613Z","end":"2026-06-09T15:09:12.758977Z","steps":["trace[1252370967] 'agreement among raft nodes before linearized reading' (duration: 554.753836ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:09:12.759501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:09:12.203592Z","time spent":"555.889563ms","remote":"127.0.0.1:59884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-09T15:09:13.062019Z","caller":"traceutil/trace.go:171","msg":"trace[604679077] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"294.94619ms","start":"2026-06-09T15:09:12.767044Z","end":"2026-06-09T15:09:13.06199Z","steps":["trace[604679077] 'process raft request' (duration: 208.984219ms)","trace[604679077] 'compare' (duration: 85.862719ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:09:13.062093Z","caller":"traceutil/trace.go:171","msg":"trace[1885120022] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"144.128867ms","start":"2026-06-09T15:09:12.917944Z","end":"2026-06-09T15:09:13.062073Z","steps":["trace[1885120022] 'process raft request' (duration: 144.056775ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:09:13.062305Z","caller":"traceutil/trace.go:171","msg":"trace[50352313] linearizableReadLoop","detail":"{readStateIndex:1345; appliedIndex:1344; }","duration":"292.195956ms","start":"2026-06-09T15:09:12.770099Z","end":"2026-06-09T15:09:13.062295Z","steps":["trace[50352313] 'read index received' (duration: 205.935589ms)","trace[50352313] 'applied index is now lower than readState.Index' (duration: 86.259327ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:09:13.062405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.002342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T15:09:13.062399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.281488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:09:13.062436Z","caller":"traceutil/trace.go:171","msg":"trace[47435356] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1290; }","duration":"216.081904ms","start":"2026-06-09T15:09:12.846344Z","end":"2026-06-09T15:09:13.062426Z","steps":["trace[47435356] 'agreement among raft nodes before linearized reading' (duration: 216.020073ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:09:13.062444Z","caller":"traceutil/trace.go:171","msg":"trace[112964763] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1290; }","duration":"292.36009ms","start":"2026-06-09T15:09:12.770071Z","end":"2026-06-09T15:09:13.062431Z","steps":["trace[112964763] 'agreement among raft nodes before linearized reading' (duration: 292.277048ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:09:13.245584Z","caller":"traceutil/trace.go:171","msg":"trace[1258938612] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"178.466432ms","start":"2026-06-09T15:09:13.067094Z","end":"2026-06-09T15:09:13.24556Z","steps":["trace[1258938612] 'process raft request' (duration: 178.398621ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:09:13.245613Z","caller":"traceutil/trace.go:171","msg":"trace[2065895896] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"178.76099ms","start":"2026-06-09T15:09:13.066819Z","end":"2026-06-09T15:09:13.24558Z","steps":["trace[2065895896] 'process raft request' (duration: 173.723653ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:09:13.245845Z","caller":"traceutil/trace.go:171","msg":"trace[1692795752] linearizableReadLoop","detail":"{readStateIndex:1351; appliedIndex:1347; }","duration":"177.731335ms","start":"2026-06-09T15:09:13.068093Z","end":"2026-06-09T15:09:13.245825Z","steps":["trace[1692795752] 'read index received' (duration: 10.421521ms)","trace[1692795752] 'applied index is now lower than readState.Index' (duration: 167.308634ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:09:13.24595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.838208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:09:13.245978Z","caller":"traceutil/trace.go:171","msg":"trace[1924202098] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:1295; }","duration":"177.91587ms","start":"2026-06-09T15:09:13.068054Z","end":"2026-06-09T15:09:13.24597Z","steps":["trace[1924202098] 'agreement among raft nodes before linearized reading' (duration: 177.846738ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:09:13.24598Z","caller":"traceutil/trace.go:171","msg":"trace[561361769] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"177.9359ms","start":"2026-06-09T15:09:13.068032Z","end":"2026-06-09T15:09:13.245968Z","steps":["trace[561361769] 'process raft request' (duration: 177.688525ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:09:13.246126Z","caller":"traceutil/trace.go:171","msg":"trace[942929828] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"178.937884ms","start":"2026-06-09T15:09:13.067118Z","end":"2026-06-09T15:09:13.246056Z","steps":["trace[942929828] 'process raft request' (duration: 178.412491ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:09:13.250168Z","caller":"traceutil/trace.go:171","msg":"trace[1497668372] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"158.575383ms","start":"2026-06-09T15:09:13.091581Z","end":"2026-06-09T15:09:13.250156Z","steps":["trace[1497668372] 'process raft request' (duration: 158.521102ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:09:17.297981Z","caller":"traceutil/trace.go:171","msg":"trace[1874111398] linearizableReadLoop","detail":"{readStateIndex:1370; appliedIndex:1369; }","duration":"208.921758ms","start":"2026-06-09T15:09:17.089041Z","end":"2026-06-09T15:09:17.297963Z","steps":["trace[1874111398] 'read index received' (duration: 208.810865ms)","trace[1874111398] 'applied index is now lower than readState.Index' (duration: 110.103µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:09:17.298084Z","caller":"traceutil/trace.go:171","msg":"trace[1180886411] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"222.789389ms","start":"2026-06-09T15:09:17.075281Z","end":"2026-06-09T15:09:17.298071Z","steps":["trace[1180886411] 'process raft request' (duration: 222.484732ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:09:17.298282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.233276ms","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-09T15:09:17.298337Z","caller":"traceutil/trace.go:171","msg":"trace[1492681156] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1314; }","duration":"209.336377ms","start":"2026-06-09T15:09:17.088987Z","end":"2026-06-09T15:09:17.298324Z","steps":["trace[1492681156] 'agreement among raft nodes before linearized reading' (duration: 209.198684ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:09:18.187921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.196614ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-09T15:09:18.188152Z","caller":"traceutil/trace.go:171","msg":"trace[1084705846] linearizableReadLoop","detail":"{readStateIndex:1371; appliedIndex:1370; }","duration":"890.08747ms","start":"2026-06-09T15:09:17.298043Z","end":"2026-06-09T15:09:18.188131Z","steps":["trace[1084705846] 'read index received' (duration: 40.706032ms)","trace[1084705846] 'applied index is now lower than readState.Index' (duration: 849.380198ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:09:18.18857Z","caller":"traceutil/trace.go:171","msg":"trace[298407821] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"885.873383ms","start":"2026-06-09T15:09:17.30268Z","end":"2026-06-09T15:09:18.188553Z","steps":["trace[298407821] 'process raft request' (duration: 885.345931ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:09:18.188582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"926.006972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-06-09T15:09:18.18858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"927.132168ms","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-09T15:09:18.188649Z","caller":"traceutil/trace.go:171","msg":"trace[1374684942] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1314; }","duration":"926.092475ms","start":"2026-06-09T15:09:17.262531Z","end":"2026-06-09T15:09:18.188623Z","steps":["trace[1374684942] 'agreement among raft nodes before linearized reading' (duration: 925.911331ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:09:18.188706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:09:17.262517Z","time spent":"926.176597ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-09T15:09:18.188697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:09:17.302657Z","time spent":"885.964565ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T15:09:18.188654Z","caller":"traceutil/trace.go:171","msg":"trace[1670066133] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1314; }","duration":"927.257181ms","start":"2026-06-09T15:09:17.261381Z","end":"2026-06-09T15:09:18.188638Z","steps":["trace[1670066133] 'agreement among raft nodes before linearized reading' (duration: 927.064517ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:09:18.188779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:09:17.261355Z","time spent":"927.406075ms","remote":"127.0.0.1:60138","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-09T15:09:18.188562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"925.831649ms","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-09T15:09:18.188898Z","caller":"traceutil/trace.go:171","msg":"trace[441933852] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1314; }","duration":"926.171476ms","start":"2026-06-09T15:09:17.262684Z","end":"2026-06-09T15:09:18.188856Z","steps":["trace[441933852] 'agreement among raft nodes before linearized reading' (duration: 925.735216ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:09:18.188966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:09:17.262671Z","time spent":"926.272369ms","remote":"127.0.0.1:60138","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-09T15:09:18.197181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"934.398617ms","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-09T15:09:18.197288Z","caller":"traceutil/trace.go:171","msg":"trace[838777384] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1315; }","duration":"934.54961ms","start":"2026-06-09T15:09:17.262724Z","end":"2026-06-09T15:09:18.197273Z","steps":["trace[838777384] 'agreement among raft nodes before linearized reading' (duration: 925.88739ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:09:18.197346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:09:17.262695Z","time spent":"934.640123ms","remote":"127.0.0.1:60138","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-09T15:09:18.197364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.724327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:09:18.197473Z","caller":"traceutil/trace.go:171","msg":"trace[418977278] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1315; }","duration":"692.041504ms","start":"2026-06-09T15:09:17.505413Z","end":"2026-06-09T15:09:18.197455Z","steps":["trace[418977278] 'agreement among raft nodes before linearized reading' (duration: 683.114918ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:09:18.197514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:09:17.505391Z","time spent":"692.112147ms","remote":"127.0.0.1:59888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-09T15:09:33.172802Z","caller":"traceutil/trace.go:171","msg":"trace[431138607] transaction","detail":"{read_only:false; response_revision:1409; number_of_response:1; }","duration":"100.58416ms","start":"2026-06-09T15:09:33.072194Z","end":"2026-06-09T15:09:33.172778Z","steps":["trace[431138607] 'process raft request' (duration: 100.432356ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:09:44.695482Z","caller":"traceutil/trace.go:171","msg":"trace[1379462961] linearizableReadLoop","detail":"{readStateIndex:1525; appliedIndex:1523; }","duration":"151.168821ms","start":"2026-06-09T15:09:44.544286Z","end":"2026-06-09T15:09:44.695454Z","steps":["trace[1379462961] 'read index received' (duration: 22.992153ms)","trace[1379462961] 'applied index is now lower than readState.Index' (duration: 128.174818ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:09:44.695517Z","caller":"traceutil/trace.go:171","msg":"trace[542433921] transaction","detail":"{read_only:false; response_revision:1463; number_of_response:1; }","duration":"153.217118ms","start":"2026-06-09T15:09:44.542269Z","end":"2026-06-09T15:09:44.695486Z","steps":["trace[542433921] 'process raft request' (duration: 152.590154ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:09:44.695724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.406556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-06-09T15:09:44.695783Z","caller":"traceutil/trace.go:171","msg":"trace[76185246] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1463; }","duration":"151.495818ms","start":"2026-06-09T15:09:44.544269Z","end":"2026-06-09T15:09:44.695765Z","steps":["trace[76185246] 'agreement among raft nodes before linearized reading' (duration: 151.296953ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:10:57.879776Z","caller":"traceutil/trace.go:171","msg":"trace[1915307778] transaction","detail":"{read_only:false; response_revision:1797; number_of_response:1; }","duration":"149.325227ms","start":"2026-06-09T15:10:57.730414Z","end":"2026-06-09T15:10:57.879739Z","steps":["trace[1915307778] 'process raft request' (duration: 149.125742ms)"],"step_count":1}