{"level":"warn","ts":"2026-05-28T06:57:16.320046Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-28T06:57:16.320165Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.186: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://162.253.55.186:2380","--initial-cluster=instance=https://162.253.55.186:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.186:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.186:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-28T06:57:16.320256Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-28T06:57:16.320265Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.186:2380"]} {"level":"info","ts":"2026-05-28T06:57:16.320326Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-28T06:57:16.320969Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.186:2379"]} {"level":"info","ts":"2026-05-28T06:57:16.321037Z","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://162.253.55.186:2380"],"listen-peer-urls":["https://162.253.55.186:2380"],"advertise-client-urls":["https://162.253.55.186:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.186:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.186:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-28T06:57:16.325774Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.451758ms"} {"level":"info","ts":"2026-05-28T06:57:16.339023Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"612d5169b08bb96b","cluster-id":"20ec8eafba602517"} {"level":"info","ts":"2026-05-28T06:57:16.339112Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b switched to configuration voters=()"} {"level":"info","ts":"2026-05-28T06:57:16.339147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became follower at term 0"} {"level":"info","ts":"2026-05-28T06:57:16.339275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 612d5169b08bb96b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-28T06:57:16.339293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became follower at term 1"} {"level":"info","ts":"2026-05-28T06:57:16.339348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b switched to configuration voters=(7002342510006352235)"} {"level":"warn","ts":"2026-05-28T06:57:16.350519Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-28T06:57:16.353742Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-28T06:57:16.355502Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-28T06:57:16.35843Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"612d5169b08bb96b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-28T06:57:16.358855Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-28T06:57:16.358955Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-28T06:57:16.358969Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-28T06:57:16.35893Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"612d5169b08bb96b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-28T06:57:16.359757Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b switched to configuration voters=(7002342510006352235)"} {"level":"info","ts":"2026-05-28T06:57:16.359957Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"20ec8eafba602517","local-member-id":"612d5169b08bb96b","added-peer-id":"612d5169b08bb96b","added-peer-peer-urls":["https://162.253.55.186:2380"]} {"level":"info","ts":"2026-05-28T06:57:16.361849Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-28T06:57:16.362113Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"612d5169b08bb96b","initial-advertise-peer-urls":["https://162.253.55.186:2380"],"listen-peer-urls":["https://162.253.55.186:2380"],"advertise-client-urls":["https://162.253.55.186:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.186:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-28T06:57:16.362159Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-28T06:57:16.3632Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.186:2380"} {"level":"info","ts":"2026-05-28T06:57:16.363244Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.186:2380"} {"level":"info","ts":"2026-05-28T06:57:16.940099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b is starting a new election at term 1"} {"level":"info","ts":"2026-05-28T06:57:16.940184Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became pre-candidate at term 1"} {"level":"info","ts":"2026-05-28T06:57:16.94024Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b received MsgPreVoteResp from 612d5169b08bb96b at term 1"} {"level":"info","ts":"2026-05-28T06:57:16.940273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became candidate at term 2"} {"level":"info","ts":"2026-05-28T06:57:16.940295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b received MsgVoteResp from 612d5169b08bb96b at term 2"} {"level":"info","ts":"2026-05-28T06:57:16.940321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became leader at term 2"} {"level":"info","ts":"2026-05-28T06:57:16.940357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 612d5169b08bb96b elected leader 612d5169b08bb96b at term 2"} {"level":"info","ts":"2026-05-28T06:57:16.941534Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T06:57:16.942483Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"612d5169b08bb96b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.186:2379]}","request-path":"/0/members/612d5169b08bb96b/attributes","cluster-id":"20ec8eafba602517","publish-timeout":"7s"} {"level":"info","ts":"2026-05-28T06:57:16.942492Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-28T06:57:16.942882Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-28T06:57:16.942916Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-28T06:57:16.943205Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"20ec8eafba602517","local-member-id":"612d5169b08bb96b","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T06:57:16.94333Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T06:57:16.943379Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T06:57:16.946031Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-28T06:57:16.946697Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-28T06:57:16.95068Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.186:2379"} {"level":"warn","ts":"2026-05-28T06:57:35.263776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.822713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-28T06:57:35.263875Z","caller":"traceutil/trace.go:171","msg":"trace[1841964998] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:376; }","duration":"122.988076ms","start":"2026-05-28T06:57:35.140872Z","end":"2026-05-28T06:57:35.26386Z","steps":["trace[1841964998] 'range keys from in-memory index tree' (duration: 122.7257ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T06:57:36.073442Z","caller":"traceutil/trace.go:171","msg":"trace[81016331] linearizableReadLoop","detail":"{readStateIndex:393; appliedIndex:392; }","duration":"218.846877ms","start":"2026-05-28T06:57:35.854573Z","end":"2026-05-28T06:57:36.07342Z","steps":["trace[81016331] 'read index received' (duration: 218.616151ms)","trace[81016331] 'applied index is now lower than readState.Index' (duration: 229.686µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T06:57:36.073486Z","caller":"traceutil/trace.go:171","msg":"trace[542165180] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"805.325352ms","start":"2026-05-28T06:57:35.268132Z","end":"2026-05-28T06:57:36.073457Z","steps":["trace[542165180] 'process raft request' (duration: 805.057605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:57:36.073655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.052672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T06:57:36.07373Z","caller":"traceutil/trace.go:171","msg":"trace[654170918] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:377; }","duration":"219.218436ms","start":"2026-05-28T06:57:35.854489Z","end":"2026-05-28T06:57:36.073708Z","steps":["trace[654170918] 'agreement among raft nodes before linearized reading' (duration: 219.090313ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:57:36.074203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:57:35.268107Z","time spent":"805.443145ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T06:57:37.330444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"947.864122ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T06:57:37.330578Z","caller":"traceutil/trace.go:171","msg":"trace[1392622903] linearizableReadLoop","detail":"{readStateIndex:394; appliedIndex:393; }","duration":"1.252397748s","start":"2026-05-28T06:57:36.078164Z","end":"2026-05-28T06:57:37.330561Z","steps":["trace[1392622903] 'read index received' (duration: 304.163367ms)","trace[1392622903] 'applied index is now lower than readState.Index' (duration: 948.233101ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T06:57:37.330693Z","caller":"traceutil/trace.go:171","msg":"trace[1676022217] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"1.252676064s","start":"2026-05-28T06:57:36.077979Z","end":"2026-05-28T06:57:37.330655Z","steps":["trace[1676022217] 'process raft request' (duration: 304.352861ms)","trace[1676022217] 'compare' (duration: 947.746019ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T06:57:37.330815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.249283244s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-28T06:57:37.330844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.821026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-28T06:57:37.330839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"958.743181ms","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-05-28T06:57:37.330867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:57:36.077948Z","time spent":"1.252829298s","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T06:57:37.330904Z","caller":"traceutil/trace.go:171","msg":"trace[1118462868] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:378; }","duration":"1.249428697s","start":"2026-05-28T06:57:36.081451Z","end":"2026-05-28T06:57:37.330879Z","steps":["trace[1118462868] 'agreement among raft nodes before linearized reading' (duration: 1.249272574s)"],"step_count":1} {"level":"info","ts":"2026-05-28T06:57:37.330915Z","caller":"traceutil/trace.go:171","msg":"trace[899800073] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:378; }","duration":"334.923849ms","start":"2026-05-28T06:57:36.995972Z","end":"2026-05-28T06:57:37.330896Z","steps":["trace[899800073] 'agreement among raft nodes before linearized reading' (duration: 334.786085ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T06:57:37.330914Z","caller":"traceutil/trace.go:171","msg":"trace[1680037371] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:378; }","duration":"958.878844ms","start":"2026-05-28T06:57:36.372019Z","end":"2026-05-28T06:57:37.330898Z","steps":["trace[1680037371] 'agreement among raft nodes before linearized reading' (duration: 958.761731ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:57:37.330951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:57:36.081429Z","time spent":"1.249514469s","remote":"127.0.0.1:34384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-28T06:57:37.330957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:57:36.995956Z","time spent":"334.99244ms","remote":"127.0.0.1:34622","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-05-28T06:57:37.330959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:57:36.371988Z","time spent":"958.964306ms","remote":"127.0.0.1:34622","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-05-28T06:57:37.3311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.25293708s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7303"} {"level":"info","ts":"2026-05-28T06:57:37.331301Z","caller":"traceutil/trace.go:171","msg":"trace[2010046196] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:378; }","duration":"1.253189917s","start":"2026-05-28T06:57:36.078097Z","end":"2026-05-28T06:57:37.331287Z","steps":["trace[2010046196] 'agreement among raft nodes before linearized reading' (duration: 1.252552842s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:57:37.331347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:57:36.07807Z","time spent":"1.253267548s","remote":"127.0.0.1:34540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7325,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-05-28T06:57:37.83986Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13360946912115701360,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-28T06:57:38.504151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.337958ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T06:57:38.504257Z","caller":"traceutil/trace.go:171","msg":"trace[2044416626] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"1.169257011s","start":"2026-05-28T06:57:37.33498Z","end":"2026-05-28T06:57:38.504237Z","steps":["trace[2044416626] 'process raft request' (duration: 559.732908ms)","trace[2044416626] 'compare' (duration: 609.080642ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T06:57:38.504317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:57:37.334958Z","time spent":"1.169331693s","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T06:57:38.684933Z","caller":"traceutil/trace.go:171","msg":"trace[654477675] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"1.347237466s","start":"2026-05-28T06:57:37.337674Z","end":"2026-05-28T06:57:38.684912Z","steps":["trace[654477675] 'process raft request' (duration: 1.34614503s)"],"step_count":1} {"level":"info","ts":"2026-05-28T06:57:38.685029Z","caller":"traceutil/trace.go:171","msg":"trace[1539962641] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"1.344705536s","start":"2026-05-28T06:57:37.340301Z","end":"2026-05-28T06:57:38.685006Z","steps":["trace[1539962641] 'process raft request' (duration: 1.344304566s)"],"step_count":1} {"level":"info","ts":"2026-05-28T06:57:38.684942Z","caller":"traceutil/trace.go:171","msg":"trace[1473969831] linearizableReadLoop","detail":"{readStateIndex:397; appliedIndex:394; }","duration":"1.346037447s","start":"2026-05-28T06:57:37.338877Z","end":"2026-05-28T06:57:38.684914Z","steps":["trace[1473969831] 'read index received' (duration: 555.823304ms)","trace[1473969831] 'applied index is now lower than readState.Index' (duration: 790.213313ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T06:57:38.685132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:57:37.340284Z","time spent":"1.344788138s","remote":"127.0.0.1:34540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7288,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T06:57:38.685179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.346282583s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T06:57:38.685279Z","caller":"traceutil/trace.go:171","msg":"trace[728862122] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:381; }","duration":"1.346371535s","start":"2026-05-28T06:57:37.338846Z","end":"2026-05-28T06:57:38.685218Z","steps":["trace[728862122] 'agreement among raft nodes before linearized reading' (duration: 1.346270483s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:57:38.68531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:57:37.337667Z","time spent":"1.347344928s","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T06:57:38.685383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:57:37.338835Z","time spent":"1.346534399s","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-28T06:57:38.685622Z","caller":"traceutil/trace.go:171","msg":"trace[1392277338] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"350.177212ms","start":"2026-05-28T06:57:38.335418Z","end":"2026-05-28T06:57:38.685595Z","steps":["trace[1392277338] 'process raft request' (duration: 349.742222ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:57:38.68587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:57:38.335385Z","time spent":"350.392337ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T06:57:38.685883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.640314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-28T06:57:38.686224Z","caller":"traceutil/trace.go:171","msg":"trace[555778084] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:382; }","duration":"606.959001ms","start":"2026-05-28T06:57:38.079202Z","end":"2026-05-28T06:57:38.686161Z","steps":["trace[555778084] 'agreement among raft nodes before linearized reading' (duration: 606.637804ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:57:38.686314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:57:38.079186Z","time spent":"607.110796ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-28T06:57:59.329483Z","caller":"traceutil/trace.go:171","msg":"trace[949223230] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"385.673936ms","start":"2026-05-28T06:57:58.943785Z","end":"2026-05-28T06:57:59.329459Z","steps":["trace[949223230] 'process raft request' (duration: 385.491282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:57:59.329735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:57:58.943779Z","time spent":"385.878591ms","remote":"127.0.0.1:34426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T06:57:59.329927Z","caller":"traceutil/trace.go:171","msg":"trace[511226545] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"385.436061ms","start":"2026-05-28T06:57:58.944469Z","end":"2026-05-28T06:57:59.329905Z","steps":["trace[511226545] 'process raft request' (duration: 384.937229ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:57:59.330042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:57:58.944463Z","time spent":"385.523563ms","remote":"127.0.0.1:34426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T06:57:59.330569Z","caller":"traceutil/trace.go:171","msg":"trace[1379262789] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"380.336619ms","start":"2026-05-28T06:57:58.950204Z","end":"2026-05-28T06:57:59.330541Z","steps":["trace[1379262789] 'process raft request' (duration: 379.467018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:57:59.330664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:57:58.95018Z","time spent":"380.439952ms","remote":"127.0.0.1:34426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-28T06:57:59.330714Z","caller":"traceutil/trace.go:171","msg":"trace[1420330543] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"380.462322ms","start":"2026-05-28T06:57:58.950225Z","end":"2026-05-28T06:57:59.330687Z","steps":["trace[1420330543] 'process raft request' (duration: 380.184196ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:57:59.330836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:57:58.950206Z","time spent":"380.576065ms","remote":"127.0.0.1:34426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-28T06:57:59.330957Z","caller":"traceutil/trace.go:171","msg":"trace[96101833] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"378.517985ms","start":"2026-05-28T06:57:58.952428Z","end":"2026-05-28T06:57:59.330946Z","steps":["trace[96101833] 'process raft request' (duration: 378.375742ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T06:57:59.330908Z","caller":"traceutil/trace.go:171","msg":"trace[896931734] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"379.10701ms","start":"2026-05-28T06:57:58.951763Z","end":"2026-05-28T06:57:59.33087Z","steps":["trace[896931734] 'process raft request' (duration: 378.995637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:57:59.331036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:57:58.95241Z","time spent":"378.601128ms","remote":"127.0.0.1:34426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-28T06:57:59.330974Z","caller":"traceutil/trace.go:171","msg":"trace[1178487350] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"379.651033ms","start":"2026-05-28T06:57:58.951202Z","end":"2026-05-28T06:57:59.330853Z","steps":["trace[1178487350] 'process raft request' (duration: 379.452819ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T06:57:59.331152Z","caller":"traceutil/trace.go:171","msg":"trace[545162652] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"339.342104ms","start":"2026-05-28T06:57:58.991804Z","end":"2026-05-28T06:57:59.331146Z","steps":["trace[545162652] 'process raft request' (duration: 339.190391ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:57:59.33121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:57:58.991787Z","time spent":"339.394435ms","remote":"127.0.0.1:34426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-28T06:57:59.331143Z","caller":"traceutil/trace.go:171","msg":"trace[769350389] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"369.068401ms","start":"2026-05-28T06:57:58.962052Z","end":"2026-05-28T06:57:59.33112Z","steps":["trace[769350389] 'process raft request' (duration: 368.826095ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:57:59.3315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:57:58.951758Z","time spent":"379.280634ms","remote":"127.0.0.1:34426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-28T06:57:59.331273Z","caller":"traceutil/trace.go:171","msg":"trace[1653014666] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"375.295439ms","start":"2026-05-28T06:57:58.955965Z","end":"2026-05-28T06:57:59.33126Z","steps":["trace[1653014666] 'process raft request' (duration: 374.874439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:57:59.331653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:57:58.961948Z","time spent":"369.45526ms","remote":"127.0.0.1:34426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-28T06:57:59.331746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:57:58.95594Z","time spent":"375.659678ms","remote":"127.0.0.1:34426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-28T06:57:59.331333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:57:58.951194Z","time spent":"380.019772ms","remote":"127.0.0.1:34426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-28T06:57:59.331119Z","caller":"traceutil/trace.go:171","msg":"trace[1876991851] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"336.572997ms","start":"2026-05-28T06:57:58.994533Z","end":"2026-05-28T06:57:59.331106Z","steps":["trace[1876991851] 'process raft request' (duration: 336.528016ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:57:59.332003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:57:58.994525Z","time spent":"337.448429ms","remote":"127.0.0.1:34426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-28T06:57:59.343955Z","caller":"traceutil/trace.go:171","msg":"trace[289361326] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"238.834513ms","start":"2026-05-28T06:57:59.1051Z","end":"2026-05-28T06:57:59.343935Z","steps":["trace[289361326] 'process raft request' (duration: 233.750992ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T06:57:59.344129Z","caller":"traceutil/trace.go:171","msg":"trace[1364925087] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"151.788152ms","start":"2026-05-28T06:57:59.192326Z","end":"2026-05-28T06:57:59.344114Z","steps":["trace[1364925087] 'process raft request' (duration: 151.536506ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T06:58:02.21Z","caller":"traceutil/trace.go:171","msg":"trace[14931155] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"165.116538ms","start":"2026-05-28T06:58:02.044856Z","end":"2026-05-28T06:58:02.209973Z","steps":["trace[14931155] 'process raft request' (duration: 164.754269ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:58:02.594793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.225346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T06:58:02.594875Z","caller":"traceutil/trace.go:171","msg":"trace[1961383185] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:581; }","duration":"309.354949ms","start":"2026-05-28T06:58:02.2855Z","end":"2026-05-28T06:58:02.594855Z","steps":["trace[1961383185] 'range keys from in-memory index tree' (duration: 309.132614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:58:02.594923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:58:02.285484Z","time spent":"309.42961ms","remote":"127.0.0.1:34556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-05-28T06:58:04.644736Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13360946912115701754,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-28T06:58:04.733196Z","caller":"traceutil/trace.go:171","msg":"trace[926721306] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:616; }","duration":"588.74864ms","start":"2026-05-28T06:58:04.144421Z","end":"2026-05-28T06:58:04.733169Z","steps":["trace[926721306] 'read index received' (duration: 587.980811ms)","trace[926721306] 'applied index is now lower than readState.Index' (duration: 766.899µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T06:58:04.733262Z","caller":"traceutil/trace.go:171","msg":"trace[966779326] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"730.84955ms","start":"2026-05-28T06:58:04.002389Z","end":"2026-05-28T06:58:04.733239Z","steps":["trace[966779326] 'process raft request' (duration: 730.258196ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:58:04.733346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.896283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-28T06:58:04.733435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:58:04.002365Z","time spent":"730.988704ms","remote":"127.0.0.1:34462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036193,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T06:58:04.73353Z","caller":"traceutil/trace.go:171","msg":"trace[1335191583] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:591; }","duration":"589.049597ms","start":"2026-05-28T06:58:04.144386Z","end":"2026-05-28T06:58:04.733436Z","steps":["trace[1335191583] 'agreement among raft nodes before linearized reading' (duration: 588.874383ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:58:04.733829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:58:04.144368Z","time spent":"589.449436ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-28T06:58:07.246753Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13360946912115701783,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-28T06:58:07.747951Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13360946912115701783,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-28T06:58:08.135987Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.719555392s","expected-duration":"1s"} {"level":"info","ts":"2026-05-28T06:58:08.137074Z","caller":"traceutil/trace.go:171","msg":"trace[379419455] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"1.963970277s","start":"2026-05-28T06:58:06.173081Z","end":"2026-05-28T06:58:08.137051Z","steps":["trace[379419455] 'process raft request' (duration: 1.963369593s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:58:08.137212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:58:06.173053Z","time spent":"1.96407319s","remote":"127.0.0.1:34462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T06:58:08.137382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.391252831s","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-05-28T06:58:08.137542Z","caller":"traceutil/trace.go:171","msg":"trace[392426211] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:605; }","duration":"1.391470566s","start":"2026-05-28T06:58:06.746052Z","end":"2026-05-28T06:58:08.137523Z","steps":["trace[392426211] 'agreement among raft nodes before linearized reading' (duration: 1.391115518s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:58:08.137601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:58:06.74603Z","time spent":"1.391562068s","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-28T06:58:08.137675Z","caller":"traceutil/trace.go:171","msg":"trace[1874408392] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:631; }","duration":"1.390897752s","start":"2026-05-28T06:58:06.746112Z","end":"2026-05-28T06:58:08.13701Z","steps":["trace[1874408392] 'read index received' (duration: 1.390061932s)","trace[1874408392] 'applied index is now lower than readState.Index' (duration: 834.89µs)"],"step_count":2} {"level":"warn","ts":"2026-05-28T06:58:08.138032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.642287ms","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-05-28T06:58:08.138062Z","caller":"traceutil/trace.go:171","msg":"trace[1754019476] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:605; }","duration":"628.696418ms","start":"2026-05-28T06:58:07.509357Z","end":"2026-05-28T06:58:08.138053Z","steps":["trace[1754019476] 'agreement among raft nodes before linearized reading' (duration: 628.622706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:58:08.138081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:58:07.50934Z","time spent":"628.73682ms","remote":"127.0.0.1:34622","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-05-28T06:58:08.138342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.316846611s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-28T06:58:08.138368Z","caller":"traceutil/trace.go:171","msg":"trace[436171476] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:605; }","duration":"1.316907212s","start":"2026-05-28T06:58:06.821454Z","end":"2026-05-28T06:58:08.138362Z","steps":["trace[436171476] 'agreement among raft nodes before linearized reading' (duration: 1.316838081s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:58:08.138386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:58:06.821445Z","time spent":"1.316937193s","remote":"127.0.0.1:34622","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-05-28T06:58:08.138442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"992.534794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T06:58:08.138796Z","caller":"traceutil/trace.go:171","msg":"trace[194944189] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:605; }","duration":"993.029386ms","start":"2026-05-28T06:58:07.145749Z","end":"2026-05-28T06:58:08.138779Z","steps":["trace[194944189] 'agreement among raft nodes before linearized reading' (duration: 992.519154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:58:08.138855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:58:07.145733Z","time spent":"993.107208ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-28T06:58:08.138656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.317930067s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-28T06:58:08.139082Z","caller":"traceutil/trace.go:171","msg":"trace[1392345233] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:605; }","duration":"1.318402118s","start":"2026-05-28T06:58:06.820668Z","end":"2026-05-28T06:58:08.13907Z","steps":["trace[1392345233] 'agreement among raft nodes before linearized reading' (duration: 1.317158779s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:58:08.139123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:58:06.820649Z","time spent":"1.318463499s","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-28T06:58:08.377641Z","caller":"traceutil/trace.go:171","msg":"trace[1506153328] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"235.920883ms","start":"2026-05-28T06:58:08.141701Z","end":"2026-05-28T06:58:08.377622Z","steps":["trace[1506153328] 'process raft request' (duration: 224.572513ms)","trace[1506153328] 'compare' (duration: 11.116325ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T06:58:08.377725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.394782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T06:58:08.377764Z","caller":"traceutil/trace.go:171","msg":"trace[1589422080] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:610; }","duration":"230.469443ms","start":"2026-05-28T06:58:08.147286Z","end":"2026-05-28T06:58:08.377755Z","steps":["trace[1589422080] 'agreement among raft nodes before linearized reading' (duration: 230.383411ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T06:58:08.377632Z","caller":"traceutil/trace.go:171","msg":"trace[1156636997] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:632; }","duration":"230.29966ms","start":"2026-05-28T06:58:08.147313Z","end":"2026-05-28T06:58:08.377613Z","steps":["trace[1156636997] 'read index received' (duration: 218.982111ms)","trace[1156636997] 'applied index is now lower than readState.Index' (duration: 11.316379ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T06:58:08.377913Z","caller":"traceutil/trace.go:171","msg":"trace[1470902190] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"235.675277ms","start":"2026-05-28T06:58:08.14223Z","end":"2026-05-28T06:58:08.377905Z","steps":["trace[1470902190] 'process raft request' (duration: 235.272247ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T06:58:08.377972Z","caller":"traceutil/trace.go:171","msg":"trace[145544310] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"235.613145ms","start":"2026-05-28T06:58:08.142353Z","end":"2026-05-28T06:58:08.377966Z","steps":["trace[145544310] 'process raft request' (duration: 235.176855ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T06:58:08.37794Z","caller":"traceutil/trace.go:171","msg":"trace[395585349] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"230.541186ms","start":"2026-05-28T06:58:08.147346Z","end":"2026-05-28T06:58:08.377887Z","steps":["trace[395585349] 'process raft request' (duration: 230.208338ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T06:58:08.378116Z","caller":"traceutil/trace.go:171","msg":"trace[518289578] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"178.447946ms","start":"2026-05-28T06:58:08.199662Z","end":"2026-05-28T06:58:08.37811Z","steps":["trace[518289578] 'process raft request' (duration: 177.916033ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T06:58:10.466968Z","caller":"traceutil/trace.go:171","msg":"trace[823313175] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"439.981239ms","start":"2026-05-28T06:58:10.026967Z","end":"2026-05-28T06:58:10.466948Z","steps":["trace[823313175] 'process raft request' (duration: 439.848236ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:58:10.467104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:58:10.026935Z","time spent":"440.107052ms","remote":"127.0.0.1:34426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10362,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T06:58:10.46988Z","caller":"traceutil/trace.go:171","msg":"trace[194449695] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"300.252074ms","start":"2026-05-28T06:58:10.169609Z","end":"2026-05-28T06:58:10.469861Z","steps":["trace[194449695] 'process raft request' (duration: 299.998218ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:58:10.470105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:58:10.16958Z","time spent":"300.436859ms","remote":"127.0.0.1:34426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-28T06:58:10.470205Z","caller":"traceutil/trace.go:171","msg":"trace[965535580] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"100.667766ms","start":"2026-05-28T06:58:10.369527Z","end":"2026-05-28T06:58:10.470195Z","steps":["trace[965535580] 'process raft request' (duration: 100.167344ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T06:58:13.690648Z","caller":"traceutil/trace.go:171","msg":"trace[765277645] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"115.073228ms","start":"2026-05-28T06:58:13.575543Z","end":"2026-05-28T06:58:13.690616Z","steps":["trace[765277645] 'process raft request' (duration: 114.781311ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T06:58:14.102305Z","caller":"traceutil/trace.go:171","msg":"trace[92680388] linearizableReadLoop","detail":"{readStateIndex:720; appliedIndex:719; }","duration":"333.554886ms","start":"2026-05-28T06:58:13.768722Z","end":"2026-05-28T06:58:14.102277Z","steps":["trace[92680388] 'read index received' (duration: 333.262939ms)","trace[92680388] 'applied index is now lower than readState.Index' (duration: 290.927µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T06:58:14.1024Z","caller":"traceutil/trace.go:171","msg":"trace[1051753723] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"504.151476ms","start":"2026-05-28T06:58:13.598232Z","end":"2026-05-28T06:58:14.102383Z","steps":["trace[1051753723] 'process raft request' (duration: 503.824258ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:58:14.102621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.876305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-05-28T06:58:14.102705Z","caller":"traceutil/trace.go:171","msg":"trace[1391795626] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:692; }","duration":"334.012457ms","start":"2026-05-28T06:58:13.768672Z","end":"2026-05-28T06:58:14.102685Z","steps":["trace[1391795626] 'agreement among raft nodes before linearized reading' (duration: 333.744951ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:58:14.102681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:58:13.598206Z","time spent":"504.387261ms","remote":"127.0.0.1:34426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-28T06:58:14.102755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:58:13.768653Z","time spent":"334.092709ms","remote":"127.0.0.1:34426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4202,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"info","ts":"2026-05-28T06:58:14.176788Z","caller":"traceutil/trace.go:171","msg":"trace[1383160069] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"219.946692ms","start":"2026-05-28T06:58:13.956819Z","end":"2026-05-28T06:58:14.176765Z","steps":["trace[1383160069] 'process raft request' (duration: 219.797679ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:58:14.177002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.220047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"warn","ts":"2026-05-28T06:58:14.177024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.562419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-28T06:58:14.177098Z","caller":"traceutil/trace.go:171","msg":"trace[1897389839] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:693; }","duration":"194.682072ms","start":"2026-05-28T06:58:13.982398Z","end":"2026-05-28T06:58:14.17708Z","steps":["trace[1897389839] 'agreement among raft nodes before linearized reading' (duration: 194.429076ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:58:14.177088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.931024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wgbpg\" ","response":"range_response_count:1 size:18337"} {"level":"info","ts":"2026-05-28T06:58:14.177167Z","caller":"traceutil/trace.go:171","msg":"trace[1129995017] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wgbpg; range_end:; response_count:1; response_revision:693; }","duration":"223.075537ms","start":"2026-05-28T06:58:13.954069Z","end":"2026-05-28T06:58:14.177144Z","steps":["trace[1129995017] 'agreement among raft nodes before linearized reading' (duration: 222.804471ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T06:58:14.177198Z","caller":"traceutil/trace.go:171","msg":"trace[583447228] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:693; }","duration":"209.32821ms","start":"2026-05-28T06:58:13.967731Z","end":"2026-05-28T06:58:14.177059Z","steps":["trace[583447228] 'agreement among raft nodes before linearized reading' (duration: 209.058214ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T06:58:14.476614Z","caller":"traceutil/trace.go:171","msg":"trace[1001861789] linearizableReadLoop","detail":"{readStateIndex:722; appliedIndex:721; }","duration":"299.860914ms","start":"2026-05-28T06:58:14.176733Z","end":"2026-05-28T06:58:14.476594Z","steps":["trace[1001861789] 'read index received' (duration: 299.66058ms)","trace[1001861789] 'applied index is now lower than readState.Index' (duration: 199.634µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T06:58:14.476683Z","caller":"traceutil/trace.go:171","msg":"trace[1694534120] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"306.721648ms","start":"2026-05-28T06:58:14.169928Z","end":"2026-05-28T06:58:14.47665Z","steps":["trace[1694534120] 'process raft request' (duration: 306.473872ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:58:14.476803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.425976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-28T06:58:14.477052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:58:14.169888Z","time spent":"307.007565ms","remote":"127.0.0.1:34426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188228,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T06:58:14.47705Z","caller":"traceutil/trace.go:171","msg":"trace[1125520878] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:694; }","duration":"331.708242ms","start":"2026-05-28T06:58:14.145311Z","end":"2026-05-28T06:58:14.477019Z","steps":["trace[1125520878] 'agreement among raft nodes before linearized reading' (duration: 331.444686ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:58:14.47703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.006242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-05-28T06:58:14.47729Z","caller":"traceutil/trace.go:171","msg":"trace[747529261] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:694; }","duration":"309.31193ms","start":"2026-05-28T06:58:14.167965Z","end":"2026-05-28T06:58:14.477277Z","steps":["trace[747529261] 'agreement among raft nodes before linearized reading' (duration: 308.701985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:58:14.477521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:58:14.167945Z","time spent":"309.564505ms","remote":"127.0.0.1:34426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":24266,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-05-28T06:58:14.477299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:58:14.14529Z","time spent":"331.905797ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-28T06:58:14.514038Z","caller":"traceutil/trace.go:171","msg":"trace[322824626] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"333.205128ms","start":"2026-05-28T06:58:14.180816Z","end":"2026-05-28T06:58:14.514022Z","steps":["trace[322824626] 'process raft request' (duration: 332.829119ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:58:14.51426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:58:14.180806Z","time spent":"333.29979ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T06:58:14.514398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.348083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"info","ts":"2026-05-28T06:58:14.514458Z","caller":"traceutil/trace.go:171","msg":"trace[434997935] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:696; }","duration":"146.444915ms","start":"2026-05-28T06:58:14.367997Z","end":"2026-05-28T06:58:14.514442Z","steps":["trace[434997935] 'agreement among raft nodes before linearized reading' (duration: 146.22808ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T06:58:14.514848Z","caller":"traceutil/trace.go:171","msg":"trace[1933879614] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"325.170567ms","start":"2026-05-28T06:58:14.189664Z","end":"2026-05-28T06:58:14.514834Z","steps":["trace[1933879614] 'process raft request' (duration: 324.095382ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:58:14.514961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:58:14.189618Z","time spent":"325.26826ms","remote":"127.0.0.1:34540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18501,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T06:58:20.785006Z","caller":"traceutil/trace.go:171","msg":"trace[333479579] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"220.440095ms","start":"2026-05-28T06:58:20.564545Z","end":"2026-05-28T06:58:20.784985Z","steps":["trace[333479579] 'process raft request' (duration: 220.396184ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T06:58:20.785026Z","caller":"traceutil/trace.go:171","msg":"trace[84942706] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"220.827464ms","start":"2026-05-28T06:58:20.564176Z","end":"2026-05-28T06:58:20.785004Z","steps":["trace[84942706] 'process raft request' (duration: 184.865539ms)","trace[84942706] 'compare' (duration: 35.766291ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T06:58:20.785096Z","caller":"traceutil/trace.go:171","msg":"trace[677762027] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"220.733832ms","start":"2026-05-28T06:58:20.564327Z","end":"2026-05-28T06:58:20.785061Z","steps":["trace[677762027] 'process raft request' (duration: 220.583468ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:58:25.154335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.311327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-28T06:58:25.154422Z","caller":"traceutil/trace.go:171","msg":"trace[382650542] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:775; }","duration":"556.447631ms","start":"2026-05-28T06:58:24.597954Z","end":"2026-05-28T06:58:25.154402Z","steps":["trace[382650542] 'range keys from in-memory index tree' (duration: 556.073941ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:58:25.154454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:58:24.597932Z","time spent":"556.515392ms","remote":"127.0.0.1:34622","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-05-28T06:58:25.154631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.926279ms","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-05-28T06:58:25.154736Z","caller":"traceutil/trace.go:171","msg":"trace[458379524] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:775; }","duration":"356.069872ms","start":"2026-05-28T06:58:24.798641Z","end":"2026-05-28T06:58:25.154711Z","steps":["trace[458379524] 'range keys from in-memory index tree' (duration: 355.728795ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:58:25.154766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.101485039s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-28T06:58:25.154788Z","caller":"traceutil/trace.go:171","msg":"trace[1518280701] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:775; }","duration":"1.101567621s","start":"2026-05-28T06:58:24.053214Z","end":"2026-05-28T06:58:25.154781Z","steps":["trace[1518280701] 'count revisions from in-memory index tree' (duration: 1.101398807s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:58:25.154816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:58:24.053192Z","time spent":"1.101618773s","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":7,"response size":29,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-05-28T06:58:25.154819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:58:24.79862Z","time spent":"356.154765ms","remote":"127.0.0.1:34622","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-05-28T06:58:25.154942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.101054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-28T06:58:25.154967Z","caller":"traceutil/trace.go:171","msg":"trace[1563426780] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:775; }","duration":"596.165195ms","start":"2026-05-28T06:58:24.558796Z","end":"2026-05-28T06:58:25.154961Z","steps":["trace[1563426780] 'range keys from in-memory index tree' (duration: 596.008392ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:58:25.154988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:58:24.558776Z","time spent":"596.207177ms","remote":"127.0.0.1:34622","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-05-28T06:58:25.155058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.009452989s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T06:58:25.155077Z","caller":"traceutil/trace.go:171","msg":"trace[512747078] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:775; }","duration":"1.009499531s","start":"2026-05-28T06:58:24.145573Z","end":"2026-05-28T06:58:25.155072Z","steps":["trace[512747078] 'range keys from in-memory index tree' (duration: 1.009354297s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:58:25.155097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:58:24.145557Z","time spent":"1.009532421s","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-28T06:58:25.155189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.046149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-28T06:58:25.15531Z","caller":"traceutil/trace.go:171","msg":"trace[1011190438] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:775; }","duration":"355.235483ms","start":"2026-05-28T06:58:24.800048Z","end":"2026-05-28T06:58:25.155284Z","steps":["trace[1011190438] 'range keys from in-memory index tree' (duration: 354.811683ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:58:25.155369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:58:24.800019Z","time spent":"355.334806ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-28T06:58:25.1558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.880408ms","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-05-28T06:58:25.155867Z","caller":"traceutil/trace.go:171","msg":"trace[2110089531] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:775; }","duration":"355.96485ms","start":"2026-05-28T06:58:24.799885Z","end":"2026-05-28T06:58:25.155849Z","steps":["trace[2110089531] 'range keys from in-memory index tree' (duration: 355.593292ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:58:25.155908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:58:24.799877Z","time spent":"356.019241ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-28T06:58:25.514061Z","caller":"traceutil/trace.go:171","msg":"trace[791967281] linearizableReadLoop","detail":"{readStateIndex:809; appliedIndex:807; }","duration":"357.429255ms","start":"2026-05-28T06:58:25.156613Z","end":"2026-05-28T06:58:25.514042Z","steps":["trace[791967281] 'read index received' (duration: 346.012853ms)","trace[791967281] 'applied index is now lower than readState.Index' (duration: 11.415312ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T06:58:25.514383Z","caller":"traceutil/trace.go:171","msg":"trace[1361589743] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"354.442164ms","start":"2026-05-28T06:58:25.159932Z","end":"2026-05-28T06:58:25.514374Z","steps":["trace[1361589743] 'process raft request' (duration: 354.068665ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T06:58:25.51439Z","caller":"traceutil/trace.go:171","msg":"trace[750350090] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"496.663667ms","start":"2026-05-28T06:58:25.01769Z","end":"2026-05-28T06:58:25.514354Z","steps":["trace[750350090] 'process raft request' (duration: 496.093154ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T06:58:25.514532Z","caller":"traceutil/trace.go:171","msg":"trace[946562017] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"355.326374ms","start":"2026-05-28T06:58:25.15915Z","end":"2026-05-28T06:58:25.514476Z","steps":["trace[946562017] 'process raft request' (duration: 354.775631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:58:25.514572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:58:25.017666Z","time spent":"496.800211ms","remote":"127.0.0.1:34598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T06:58:25.514551Z","caller":"traceutil/trace.go:171","msg":"trace[756276148] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"355.050698ms","start":"2026-05-28T06:58:25.159479Z","end":"2026-05-28T06:58:25.51453Z","steps":["trace[756276148] 'process raft request' (duration: 354.478775ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:58:25.514714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:58:25.159462Z","time spent":"355.170301ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T06:58:25.51472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.10652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T06:58:25.514781Z","caller":"traceutil/trace.go:171","msg":"trace[1972748562] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:781; }","duration":"358.200953ms","start":"2026-05-28T06:58:25.156562Z","end":"2026-05-28T06:58:25.514763Z","steps":["trace[1972748562] 'agreement among raft nodes before linearized reading' (duration: 358.106041ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:58:25.51476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:58:25.159126Z","time spent":"355.493609ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T06:58:25.514836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:58:25.156544Z","time spent":"358.279694ms","remote":"127.0.0.1:34384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-28T06:58:25.514981Z","caller":"traceutil/trace.go:171","msg":"trace[1799812563] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"354.477594ms","start":"2026-05-28T06:58:25.160491Z","end":"2026-05-28T06:58:25.514969Z","steps":["trace[1799812563] 'process raft request' (duration: 353.527551ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:58:25.515052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:58:25.160484Z","time spent":"354.542186ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T06:58:25.515244Z","caller":"traceutil/trace.go:171","msg":"trace[684512158] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"355.342205ms","start":"2026-05-28T06:58:25.159881Z","end":"2026-05-28T06:58:25.515223Z","steps":["trace[684512158] 'process raft request' (duration: 354.101025ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:58:25.515354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:58:25.159863Z","time spent":"355.436627ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T06:58:25.515826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:58:25.159907Z","time spent":"355.819856ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T06:58:37.858404Z","caller":"traceutil/trace.go:171","msg":"trace[1245686733] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"151.91609ms","start":"2026-05-28T06:58:37.706466Z","end":"2026-05-28T06:58:37.858383Z","steps":["trace[1245686733] 'process raft request' (duration: 99.329214ms)","trace[1245686733] 'compare' (duration: 52.462393ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T06:58:37.858625Z","caller":"traceutil/trace.go:171","msg":"trace[278530049] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"152.059163ms","start":"2026-05-28T06:58:37.70653Z","end":"2026-05-28T06:58:37.858589Z","steps":["trace[278530049] 'process raft request' (duration: 151.819347ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:58:39.976183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.980745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-28T06:58:39.976262Z","caller":"traceutil/trace.go:171","msg":"trace[1058530283] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:915; }","duration":"113.130519ms","start":"2026-05-28T06:58:39.863113Z","end":"2026-05-28T06:58:39.976243Z","steps":["trace[1058530283] 'range keys from in-memory index tree' (duration: 112.80751ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:58:39.976372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.128082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.186\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-28T06:58:39.976508Z","caller":"traceutil/trace.go:171","msg":"trace[1558704552] range","detail":"{range_begin:/registry/masterleases/162.253.55.186; range_end:; response_count:1; response_revision:915; }","duration":"114.306906ms","start":"2026-05-28T06:58:39.862175Z","end":"2026-05-28T06:58:39.976482Z","steps":["trace[1558704552] 'range keys from in-memory index tree' (duration: 113.889807ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:58:39.976636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.703492ms","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-05-28T06:58:39.976695Z","caller":"traceutil/trace.go:171","msg":"trace[1507353627] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:915; }","duration":"114.118192ms","start":"2026-05-28T06:58:39.862559Z","end":"2026-05-28T06:58:39.976677Z","steps":["trace[1507353627] 'range keys from in-memory index tree' (duration: 113.530528ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T06:58:54.329867Z","caller":"traceutil/trace.go:171","msg":"trace[1364045052] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"202.804715ms","start":"2026-05-28T06:58:54.127048Z","end":"2026-05-28T06:58:54.329853Z","steps":["trace[1364045052] 'process raft request' (duration: 202.688493ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T06:58:55.017043Z","caller":"traceutil/trace.go:171","msg":"trace[99454355] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"888.53332ms","start":"2026-05-28T06:58:54.128487Z","end":"2026-05-28T06:58:55.01702Z","steps":["trace[99454355] 'process raft request' (duration: 888.365086ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T06:58:55.017147Z","caller":"traceutil/trace.go:171","msg":"trace[1951752509] linearizableReadLoop","detail":"{readStateIndex:1007; appliedIndex:1005; }","duration":"871.655033ms","start":"2026-05-28T06:58:54.145477Z","end":"2026-05-28T06:58:55.017132Z","steps":["trace[1951752509] 'read index received' (duration: 184.27112ms)","trace[1951752509] 'applied index is now lower than readState.Index' (duration: 687.383073ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T06:58:55.017176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:58:54.128469Z","time spent":"888.640882ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T06:58:55.017243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"871.758625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T06:58:55.017276Z","caller":"traceutil/trace.go:171","msg":"trace[952238363] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:969; }","duration":"871.818827ms","start":"2026-05-28T06:58:54.145446Z","end":"2026-05-28T06:58:55.017264Z","steps":["trace[952238363] 'agreement among raft nodes before linearized reading' (duration: 871.749955ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:58:55.017305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:58:54.145433Z","time spent":"871.866028ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-28T06:58:55.017475Z","caller":"traceutil/trace.go:171","msg":"trace[1149563257] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"709.514423ms","start":"2026-05-28T06:58:54.307946Z","end":"2026-05-28T06:58:55.01746Z","steps":["trace[1149563257] 'process raft request' (duration: 709.038101ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:58:55.017536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:58:54.307929Z","time spent":"709.569494ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T06:59:00.712779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.751238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T06:59:00.71288Z","caller":"traceutil/trace.go:171","msg":"trace[1684704382] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:989; }","duration":"566.910742ms","start":"2026-05-28T06:59:00.145946Z","end":"2026-05-28T06:59:00.712857Z","steps":["trace[1684704382] 'range keys from in-memory index tree' (duration: 566.650166ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:59:00.712931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:59:00.145923Z","time spent":"566.997635ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-28T06:59:00.713147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.696183ms","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-05-28T06:59:00.713185Z","caller":"traceutil/trace.go:171","msg":"trace[1134040077] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:989; }","duration":"362.769225ms","start":"2026-05-28T06:59:00.350406Z","end":"2026-05-28T06:59:00.713175Z","steps":["trace[1134040077] 'range keys from in-memory index tree' (duration: 362.462918ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:59:00.713234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:59:00.350386Z","time spent":"362.841637ms","remote":"127.0.0.1:34622","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-05-28T06:59:01.400477Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13360946912115702632,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-28T06:59:01.657014Z","caller":"traceutil/trace.go:171","msg":"trace[174820121] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"940.989722ms","start":"2026-05-28T06:59:00.715997Z","end":"2026-05-28T06:59:01.656987Z","steps":["trace[174820121] 'process raft request' (duration: 940.831988ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:59:01.657166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:59:00.715974Z","time spent":"941.115276ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T06:59:01.657827Z","caller":"traceutil/trace.go:171","msg":"trace[1842761817] linearizableReadLoop","detail":"{readStateIndex:1034; appliedIndex:1031; }","duration":"758.137005ms","start":"2026-05-28T06:59:00.899661Z","end":"2026-05-28T06:59:01.657798Z","steps":["trace[1842761817] 'read index received' (duration: 757.189813ms)","trace[1842761817] 'applied index is now lower than readState.Index' (duration: 945.602µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T06:59:01.658361Z","caller":"traceutil/trace.go:171","msg":"trace[1022011486] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"933.361283ms","start":"2026-05-28T06:59:00.724943Z","end":"2026-05-28T06:59:01.658305Z","steps":["trace[1022011486] 'process raft request' (duration: 932.644466ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:59:01.658815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.686198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-28T06:59:01.659167Z","caller":"traceutil/trace.go:171","msg":"trace[1590382448] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:992; }","duration":"759.543188ms","start":"2026-05-28T06:59:00.899608Z","end":"2026-05-28T06:59:01.659151Z","steps":["trace[1590382448] 'agreement among raft nodes before linearized reading' (duration: 758.552295ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T06:59:01.659176Z","caller":"traceutil/trace.go:171","msg":"trace[1248886329] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"625.462967ms","start":"2026-05-28T06:59:01.0337Z","end":"2026-05-28T06:59:01.659163Z","steps":["trace[1248886329] 'process raft request' (duration: 624.054424ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:59:01.659208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:59:00.899587Z","time spent":"759.60927ms","remote":"127.0.0.1:34770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-28T06:59:01.659244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:59:01.033679Z","time spent":"625.528809ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T06:59:01.659313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.735911ms","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-05-28T06:59:01.659381Z","caller":"traceutil/trace.go:171","msg":"trace[1198378535] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:992; }","duration":"587.849414ms","start":"2026-05-28T06:59:01.071513Z","end":"2026-05-28T06:59:01.659363Z","steps":["trace[1198378535] 'agreement among raft nodes before linearized reading' (duration: 587.620629ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:59:01.658853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:59:00.724921Z","time spent":"933.704732ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T06:59:01.65942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:59:01.071497Z","time spent":"587.914145ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-28T06:59:01.659546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.936131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-28T06:59:01.65957Z","caller":"traceutil/trace.go:171","msg":"trace[1708019501] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:992; }","duration":"377.957721ms","start":"2026-05-28T06:59:01.2816Z","end":"2026-05-28T06:59:01.659558Z","steps":["trace[1708019501] 'agreement among raft nodes before linearized reading' (duration: 377.91301ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:59:01.659587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:59:01.281545Z","time spent":"378.037323ms","remote":"127.0.0.1:34792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-05-28T06:59:19.923932Z","caller":"traceutil/trace.go:171","msg":"trace[1257396264] linearizableReadLoop","detail":"{readStateIndex:1094; appliedIndex:1093; }","duration":"135.626568ms","start":"2026-05-28T06:59:19.788272Z","end":"2026-05-28T06:59:19.923899Z","steps":["trace[1257396264] 'read index received' (duration: 135.441373ms)","trace[1257396264] 'applied index is now lower than readState.Index' (duration: 184.045µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T06:59:19.923983Z","caller":"traceutil/trace.go:171","msg":"trace[1481585446] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"162.1235ms","start":"2026-05-28T06:59:19.761836Z","end":"2026-05-28T06:59:19.923959Z","steps":["trace[1481585446] 'process raft request' (duration: 161.900825ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:59:19.924171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.362008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-28T06:59:19.924219Z","caller":"traceutil/trace.go:171","msg":"trace[636338879] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1049; }","duration":"131.415489ms","start":"2026-05-28T06:59:19.792792Z","end":"2026-05-28T06:59:19.924207Z","steps":["trace[636338879] 'agreement among raft nodes before linearized reading' (duration: 131.253935ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:59:19.924571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.290043ms","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-05-28T06:59:19.924634Z","caller":"traceutil/trace.go:171","msg":"trace[1270978538] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1049; }","duration":"136.400816ms","start":"2026-05-28T06:59:19.788222Z","end":"2026-05-28T06:59:19.924623Z","steps":["trace[1270978538] 'agreement among raft nodes before linearized reading' (duration: 135.806142ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:59:20.209849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.976077ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T06:59:20.210101Z","caller":"traceutil/trace.go:171","msg":"trace[1162082808] linearizableReadLoop","detail":"{readStateIndex:1099; appliedIndex:1095; }","duration":"215.097575ms","start":"2026-05-28T06:59:19.994987Z","end":"2026-05-28T06:59:20.210085Z","steps":["trace[1162082808] 'read index received' (duration: 69.80101ms)","trace[1162082808] 'applied index is now lower than readState.Index' (duration: 145.295615ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T06:59:20.2102Z","caller":"traceutil/trace.go:171","msg":"trace[537687118] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"281.224008ms","start":"2026-05-28T06:59:19.928966Z","end":"2026-05-28T06:59:20.21019Z","steps":["trace[537687118] 'process raft request' (duration: 281.057274ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T06:59:20.210241Z","caller":"traceutil/trace.go:171","msg":"trace[441236413] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"281.244329ms","start":"2026-05-28T06:59:19.928952Z","end":"2026-05-28T06:59:20.210197Z","steps":["trace[441236413] 'process raft request' (duration: 281.002164ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:59:20.210747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.438412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-28T06:59:20.210789Z","caller":"traceutil/trace.go:171","msg":"trace[1469109528] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1053; }","duration":"209.515493ms","start":"2026-05-28T06:59:20.001264Z","end":"2026-05-28T06:59:20.210779Z","steps":["trace[1469109528] 'agreement among raft nodes before linearized reading' (duration: 209.174285ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:59:20.210768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.77076ms","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-05-28T06:59:20.210839Z","caller":"traceutil/trace.go:171","msg":"trace[2086542505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1053; }","duration":"215.882863ms","start":"2026-05-28T06:59:19.994938Z","end":"2026-05-28T06:59:20.21082Z","steps":["trace[2086542505] 'agreement among raft nodes before linearized reading' (duration: 215.33307ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T06:59:20.210814Z","caller":"traceutil/trace.go:171","msg":"trace[987374291] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"281.72007ms","start":"2026-05-28T06:59:19.929064Z","end":"2026-05-28T06:59:20.210784Z","steps":["trace[987374291] 'process raft request' (duration: 280.991143ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T06:59:20.210842Z","caller":"traceutil/trace.go:171","msg":"trace[1134205077] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"282.364905ms","start":"2026-05-28T06:59:19.928455Z","end":"2026-05-28T06:59:20.21082Z","steps":["trace[1134205077] 'process raft request' (duration: 136.317933ms)","trace[1134205077] 'compare' (duration: 144.67551ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T06:59:24.438409Z","caller":"traceutil/trace.go:171","msg":"trace[1315081443] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"206.930973ms","start":"2026-05-28T06:59:24.231452Z","end":"2026-05-28T06:59:24.438383Z","steps":["trace[1315081443] 'process raft request' (duration: 206.80994ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T06:59:24.438431Z","caller":"traceutil/trace.go:171","msg":"trace[1263334807] linearizableReadLoop","detail":"{readStateIndex:1111; appliedIndex:1111; }","duration":"146.207956ms","start":"2026-05-28T06:59:24.292193Z","end":"2026-05-28T06:59:24.438401Z","steps":["trace[1263334807] 'read index received' (duration: 146.198356ms)","trace[1263334807] 'applied index is now lower than readState.Index' (duration: 7.76µs)"],"step_count":2} {"level":"warn","ts":"2026-05-28T06:59:24.43877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.848163ms","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-05-28T06:59:24.43883Z","caller":"traceutil/trace.go:171","msg":"trace[1556261085] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1065; }","duration":"204.935926ms","start":"2026-05-28T06:59:24.233878Z","end":"2026-05-28T06:59:24.438814Z","steps":["trace[1556261085] 'agreement among raft nodes before linearized reading' (duration: 204.767872ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:59:24.439048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.854153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-28T06:59:24.439292Z","caller":"traceutil/trace.go:171","msg":"trace[1731849351] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1065; }","duration":"205.178031ms","start":"2026-05-28T06:59:24.233921Z","end":"2026-05-28T06:59:24.439099Z","steps":["trace[1731849351] 'agreement among raft nodes before linearized reading' (duration: 204.549677ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T06:59:24.449252Z","caller":"traceutil/trace.go:171","msg":"trace[4654896] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"152.601146ms","start":"2026-05-28T06:59:24.296631Z","end":"2026-05-28T06:59:24.449232Z","steps":["trace[4654896] 'process raft request' (duration: 152.490723ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:59:25.094312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.331511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T06:59:25.094414Z","caller":"traceutil/trace.go:171","msg":"trace[1955196670] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1069; }","duration":"158.502205ms","start":"2026-05-28T06:59:24.935885Z","end":"2026-05-28T06:59:25.094387Z","steps":["trace[1955196670] 'count revisions from in-memory index tree' (duration: 158.200858ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:59:25.276991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.827356ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-28T06:59:25.277177Z","caller":"traceutil/trace.go:171","msg":"trace[31109613] linearizableReadLoop","detail":"{readStateIndex:1116; appliedIndex:1115; }","duration":"133.665211ms","start":"2026-05-28T06:59:25.143481Z","end":"2026-05-28T06:59:25.277146Z","steps":["trace[31109613] 'read index received' (duration: 40.031µs)","trace[31109613] 'applied index is now lower than readState.Index' (duration: 133.62267ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T06:59:25.277312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.815445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T06:59:25.277419Z","caller":"traceutil/trace.go:171","msg":"trace[2091267257] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1069; }","duration":"133.964757ms","start":"2026-05-28T06:59:25.143429Z","end":"2026-05-28T06:59:25.277394Z","steps":["trace[2091267257] 'agreement among raft nodes before linearized reading' (duration: 133.804454ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T06:59:26.763434Z","caller":"traceutil/trace.go:171","msg":"trace[1004866067] linearizableReadLoop","detail":"{readStateIndex:1118; appliedIndex:1117; }","duration":"320.838999ms","start":"2026-05-28T06:59:26.44257Z","end":"2026-05-28T06:59:26.763409Z","steps":["trace[1004866067] 'read index received' (duration: 320.603814ms)","trace[1004866067] 'applied index is now lower than readState.Index' (duration: 234.265µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T06:59:26.763523Z","caller":"traceutil/trace.go:171","msg":"trace[1821355984] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"463.120873ms","start":"2026-05-28T06:59:26.300365Z","end":"2026-05-28T06:59:26.763486Z","steps":["trace[1821355984] 'process raft request' (duration: 462.862077ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:59:26.76364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.918796ms","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-05-28T06:59:26.763682Z","caller":"traceutil/trace.go:171","msg":"trace[488582203] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1071; }","duration":"311.024918ms","start":"2026-05-28T06:59:26.452645Z","end":"2026-05-28T06:59:26.76367Z","steps":["trace[488582203] 'agreement among raft nodes before linearized reading' (duration: 310.890775ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:59:26.76364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.061264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-05-28T06:59:26.76372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:59:26.452618Z","time spent":"311.09294ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-28T06:59:26.763748Z","caller":"traceutil/trace.go:171","msg":"trace[566921281] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1071; }","duration":"321.214228ms","start":"2026-05-28T06:59:26.442521Z","end":"2026-05-28T06:59:26.763735Z","steps":["trace[566921281] 'agreement among raft nodes before linearized reading' (duration: 320.986623ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:59:26.763748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.973635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-28T06:59:26.763789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:59:26.442475Z","time spent":"321.30514ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-28T06:59:26.763811Z","caller":"traceutil/trace.go:171","msg":"trace[1720617644] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1071; }","duration":"296.065537ms","start":"2026-05-28T06:59:26.467729Z","end":"2026-05-28T06:59:26.763794Z","steps":["trace[1720617644] 'agreement among raft nodes before linearized reading' (duration: 295.823572ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:59:26.763962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:59:26.300348Z","time spent":"463.249706ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T06:59:26.764093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.63919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-28T06:59:26.764226Z","caller":"traceutil/trace.go:171","msg":"trace[1694555631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1071; }","duration":"296.859136ms","start":"2026-05-28T06:59:26.467343Z","end":"2026-05-28T06:59:26.764202Z","steps":["trace[1694555631] 'agreement among raft nodes before linearized reading' (duration: 296.422636ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:59:27.244701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.58391ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T06:59:27.245005Z","caller":"traceutil/trace.go:171","msg":"trace[1755939041] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"475.526454ms","start":"2026-05-28T06:59:26.769455Z","end":"2026-05-28T06:59:27.244981Z","steps":["trace[1755939041] 'process raft request' (duration: 475.385381ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T06:59:27.245079Z","caller":"traceutil/trace.go:171","msg":"trace[948353147] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"475.584385ms","start":"2026-05-28T06:59:26.769455Z","end":"2026-05-28T06:59:27.245039Z","steps":["trace[948353147] 'process raft request' (duration: 112.582565ms)","trace[948353147] 'compare' (duration: 362.324725ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T06:59:27.245173Z","caller":"traceutil/trace.go:171","msg":"trace[1095214685] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"475.622567ms","start":"2026-05-28T06:59:26.769508Z","end":"2026-05-28T06:59:27.245131Z","steps":["trace[1095214685] 'process raft request' (duration: 475.398031ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T06:59:27.245224Z","caller":"traceutil/trace.go:171","msg":"trace[722610152] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"475.582175ms","start":"2026-05-28T06:59:26.769624Z","end":"2026-05-28T06:59:27.245207Z","steps":["trace[722610152] 'process raft request' (duration: 475.305739ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:59:27.245384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:59:26.76949Z","time spent":"475.78109ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T06:59:27.245294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:59:26.769434Z","time spent":"475.73752ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T06:59:27.24513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:59:26.769434Z","time spent":"475.632957ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T06:59:27.24542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:59:26.76959Z","time spent":"475.79175ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T06:59:28.517359Z","caller":"traceutil/trace.go:171","msg":"trace[2072283111] linearizableReadLoop","detail":"{readStateIndex:1123; appliedIndex:1122; }","duration":"371.372607ms","start":"2026-05-28T06:59:28.145964Z","end":"2026-05-28T06:59:28.517337Z","steps":["trace[2072283111] 'read index received' (duration: 371.227113ms)","trace[2072283111] 'applied index is now lower than readState.Index' (duration: 144.484µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T06:59:28.517428Z","caller":"traceutil/trace.go:171","msg":"trace[2137416979] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"454.033689ms","start":"2026-05-28T06:59:28.063359Z","end":"2026-05-28T06:59:28.517393Z","steps":["trace[2137416979] 'process raft request' (duration: 453.819054ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:59:28.517479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.50372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T06:59:28.517511Z","caller":"traceutil/trace.go:171","msg":"trace[347698297] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1076; }","duration":"371.579982ms","start":"2026-05-28T06:59:28.145922Z","end":"2026-05-28T06:59:28.517502Z","steps":["trace[347698297] 'agreement among raft nodes before linearized reading' (duration: 371.50813ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:59:28.517543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:59:28.145906Z","time spent":"371.629923ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-28T06:59:28.517603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:59:28.063336Z","time spent":"454.168353ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T06:59:29.052499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.085423ms","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-05-28T06:59:29.052577Z","caller":"traceutil/trace.go:171","msg":"trace[734872167] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1076; }","duration":"283.214795ms","start":"2026-05-28T06:59:28.769344Z","end":"2026-05-28T06:59:29.052559Z","steps":["trace[734872167] 'range keys from in-memory index tree' (duration: 282.870907ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T06:59:29.528271Z","caller":"traceutil/trace.go:171","msg":"trace[976317835] linearizableReadLoop","detail":"{readStateIndex:1124; appliedIndex:1123; }","duration":"467.810564ms","start":"2026-05-28T06:59:29.060441Z","end":"2026-05-28T06:59:29.528251Z","steps":["trace[976317835] 'read index received' (duration: 467.691771ms)","trace[976317835] 'applied index is now lower than readState.Index' (duration: 117.823µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T06:59:29.528358Z","caller":"traceutil/trace.go:171","msg":"trace[1910570329] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"471.607032ms","start":"2026-05-28T06:59:29.056718Z","end":"2026-05-28T06:59:29.528325Z","steps":["trace[1910570329] 'process raft request' (duration: 471.425758ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:59:29.528759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.8455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-28T06:59:29.528763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.267529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-28T06:59:29.528457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.995108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T06:59:29.528889Z","caller":"traceutil/trace.go:171","msg":"trace[456356008] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1077; }","duration":"468.480499ms","start":"2026-05-28T06:59:29.060398Z","end":"2026-05-28T06:59:29.528879Z","steps":["trace[456356008] 'agreement among raft nodes before linearized reading' (duration: 468.010248ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:59:29.528672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.214988ms","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-05-28T06:59:29.528881Z","caller":"traceutil/trace.go:171","msg":"trace[1520293057] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1077; }","duration":"278.399622ms","start":"2026-05-28T06:59:29.250461Z","end":"2026-05-28T06:59:29.528861Z","steps":["trace[1520293057] 'agreement among raft nodes before linearized reading' (duration: 278.133736ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T06:59:29.528972Z","caller":"traceutil/trace.go:171","msg":"trace[2092912262] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1077; }","duration":"278.524515ms","start":"2026-05-28T06:59:29.250436Z","end":"2026-05-28T06:59:29.52896Z","steps":["trace[2092912262] 'agreement among raft nodes before linearized reading' (duration: 278.169027ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:59:29.529031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:59:29.056696Z","time spent":"472.089784ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T06:59:29.528825Z","caller":"traceutil/trace.go:171","msg":"trace[1557670111] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1077; }","duration":"277.943962ms","start":"2026-05-28T06:59:29.250863Z","end":"2026-05-28T06:59:29.528807Z","steps":["trace[1557670111] 'agreement among raft nodes before linearized reading' (duration: 277.727247ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:59:29.528934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:59:29.060379Z","time spent":"468.54529ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-05-28T06:59:29.528725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.314631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-28T06:59:29.529336Z","caller":"traceutil/trace.go:171","msg":"trace[465527072] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1077; }","duration":"278.979306ms","start":"2026-05-28T06:59:29.250346Z","end":"2026-05-28T06:59:29.529326Z","steps":["trace[465527072] 'agreement among raft nodes before linearized reading' (duration: 278.213498ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:59:29.977891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.568658ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T06:59:29.978317Z","caller":"traceutil/trace.go:171","msg":"trace[490034672] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"444.527186ms","start":"2026-05-28T06:59:29.533759Z","end":"2026-05-28T06:59:29.978286Z","steps":["trace[490034672] 'process raft request' (duration: 193.483086ms)","trace[490034672] 'compare' (duration: 250.402595ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T06:59:29.97833Z","caller":"traceutil/trace.go:171","msg":"trace[1932758765] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"444.069625ms","start":"2026-05-28T06:59:29.53424Z","end":"2026-05-28T06:59:29.97831Z","steps":["trace[1932758765] 'process raft request' (duration: 443.760608ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:59:29.978484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:59:29.534215Z","time spent":"444.179427ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T06:59:29.978534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:59:29.533743Z","time spent":"444.640459ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T06:59:29.978771Z","caller":"traceutil/trace.go:171","msg":"trace[1632673126] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"443.742298ms","start":"2026-05-28T06:59:29.534971Z","end":"2026-05-28T06:59:29.978713Z","steps":["trace[1632673126] 'process raft request' (duration: 443.297457ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T06:59:29.978987Z","caller":"traceutil/trace.go:171","msg":"trace[1381681315] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"443.635525ms","start":"2026-05-28T06:59:29.535327Z","end":"2026-05-28T06:59:29.978963Z","steps":["trace[1381681315] 'process raft request' (duration: 443.214185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:59:29.979106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:59:29.535318Z","time spent":"443.736618ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T06:59:29.978992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:59:29.534956Z","time spent":"443.916581ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T06:59:29.979276Z","caller":"traceutil/trace.go:171","msg":"trace[816633905] linearizableReadLoop","detail":"{readStateIndex:1128; appliedIndex:1124; }","duration":"267.885475ms","start":"2026-05-28T06:59:29.711369Z","end":"2026-05-28T06:59:29.979254Z","steps":["trace[816633905] 'read index received' (duration: 15.954965ms)","trace[816633905] 'applied index is now lower than readState.Index' (duration: 251.92809ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T06:59:29.979721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.337436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T06:59:29.979781Z","caller":"traceutil/trace.go:171","msg":"trace[2140815609] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1081; }","duration":"268.433628ms","start":"2026-05-28T06:59:29.711333Z","end":"2026-05-28T06:59:29.979767Z","steps":["trace[2140815609] 'agreement among raft nodes before linearized reading' (duration: 268.325156ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:59:29.980128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.567942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.186\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-28T06:59:29.98093Z","caller":"traceutil/trace.go:171","msg":"trace[1213812298] range","detail":"{range_begin:/registry/masterleases/162.253.55.186; range_end:; response_count:1; response_revision:1081; }","duration":"115.400801ms","start":"2026-05-28T06:59:29.865508Z","end":"2026-05-28T06:59:29.980909Z","steps":["trace[1213812298] 'agreement among raft nodes before linearized reading' (duration: 114.328626ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T06:59:32.100907Z","caller":"traceutil/trace.go:171","msg":"trace[1423143322] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"108.546611ms","start":"2026-05-28T06:59:31.99233Z","end":"2026-05-28T06:59:32.100877Z","steps":["trace[1423143322] 'process raft request' (duration: 108.346586ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T06:59:32.101164Z","caller":"traceutil/trace.go:171","msg":"trace[351771804] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"107.335812ms","start":"2026-05-28T06:59:31.993806Z","end":"2026-05-28T06:59:32.101142Z","steps":["trace[351771804] 'process raft request' (duration: 107.028585ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T06:59:32.101328Z","caller":"traceutil/trace.go:171","msg":"trace[2127736019] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"107.110007ms","start":"2026-05-28T06:59:31.994206Z","end":"2026-05-28T06:59:32.101316Z","steps":["trace[2127736019] 'process raft request' (duration: 106.864791ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:59:32.338056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.066463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T06:59:32.338143Z","caller":"traceutil/trace.go:171","msg":"trace[1595666183] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1088; }","duration":"192.207327ms","start":"2026-05-28T06:59:32.145915Z","end":"2026-05-28T06:59:32.338122Z","steps":["trace[1595666183] 'range keys from in-memory index tree' (duration: 191.92792ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T06:59:32.415626Z","caller":"traceutil/trace.go:171","msg":"trace[1010960317] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"225.210122ms","start":"2026-05-28T06:59:32.190387Z","end":"2026-05-28T06:59:32.415597Z","steps":["trace[1010960317] 'process raft request' (duration: 225.026478ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:59:48.766471Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13360946912115703102,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-28T06:59:49.109314Z","caller":"traceutil/trace.go:171","msg":"trace[714659695] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"991.330788ms","start":"2026-05-28T06:59:48.117957Z","end":"2026-05-28T06:59:49.109288Z","steps":["trace[714659695] 'process raft request' (duration: 991.153074ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:59:49.109494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:59:48.117934Z","time spent":"991.467412ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T06:59:49.956769Z","caller":"traceutil/trace.go:171","msg":"trace[124751764] linearizableReadLoop","detail":"{readStateIndex:1244; appliedIndex:1242; }","duration":"1.691187431s","start":"2026-05-28T06:59:48.265564Z","end":"2026-05-28T06:59:49.956752Z","steps":["trace[124751764] 'read index received' (duration: 843.502587ms)","trace[124751764] 'applied index is now lower than readState.Index' (duration: 847.684234ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T06:59:49.956963Z","caller":"traceutil/trace.go:171","msg":"trace[708671424] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"1.246237684s","start":"2026-05-28T06:59:48.710717Z","end":"2026-05-28T06:59:49.956955Z","steps":["trace[708671424] 'process raft request' (duration: 1.229996352s)","trace[708671424] 'compare' (duration: 15.669358ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T06:59:49.957035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:59:48.710698Z","time spent":"1.246280974s","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T06:59:49.957091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.691497199s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-28T06:59:49.957199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.373366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-28T06:59:49.957191Z","caller":"traceutil/trace.go:171","msg":"trace[1709780353] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1191; }","duration":"1.691636372s","start":"2026-05-28T06:59:48.265529Z","end":"2026-05-28T06:59:49.957166Z","steps":["trace[1709780353] 'agreement among raft nodes before linearized reading' (duration: 1.691356745s)"],"step_count":1} {"level":"info","ts":"2026-05-28T06:59:49.957219Z","caller":"traceutil/trace.go:171","msg":"trace[983924294] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1191; }","duration":"384.452288ms","start":"2026-05-28T06:59:49.572761Z","end":"2026-05-28T06:59:49.957213Z","steps":["trace[983924294] 'agreement among raft nodes before linearized reading' (duration: 384.418087ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:59:49.957241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:59:49.572729Z","time spent":"384.506199ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":8,"response size":29,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-05-28T06:59:49.957241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:59:48.265509Z","time spent":"1.691723994s","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-28T06:59:49.957302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.900504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T06:59:49.957358Z","caller":"traceutil/trace.go:171","msg":"trace[1374594393] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1191; }","duration":"811.979836ms","start":"2026-05-28T06:59:49.145361Z","end":"2026-05-28T06:59:49.957341Z","steps":["trace[1374594393] 'agreement among raft nodes before linearized reading' (duration: 811.913374ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:59:49.957393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.690208868s","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-05-28T06:59:49.957395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:59:49.145344Z","time spent":"812.042277ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-28T06:59:49.957408Z","caller":"traceutil/trace.go:171","msg":"trace[1346583671] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1191; }","duration":"1.690242429s","start":"2026-05-28T06:59:48.267161Z","end":"2026-05-28T06:59:49.957404Z","steps":["trace[1346583671] 'agreement among raft nodes before linearized reading' (duration: 1.690203718s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:59:49.957423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:59:48.267153Z","time spent":"1.69026693s","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-28T06:59:49.957125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.690863973s","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-05-28T06:59:49.957463Z","caller":"traceutil/trace.go:171","msg":"trace[839332824] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1191; }","duration":"1.691234613s","start":"2026-05-28T06:59:48.266224Z","end":"2026-05-28T06:59:49.957459Z","steps":["trace[839332824] 'agreement among raft nodes before linearized reading' (duration: 1.690764592s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T06:59:49.957474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T06:59:48.266214Z","time spent":"1.691256533s","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-28T06:59:49.957325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.517218ms","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-05-28T06:59:49.957507Z","caller":"traceutil/trace.go:171","msg":"trace[1678578381] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1191; }","duration":"286.734653ms","start":"2026-05-28T06:59:49.670768Z","end":"2026-05-28T06:59:49.957503Z","steps":["trace[1678578381] 'agreement among raft nodes before linearized reading' (duration: 286.524858ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T06:59:52.139518Z","caller":"traceutil/trace.go:171","msg":"trace[1085413652] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"145.230301ms","start":"2026-05-28T06:59:51.994263Z","end":"2026-05-28T06:59:52.139493Z","steps":["trace[1085413652] 'process raft request' (duration: 145.18736ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T06:59:52.139725Z","caller":"traceutil/trace.go:171","msg":"trace[1631672793] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"146.91367ms","start":"2026-05-28T06:59:51.9928Z","end":"2026-05-28T06:59:52.139714Z","steps":["trace[1631672793] 'process raft request' (duration: 129.202394ms)","trace[1631672793] 'compare' (duration: 17.278106ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T06:59:52.139822Z","caller":"traceutil/trace.go:171","msg":"trace[1827223097] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"146.371937ms","start":"2026-05-28T06:59:51.993442Z","end":"2026-05-28T06:59:52.139814Z","steps":["trace[1827223097] 'process raft request' (duration: 145.963617ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:00:02.320108Z","caller":"traceutil/trace.go:171","msg":"trace[371125638] linearizableReadLoop","detail":"{readStateIndex:1290; appliedIndex:1289; }","duration":"125.711133ms","start":"2026-05-28T07:00:02.194369Z","end":"2026-05-28T07:00:02.32008Z","steps":["trace[371125638] 'read index received' (duration: 94.50252ms)","trace[371125638] 'applied index is now lower than readState.Index' (duration: 31.207573ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T07:00:02.320373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.977889ms","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-05-28T07:00:02.320421Z","caller":"traceutil/trace.go:171","msg":"trace[1221217259] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1234; }","duration":"126.0748ms","start":"2026-05-28T07:00:02.194334Z","end":"2026-05-28T07:00:02.320409Z","steps":["trace[1221217259] 'agreement among raft nodes before linearized reading' (duration: 125.841185ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:00:02.320399Z","caller":"traceutil/trace.go:171","msg":"trace[867166221] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"137.948699ms","start":"2026-05-28T07:00:02.182423Z","end":"2026-05-28T07:00:02.320372Z","steps":["trace[867166221] 'process raft request' (duration: 106.519751ms)","trace[867166221] 'compare' (duration: 31.026879ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T07:00:03.359861Z","caller":"traceutil/trace.go:171","msg":"trace[1982568426] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"158.500442ms","start":"2026-05-28T07:00:03.201315Z","end":"2026-05-28T07:00:03.359815Z","steps":["trace[1982568426] 'process raft request' (duration: 158.280617ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:00:04.539671Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13360946912115703238,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-28T07:00:04.588045Z","caller":"traceutil/trace.go:171","msg":"trace[1100491698] linearizableReadLoop","detail":"{readStateIndex:1302; appliedIndex:1301; }","duration":"548.770036ms","start":"2026-05-28T07:00:04.039246Z","end":"2026-05-28T07:00:04.588016Z","steps":["trace[1100491698] 'read index received' (duration: 548.50443ms)","trace[1100491698] 'applied index is now lower than readState.Index' (duration: 264.086µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T07:00:04.588161Z","caller":"traceutil/trace.go:171","msg":"trace[856814131] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"580.798368ms","start":"2026-05-28T07:00:04.007326Z","end":"2026-05-28T07:00:04.588124Z","steps":["trace[856814131] 'process raft request' (duration: 580.486201ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:00:04.588476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:00:04.007301Z","time spent":"581.094286ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T07:00:04.588494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.221867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-28T07:00:04.588569Z","caller":"traceutil/trace.go:171","msg":"trace[756530572] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1246; }","duration":"549.351129ms","start":"2026-05-28T07:00:04.039204Z","end":"2026-05-28T07:00:04.588555Z","steps":["trace[756530572] 'agreement among raft nodes before linearized reading' (duration: 549.063233ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:00:04.588649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:00:04.039184Z","time spent":"549.453282ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-28T07:00:04.589308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.234762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T07:00:04.589357Z","caller":"traceutil/trace.go:171","msg":"trace[1594056818] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1246; }","duration":"444.284393ms","start":"2026-05-28T07:00:04.14506Z","end":"2026-05-28T07:00:04.589344Z","steps":["trace[1594056818] 'agreement among raft nodes before linearized reading' (duration: 444.212461ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:00:04.589436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:00:04.14499Z","time spent":"444.435946ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-28T07:00:04.589848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.701576ms","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-05-28T07:00:04.58989Z","caller":"traceutil/trace.go:171","msg":"trace[786779200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1246; }","duration":"244.766767ms","start":"2026-05-28T07:00:04.345112Z","end":"2026-05-28T07:00:04.589879Z","steps":["trace[786779200] 'agreement among raft nodes before linearized reading' (duration: 244.654625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:00:04.590191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.675179ms","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-05-28T07:00:04.590227Z","caller":"traceutil/trace.go:171","msg":"trace[1902643598] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1246; }","duration":"265.75231ms","start":"2026-05-28T07:00:04.324465Z","end":"2026-05-28T07:00:04.590218Z","steps":["trace[1902643598] 'agreement among raft nodes before linearized reading' (duration: 265.656808ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:00:04.590291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.361083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-28T07:00:04.590369Z","caller":"traceutil/trace.go:171","msg":"trace[1071103875] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1246; }","duration":"392.483836ms","start":"2026-05-28T07:00:04.197867Z","end":"2026-05-28T07:00:04.590351Z","steps":["trace[1071103875] 'agreement among raft nodes before linearized reading' (duration: 392.23189ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:00:04.590415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:00:04.197851Z","time spent":"392.555068ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-28T07:00:05.235627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.954152ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T07:00:05.23576Z","caller":"traceutil/trace.go:171","msg":"trace[149618538] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"640.395008ms","start":"2026-05-28T07:00:04.595342Z","end":"2026-05-28T07:00:05.235737Z","steps":["trace[149618538] 'process raft request' (duration: 533.250201ms)","trace[149618538] 'compare' (duration: 106.754247ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T07:00:05.235832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:00:04.595329Z","time spent":"640.46768ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T07:00:05.456915Z","caller":"traceutil/trace.go:171","msg":"trace[2072645073] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"861.350437ms","start":"2026-05-28T07:00:04.595541Z","end":"2026-05-28T07:00:05.456891Z","steps":["trace[2072645073] 'process raft request' (duration: 861.08672ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:00:05.456911Z","caller":"traceutil/trace.go:171","msg":"trace[74809800] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"861.492489ms","start":"2026-05-28T07:00:04.595382Z","end":"2026-05-28T07:00:05.456875Z","steps":["trace[74809800] 'process raft request' (duration: 859.552254ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:00:05.457015Z","caller":"traceutil/trace.go:171","msg":"trace[305380340] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"861.314716ms","start":"2026-05-28T07:00:04.595665Z","end":"2026-05-28T07:00:05.45698Z","steps":["trace[305380340] 'process raft request' (duration: 861.005179ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:00:05.457241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:00:04.595354Z","time spent":"861.753216ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T07:00:05.457257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:00:04.595658Z","time spent":"861.52273ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T07:00:05.457052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:00:04.595519Z","time spent":"861.463789ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T07:00:06.09537Z","caller":"traceutil/trace.go:171","msg":"trace[1862756770] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"208.661919ms","start":"2026-05-28T07:00:05.886682Z","end":"2026-05-28T07:00:06.095344Z","steps":["trace[1862756770] 'process raft request' (duration: 208.512056ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:00:06.768503Z","caller":"traceutil/trace.go:171","msg":"trace[363857334] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"173.32823ms","start":"2026-05-28T07:00:06.595156Z","end":"2026-05-28T07:00:06.768485Z","steps":["trace[363857334] 'process raft request' (duration: 173.214048ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:00:07.254342Z","caller":"traceutil/trace.go:171","msg":"trace[1867208050] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"429.477503ms","start":"2026-05-28T07:00:06.824839Z","end":"2026-05-28T07:00:07.254317Z","steps":["trace[1867208050] 'process raft request' (duration: 340.425252ms)","trace[1867208050] 'compare' (duration: 88.903968ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T07:00:07.254477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:00:06.824812Z","time spent":"429.591146ms","remote":"127.0.0.1:34448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-28T07:00:07.254629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.918227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T07:00:07.25435Z","caller":"traceutil/trace.go:171","msg":"trace[426683615] linearizableReadLoop","detail":"{readStateIndex:1312; appliedIndex:1311; }","duration":"108.61039ms","start":"2026-05-28T07:00:07.145704Z","end":"2026-05-28T07:00:07.254315Z","steps":["trace[426683615] 'read index received' (duration: 19.477637ms)","trace[426683615] 'applied index is now lower than readState.Index' (duration: 89.131753ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T07:00:07.254736Z","caller":"traceutil/trace.go:171","msg":"trace[1595143122] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1255; }","duration":"109.065021ms","start":"2026-05-28T07:00:07.145656Z","end":"2026-05-28T07:00:07.254721Z","steps":["trace[1595143122] 'agreement among raft nodes before linearized reading' (duration: 108.730323ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:00:07.850574Z","caller":"traceutil/trace.go:171","msg":"trace[1007200084] linearizableReadLoop","detail":"{readStateIndex:1313; appliedIndex:1312; }","duration":"457.512664ms","start":"2026-05-28T07:00:07.393026Z","end":"2026-05-28T07:00:07.850539Z","steps":["trace[1007200084] 'read index received' (duration: 416.978712ms)","trace[1007200084] 'applied index is now lower than readState.Index' (duration: 40.487161ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T07:00:07.851107Z","caller":"traceutil/trace.go:171","msg":"trace[1684541109] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"592.32869ms","start":"2026-05-28T07:00:07.258744Z","end":"2026-05-28T07:00:07.851073Z","steps":["trace[1684541109] 'process raft request' (duration: 551.179613ms)","trace[1684541109] 'compare' (duration: 40.191204ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T07:00:07.851246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:00:07.258732Z","time spent":"592.438662ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T07:00:07.85155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.62909ms","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-05-28T07:00:07.851576Z","caller":"traceutil/trace.go:171","msg":"trace[910008427] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1256; }","duration":"432.67113ms","start":"2026-05-28T07:00:07.418899Z","end":"2026-05-28T07:00:07.85157Z","steps":["trace[910008427] 'agreement among raft nodes before linearized reading' (duration: 432.599259ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:00:07.851593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:00:07.418892Z","time spent":"432.697151ms","remote":"127.0.0.1:34540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":13,"response size":29,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-05-28T07:00:07.851725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.550208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-28T07:00:07.851744Z","caller":"traceutil/trace.go:171","msg":"trace[402180223] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1256; }","duration":"389.598819ms","start":"2026-05-28T07:00:07.46214Z","end":"2026-05-28T07:00:07.851739Z","steps":["trace[402180223] 'agreement among raft nodes before linearized reading' (duration: 389.526117ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:00:07.851757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:00:07.46213Z","time spent":"389.62442ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-28T07:00:07.851737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.465665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-28T07:00:07.851745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.524037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-28T07:00:07.851798Z","caller":"traceutil/trace.go:171","msg":"trace[764837769] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1256; }","duration":"389.564028ms","start":"2026-05-28T07:00:07.462217Z","end":"2026-05-28T07:00:07.851781Z","steps":["trace[764837769] 'agreement among raft nodes before linearized reading' (duration: 389.348693ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:00:07.851806Z","caller":"traceutil/trace.go:171","msg":"trace[2093540405] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1256; }","duration":"389.600158ms","start":"2026-05-28T07:00:07.462193Z","end":"2026-05-28T07:00:07.851793Z","steps":["trace[2093540405] 'agreement among raft nodes before linearized reading' (duration: 389.395514ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:00:07.851846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:00:07.462213Z","time spent":"389.623689ms","remote":"127.0.0.1:34622","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-05-28T07:00:07.851851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:00:07.462187Z","time spent":"389.65611ms","remote":"127.0.0.1:34622","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-05-28T07:00:07.851955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.05473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-28T07:00:07.851974Z","caller":"traceutil/trace.go:171","msg":"trace[313472550] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1256; }","duration":"347.105451ms","start":"2026-05-28T07:00:07.504864Z","end":"2026-05-28T07:00:07.85197Z","steps":["trace[313472550] 'agreement among raft nodes before linearized reading' (duration: 347.043809ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:00:07.851989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:00:07.504843Z","time spent":"347.141622ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-28T07:00:07.852264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.212139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T07:00:07.852305Z","caller":"traceutil/trace.go:171","msg":"trace[937205660] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1256; }","duration":"383.28742ms","start":"2026-05-28T07:00:07.469007Z","end":"2026-05-28T07:00:07.852294Z","steps":["trace[937205660] 'agreement among raft nodes before linearized reading' (duration: 383.212888ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:00:07.852334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:00:07.468998Z","time spent":"383.329192ms","remote":"127.0.0.1:34744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-05-28T07:00:07.852942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.262242ms","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":"info","ts":"2026-05-28T07:00:07.852978Z","caller":"traceutil/trace.go:171","msg":"trace[475246816] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1256; }","duration":"459.971381ms","start":"2026-05-28T07:00:07.392998Z","end":"2026-05-28T07:00:07.852969Z","steps":["trace[475246816] 'agreement among raft nodes before linearized reading' (duration: 458.20728ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:00:07.852996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:00:07.392984Z","time spent":"460.006903ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-28T07:00:08.580036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.006196ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T07:00:08.580132Z","caller":"traceutil/trace.go:171","msg":"trace[464154598] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"723.729155ms","start":"2026-05-28T07:00:07.856386Z","end":"2026-05-28T07:00:08.580115Z","steps":["trace[464154598] 'process raft request' (duration: 164.577485ms)","trace[464154598] 'compare' (duration: 558.764181ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T07:00:08.580183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:00:07.856363Z","time spent":"723.794946ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T07:00:08.775553Z","caller":"traceutil/trace.go:171","msg":"trace[1377258450] linearizableReadLoop","detail":"{readStateIndex:1318; appliedIndex:1314; }","duration":"338.931049ms","start":"2026-05-28T07:00:08.4366Z","end":"2026-05-28T07:00:08.775531Z","steps":["trace[1377258450] 'read index received' (duration: 313.373819ms)","trace[1377258450] 'applied index is now lower than readState.Index' (duration: 25.55612ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T07:00:08.775612Z","caller":"traceutil/trace.go:171","msg":"trace[932172735] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"918.957559ms","start":"2026-05-28T07:00:07.856631Z","end":"2026-05-28T07:00:08.775589Z","steps":["trace[932172735] 'process raft request' (duration: 918.783045ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:00:08.77574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:00:07.856619Z","time spent":"919.050581ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T07:00:08.775866Z","caller":"traceutil/trace.go:171","msg":"trace[594140370] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"919.461261ms","start":"2026-05-28T07:00:07.856392Z","end":"2026-05-28T07:00:08.775853Z","steps":["trace[594140370] 'process raft request' (duration: 893.571613ms)","trace[594140370] 'compare' (duration: 25.316494ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T07:00:08.775923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:00:07.856365Z","time spent":"919.529202ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T07:00:08.776001Z","caller":"traceutil/trace.go:171","msg":"trace[1981793511] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"918.640481ms","start":"2026-05-28T07:00:07.857352Z","end":"2026-05-28T07:00:08.775992Z","steps":["trace[1981793511] 'process raft request' (duration: 918.114529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:00:08.776052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:00:07.85734Z","time spent":"918.685102ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T07:00:08.776082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.083913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-28T07:00:08.776197Z","caller":"traceutil/trace.go:171","msg":"trace[374903594] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"916.930881ms","start":"2026-05-28T07:00:07.859257Z","end":"2026-05-28T07:00:08.776188Z","steps":["trace[374903594] 'process raft request' (duration: 916.242605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:00:08.776238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:00:07.859249Z","time spent":"916.968241ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-28T07:00:08.776215Z","caller":"traceutil/trace.go:171","msg":"trace[1736478021] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1261; }","duration":"339.600455ms","start":"2026-05-28T07:00:08.436593Z","end":"2026-05-28T07:00:08.776194Z","steps":["trace[1736478021] 'agreement among raft nodes before linearized reading' (duration: 339.026751ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:00:08.776645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:00:08.436535Z","time spent":"340.086546ms","remote":"127.0.0.1:46770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":4,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"info","ts":"2026-05-28T07:00:08.793488Z","caller":"traceutil/trace.go:171","msg":"trace[166493685] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"277.205349ms","start":"2026-05-28T07:00:08.516259Z","end":"2026-05-28T07:00:08.793464Z","steps":["trace[166493685] 'process raft request' (duration: 277.073226ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:00:09.090488Z","caller":"traceutil/trace.go:171","msg":"trace[587014706] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"294.27104ms","start":"2026-05-28T07:00:08.796199Z","end":"2026-05-28T07:00:09.09047Z","steps":["trace[587014706] 'process raft request' (duration: 294.247479ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:00:09.090703Z","caller":"traceutil/trace.go:171","msg":"trace[938912405] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"307.869829ms","start":"2026-05-28T07:00:08.782826Z","end":"2026-05-28T07:00:09.090696Z","steps":["trace[938912405] 'process raft request' (duration: 307.561782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:00:09.090779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:00:08.782811Z","time spent":"307.918711ms","remote":"127.0.0.1:34448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-28T07:00:09.090486Z","caller":"traceutil/trace.go:171","msg":"trace[669396402] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"317.747931ms","start":"2026-05-28T07:00:08.772708Z","end":"2026-05-28T07:00:09.090456Z","steps":["trace[669396402] 'process raft request' (duration: 239.842062ms)","trace[669396402] 'compare' (duration: 77.549671ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T07:00:09.090914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:00:08.772683Z","time spent":"318.162682ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T07:00:09.091006Z","caller":"traceutil/trace.go:171","msg":"trace[1244968733] linearizableReadLoop","detail":"{readStateIndex:1320; appliedIndex:1318; }","duration":"309.369414ms","start":"2026-05-28T07:00:08.781594Z","end":"2026-05-28T07:00:09.090963Z","steps":["trace[1244968733] 'read index received' (duration: 11.693404ms)","trace[1244968733] 'applied index is now lower than readState.Index' (duration: 297.67424ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T07:00:09.091133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.536018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-05-28T07:00:09.091175Z","caller":"traceutil/trace.go:171","msg":"trace[2000482467] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1265; }","duration":"309.60418ms","start":"2026-05-28T07:00:08.781555Z","end":"2026-05-28T07:00:09.091159Z","steps":["trace[2000482467] 'agreement among raft nodes before linearized reading' (duration: 309.485327ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:00:09.091198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:00:08.781537Z","time spent":"309.656532ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-28T07:00:10.139109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.078199ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T07:00:10.139313Z","caller":"traceutil/trace.go:171","msg":"trace[2119656087] linearizableReadLoop","detail":"{readStateIndex:1323; appliedIndex:1322; }","duration":"994.320038ms","start":"2026-05-28T07:00:09.14497Z","end":"2026-05-28T07:00:10.13929Z","steps":["trace[2119656087] 'read index received' (duration: 115.87514ms)","trace[2119656087] 'applied index is now lower than readState.Index' (duration: 878.442608ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T07:00:10.139477Z","caller":"traceutil/trace.go:171","msg":"trace[1790779237] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"1.043897653s","start":"2026-05-28T07:00:09.09556Z","end":"2026-05-28T07:00:10.139457Z","steps":["trace[1790779237] 'process raft request' (duration: 165.354283ms)","trace[1790779237] 'compare' (duration: 877.813143ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T07:00:10.139617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:00:09.095537Z","time spent":"1.044028336s","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T07:00:10.13996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.336835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.186\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-28T07:00:10.139965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.693478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-28T07:00:10.14003Z","caller":"traceutil/trace.go:171","msg":"trace[1607812235] range","detail":"{range_begin:/registry/masterleases/162.253.55.186; range_end:; response_count:1; response_revision:1266; }","duration":"272.428707ms","start":"2026-05-28T07:00:09.867584Z","end":"2026-05-28T07:00:10.140012Z","steps":["trace[1607812235] 'agreement among raft nodes before linearized reading' (duration: 272.250433ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:00:10.140039Z","caller":"traceutil/trace.go:171","msg":"trace[1642049955] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1266; }","duration":"101.817561ms","start":"2026-05-28T07:00:10.038204Z","end":"2026-05-28T07:00:10.140022Z","steps":["trace[1642049955] 'agreement among raft nodes before linearized reading' (duration: 101.660118ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:00:10.140058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.217519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T07:00:10.140096Z","caller":"traceutil/trace.go:171","msg":"trace[2048297660] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1266; }","duration":"271.284211ms","start":"2026-05-28T07:00:09.868799Z","end":"2026-05-28T07:00:10.140083Z","steps":["trace[2048297660] 'agreement among raft nodes before linearized reading' (duration: 271.230759ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:00:10.139982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.45848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-28T07:00:10.140363Z","caller":"traceutil/trace.go:171","msg":"trace[1472172605] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1266; }","duration":"284.87651ms","start":"2026-05-28T07:00:09.855473Z","end":"2026-05-28T07:00:10.140349Z","steps":["trace[1472172605] 'agreement among raft nodes before linearized reading' (duration: 284.387778ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:00:10.140456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.096688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T07:00:10.140524Z","caller":"traceutil/trace.go:171","msg":"trace[33285005] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1266; }","duration":"467.204491ms","start":"2026-05-28T07:00:09.673309Z","end":"2026-05-28T07:00:10.140513Z","steps":["trace[33285005] 'agreement among raft nodes before linearized reading' (duration: 467.111939ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:00:10.140588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:00:09.673287Z","time spent":"467.289443ms","remote":"127.0.0.1:34632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":27,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-28T07:00:10.140898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"994.706188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T07:00:10.141004Z","caller":"traceutil/trace.go:171","msg":"trace[1793635003] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1266; }","duration":"996.045098ms","start":"2026-05-28T07:00:09.144923Z","end":"2026-05-28T07:00:10.140968Z","steps":["trace[1793635003] 'agreement among raft nodes before linearized reading' (duration: 994.679396ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:00:10.141046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:00:09.144903Z","time spent":"996.132841ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-28T07:00:10.484337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.080756ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-05-28T07:00:10.484558Z","caller":"traceutil/trace.go:171","msg":"trace[28396428] linearizableReadLoop","detail":"{readStateIndex:1325; appliedIndex:1323; }","duration":"336.946352ms","start":"2026-05-28T07:00:10.147596Z","end":"2026-05-28T07:00:10.484543Z","steps":["trace[28396428] 'read index received' (duration: 128.663651ms)","trace[28396428] 'applied index is now lower than readState.Index' (duration: 208.281701ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T07:00:10.484654Z","caller":"traceutil/trace.go:171","msg":"trace[1618533939] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"340.398483ms","start":"2026-05-28T07:00:10.144246Z","end":"2026-05-28T07:00:10.484645Z","steps":["trace[1618533939] 'process raft request' (duration: 340.190768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:00:10.484729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:00:10.144235Z","time spent":"340.442694ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T07:00:10.484808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:00:10.141906Z","time spent":"342.895102ms","remote":"127.0.0.1:34400","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-28T07:00:10.484864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.228635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-28T07:00:10.484926Z","caller":"traceutil/trace.go:171","msg":"trace[1216809549] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1267; }","duration":"224.298837ms","start":"2026-05-28T07:00:10.260611Z","end":"2026-05-28T07:00:10.48491Z","steps":["trace[1216809549] 'agreement among raft nodes before linearized reading' (duration: 224.191404ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:00:10.485011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.406963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T07:00:10.485041Z","caller":"traceutil/trace.go:171","msg":"trace[1734675992] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1267; }","duration":"337.453793ms","start":"2026-05-28T07:00:10.147578Z","end":"2026-05-28T07:00:10.485031Z","steps":["trace[1734675992] 'agreement among raft nodes before linearized reading' (duration: 337.404382ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:00:10.485084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:00:10.14757Z","time spent":"337.506895ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-28T07:00:13.217151Z","caller":"traceutil/trace.go:171","msg":"trace[116078251] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"113.876234ms","start":"2026-05-28T07:00:13.103255Z","end":"2026-05-28T07:00:13.217132Z","steps":["trace[116078251] 'process raft request' (duration: 113.739161ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:00:14.667149Z","caller":"traceutil/trace.go:171","msg":"trace[793942105] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"166.564372ms","start":"2026-05-28T07:00:14.500561Z","end":"2026-05-28T07:00:14.667125Z","steps":["trace[793942105] 'process raft request' (duration: 166.412568ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:00:18.934899Z","caller":"traceutil/trace.go:171","msg":"trace[465737720] linearizableReadLoop","detail":"{readStateIndex:1401; appliedIndex:1400; }","duration":"235.710564ms","start":"2026-05-28T07:00:18.699167Z","end":"2026-05-28T07:00:18.934877Z","steps":["trace[465737720] 'read index received' (duration: 235.5204ms)","trace[465737720] 'applied index is now lower than readState.Index' (duration: 189.244µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T07:00:18.935034Z","caller":"traceutil/trace.go:171","msg":"trace[1106536406] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"252.062819ms","start":"2026-05-28T07:00:18.682946Z","end":"2026-05-28T07:00:18.935009Z","steps":["trace[1106536406] 'process raft request' (duration: 251.792242ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:00:18.935244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.554484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-28T07:00:18.93524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.521832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-28T07:00:18.93541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.236241ms","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":"warn","ts":"2026-05-28T07:00:18.935481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.282509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-28T07:00:18.935577Z","caller":"traceutil/trace.go:171","msg":"trace[2017589601] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1342; }","duration":"236.453112ms","start":"2026-05-28T07:00:18.699108Z","end":"2026-05-28T07:00:18.935561Z","steps":["trace[2017589601] 'agreement among raft nodes before linearized reading' (duration: 235.894039ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:00:18.935131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.041618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-28T07:00:18.935692Z","caller":"traceutil/trace.go:171","msg":"trace[1850591544] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1342; }","duration":"114.644732ms","start":"2026-05-28T07:00:18.821037Z","end":"2026-05-28T07:00:18.935681Z","steps":["trace[1850591544] 'agreement among raft nodes before linearized reading' (duration: 114.007407ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:00:18.935525Z","caller":"traceutil/trace.go:171","msg":"trace[1174660505] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1342; }","duration":"108.423666ms","start":"2026-05-28T07:00:18.827079Z","end":"2026-05-28T07:00:18.935503Z","steps":["trace[1174660505] 'agreement among raft nodes before linearized reading' (duration: 108.025847ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:00:18.935437Z","caller":"traceutil/trace.go:171","msg":"trace[1116657205] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1342; }","duration":"112.770348ms","start":"2026-05-28T07:00:18.822642Z","end":"2026-05-28T07:00:18.935412Z","steps":["trace[1116657205] 'agreement among raft nodes before linearized reading' (duration: 112.44284ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:00:18.935362Z","caller":"traceutil/trace.go:171","msg":"trace[108442685] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1342; }","duration":"112.693926ms","start":"2026-05-28T07:00:18.82265Z","end":"2026-05-28T07:00:18.935344Z","steps":["trace[108442685] 'agreement among raft nodes before linearized reading' (duration: 112.493102ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:00:19.528044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.589971ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T07:00:19.528162Z","caller":"traceutil/trace.go:171","msg":"trace[1131772271] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"586.698457ms","start":"2026-05-28T07:00:18.941444Z","end":"2026-05-28T07:00:19.528142Z","steps":["trace[1131772271] 'process raft request' (duration: 244.917661ms)","trace[1131772271] 'compare' (duration: 341.11664ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T07:00:19.528385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:00:18.941421Z","time spent":"586.923582ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T07:00:20.655691Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.46921962s","expected-duration":"1s"} {"level":"info","ts":"2026-05-28T07:00:20.656133Z","caller":"traceutil/trace.go:171","msg":"trace[1233440927] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"1.714307685s","start":"2026-05-28T07:00:18.941806Z","end":"2026-05-28T07:00:20.656114Z","steps":["trace[1233440927] 'process raft request' (duration: 1.714226283s)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:00:20.656162Z","caller":"traceutil/trace.go:171","msg":"trace[1496595119] linearizableReadLoop","detail":"{readStateIndex:1406; appliedIndex:1401; }","duration":"1.509864425s","start":"2026-05-28T07:00:19.146278Z","end":"2026-05-28T07:00:20.656142Z","steps":["trace[1496595119] 'read index received' (duration: 40.119863ms)","trace[1496595119] 'applied index is now lower than readState.Index' (duration: 1.469743642s)"],"step_count":2} {"level":"warn","ts":"2026-05-28T07:00:20.656219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:00:18.9418Z","time spent":"1.714369036s","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T07:00:20.656243Z","caller":"traceutil/trace.go:171","msg":"trace[2143231836] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"1.713462505s","start":"2026-05-28T07:00:18.94277Z","end":"2026-05-28T07:00:20.656232Z","steps":["trace[2143231836] 'process raft request' (duration: 1.713319141s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:00:20.656313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:00:18.942761Z","time spent":"1.713503326s","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T07:00:20.656378Z","caller":"traceutil/trace.go:171","msg":"trace[1011375798] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"1.714582281s","start":"2026-05-28T07:00:18.941784Z","end":"2026-05-28T07:00:20.656366Z","steps":["trace[1011375798] 'process raft request' (duration: 1.714201122s)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:00:20.656426Z","caller":"traceutil/trace.go:171","msg":"trace[1274314756] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"1.714617092s","start":"2026-05-28T07:00:18.941756Z","end":"2026-05-28T07:00:20.656373Z","steps":["trace[1274314756] 'process raft request' (duration: 1.714062869s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:00:20.656508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:00:18.941762Z","time spent":"1.714686514s","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T07:00:20.65653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:00:18.941746Z","time spent":"1.714727354s","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T07:00:20.656488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.510176982s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T07:00:20.656666Z","caller":"traceutil/trace.go:171","msg":"trace[1956998815] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1347; }","duration":"1.510413447s","start":"2026-05-28T07:00:19.146233Z","end":"2026-05-28T07:00:20.656646Z","steps":["trace[1956998815] 'agreement among raft nodes before linearized reading' (duration: 1.510061679s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:00:20.65672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:00:19.146212Z","time spent":"1.510496619s","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-28T07:00:20.662184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.713247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T07:00:20.662409Z","caller":"traceutil/trace.go:171","msg":"trace[1396260529] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"1.428719769s","start":"2026-05-28T07:00:19.233677Z","end":"2026-05-28T07:00:20.662397Z","steps":["trace[1396260529] 'process raft request' (duration: 1.42834782s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:00:20.662202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.097084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.186\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-28T07:00:20.662469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:00:19.233652Z","time spent":"1.428786231s","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T07:00:20.662487Z","caller":"traceutil/trace.go:171","msg":"trace[808049463] range","detail":"{range_begin:/registry/masterleases/162.253.55.186; range_end:; response_count:1; response_revision:1348; }","duration":"793.422131ms","start":"2026-05-28T07:00:19.869054Z","end":"2026-05-28T07:00:20.662476Z","steps":["trace[808049463] 'agreement among raft nodes before linearized reading' (duration: 793.050703ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:00:20.662408Z","caller":"traceutil/trace.go:171","msg":"trace[428654703] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1348; }","duration":"365.942752ms","start":"2026-05-28T07:00:20.296452Z","end":"2026-05-28T07:00:20.662394Z","steps":["trace[428654703] 'agreement among raft nodes before linearized reading' (duration: 365.692957ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:00:20.662555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:00:20.296396Z","time spent":"366.145228ms","remote":"127.0.0.1:34490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-05-28T07:00:20.662258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.773707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-05-28T07:00:20.662716Z","caller":"traceutil/trace.go:171","msg":"trace[941473930] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1348; }","duration":"509.252408ms","start":"2026-05-28T07:00:20.153453Z","end":"2026-05-28T07:00:20.662705Z","steps":["trace[941473930] 'agreement among raft nodes before linearized reading' (duration: 508.766376ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:00:20.662753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:00:20.153437Z","time spent":"509.30652ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-28T07:00:20.662337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.790282ms","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-05-28T07:00:20.662541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:00:19.869032Z","time spent":"793.498543ms","remote":"127.0.0.1:34400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.186\" "} {"level":"info","ts":"2026-05-28T07:00:20.662822Z","caller":"traceutil/trace.go:171","msg":"trace[497014952] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1348; }","duration":"763.306334ms","start":"2026-05-28T07:00:19.899505Z","end":"2026-05-28T07:00:20.662811Z","steps":["trace[497014952] 'agreement among raft nodes before linearized reading' (duration: 762.729131ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:00:20.662234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.168288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-28T07:00:20.662853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:00:19.899492Z","time spent":"763.350255ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-28T07:00:20.662917Z","caller":"traceutil/trace.go:171","msg":"trace[420247119] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1348; }","duration":"886.803113ms","start":"2026-05-28T07:00:19.776053Z","end":"2026-05-28T07:00:20.662856Z","steps":["trace[420247119] 'agreement among raft nodes before linearized reading' (duration: 886.145688ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:00:20.662345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"894.091694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-28T07:00:20.662989Z","caller":"traceutil/trace.go:171","msg":"trace[1388068566] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1348; }","duration":"894.77222ms","start":"2026-05-28T07:00:19.768208Z","end":"2026-05-28T07:00:20.66298Z","steps":["trace[1388068566] 'agreement among raft nodes before linearized reading' (duration: 894.045043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:00:20.663095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:00:19.775995Z","time spent":"886.953626ms","remote":"127.0.0.1:34836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":5,"response size":29,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-05-28T07:00:20.664103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:00:19.768189Z","time spent":"895.899397ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-05-28T07:00:27.265205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.101247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T07:00:27.265398Z","caller":"traceutil/trace.go:171","msg":"trace[673713341] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1381; }","duration":"119.356033ms","start":"2026-05-28T07:00:27.145993Z","end":"2026-05-28T07:00:27.265349Z","steps":["trace[673713341] 'range keys from in-memory index tree' (duration: 118.985654ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:00:53.129118Z","caller":"traceutil/trace.go:171","msg":"trace[383593590] transaction","detail":"{read_only:false; response_revision:1498; number_of_response:1; }","duration":"121.463368ms","start":"2026-05-28T07:00:53.007624Z","end":"2026-05-28T07:00:53.129087Z","steps":["trace[383593590] 'process raft request' (duration: 121.285124ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:00:53.129209Z","caller":"traceutil/trace.go:171","msg":"trace[1423136602] transaction","detail":"{read_only:false; response_revision:1499; number_of_response:1; }","duration":"121.55206ms","start":"2026-05-28T07:00:53.007631Z","end":"2026-05-28T07:00:53.129183Z","steps":["trace[1423136602] 'process raft request' (duration: 121.409147ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:01:33.240316Z","caller":"traceutil/trace.go:171","msg":"trace[1126811351] transaction","detail":"{read_only:false; response_revision:1679; number_of_response:1; }","duration":"109.061405ms","start":"2026-05-28T07:01:33.131237Z","end":"2026-05-28T07:01:33.240299Z","steps":["trace[1126811351] 'process raft request' (duration: 108.909321ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:02:47.174359Z","caller":"traceutil/trace.go:171","msg":"trace[1854791920] transaction","detail":"{read_only:false; response_revision:2019; number_of_response:1; }","duration":"116.648953ms","start":"2026-05-28T07:02:47.057682Z","end":"2026-05-28T07:02:47.174331Z","steps":["trace[1854791920] 'process raft request' (duration: 116.493599ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:04:11.663526Z","caller":"traceutil/trace.go:171","msg":"trace[1536108926] linearizableReadLoop","detail":"{readStateIndex:2539; appliedIndex:2538; }","duration":"409.574146ms","start":"2026-05-28T07:04:11.253918Z","end":"2026-05-28T07:04:11.663492Z","steps":["trace[1536108926] 'read index received' (duration: 409.385861ms)","trace[1536108926] 'applied index is now lower than readState.Index' (duration: 186.535µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T07:04:11.663687Z","caller":"traceutil/trace.go:171","msg":"trace[43412279] transaction","detail":"{read_only:false; response_revision:2432; number_of_response:1; }","duration":"423.003983ms","start":"2026-05-28T07:04:11.24067Z","end":"2026-05-28T07:04:11.663674Z","steps":["trace[43412279] 'process raft request' (duration: 422.623204ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:04:11.663964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:04:11.24064Z","time spent":"423.082175ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T07:04:11.664041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.096479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-05-28T07:04:11.66425Z","caller":"traceutil/trace.go:171","msg":"trace[1378508816] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2432; }","duration":"410.356355ms","start":"2026-05-28T07:04:11.253866Z","end":"2026-05-28T07:04:11.664222Z","steps":["trace[1378508816] 'agreement among raft nodes before linearized reading' (duration: 410.016657ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:04:11.664443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:04:11.253847Z","time spent":"410.533239ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":547,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-28T07:04:11.667305Z","caller":"traceutil/trace.go:171","msg":"trace[2128667883] transaction","detail":"{read_only:false; response_revision:2433; number_of_response:1; }","duration":"133.448512ms","start":"2026-05-28T07:04:11.533833Z","end":"2026-05-28T07:04:11.667282Z","steps":["trace[2128667883] 'process raft request' (duration: 133.279558ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:04:11.667399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.543961ms","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-05-28T07:04:11.667594Z","caller":"traceutil/trace.go:171","msg":"trace[1167263200] 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:2433; }","duration":"121.737446ms","start":"2026-05-28T07:04:11.545792Z","end":"2026-05-28T07:04:11.667529Z","steps":["trace[1167263200] 'agreement among raft nodes before linearized reading' (duration: 121.525661ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:04:11.667456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.560107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-05-28T07:04:11.667878Z","caller":"traceutil/trace.go:171","msg":"trace[965741626] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2433; }","duration":"412.960806ms","start":"2026-05-28T07:04:11.254834Z","end":"2026-05-28T07:04:11.667795Z","steps":["trace[965741626] 'agreement among raft nodes before linearized reading' (duration: 412.426254ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:04:11.667935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:04:11.25482Z","time spent":"413.099549ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-28T07:04:16.326192Z","caller":"traceutil/trace.go:171","msg":"trace[1188016821] transaction","detail":"{read_only:false; response_revision:2459; number_of_response:1; }","duration":"113.415759ms","start":"2026-05-28T07:04:16.212759Z","end":"2026-05-28T07:04:16.326175Z","steps":["trace[1188016821] 'process raft request' (duration: 112.561419ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:04:17.10031Z","caller":"traceutil/trace.go:171","msg":"trace[908426720] linearizableReadLoop","detail":"{readStateIndex:2572; appliedIndex:2571; }","duration":"116.074372ms","start":"2026-05-28T07:04:16.984211Z","end":"2026-05-28T07:04:17.100286Z","steps":["trace[908426720] 'read index received' (duration: 115.794356ms)","trace[908426720] 'applied index is now lower than readState.Index' (duration: 278.886µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T07:04:17.10043Z","caller":"traceutil/trace.go:171","msg":"trace[1572853371] transaction","detail":"{read_only:false; response_revision:2464; number_of_response:1; }","duration":"213.018452ms","start":"2026-05-28T07:04:16.887377Z","end":"2026-05-28T07:04:17.100395Z","steps":["trace[1572853371] 'process raft request' (duration: 212.728955ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:04:17.100498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.265797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-node-lease/\" range_end:\"/registry/secrets/kube-node-lease0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T07:04:17.100713Z","caller":"traceutil/trace.go:171","msg":"trace[333372019] range","detail":"{range_begin:/registry/secrets/kube-node-lease/; range_end:/registry/secrets/kube-node-lease0; response_count:0; response_revision:2464; }","duration":"116.528813ms","start":"2026-05-28T07:04:16.984171Z","end":"2026-05-28T07:04:17.1007Z","steps":["trace[333372019] 'agreement among raft nodes before linearized reading' (duration: 116.209536ms)"],"step_count":1}