{"level":"warn","ts":"2026-08-14T09:01:17.135409Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-14T09:01:17.135508Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.37:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.37:2380","--initial-cluster=instance=https://199.19.213.37:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.37:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.37: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-08-14T09:01:17.135579Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-14T09:01:17.135588Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.37:2380"]} {"level":"info","ts":"2026-08-14T09:01:17.135604Z","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-08-14T09:01:17.136299Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.37:2379"]} {"level":"info","ts":"2026-08-14T09:01:17.136382Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.37:2380"],"listen-peer-urls":["https://199.19.213.37:2380"],"advertise-client-urls":["https://199.19.213.37:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.37:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.37: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-08-14T09:01:17.138628Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.045701ms"} {"level":"info","ts":"2026-08-14T09:01:17.14728Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"7252794153f4f3cd","cluster-id":"2552855285ac01f"} {"level":"info","ts":"2026-08-14T09:01:17.147421Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd switched to configuration voters=()"} {"level":"info","ts":"2026-08-14T09:01:17.147445Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd became follower at term 0"} {"level":"info","ts":"2026-08-14T09:01:17.14746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 7252794153f4f3cd [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-14T09:01:17.147473Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd became follower at term 1"} {"level":"info","ts":"2026-08-14T09:01:17.147638Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd switched to configuration voters=(8237779989902455757)"} {"level":"warn","ts":"2026-08-14T09:01:17.153062Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-14T09:01:17.154815Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-14T09:01:17.156233Z","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-08-14T09:01:17.159947Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"7252794153f4f3cd","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-14T09:01:17.160116Z","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-08-14T09:01:17.160213Z","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-08-14T09:01:17.160245Z","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-08-14T09:01:17.160434Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"7252794153f4f3cd","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-14T09:01:17.164027Z","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-08-14T09:01:17.164305Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"7252794153f4f3cd","initial-advertise-peer-urls":["https://199.19.213.37:2380"],"listen-peer-urls":["https://199.19.213.37:2380"],"advertise-client-urls":["https://199.19.213.37:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.37:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-14T09:01:17.164346Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-14T09:01:17.164431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd switched to configuration voters=(8237779989902455757)"} {"level":"info","ts":"2026-08-14T09:01:17.164546Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.37:2380"} {"level":"info","ts":"2026-08-14T09:01:17.164564Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.37:2380"} {"level":"info","ts":"2026-08-14T09:01:17.164671Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2552855285ac01f","local-member-id":"7252794153f4f3cd","added-peer-id":"7252794153f4f3cd","added-peer-peer-urls":["https://199.19.213.37:2380"]} {"level":"info","ts":"2026-08-14T09:01:17.348164Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd is starting a new election at term 1"} {"level":"info","ts":"2026-08-14T09:01:17.348287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd became pre-candidate at term 1"} {"level":"info","ts":"2026-08-14T09:01:17.348331Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd received MsgPreVoteResp from 7252794153f4f3cd at term 1"} {"level":"info","ts":"2026-08-14T09:01:17.348367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd became candidate at term 2"} {"level":"info","ts":"2026-08-14T09:01:17.348392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd received MsgVoteResp from 7252794153f4f3cd at term 2"} {"level":"info","ts":"2026-08-14T09:01:17.348422Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd became leader at term 2"} {"level":"info","ts":"2026-08-14T09:01:17.348446Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 7252794153f4f3cd elected leader 7252794153f4f3cd at term 2"} {"level":"info","ts":"2026-08-14T09:01:17.349419Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T09:01:17.350123Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"7252794153f4f3cd","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.37:2379]}","request-path":"/0/members/7252794153f4f3cd/attributes","cluster-id":"2552855285ac01f","publish-timeout":"7s"} {"level":"info","ts":"2026-08-14T09:01:17.350354Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-14T09:01:17.35043Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-14T09:01:17.350993Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-14T09:01:17.351083Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-14T09:01:17.354929Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2552855285ac01f","local-member-id":"7252794153f4f3cd","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T09:01:17.355122Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T09:01:17.355659Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T09:01:17.35614Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.37:2379"} {"level":"info","ts":"2026-08-14T09:01:17.356387Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-08-14T09:01:33.160977Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.531349767s","expected-duration":"1s"} {"level":"info","ts":"2026-08-14T09:01:33.161462Z","caller":"traceutil/trace.go:171","msg":"trace[56287286] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"1.531922001s","start":"2026-08-14T09:01:31.629518Z","end":"2026-08-14T09:01:33.16144Z","steps":["trace[56287286] 'process raft request' (duration: 1.531795768s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:01:33.161949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:01:31.629495Z","time spent":"1.532044484s","remote":"127.0.0.1:53242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:01:33.683431Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17567873641173212798,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-14T09:01:33.771093Z","caller":"traceutil/trace.go:171","msg":"trace[1459255847] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"928.837471ms","start":"2026-08-14T09:01:32.842232Z","end":"2026-08-14T09:01:33.771069Z","steps":["trace[1459255847] 'process raft request' (duration: 928.702078ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:01:33.771245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:01:32.842208Z","time spent":"928.959265ms","remote":"127.0.0.1:53242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:01:33.771608Z","caller":"traceutil/trace.go:171","msg":"trace[560362886] linearizableReadLoop","detail":"{readStateIndex:416; appliedIndex:416; }","duration":"588.635422ms","start":"2026-08-14T09:01:33.182956Z","end":"2026-08-14T09:01:33.771591Z","steps":["trace[560362886] 'read index received' (duration: 588.629252ms)","trace[560362886] 'applied index is now lower than readState.Index' (duration: 4.86µs)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:01:33.771731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.765225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-14T09:01:33.771779Z","caller":"traceutil/trace.go:171","msg":"trace[579970398] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:403; }","duration":"588.854908ms","start":"2026-08-14T09:01:33.182914Z","end":"2026-08-14T09:01:33.771769Z","steps":["trace[579970398] 'agreement among raft nodes before linearized reading' (duration: 588.769906ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:01:33.771845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:01:33.182897Z","time spent":"588.934529ms","remote":"127.0.0.1:53242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-14T09:01:33.772086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.135828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-08-14T09:01:33.772146Z","caller":"traceutil/trace.go:171","msg":"trace[1522416090] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:403; }","duration":"569.228681ms","start":"2026-08-14T09:01:33.202903Z","end":"2026-08-14T09:01:33.772132Z","steps":["trace[1522416090] 'agreement among raft nodes before linearized reading' (duration: 569.113278ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:01:33.772193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:01:33.202887Z","time spent":"569.283832ms","remote":"127.0.0.1:53242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-14T09:01:33.772291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.090964ms","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-08-14T09:01:33.772341Z","caller":"traceutil/trace.go:171","msg":"trace[1817532555] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:403; }","duration":"456.183067ms","start":"2026-08-14T09:01:33.316143Z","end":"2026-08-14T09:01:33.772326Z","steps":["trace[1817532555] 'agreement among raft nodes before linearized reading' (duration: 456.079414ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:01:33.772448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:01:33.316125Z","time spent":"456.255909ms","remote":"127.0.0.1:53242","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-08-14T09:01:37.107036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.74295ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-14T09:01:37.10716Z","caller":"traceutil/trace.go:171","msg":"trace[2045602650] linearizableReadLoop","detail":"{readStateIndex:426; appliedIndex:425; }","duration":"662.53852ms","start":"2026-08-14T09:01:36.444604Z","end":"2026-08-14T09:01:37.107143Z","steps":["trace[2045602650] 'read index received' (duration: 490.522134ms)","trace[2045602650] 'applied index is now lower than readState.Index' (duration: 172.015086ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:01:37.107255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.643344ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:01:37.107414Z","caller":"traceutil/trace.go:171","msg":"trace[1495312138] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:412; }","duration":"662.806129ms","start":"2026-08-14T09:01:36.444593Z","end":"2026-08-14T09:01:37.107399Z","steps":["trace[1495312138] 'agreement among raft nodes before linearized reading' (duration: 662.620683ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:01:37.107387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.08271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:01:37.107499Z","caller":"traceutil/trace.go:171","msg":"trace[324893671] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:412; }","duration":"152.216744ms","start":"2026-08-14T09:01:36.955258Z","end":"2026-08-14T09:01:37.107475Z","steps":["trace[324893671] 'agreement among raft nodes before linearized reading' (duration: 152.013238ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:01:37.107611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.364232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"info","ts":"2026-08-14T09:01:37.107715Z","caller":"traceutil/trace.go:171","msg":"trace[759247342] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:412; }","duration":"125.526776ms","start":"2026-08-14T09:01:36.982165Z","end":"2026-08-14T09:01:37.107692Z","steps":["trace[759247342] 'agreement among raft nodes before linearized reading' (duration: 125.314471ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:01:37.502047Z","caller":"traceutil/trace.go:171","msg":"trace[2090538412] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"386.788842ms","start":"2026-08-14T09:01:37.115239Z","end":"2026-08-14T09:01:37.502028Z","steps":["trace[2090538412] 'process raft request' (duration: 386.537086ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:01:37.502176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.748858ms","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-08-14T09:01:37.502201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:01:37.115213Z","time spent":"386.905586ms","remote":"127.0.0.1:53146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4307,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:01:37.502219Z","caller":"traceutil/trace.go:171","msg":"trace[1599726782] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:413; }","duration":"293.825ms","start":"2026-08-14T09:01:37.208376Z","end":"2026-08-14T09:01:37.502201Z","steps":["trace[1599726782] 'agreement among raft nodes before linearized reading' (duration: 293.734677ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:01:37.501976Z","caller":"traceutil/trace.go:171","msg":"trace[676643974] linearizableReadLoop","detail":"{readStateIndex:427; appliedIndex:426; }","duration":"293.534143ms","start":"2026-08-14T09:01:37.208419Z","end":"2026-08-14T09:01:37.501953Z","steps":["trace[676643974] 'read index received' (duration: 293.451321ms)","trace[676643974] 'applied index is now lower than readState.Index' (duration: 81.652µs)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:01:38.299163Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17567873641173212824,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:01:38.799431Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17567873641173212824,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:01:38.937329Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.430625239s","expected-duration":"1s"} {"level":"info","ts":"2026-08-14T09:01:38.93776Z","caller":"traceutil/trace.go:171","msg":"trace[1669022069] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"1.431104541s","start":"2026-08-14T09:01:37.506625Z","end":"2026-08-14T09:01:38.93773Z","steps":["trace[1669022069] 'process raft request' (duration: 1.430933277s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:01:38.937933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:01:37.506611Z","time spent":"1.431241685s","remote":"127.0.0.1:53242","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-08-14T09:01:39.31751Z","caller":"traceutil/trace.go:171","msg":"trace[1745747685] linearizableReadLoop","detail":"{readStateIndex:429; appliedIndex:427; }","duration":"1.518364041s","start":"2026-08-14T09:01:37.799115Z","end":"2026-08-14T09:01:39.317479Z","steps":["trace[1745747685] 'read index received' (duration: 1.138487542s)","trace[1745747685] 'applied index is now lower than readState.Index' (duration: 379.875379ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:01:39.317624Z","caller":"traceutil/trace.go:171","msg":"trace[1775776907] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"1.805626537s","start":"2026-08-14T09:01:37.511955Z","end":"2026-08-14T09:01:39.317581Z","steps":["trace[1775776907] 'process raft request' (duration: 1.768095169s)","trace[1775776907] 'compare' (duration: 37.285042ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:01:39.317679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.518545297s","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-08-14T09:01:39.317712Z","caller":"traceutil/trace.go:171","msg":"trace[813594542] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:415; }","duration":"1.518632299s","start":"2026-08-14T09:01:37.799071Z","end":"2026-08-14T09:01:39.317704Z","steps":["trace[813594542] 'agreement among raft nodes before linearized reading' (duration: 1.518536615s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:01:39.317693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.51586878s","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-08-14T09:01:39.317738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:01:37.79905Z","time spent":"1.518681571s","remote":"127.0.0.1:53242","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":"info","ts":"2026-08-14T09:01:39.317763Z","caller":"traceutil/trace.go:171","msg":"trace[1848380173] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:415; }","duration":"1.515967552s","start":"2026-08-14T09:01:37.801777Z","end":"2026-08-14T09:01:39.317744Z","steps":["trace[1848380173] 'agreement among raft nodes before linearized reading' (duration: 1.515861669s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:01:39.31776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.342649202s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-14T09:01:39.317811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:01:37.511921Z","time spent":"1.805788172s","remote":"127.0.0.1:53146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4115,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:01:39.317847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.516013923s","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-08-14T09:01:39.317879Z","caller":"traceutil/trace.go:171","msg":"trace[1217052174] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:415; }","duration":"1.516062655s","start":"2026-08-14T09:01:37.801808Z","end":"2026-08-14T09:01:39.317871Z","steps":["trace[1217052174] 'agreement among raft nodes before linearized reading' (duration: 1.516013714s)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:01:39.317859Z","caller":"traceutil/trace.go:171","msg":"trace[692615121] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:415; }","duration":"1.342764875s","start":"2026-08-14T09:01:37.975069Z","end":"2026-08-14T09:01:39.317833Z","steps":["trace[692615121] 'agreement among raft nodes before linearized reading' (duration: 1.342587661s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:01:39.31792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:01:37.801795Z","time spent":"1.516119006s","remote":"127.0.0.1:53242","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-08-14T09:01:39.317958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:01:37.975018Z","time spent":"1.34293093s","remote":"127.0.0.1:53000","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-08-14T09:01:39.317975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.615836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:01:39.318089Z","caller":"traceutil/trace.go:171","msg":"trace[525809256] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:415; }","duration":"863.77317ms","start":"2026-08-14T09:01:38.454294Z","end":"2026-08-14T09:01:39.318067Z","steps":["trace[525809256] 'agreement among raft nodes before linearized reading' (duration: 863.593405ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:01:39.31813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:01:38.454276Z","time spent":"863.845171ms","remote":"127.0.0.1:53010","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-08-14T09:01:39.317801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:01:37.801763Z","time spent":"1.516030884s","remote":"127.0.0.1:53242","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-08-14T09:01:39.544118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.674404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5326"} {"level":"info","ts":"2026-08-14T09:01:39.544217Z","caller":"traceutil/trace.go:171","msg":"trace[642284739] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:419; }","duration":"124.823068ms","start":"2026-08-14T09:01:39.419377Z","end":"2026-08-14T09:01:39.5442Z","steps":["trace[642284739] 'range keys from in-memory index tree' (duration: 124.529471ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:01:41.066306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.963238ms","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-08-14T09:01:41.066396Z","caller":"traceutil/trace.go:171","msg":"trace[1964727007] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:422; }","duration":"124.106771ms","start":"2026-08-14T09:01:40.942272Z","end":"2026-08-14T09:01:41.066378Z","steps":["trace[1964727007] 'range keys from in-memory index tree' (duration: 123.809474ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:01:41.276592Z","caller":"traceutil/trace.go:171","msg":"trace[1871126603] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"206.523429ms","start":"2026-08-14T09:01:41.070051Z","end":"2026-08-14T09:01:41.276575Z","steps":["trace[1871126603] 'process raft request' (duration: 206.378156ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:01:41.486442Z","caller":"traceutil/trace.go:171","msg":"trace[337670794] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"128.286045ms","start":"2026-08-14T09:01:41.358115Z","end":"2026-08-14T09:01:41.486401Z","steps":["trace[337670794] 'process raft request' (duration: 60.901372ms)","trace[337670794] 'compare' (duration: 67.111477ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:02:03.95532Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17567873641173213089,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:02:04.374696Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.081372815s","expected-duration":"1s"} {"level":"info","ts":"2026-08-14T09:02:04.375188Z","caller":"traceutil/trace.go:171","msg":"trace[1844856943] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"1.081975969s","start":"2026-08-14T09:02:03.293183Z","end":"2026-08-14T09:02:04.375159Z","steps":["trace[1844856943] 'process raft request' (duration: 1.081726013s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:02:04.375341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:03.293159Z","time spent":"1.082114523s","remote":"127.0.0.1:53050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-14T09:02:04.467472Z","caller":"traceutil/trace.go:171","msg":"trace[2057133397] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"1.168963573s","start":"2026-08-14T09:02:03.298448Z","end":"2026-08-14T09:02:04.467411Z","steps":["trace[2057133397] 'process raft request' (duration: 1.168491571s)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:04.467594Z","caller":"traceutil/trace.go:171","msg":"trace[107109669] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"1.157500406s","start":"2026-08-14T09:02:03.310053Z","end":"2026-08-14T09:02:04.467554Z","steps":["trace[107109669] 'process raft request' (duration: 1.157175908s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:02:04.467798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:03.310043Z","time spent":"1.15767206s","remote":"127.0.0.1:53050","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-08-14T09:02:04.467819Z","caller":"traceutil/trace.go:171","msg":"trace[1962153508] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"1.166341506s","start":"2026-08-14T09:02:03.301455Z","end":"2026-08-14T09:02:04.467796Z","steps":["trace[1962153508] 'process raft request' (duration: 1.16565973s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:02:04.467845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:03.298432Z","time spent":"1.169165767s","remote":"127.0.0.1:53050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-14T09:02:04.467921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:03.301443Z","time spent":"1.166420989s","remote":"127.0.0.1:53050","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-08-14T09:02:04.46811Z","caller":"traceutil/trace.go:171","msg":"trace[1921852149] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"1.149195059s","start":"2026-08-14T09:02:03.318899Z","end":"2026-08-14T09:02:04.468094Z","steps":["trace[1921852149] 'process raft request' (duration: 1.148551663s)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:04.468183Z","caller":"traceutil/trace.go:171","msg":"trace[112532018] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"1.158085441s","start":"2026-08-14T09:02:03.310089Z","end":"2026-08-14T09:02:04.468174Z","steps":["trace[112532018] 'process raft request' (duration: 1.157223649s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:02:04.468261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:03.318861Z","time spent":"1.149321822s","remote":"127.0.0.1:53050","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-08-14T09:02:04.468313Z","caller":"traceutil/trace.go:171","msg":"trace[454115172] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"1.159929147s","start":"2026-08-14T09:02:03.308369Z","end":"2026-08-14T09:02:04.468298Z","steps":["trace[454115172] 'process raft request' (duration: 1.158819279s)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:04.46838Z","caller":"traceutil/trace.go:171","msg":"trace[1383926731] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"1.130651575s","start":"2026-08-14T09:02:03.337719Z","end":"2026-08-14T09:02:04.468371Z","steps":["trace[1383926731] 'process raft request' (duration: 1.129885766s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:02:04.468403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:03.30834Z","time spent":"1.16003052s","remote":"127.0.0.1:53050","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-08-14T09:02:04.46841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.014029782s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-14T09:02:04.468443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:03.337691Z","time spent":"1.130728558s","remote":"127.0.0.1:53050","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-08-14T09:02:04.468133Z","caller":"traceutil/trace.go:171","msg":"trace[882733596] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"1.149193248s","start":"2026-08-14T09:02:03.318926Z","end":"2026-08-14T09:02:04.468119Z","steps":["trace[882733596] 'process raft request' (duration: 1.148597354s)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:04.468524Z","caller":"traceutil/trace.go:171","msg":"trace[1568469843] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:549; }","duration":"1.014179087s","start":"2026-08-14T09:02:03.454306Z","end":"2026-08-14T09:02:04.468485Z","steps":["trace[1568469843] 'agreement among raft nodes before linearized reading' (duration: 1.013996022s)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:04.468343Z","caller":"traceutil/trace.go:171","msg":"trace[1885537429] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"1.124980284s","start":"2026-08-14T09:02:03.343352Z","end":"2026-08-14T09:02:04.468332Z","steps":["trace[1885537429] 'process raft request' (duration: 1.124370729s)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:04.468393Z","caller":"traceutil/trace.go:171","msg":"trace[563671083] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"1.149347773s","start":"2026-08-14T09:02:03.318908Z","end":"2026-08-14T09:02:04.468256Z","steps":["trace[563671083] 'process raft request' (duration: 1.148474981s)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:04.468636Z","caller":"traceutil/trace.go:171","msg":"trace[2105626368] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"980.661419ms","start":"2026-08-14T09:02:03.487964Z","end":"2026-08-14T09:02:04.468626Z","steps":["trace[2105626368] 'process raft request' (duration: 980.022962ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:02:04.468607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:03.318901Z","time spent":"1.149673762s","remote":"127.0.0.1:53050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-14T09:02:04.46822Z","caller":"traceutil/trace.go:171","msg":"trace[1466999479] linearizableReadLoop","detail":"{readStateIndex:569; appliedIndex:557; }","duration":"1.013832486s","start":"2026-08-14T09:02:03.454361Z","end":"2026-08-14T09:02:04.468193Z","steps":["trace[1466999479] 'read index received' (duration: 920.661319ms)","trace[1466999479] 'applied index is now lower than readState.Index' (duration: 93.169727ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:02:04.468609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:03.343325Z","time spent":"1.125260042s","remote":"127.0.0.1:53050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-14T09:02:04.468817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:03.487927Z","time spent":"980.808583ms","remote":"127.0.0.1:53050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-14T09:02:04.468852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:03.318896Z","time spent":"1.149810375s","remote":"127.0.0.1:53050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-14T09:02:04.46857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:03.454283Z","time spent":"1.014275939s","remote":"127.0.0.1:53000","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-08-14T09:02:04.468276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:03.310067Z","time spent":"1.158162142s","remote":"127.0.0.1:53050","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-08-14T09:02:04.495198Z","caller":"traceutil/trace.go:171","msg":"trace[2138870022] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"112.216843ms","start":"2026-08-14T09:02:04.38293Z","end":"2026-08-14T09:02:04.495147Z","steps":["trace[2138870022] 'process raft request' (duration: 111.942806ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:02:04.495437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.998748ms","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-08-14T09:02:04.495471Z","caller":"traceutil/trace.go:171","msg":"trace[16193247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:551; }","duration":"652.075751ms","start":"2026-08-14T09:02:03.843388Z","end":"2026-08-14T09:02:04.495463Z","steps":["trace[16193247] 'agreement among raft nodes before linearized reading' (duration: 651.874506ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:02:04.495626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:03.843371Z","time spent":"652.246304ms","remote":"127.0.0.1:53242","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":"info","ts":"2026-08-14T09:02:04.495613Z","caller":"traceutil/trace.go:171","msg":"trace[651443653] transaction","detail":"{read_only:false; number_of_response:1; response_revision:551; }","duration":"109.464124ms","start":"2026-08-14T09:02:04.386065Z","end":"2026-08-14T09:02:04.495529Z","steps":["trace[651443653] 'process raft request' (duration: 109.033934ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:02:04.495643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.298953ms","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-08-14T09:02:04.495759Z","caller":"traceutil/trace.go:171","msg":"trace[93912004] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:551; }","duration":"820.543998ms","start":"2026-08-14T09:02:03.675195Z","end":"2026-08-14T09:02:04.495739Z","steps":["trace[93912004] 'agreement among raft nodes before linearized reading' (duration: 820.20486ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:02:04.495835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:03.675178Z","time spent":"820.647292ms","remote":"127.0.0.1:53242","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-08-14T09:02:04.495881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"985.662524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-14T09:02:04.495916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.434873ms","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-08-14T09:02:04.496053Z","caller":"traceutil/trace.go:171","msg":"trace[1620696698] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:551; }","duration":"685.71576ms","start":"2026-08-14T09:02:03.810313Z","end":"2026-08-14T09:02:04.496029Z","steps":["trace[1620696698] 'agreement among raft nodes before linearized reading' (duration: 685.193267ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:04.496126Z","caller":"traceutil/trace.go:171","msg":"trace[272960822] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"111.063864ms","start":"2026-08-14T09:02:04.385043Z","end":"2026-08-14T09:02:04.496106Z","steps":["trace[272960822] 'process raft request' (duration: 109.970747ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:02:04.496143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:03.810294Z","time spent":"685.835223ms","remote":"127.0.0.1:53242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-14T09:02:04.495904Z","caller":"traceutil/trace.go:171","msg":"trace[1917811440] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:551; }","duration":"985.721925ms","start":"2026-08-14T09:02:03.510174Z","end":"2026-08-14T09:02:04.495896Z","steps":["trace[1917811440] 'agreement among raft nodes before linearized reading' (duration: 985.674134ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:02:04.49636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:03.510154Z","time spent":"986.198498ms","remote":"127.0.0.1:53242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-14T09:02:04.661512Z","caller":"traceutil/trace.go:171","msg":"trace[653127155] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"184.217312ms","start":"2026-08-14T09:02:04.477257Z","end":"2026-08-14T09:02:04.661474Z","steps":["trace[653127155] 'process raft request' (duration: 183.977896ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:04.661666Z","caller":"traceutil/trace.go:171","msg":"trace[1214232099] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"180.536129ms","start":"2026-08-14T09:02:04.481056Z","end":"2026-08-14T09:02:04.661592Z","steps":["trace[1214232099] 'process raft request' (duration: 180.366565ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:02:04.661799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.91677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:02:04.661849Z","caller":"traceutil/trace.go:171","msg":"trace[1829727679] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:558; }","duration":"191.025303ms","start":"2026-08-14T09:02:04.470814Z","end":"2026-08-14T09:02:04.661839Z","steps":["trace[1829727679] 'agreement among raft nodes before linearized reading' (duration: 190.9307ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:04.661849Z","caller":"traceutil/trace.go:171","msg":"trace[607489584] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"180.623533ms","start":"2026-08-14T09:02:04.481217Z","end":"2026-08-14T09:02:04.66184Z","steps":["trace[607489584] 'process raft request' (duration: 180.242412ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:04.661891Z","caller":"traceutil/trace.go:171","msg":"trace[1226548875] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"171.480004ms","start":"2026-08-14T09:02:04.490394Z","end":"2026-08-14T09:02:04.661874Z","steps":["trace[1226548875] 'process raft request' (duration: 171.170096ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:04.661694Z","caller":"traceutil/trace.go:171","msg":"trace[1453822322] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:572; }","duration":"166.459988ms","start":"2026-08-14T09:02:04.495217Z","end":"2026-08-14T09:02:04.661677Z","steps":["trace[1453822322] 'read index received' (duration: 166.088338ms)","trace[1453822322] 'applied index is now lower than readState.Index' (duration: 370.8µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:02:04.662035Z","caller":"traceutil/trace.go:171","msg":"trace[913546652] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"170.975121ms","start":"2026-08-14T09:02:04.491049Z","end":"2026-08-14T09:02:04.662024Z","steps":["trace[913546652] 'process raft request' (duration: 170.57799ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:04.661804Z","caller":"traceutil/trace.go:171","msg":"trace[1787492983] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"173.979437ms","start":"2026-08-14T09:02:04.487786Z","end":"2026-08-14T09:02:04.661765Z","steps":["trace[1787492983] 'process raft request' (duration: 173.74337ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:04.66157Z","caller":"traceutil/trace.go:171","msg":"trace[1405285278] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"183.912984ms","start":"2026-08-14T09:02:04.477627Z","end":"2026-08-14T09:02:04.66154Z","steps":["trace[1405285278] 'process raft request' (duration: 183.76247ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:04.706931Z","caller":"traceutil/trace.go:171","msg":"trace[1480266834] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"207.999686ms","start":"2026-08-14T09:02:04.498914Z","end":"2026-08-14T09:02:04.706914Z","steps":["trace[1480266834] 'process raft request' (duration: 207.560895ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:04.707013Z","caller":"traceutil/trace.go:171","msg":"trace[945643689] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"173.071923ms","start":"2026-08-14T09:02:04.533914Z","end":"2026-08-14T09:02:04.706986Z","steps":["trace[945643689] 'process raft request' (duration: 173.015352ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:04.70738Z","caller":"traceutil/trace.go:171","msg":"trace[2004225284] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"209.755ms","start":"2026-08-14T09:02:04.497614Z","end":"2026-08-14T09:02:04.707369Z","steps":["trace[2004225284] 'process raft request' (duration: 208.671202ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:04.707449Z","caller":"traceutil/trace.go:171","msg":"trace[18003108] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"205.939615ms","start":"2026-08-14T09:02:04.501504Z","end":"2026-08-14T09:02:04.707444Z","steps":["trace[18003108] 'process raft request' (duration: 205.104615ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:04.707553Z","caller":"traceutil/trace.go:171","msg":"trace[605002520] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"206.029077ms","start":"2026-08-14T09:02:04.501516Z","end":"2026-08-14T09:02:04.707545Z","steps":["trace[605002520] 'process raft request' (duration: 205.121765ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:04.707626Z","caller":"traceutil/trace.go:171","msg":"trace[2105311684] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"205.436652ms","start":"2026-08-14T09:02:04.50218Z","end":"2026-08-14T09:02:04.707617Z","steps":["trace[2105311684] 'process raft request' (duration: 204.488098ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:04.707427Z","caller":"traceutil/trace.go:171","msg":"trace[1070686335] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"207.082553ms","start":"2026-08-14T09:02:04.500338Z","end":"2026-08-14T09:02:04.707421Z","steps":["trace[1070686335] 'process raft request' (duration: 206.236692ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:04.707733Z","caller":"traceutil/trace.go:171","msg":"trace[1571341834] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"201.579515ms","start":"2026-08-14T09:02:04.506146Z","end":"2026-08-14T09:02:04.707726Z","steps":["trace[1571341834] 'process raft request' (duration: 200.573611ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:04.707752Z","caller":"traceutil/trace.go:171","msg":"trace[1869584635] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"191.113624ms","start":"2026-08-14T09:02:04.516634Z","end":"2026-08-14T09:02:04.707747Z","steps":["trace[1869584635] 'process raft request' (duration: 190.219032ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:02:04.991685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.609683ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-08-14T09:02:04.992132Z","caller":"traceutil/trace.go:171","msg":"trace[1280727922] linearizableReadLoop","detail":"{readStateIndex:589; appliedIndex:579; }","duration":"317.664165ms","start":"2026-08-14T09:02:04.674424Z","end":"2026-08-14T09:02:04.992088Z","steps":["trace[1280727922] 'read index received' (duration: 31.791613ms)","trace[1280727922] 'applied index is now lower than readState.Index' (duration: 285.871612ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:02:04.992222Z","caller":"traceutil/trace.go:171","msg":"trace[666230153] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"286.922258ms","start":"2026-08-14T09:02:04.70527Z","end":"2026-08-14T09:02:04.992192Z","steps":["trace[666230153] 'process raft request' (duration: 286.545358ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:04.992266Z","caller":"traceutil/trace.go:171","msg":"trace[1462498379] transaction","detail":"{read_only:false; number_of_response:0; response_revision:567; }","duration":"325.86211ms","start":"2026-08-14T09:02:04.666358Z","end":"2026-08-14T09:02:04.992221Z","steps":["trace[1462498379] 'process raft request' (duration: 198.640422ms)","trace[1462498379] 'compare' (duration: 126.549282ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:02:04.992292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.858892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-14T09:02:04.992505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:04.66633Z","time spent":"326.027176ms","remote":"127.0.0.1:53490","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-14T09:02:04.992424Z","caller":"traceutil/trace.go:171","msg":"trace[641686423] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:568; }","duration":"318.009745ms","start":"2026-08-14T09:02:04.674374Z","end":"2026-08-14T09:02:04.992384Z","steps":["trace[641686423] 'agreement among raft nodes before linearized reading' (duration: 317.800779ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:02:04.992616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:04.67435Z","time spent":"318.22131ms","remote":"127.0.0.1:53000","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-08-14T09:02:05.015749Z","caller":"traceutil/trace.go:171","msg":"trace[1121171052] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"308.874256ms","start":"2026-08-14T09:02:04.706849Z","end":"2026-08-14T09:02:05.015724Z","steps":["trace[1121171052] 'process raft request' (duration: 308.547308ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:02:05.016001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:04.706822Z","time spent":"309.05265ms","remote":"127.0.0.1:53050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:02:05.016237Z","caller":"traceutil/trace.go:171","msg":"trace[231187557] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"300.675291ms","start":"2026-08-14T09:02:04.715535Z","end":"2026-08-14T09:02:05.01621Z","steps":["trace[231187557] 'process raft request' (duration: 300.106697ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:02:05.016388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:04.715504Z","time spent":"300.797504ms","remote":"127.0.0.1:53050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10846,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:02:05.016374Z","caller":"traceutil/trace.go:171","msg":"trace[870902615] transaction","detail":"{read_only:false; number_of_response:1; response_revision:570; }","duration":"300.646531ms","start":"2026-08-14T09:02:04.715677Z","end":"2026-08-14T09:02:05.016324Z","steps":["trace[870902615] 'process raft request' (duration: 300.371934ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:05.016463Z","caller":"traceutil/trace.go:171","msg":"trace[1310005600] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"293.376409ms","start":"2026-08-14T09:02:04.723058Z","end":"2026-08-14T09:02:05.016435Z","steps":["trace[1310005600] 'process raft request' (duration: 293.134183ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:02:05.016583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:04.71567Z","time spent":"300.790494ms","remote":"127.0.0.1:53050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7374,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:02:05.016683Z","caller":"traceutil/trace.go:171","msg":"trace[1787918559] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"292.594869ms","start":"2026-08-14T09:02:04.724073Z","end":"2026-08-14T09:02:05.016668Z","steps":["trace[1787918559] 'process raft request' (duration: 292.22109ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:05.016801Z","caller":"traceutil/trace.go:171","msg":"trace[1223304699] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"264.406565ms","start":"2026-08-14T09:02:04.752385Z","end":"2026-08-14T09:02:05.016791Z","steps":["trace[1223304699] 'process raft request' (duration: 264.028336ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:05.016892Z","caller":"traceutil/trace.go:171","msg":"trace[773517383] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"173.279269ms","start":"2026-08-14T09:02:04.843599Z","end":"2026-08-14T09:02:05.016879Z","steps":["trace[773517383] 'process raft request' (duration: 173.145436ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:05.016944Z","caller":"traceutil/trace.go:171","msg":"trace[2079046266] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"226.360965ms","start":"2026-08-14T09:02:04.790517Z","end":"2026-08-14T09:02:05.016878Z","steps":["trace[2079046266] 'process raft request' (duration: 226.16673ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:05.239638Z","caller":"traceutil/trace.go:171","msg":"trace[1926169067] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"206.721854ms","start":"2026-08-14T09:02:05.032894Z","end":"2026-08-14T09:02:05.239616Z","steps":["trace[1926169067] 'process raft request' (duration: 136.357117ms)","trace[1926169067] 'compare' (duration: 70.171383ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:02:05.23978Z","caller":"traceutil/trace.go:171","msg":"trace[1861827362] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"199.301029ms","start":"2026-08-14T09:02:05.040465Z","end":"2026-08-14T09:02:05.239767Z","steps":["trace[1861827362] 'process raft request' (duration: 199.091814ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:05.25301Z","caller":"traceutil/trace.go:171","msg":"trace[300364755] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"174.781796ms","start":"2026-08-14T09:02:05.078201Z","end":"2026-08-14T09:02:05.252983Z","steps":["trace[300364755] 'process raft request' (duration: 174.644163ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:05.471494Z","caller":"traceutil/trace.go:171","msg":"trace[2106638538] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"213.079274ms","start":"2026-08-14T09:02:05.258371Z","end":"2026-08-14T09:02:05.47145Z","steps":["trace[2106638538] 'process raft request' (duration: 143.994488ms)","trace[2106638538] 'compare' (duration: 68.87696ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:02:05.473924Z","caller":"traceutil/trace.go:171","msg":"trace[1747482917] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"203.578375ms","start":"2026-08-14T09:02:05.270315Z","end":"2026-08-14T09:02:05.473894Z","steps":["trace[1747482917] 'process raft request' (duration: 203.344079ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:05.474344Z","caller":"traceutil/trace.go:171","msg":"trace[664111630] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"153.441503ms","start":"2026-08-14T09:02:05.320848Z","end":"2026-08-14T09:02:05.474289Z","steps":["trace[664111630] 'process raft request' (duration: 152.990711ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:05.672291Z","caller":"traceutil/trace.go:171","msg":"trace[437794152] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"185.411632ms","start":"2026-08-14T09:02:05.486842Z","end":"2026-08-14T09:02:05.672253Z","steps":["trace[437794152] 'process raft request' (duration: 129.29369ms)","trace[437794152] 'compare' (duration: 55.780333ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:02:05.672675Z","caller":"traceutil/trace.go:171","msg":"trace[1106261209] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"166.56615ms","start":"2026-08-14T09:02:05.506065Z","end":"2026-08-14T09:02:05.672631Z","steps":["trace[1106261209] 'process raft request' (duration: 166.123799ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:05.673979Z","caller":"traceutil/trace.go:171","msg":"trace[125293097] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"116.87508ms","start":"2026-08-14T09:02:05.55707Z","end":"2026-08-14T09:02:05.673945Z","steps":["trace[125293097] 'process raft request' (duration: 116.549052ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:05.811677Z","caller":"traceutil/trace.go:171","msg":"trace[1651646707] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"120.866899ms","start":"2026-08-14T09:02:05.690781Z","end":"2026-08-14T09:02:05.811648Z","steps":["trace[1651646707] 'process raft request' (duration: 103.916876ms)","trace[1651646707] 'compare' (duration: 16.792099ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:02:09.256052Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17567873641173213208,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:02:09.757304Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17567873641173213208,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:02:10.258206Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17567873641173213208,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:02:10.759301Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17567873641173213208,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:02:11.259626Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17567873641173213208,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:02:11.454526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999953642s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-14T09:02:11.454624Z","caller":"traceutil/trace.go:171","msg":"trace[616413137] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000119256s","start":"2026-08-14T09:02:09.454482Z","end":"2026-08-14T09:02:11.454601Z","steps":["trace[616413137] 'agreement among raft nodes before linearized reading' (duration: 1.999988793s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:02:11.454677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:09.454462Z","time spent":"2.000204229s","remote":"127.0.0.1:53000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/14 09:02:11 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-14T09:02:11.61256Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.858516012s","expected-duration":"1s"} {"level":"info","ts":"2026-08-14T09:02:11.613016Z","caller":"traceutil/trace.go:171","msg":"trace[1614727032] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"2.859079405s","start":"2026-08-14T09:02:08.753903Z","end":"2026-08-14T09:02:11.612982Z","steps":["trace[1614727032] 'process raft request' (duration: 2.858897791s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:02:11.613199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:08.75386Z","time spent":"2.859241719s","remote":"127.0.0.1:53242","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-08-14T09:02:11.616717Z","caller":"traceutil/trace.go:171","msg":"trace[1777950895] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"2.859550227s","start":"2026-08-14T09:02:08.757145Z","end":"2026-08-14T09:02:11.616695Z","steps":["trace[1777950895] 'process raft request' (duration: 2.859473045s)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:11.616741Z","caller":"traceutil/trace.go:171","msg":"trace[1706816221] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"2.859743811s","start":"2026-08-14T09:02:08.756979Z","end":"2026-08-14T09:02:11.616723Z","steps":["trace[1706816221] 'process raft request' (duration: 2.859511656s)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:11.616841Z","caller":"traceutil/trace.go:171","msg":"trace[1919248190] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"1.588384501s","start":"2026-08-14T09:02:10.028433Z","end":"2026-08-14T09:02:11.616817Z","steps":["trace[1919248190] 'process raft request' (duration: 1.588222427s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:02:11.616871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:08.756959Z","time spent":"2.859854214s","remote":"127.0.0.1:53242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:02:11.616893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:08.757136Z","time spent":"2.859647749s","remote":"127.0.0.1:53242","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-08-14T09:02:11.61694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:10.028405Z","time spent":"1.588487403s","remote":"127.0.0.1:53242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:02:11.616838Z","caller":"traceutil/trace.go:171","msg":"trace[369885327] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:645; }","duration":"2.8617033s","start":"2026-08-14T09:02:08.755107Z","end":"2026-08-14T09:02:11.61681Z","steps":["trace[369885327] 'read index received' (duration: 2.85769863s)","trace[369885327] 'applied index is now lower than readState.Index' (duration: 4.00321ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:02:11.617046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.861919905s","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-08-14T09:02:11.617082Z","caller":"traceutil/trace.go:171","msg":"trace[1856475477] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:625; }","duration":"2.862033438s","start":"2026-08-14T09:02:08.755041Z","end":"2026-08-14T09:02:11.617074Z","steps":["trace[1856475477] 'agreement among raft nodes before linearized reading' (duration: 2.861839343s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:02:11.617107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:08.75502Z","time spent":"2.862081679s","remote":"127.0.0.1:53242","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-08-14T09:02:11.617169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.974516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-14T09:02:11.617223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.6019234s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-08-14T09:02:11.617238Z","caller":"traceutil/trace.go:171","msg":"trace[757440804] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:625; }","duration":"162.078749ms","start":"2026-08-14T09:02:11.455142Z","end":"2026-08-14T09:02:11.61722Z","steps":["trace[757440804] 'agreement among raft nodes before linearized reading' (duration: 161.950866ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:02:11.61726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"912.370072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.37\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-14T09:02:11.617359Z","caller":"traceutil/trace.go:171","msg":"trace[1137901515] range","detail":"{range_begin:/registry/masterleases/199.19.213.37; range_end:; response_count:1; response_revision:625; }","duration":"912.498535ms","start":"2026-08-14T09:02:10.70484Z","end":"2026-08-14T09:02:11.617339Z","steps":["trace[1137901515] 'agreement among raft nodes before linearized reading' (duration: 912.31593ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:02:11.617442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:10.704819Z","time spent":"912.590597ms","remote":"127.0.0.1:53038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.19.213.37\" "} {"level":"info","ts":"2026-08-14T09:02:11.617272Z","caller":"traceutil/trace.go:171","msg":"trace[1495687064] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:625; }","duration":"2.602007942s","start":"2026-08-14T09:02:09.015248Z","end":"2026-08-14T09:02:11.617256Z","steps":["trace[1495687064] 'agreement among raft nodes before linearized reading' (duration: 2.601832207s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:02:11.617537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:09.015227Z","time spent":"2.602298749s","remote":"127.0.0.1:53050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":1,"response size":29592,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-08-14T09:02:14.955Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17567873641173213267,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-14T09:02:14.990576Z","caller":"traceutil/trace.go:171","msg":"trace[1991841869] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:661; }","duration":"535.997181ms","start":"2026-08-14T09:02:14.4545Z","end":"2026-08-14T09:02:14.990497Z","steps":["trace[1991841869] 'read index received' (duration: 529.673992ms)","trace[1991841869] 'applied index is now lower than readState.Index' (duration: 6.320509ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:02:14.990654Z","caller":"traceutil/trace.go:171","msg":"trace[1523019382] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"962.173837ms","start":"2026-08-14T09:02:14.028433Z","end":"2026-08-14T09:02:14.990607Z","steps":["trace[1523019382] 'process raft request' (duration: 956.940666ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:02:14.990732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.206416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:02:14.990797Z","caller":"traceutil/trace.go:171","msg":"trace[2141925863] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"536.320029ms","start":"2026-08-14T09:02:14.45446Z","end":"2026-08-14T09:02:14.99078Z","steps":["trace[2141925863] 'agreement among raft nodes before linearized reading' (duration: 536.204066ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:02:14.990845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:14.454442Z","time spent":"536.383411ms","remote":"127.0.0.1:53000","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-08-14T09:02:14.990845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:14.028405Z","time spent":"962.340651ms","remote":"127.0.0.1:53070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036105,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:02:16.125697Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17567873641173213271,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:02:16.626681Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17567873641173213271,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:02:17.127334Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17567873641173213271,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:02:17.627836Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17567873641173213271,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:02:17.653236Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.264224364s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-14T09:02:18.261641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.872554441s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:02:18.261902Z","caller":"traceutil/trace.go:171","msg":"trace[284445202] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"3.195443677s","start":"2026-08-14T09:02:15.066437Z","end":"2026-08-14T09:02:18.261881Z","steps":["trace[284445202] 'process raft request' (duration: 3.195350844s)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:18.261936Z","caller":"traceutil/trace.go:171","msg":"trace[1773371556] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"3.213729244s","start":"2026-08-14T09:02:15.048179Z","end":"2026-08-14T09:02:18.261908Z","steps":["trace[1773371556] 'process raft request' (duration: 340.809764ms)","trace[1773371556] 'compare' (duration: 2.872141641s)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:02:18.262053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:15.066388Z","time spent":"3.195561759s","remote":"127.0.0.1:53242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:02:18.262104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:15.04816Z","time spent":"3.213904918s","remote":"127.0.0.1:53174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-14T09:02:18.42778Z","caller":"traceutil/trace.go:171","msg":"trace[204548320] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:662; }","duration":"2.802774499s","start":"2026-08-14T09:02:15.624965Z","end":"2026-08-14T09:02:18.427739Z","steps":["trace[204548320] 'read index received' (duration: 2.028417654s)","trace[204548320] 'applied index is now lower than readState.Index' (duration: 774.354945ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:02:18.428219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.803222929s","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-08-14T09:02:18.428343Z","caller":"traceutil/trace.go:171","msg":"trace[1334483537] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:639; }","duration":"2.803402234s","start":"2026-08-14T09:02:15.624914Z","end":"2026-08-14T09:02:18.428316Z","steps":["trace[1334483537] 'agreement among raft nodes before linearized reading' (duration: 2.803009734s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:02:18.428429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:15.624896Z","time spent":"2.803517366s","remote":"127.0.0.1:53242","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-08-14T09:02:18.428589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.796877192s","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-08-14T09:02:18.428653Z","caller":"traceutil/trace.go:171","msg":"trace[1103442082] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:639; }","duration":"2.796977034s","start":"2026-08-14T09:02:15.631654Z","end":"2026-08-14T09:02:18.428631Z","steps":["trace[1103442082] 'agreement among raft nodes before linearized reading' (duration: 2.796724718s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:02:18.428807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:15.631646Z","time spent":"2.797147328s","remote":"127.0.0.1:53242","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-08-14T09:02:18.42899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.974591159s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-14T09:02:18.428973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.208416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:02:18.429047Z","caller":"traceutil/trace.go:171","msg":"trace[1437247529] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"1.974672971s","start":"2026-08-14T09:02:16.45436Z","end":"2026-08-14T09:02:18.429033Z","steps":["trace[1437247529] 'agreement among raft nodes before linearized reading' (duration: 1.974573968s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:02:18.429092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:16.454342Z","time spent":"1.974743503s","remote":"127.0.0.1:53000","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-08-14T09:02:18.429099Z","caller":"traceutil/trace.go:171","msg":"trace[235952211] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"452.34051ms","start":"2026-08-14T09:02:17.976729Z","end":"2026-08-14T09:02:18.42907Z","steps":["trace[235952211] 'agreement among raft nodes before linearized reading' (duration: 452.145585ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:02:18.429146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:17.976644Z","time spent":"452.493744ms","remote":"127.0.0.1:53000","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-08-14T09:02:18.42917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.797222509s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-14T09:02:18.429248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.791566588s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-08-14T09:02:18.42926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.633749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:02:18.429389Z","caller":"traceutil/trace.go:171","msg":"trace[405819487] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:639; }","duration":"2.791772782s","start":"2026-08-14T09:02:15.637574Z","end":"2026-08-14T09:02:18.429347Z","steps":["trace[405819487] 'agreement among raft nodes before linearized reading' (duration: 2.791322071s)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:18.4294Z","caller":"traceutil/trace.go:171","msg":"trace[1433883323] range","detail":"{range_begin:/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:639; }","duration":"155.882565ms","start":"2026-08-14T09:02:18.273493Z","end":"2026-08-14T09:02:18.429375Z","steps":["trace[1433883323] 'agreement among raft nodes before linearized reading' (duration: 155.582978ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:02:18.429445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:15.637564Z","time spent":"2.791872146s","remote":"127.0.0.1:53242","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":"info","ts":"2026-08-14T09:02:18.429279Z","caller":"traceutil/trace.go:171","msg":"trace[1075983596] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:639; }","duration":"2.797364843s","start":"2026-08-14T09:02:15.631893Z","end":"2026-08-14T09:02:18.429258Z","steps":["trace[1075983596] 'agreement among raft nodes before linearized reading' (duration: 2.797031235s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:02:18.429592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:15.631873Z","time spent":"2.797701012s","remote":"127.0.0.1:53242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-14T09:02:19.608266Z","caller":"traceutil/trace.go:171","msg":"trace[407269544] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:670; }","duration":"155.788002ms","start":"2026-08-14T09:02:19.452455Z","end":"2026-08-14T09:02:19.608243Z","steps":["trace[407269544] 'read index received' (duration: 154.88758ms)","trace[407269544] 'applied index is now lower than readState.Index' (duration: 899.482µs)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:02:19.608395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.928055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:02:19.608375Z","caller":"traceutil/trace.go:171","msg":"trace[692461104] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"765.081363ms","start":"2026-08-14T09:02:18.843259Z","end":"2026-08-14T09:02:19.608341Z","steps":["trace[692461104] 'process raft request' (duration: 764.351595ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:19.608431Z","caller":"traceutil/trace.go:171","msg":"trace[1584576553] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:645; }","duration":"156.048659ms","start":"2026-08-14T09:02:19.452373Z","end":"2026-08-14T09:02:19.608421Z","steps":["trace[1584576553] 'agreement among raft nodes before linearized reading' (duration: 155.954606ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:02:19.608528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:18.843222Z","time spent":"765.222546ms","remote":"127.0.0.1:53070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036161,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:02:20.955706Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17567873641173213295,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:02:21.456661Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17567873641173213295,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:02:21.957396Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17567873641173213295,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:02:22.204343Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.077648533s","expected-duration":"1s"} {"level":"info","ts":"2026-08-14T09:02:22.207623Z","caller":"traceutil/trace.go:171","msg":"trace[1170395037] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"2.082574727s","start":"2026-08-14T09:02:20.125022Z","end":"2026-08-14T09:02:22.207597Z","steps":["trace[1170395037] 'process raft request' (duration: 2.081299894s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:02:22.207774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:20.12499Z","time spent":"2.0827022s","remote":"127.0.0.1:53070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036197,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:02:22.457175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000451014s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-14T09:02:22.457277Z","caller":"traceutil/trace.go:171","msg":"trace[403166394] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000594208s","start":"2026-08-14T09:02:20.456662Z","end":"2026-08-14T09:02:22.457256Z","steps":["trace[403166394] 'agreement among raft nodes before linearized reading' (duration: 2.000463905s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:02:22.45733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:20.456623Z","time spent":"2.000695341s","remote":"127.0.0.1:53000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/14 09:02:22 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-08-14T09:02:22.731225Z","caller":"traceutil/trace.go:171","msg":"trace[1012193626] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:676; }","duration":"2.275560788s","start":"2026-08-14T09:02:20.455645Z","end":"2026-08-14T09:02:22.731206Z","steps":["trace[1012193626] 'read index received' (duration: 1.748947883s)","trace[1012193626] 'applied index is now lower than readState.Index' (duration: 526.611975ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:02:22.73131Z","caller":"traceutil/trace.go:171","msg":"trace[217427560] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"784.794146ms","start":"2026-08-14T09:02:21.946476Z","end":"2026-08-14T09:02:22.73127Z","steps":["trace[217427560] 'process raft request' (duration: 783.909593ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:02:22.731455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.275793314s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-14T09:02:22.731491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:21.946441Z","time spent":"784.959779ms","remote":"127.0.0.1:53242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:02:22.731508Z","caller":"traceutil/trace.go:171","msg":"trace[1221326346] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:651; }","duration":"2.275888326s","start":"2026-08-14T09:02:20.455606Z","end":"2026-08-14T09:02:22.731494Z","steps":["trace[1221326346] 'agreement among raft nodes before linearized reading' (duration: 2.275678341s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:02:22.731544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:20.455557Z","time spent":"2.275978308s","remote":"127.0.0.1:53242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-14T09:02:22.731847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.150203ms","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-08-14T09:02:22.731898Z","caller":"traceutil/trace.go:171","msg":"trace[804839748] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:652; }","duration":"560.208595ms","start":"2026-08-14T09:02:22.171675Z","end":"2026-08-14T09:02:22.731884Z","steps":["trace[804839748] 'agreement among raft nodes before linearized reading' (duration: 560.122563ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:02:22.731899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.275434574s","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-08-14T09:02:22.731932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.275961967s","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-08-14T09:02:22.731947Z","caller":"traceutil/trace.go:171","msg":"trace[782259282] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:652; }","duration":"2.275506095s","start":"2026-08-14T09:02:20.456408Z","end":"2026-08-14T09:02:22.731933Z","steps":["trace[782259282] 'agreement among raft nodes before linearized reading' (duration: 2.275395432s)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:22.731975Z","caller":"traceutil/trace.go:171","msg":"trace[1230813870] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:652; }","duration":"2.276093181s","start":"2026-08-14T09:02:20.455872Z","end":"2026-08-14T09:02:22.731965Z","steps":["trace[1230813870] 'agreement among raft nodes before linearized reading' (duration: 2.275990188s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:02:22.731971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"927.4548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-14T09:02:22.731969Z","caller":"traceutil/trace.go:171","msg":"trace[729812701] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"457.026747ms","start":"2026-08-14T09:02:22.274912Z","end":"2026-08-14T09:02:22.731939Z","steps":["trace[729812701] 'process raft request' (duration: 456.784041ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:02:22.732007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:20.455826Z","time spent":"2.276175483s","remote":"127.0.0.1:53242","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-08-14T09:02:22.732013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.409802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:02:22.732037Z","caller":"traceutil/trace.go:171","msg":"trace[82924375] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:652; }","duration":"927.561042ms","start":"2026-08-14T09:02:21.804463Z","end":"2026-08-14T09:02:22.732024Z","steps":["trace[82924375] 'agreement among raft nodes before linearized reading' (duration: 927.46101ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:22.732043Z","caller":"traceutil/trace.go:171","msg":"trace[26770028] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:652; }","duration":"452.467134ms","start":"2026-08-14T09:02:22.279567Z","end":"2026-08-14T09:02:22.732034Z","steps":["trace[26770028] 'agreement among raft nodes before linearized reading' (duration: 452.429043ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:02:22.731979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:20.456397Z","time spent":"2.275576207s","remote":"127.0.0.1:53242","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-08-14T09:02:22.732075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:22.279554Z","time spent":"452.514155ms","remote":"127.0.0.1:53220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-08-14T09:02:22.732078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:21.804438Z","time spent":"927.630923ms","remote":"127.0.0.1:53456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":29,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-08-14T09:02:22.731939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:22.17162Z","time spent":"560.310107ms","remote":"127.0.0.1:53270","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-08-14T09:02:22.732079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.069202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-14T09:02:22.732051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.276111401s","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-08-14T09:02:22.732186Z","caller":"traceutil/trace.go:171","msg":"trace[1675912183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:652; }","duration":"2.276327327s","start":"2026-08-14T09:02:20.455849Z","end":"2026-08-14T09:02:22.732177Z","steps":["trace[1675912183] 'agreement among raft nodes before linearized reading' (duration: 2.275921527s)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:22.732194Z","caller":"traceutil/trace.go:171","msg":"trace[299007034] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:652; }","duration":"269.254797ms","start":"2026-08-14T09:02:22.462909Z","end":"2026-08-14T09:02:22.732163Z","steps":["trace[299007034] 'agreement among raft nodes before linearized reading' (duration: 269.010071ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:02:22.732212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:20.455776Z","time spent":"2.276430629s","remote":"127.0.0.1:53242","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-08-14T09:02:22.731882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.026349771s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.37\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-14T09:02:22.732298Z","caller":"traceutil/trace.go:171","msg":"trace[1123982325] range","detail":"{range_begin:/registry/masterleases/199.19.213.37; range_end:; response_count:1; response_revision:652; }","duration":"2.026833503s","start":"2026-08-14T09:02:20.705458Z","end":"2026-08-14T09:02:22.732292Z","steps":["trace[1123982325] 'agreement among raft nodes before linearized reading' (duration: 2.026359611s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:02:22.732322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:20.705434Z","time spent":"2.026882995s","remote":"127.0.0.1:53038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.19.213.37\" "} {"level":"warn","ts":"2026-08-14T09:02:22.731976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.454166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:02:22.732395Z","caller":"traceutil/trace.go:171","msg":"trace[1741761419] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:652; }","duration":"274.903337ms","start":"2026-08-14T09:02:22.457477Z","end":"2026-08-14T09:02:22.73238Z","steps":["trace[1741761419] 'agreement among raft nodes before linearized reading' (duration: 274.480787ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:02:22.732059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:22.274885Z","time spent":"457.14082ms","remote":"127.0.0.1:53330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1044,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-14T09:02:24.282109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.339435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:02:24.28222Z","caller":"traceutil/trace.go:171","msg":"trace[1291350661] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:0; response_revision:696; }","duration":"204.507749ms","start":"2026-08-14T09:02:24.077687Z","end":"2026-08-14T09:02:24.282195Z","steps":["trace[1291350661] 'range keys from in-memory index tree' (duration: 204.240252ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:24.288759Z","caller":"traceutil/trace.go:171","msg":"trace[254829844] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"176.023308ms","start":"2026-08-14T09:02:24.112718Z","end":"2026-08-14T09:02:24.288741Z","steps":["trace[254829844] 'process raft request' (duration: 171.400072ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:02:37.938789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.03425ms","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-08-14T09:02:37.938852Z","caller":"traceutil/trace.go:171","msg":"trace[91769989] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:823; }","duration":"181.146553ms","start":"2026-08-14T09:02:37.757694Z","end":"2026-08-14T09:02:37.93884Z","steps":["trace[91769989] 'range keys from in-memory index tree' (duration: 180.906047ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:52.406131Z","caller":"traceutil/trace.go:171","msg":"trace[676144814] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"344.003647ms","start":"2026-08-14T09:02:52.0621Z","end":"2026-08-14T09:02:52.406103Z","steps":["trace[676144814] 'process raft request' (duration: 343.804202ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:02:52.406285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:52.062083Z","time spent":"344.12771ms","remote":"127.0.0.1:53242","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-08-14T09:02:52.670686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.405097ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:02:52.670782Z","caller":"traceutil/trace.go:171","msg":"trace[1439638213] linearizableReadLoop","detail":"{readStateIndex:994; appliedIndex:993; }","duration":"113.014814ms","start":"2026-08-14T09:02:52.557752Z","end":"2026-08-14T09:02:52.670767Z","steps":["trace[1439638213] 'read index received' (duration: 35.831µs)","trace[1439638213] 'applied index is now lower than readState.Index' (duration: 112.977703ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:02:52.670852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.094117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:02:52.670834Z","caller":"traceutil/trace.go:171","msg":"trace[2147445820] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"215.531268ms","start":"2026-08-14T09:02:52.455274Z","end":"2026-08-14T09:02:52.670805Z","steps":["trace[2147445820] 'process raft request' (duration: 80.903485ms)","trace[2147445820] 'compare' (duration: 134.034408ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:02:52.670883Z","caller":"traceutil/trace.go:171","msg":"trace[994013440] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:958; }","duration":"113.156058ms","start":"2026-08-14T09:02:52.557718Z","end":"2026-08-14T09:02:52.670874Z","steps":["trace[994013440] 'agreement among raft nodes before linearized reading' (duration: 113.092006ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:53.271948Z","caller":"traceutil/trace.go:171","msg":"trace[474565430] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"288.072114ms","start":"2026-08-14T09:02:52.983846Z","end":"2026-08-14T09:02:53.271918Z","steps":["trace[474565430] 'process raft request' (duration: 287.934951ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:53.272177Z","caller":"traceutil/trace.go:171","msg":"trace[1816031305] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"288.209987ms","start":"2026-08-14T09:02:52.983942Z","end":"2026-08-14T09:02:53.272152Z","steps":["trace[1816031305] 'process raft request' (duration: 287.9347ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:53.272502Z","caller":"traceutil/trace.go:171","msg":"trace[295938559] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"288.2956ms","start":"2026-08-14T09:02:52.984198Z","end":"2026-08-14T09:02:53.272494Z","steps":["trace[295938559] 'process raft request' (duration: 287.885569ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:53.642253Z","caller":"traceutil/trace.go:171","msg":"trace[1133170561] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"130.631044ms","start":"2026-08-14T09:02:53.511599Z","end":"2026-08-14T09:02:53.64223Z","steps":["trace[1133170561] 'process raft request' (duration: 130.49512ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:02:55.227894Z","caller":"traceutil/trace.go:171","msg":"trace[1918434473] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"552.626282ms","start":"2026-08-14T09:02:54.675236Z","end":"2026-08-14T09:02:55.227862Z","steps":["trace[1918434473] 'process raft request' (duration: 552.440388ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:02:55.228079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:54.675218Z","time spent":"552.751825ms","remote":"127.0.0.1:53142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5316,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:02:55.304513Z","caller":"traceutil/trace.go:171","msg":"trace[490265750] linearizableReadLoop","detail":"{readStateIndex:1012; appliedIndex:1010; }","duration":"222.948122ms","start":"2026-08-14T09:02:55.081544Z","end":"2026-08-14T09:02:55.304492Z","steps":["trace[490265750] 'read index received' (duration: 146.16107ms)","trace[490265750] 'applied index is now lower than readState.Index' (duration: 76.785872ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:02:55.304582Z","caller":"traceutil/trace.go:171","msg":"trace[72841253] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"628.492412ms","start":"2026-08-14T09:02:54.676065Z","end":"2026-08-14T09:02:55.304557Z","steps":["trace[72841253] 'process raft request' (duration: 616.35333ms)","trace[72841253] 'compare' (duration: 11.866126ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:02:55.304747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:02:54.676044Z","time spent":"628.584434ms","remote":"127.0.0.1:53242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:02:55.304738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.175417ms","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-08-14T09:02:55.304815Z","caller":"traceutil/trace.go:171","msg":"trace[1734583967] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:976; }","duration":"223.2881ms","start":"2026-08-14T09:02:55.081508Z","end":"2026-08-14T09:02:55.304796Z","steps":["trace[1734583967] 'agreement among raft nodes before linearized reading' (duration: 223.069185ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:03:57.09018Z","caller":"traceutil/trace.go:171","msg":"trace[2011894358] linearizableReadLoop","detail":"{readStateIndex:1232; appliedIndex:1231; }","duration":"149.022822ms","start":"2026-08-14T09:03:56.941137Z","end":"2026-08-14T09:03:57.09016Z","steps":["trace[2011894358] 'read index received' (duration: 148.783316ms)","trace[2011894358] 'applied index is now lower than readState.Index' (duration: 238.616µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:03:57.090326Z","caller":"traceutil/trace.go:171","msg":"trace[218273539] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"361.713027ms","start":"2026-08-14T09:03:56.728559Z","end":"2026-08-14T09:03:57.090272Z","steps":["trace[218273539] 'process raft request' (duration: 361.473801ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:03:57.090329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.167696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:03:57.090407Z","caller":"traceutil/trace.go:171","msg":"trace[1245349814] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1183; }","duration":"149.292929ms","start":"2026-08-14T09:03:56.941103Z","end":"2026-08-14T09:03:57.090396Z","steps":["trace[1245349814] 'agreement among raft nodes before linearized reading' (duration: 149.142985ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:03:57.090503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:03:56.728542Z","time spent":"361.8622ms","remote":"127.0.0.1:53242","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-08-14T09:04:19.34588Z","caller":"traceutil/trace.go:171","msg":"trace[1299674507] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"120.815642ms","start":"2026-08-14T09:04:19.225033Z","end":"2026-08-14T09:04:19.345849Z","steps":["trace[1299674507] 'process raft request' (duration: 120.625847ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:04:19.816263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.478806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:04:19.816403Z","caller":"traceutil/trace.go:171","msg":"trace[1034902754] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1255; }","duration":"360.6505ms","start":"2026-08-14T09:04:19.455705Z","end":"2026-08-14T09:04:19.816355Z","steps":["trace[1034902754] 'range keys from in-memory index tree' (duration: 360.391704ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:04:19.816455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:04:19.455686Z","time spent":"360.758673ms","remote":"127.0.0.1:53000","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-08-14T09:04:19.846586Z","caller":"traceutil/trace.go:171","msg":"trace[2080134608] linearizableReadLoop","detail":"{readStateIndex:1309; appliedIndex:1308; }","duration":"182.28028ms","start":"2026-08-14T09:04:19.664284Z","end":"2026-08-14T09:04:19.846564Z","steps":["trace[2080134608] 'read index received' (duration: 182.098115ms)","trace[2080134608] 'applied index is now lower than readState.Index' (duration: 180.984µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:04:19.846683Z","caller":"traceutil/trace.go:171","msg":"trace[154761836] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"196.985614ms","start":"2026-08-14T09:04:19.649669Z","end":"2026-08-14T09:04:19.846655Z","steps":["trace[154761836] 'process raft request' (duration: 196.763718ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:04:19.846807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.476854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:04:19.846918Z","caller":"traceutil/trace.go:171","msg":"trace[834680653] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1256; }","duration":"182.619088ms","start":"2026-08-14T09:04:19.664274Z","end":"2026-08-14T09:04:19.846893Z","steps":["trace[834680653] 'agreement among raft nodes before linearized reading' (duration: 182.409042ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:04:19.955177Z","caller":"traceutil/trace.go:171","msg":"trace[470115382] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"103.407149ms","start":"2026-08-14T09:04:19.851726Z","end":"2026-08-14T09:04:19.955133Z","steps":["trace[470115382] 'process raft request' (duration: 103.304347ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:04:19.955241Z","caller":"traceutil/trace.go:171","msg":"trace[2068126094] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"103.602804ms","start":"2026-08-14T09:04:19.851587Z","end":"2026-08-14T09:04:19.95519Z","steps":["trace[2068126094] 'process raft request' (duration: 102.67044ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:04:19.955832Z","caller":"traceutil/trace.go:171","msg":"trace[1093173988] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"103.282796ms","start":"2026-08-14T09:04:19.85251Z","end":"2026-08-14T09:04:19.955793Z","steps":["trace[1093173988] 'process raft request' (duration: 102.583879ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:04:35.533956Z","caller":"traceutil/trace.go:171","msg":"trace[1977172700] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"114.894405ms","start":"2026-08-14T09:04:35.41905Z","end":"2026-08-14T09:04:35.533944Z","steps":["trace[1977172700] 'process raft request' (duration: 114.69321ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:04:51.491791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.70503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-f9t5p\" ","response":"range_response_count:1 size:11057"} {"level":"info","ts":"2026-08-14T09:04:51.491852Z","caller":"traceutil/trace.go:171","msg":"trace[1225468939] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-f9t5p; range_end:; response_count:1; response_revision:1429; }","duration":"146.804423ms","start":"2026-08-14T09:04:51.345035Z","end":"2026-08-14T09:04:51.491839Z","steps":["trace[1225468939] 'range keys from in-memory index tree' (duration: 146.577066ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:04:54.069903Z","caller":"traceutil/trace.go:171","msg":"trace[845522721] transaction","detail":"{read_only:false; response_revision:1447; number_of_response:1; }","duration":"454.539071ms","start":"2026-08-14T09:04:53.615342Z","end":"2026-08-14T09:04:54.069882Z","steps":["trace[845522721] 'process raft request' (duration: 454.377737ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:04:54.070045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:04:53.615326Z","time spent":"454.634613ms","remote":"127.0.0.1:53242","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-08-14T09:04:54.221901Z","caller":"traceutil/trace.go:171","msg":"trace[1185098717] linearizableReadLoop","detail":"{readStateIndex:1510; appliedIndex:1508; }","duration":"174.628457ms","start":"2026-08-14T09:04:54.047252Z","end":"2026-08-14T09:04:54.22188Z","steps":["trace[1185098717] 'read index received' (duration: 22.497182ms)","trace[1185098717] 'applied index is now lower than readState.Index' (duration: 152.130035ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:04:54.22219Z","caller":"traceutil/trace.go:171","msg":"trace[1217327361] transaction","detail":"{read_only:false; response_revision:1448; number_of_response:1; }","duration":"271.081653ms","start":"2026-08-14T09:04:53.951095Z","end":"2026-08-14T09:04:54.222176Z","steps":["trace[1217327361] 'process raft request' (duration: 235.955186ms)","trace[1217327361] 'compare' (duration: 34.507152ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:04:54.222446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.18936ms","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-08-14T09:04:54.222488Z","caller":"traceutil/trace.go:171","msg":"trace[146193658] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1448; }","duration":"175.276162ms","start":"2026-08-14T09:04:54.047202Z","end":"2026-08-14T09:04:54.222478Z","steps":["trace[146193658] 'agreement among raft nodes before linearized reading' (duration: 175.143499ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:04:54.222643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.061209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-14T09:04:54.222694Z","caller":"traceutil/trace.go:171","msg":"trace[58674683] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1448; }","duration":"143.153852ms","start":"2026-08-14T09:04:54.079531Z","end":"2026-08-14T09:04:54.222685Z","steps":["trace[58674683] 'agreement among raft nodes before linearized reading' (duration: 143.07726ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:04:54.222957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.64737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-14T09:04:54.222993Z","caller":"traceutil/trace.go:171","msg":"trace[147875498] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1448; }","duration":"110.697481ms","start":"2026-08-14T09:04:54.112287Z","end":"2026-08-14T09:04:54.222985Z","steps":["trace[147875498] 'agreement among raft nodes before linearized reading' (duration: 110.606489ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:04:54.22315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.874136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-14T09:04:54.223182Z","caller":"traceutil/trace.go:171","msg":"trace[691622915] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1448; }","duration":"110.926767ms","start":"2026-08-14T09:04:54.112248Z","end":"2026-08-14T09:04:54.223175Z","steps":["trace[691622915] 'agreement among raft nodes before linearized reading' (duration: 110.848055ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:04:54.223331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.610558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-08-14T09:04:54.223361Z","caller":"traceutil/trace.go:171","msg":"trace[792775587] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1448; }","duration":"122.660169ms","start":"2026-08-14T09:04:54.100693Z","end":"2026-08-14T09:04:54.223353Z","steps":["trace[792775587] 'agreement among raft nodes before linearized reading' (duration: 122.587427ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:04:55.950492Z","caller":"traceutil/trace.go:171","msg":"trace[1327115093] transaction","detail":"{read_only:false; response_revision:1454; number_of_response:1; }","duration":"309.951734ms","start":"2026-08-14T09:04:55.640517Z","end":"2026-08-14T09:04:55.950469Z","steps":["trace[1327115093] 'process raft request' (duration: 309.41176ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:04:55.950621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:04:55.640491Z","time spent":"310.062396ms","remote":"127.0.0.1:53242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:04:57.500639Z","caller":"traceutil/trace.go:171","msg":"trace[2112468479] transaction","detail":"{read_only:false; response_revision:1468; number_of_response:1; }","duration":"173.405297ms","start":"2026-08-14T09:04:57.327198Z","end":"2026-08-14T09:04:57.500604Z","steps":["trace[2112468479] 'process raft request' (duration: 173.1535ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:05:01.427885Z","caller":"traceutil/trace.go:171","msg":"trace[1786290850] transaction","detail":"{read_only:false; response_revision:1508; number_of_response:1; }","duration":"327.769028ms","start":"2026-08-14T09:05:01.100091Z","end":"2026-08-14T09:05:01.42786Z","steps":["trace[1786290850] 'process raft request' (duration: 327.605184ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:05:01.428053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:05:01.10007Z","time spent":"327.89785ms","remote":"127.0.0.1:53242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:05:02.003464Z","caller":"traceutil/trace.go:171","msg":"trace[1550000726] transaction","detail":"{read_only:false; response_revision:1509; number_of_response:1; }","duration":"478.951219ms","start":"2026-08-14T09:05:01.524413Z","end":"2026-08-14T09:05:02.003364Z","steps":["trace[1550000726] 'process raft request' (duration: 420.457199ms)","trace[1550000726] 'compare' (duration: 58.358756ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:05:02.003647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:05:01.52438Z","time spent":"479.178414ms","remote":"127.0.0.1:53242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:06:56.868919Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17567873641173216546,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-14T09:06:56.89518Z","caller":"traceutil/trace.go:171","msg":"trace[695120639] linearizableReadLoop","detail":"{readStateIndex:2122; appliedIndex:2120; }","duration":"526.782211ms","start":"2026-08-14T09:06:56.368382Z","end":"2026-08-14T09:06:56.895164Z","steps":["trace[695120639] 'read index received' (duration: 524.972677ms)","trace[695120639] 'applied index is now lower than readState.Index' (duration: 1.808844ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:06:56.895247Z","caller":"traceutil/trace.go:171","msg":"trace[60529915] transaction","detail":"{read_only:false; response_revision:2035; number_of_response:1; }","duration":"201.602215ms","start":"2026-08-14T09:06:56.693631Z","end":"2026-08-14T09:06:56.895233Z","steps":["trace[60529915] 'process raft request' (duration: 201.431391ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:56.895352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.620601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-08-14T09:06:56.895354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.967345ms","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-08-14T09:06:56.895384Z","caller":"traceutil/trace.go:171","msg":"trace[1716953766] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:2035; }","duration":"527.005566ms","start":"2026-08-14T09:06:56.368371Z","end":"2026-08-14T09:06:56.895376Z","steps":["trace[1716953766] 'agreement among raft nodes before linearized reading' (duration: 526.892324ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:56.895383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.329686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"warn","ts":"2026-08-14T09:06:56.895406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:06:56.368327Z","time spent":"527.072098ms","remote":"127.0.0.1:53336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":70,"response size":29,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"info","ts":"2026-08-14T09:06:56.895413Z","caller":"traceutil/trace.go:171","msg":"trace[1923248626] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2035; }","duration":"131.382387ms","start":"2026-08-14T09:06:56.764025Z","end":"2026-08-14T09:06:56.895407Z","steps":["trace[1923248626] 'agreement among raft nodes before linearized reading' (duration: 131.310475ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:06:56.895445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.170602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-14T09:06:56.895463Z","caller":"traceutil/trace.go:171","msg":"trace[1581383388] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2035; }","duration":"133.217582ms","start":"2026-08-14T09:06:56.76224Z","end":"2026-08-14T09:06:56.895458Z","steps":["trace[1581383388] 'agreement among raft nodes before linearized reading' (duration: 133.165791ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:06:56.895393Z","caller":"traceutil/trace.go:171","msg":"trace[1906973839] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2035; }","duration":"272.681372ms","start":"2026-08-14T09:06:56.622688Z","end":"2026-08-14T09:06:56.89537Z","steps":["trace[1906973839] 'agreement among raft nodes before linearized reading' (duration: 272.57485ms)"],"step_count":1}