{"level":"warn","ts":"2026-05-28T21:45:03.864314Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-28T21:45:03.864449Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.43: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.43:2380","--initial-cluster=instance=https://199.19.213.43:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.43:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.43:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-28T21:45:03.864559Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-28T21:45:03.86458Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.43:2380"]} {"level":"info","ts":"2026-05-28T21:45:03.864618Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-28T21:45:03.865831Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.43:2379"]} {"level":"info","ts":"2026-05-28T21:45:03.865942Z","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.43:2380"],"listen-peer-urls":["https://199.19.213.43:2380"],"advertise-client-urls":["https://199.19.213.43:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.43:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.43:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-28T21:45:03.872246Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.031768ms"} {"level":"info","ts":"2026-05-28T21:45:03.881943Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a8d0274548ffaee4","cluster-id":"858b677d1d9175b1"} {"level":"info","ts":"2026-05-28T21:45:03.882048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a8d0274548ffaee4 switched to configuration voters=()"} {"level":"info","ts":"2026-05-28T21:45:03.882074Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a8d0274548ffaee4 became follower at term 0"} {"level":"info","ts":"2026-05-28T21:45:03.882097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a8d0274548ffaee4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-28T21:45:03.882109Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a8d0274548ffaee4 became follower at term 1"} {"level":"info","ts":"2026-05-28T21:45:03.882166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a8d0274548ffaee4 switched to configuration voters=(12164265772058652388)"} {"level":"warn","ts":"2026-05-28T21:45:03.89416Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-28T21:45:03.896919Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-28T21:45:03.898379Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-28T21:45:03.901759Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a8d0274548ffaee4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-28T21:45:03.902001Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a8d0274548ffaee4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-28T21:45:03.902947Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a8d0274548ffaee4 switched to configuration voters=(12164265772058652388)"} {"level":"info","ts":"2026-05-28T21:45:03.903091Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"858b677d1d9175b1","local-member-id":"a8d0274548ffaee4","added-peer-id":"a8d0274548ffaee4","added-peer-peer-urls":["https://199.19.213.43:2380"]} {"level":"info","ts":"2026-05-28T21:45:03.903134Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-28T21:45:03.903338Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-28T21:45:03.903367Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-28T21:45:03.909987Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-28T21:45:03.910221Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a8d0274548ffaee4","initial-advertise-peer-urls":["https://199.19.213.43:2380"],"listen-peer-urls":["https://199.19.213.43:2380"],"advertise-client-urls":["https://199.19.213.43:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.43:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-28T21:45:03.910269Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-28T21:45:03.91041Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.43:2380"} {"level":"info","ts":"2026-05-28T21:45:03.910449Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.43:2380"} {"level":"info","ts":"2026-05-28T21:45:04.082686Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a8d0274548ffaee4 is starting a new election at term 1"} {"level":"info","ts":"2026-05-28T21:45:04.082746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a8d0274548ffaee4 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-28T21:45:04.082767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a8d0274548ffaee4 received MsgPreVoteResp from a8d0274548ffaee4 at term 1"} {"level":"info","ts":"2026-05-28T21:45:04.082778Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a8d0274548ffaee4 became candidate at term 2"} {"level":"info","ts":"2026-05-28T21:45:04.082783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a8d0274548ffaee4 received MsgVoteResp from a8d0274548ffaee4 at term 2"} {"level":"info","ts":"2026-05-28T21:45:04.082791Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a8d0274548ffaee4 became leader at term 2"} {"level":"info","ts":"2026-05-28T21:45:04.082797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a8d0274548ffaee4 elected leader a8d0274548ffaee4 at term 2"} {"level":"info","ts":"2026-05-28T21:45:04.090167Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a8d0274548ffaee4","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.43:2379]}","request-path":"/0/members/a8d0274548ffaee4/attributes","cluster-id":"858b677d1d9175b1","publish-timeout":"7s"} {"level":"info","ts":"2026-05-28T21:45:04.090233Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-28T21:45:04.090295Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-28T21:45:04.090569Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-28T21:45:04.090619Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-28T21:45:04.093033Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.43:2379"} {"level":"info","ts":"2026-05-28T21:45:04.093205Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T21:45:04.094065Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-28T21:45:04.094166Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"858b677d1d9175b1","local-member-id":"a8d0274548ffaee4","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T21:45:04.094333Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T21:45:04.094398Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T21:45:07.339175Z","caller":"traceutil/trace.go:171","msg":"trace[15047628] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"261.399201ms","start":"2026-05-28T21:45:07.07774Z","end":"2026-05-28T21:45:07.339139Z","steps":["trace[15047628] 'read index received' (duration: 261.168876ms)","trace[15047628] 'applied index is now lower than readState.Index' (duration: 229.125µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:45:07.339348Z","caller":"traceutil/trace.go:171","msg":"trace[2125224563] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"262.797006ms","start":"2026-05-28T21:45:07.076538Z","end":"2026-05-28T21:45:07.339335Z","steps":["trace[2125224563] 'process raft request' (duration: 262.449858ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:07.339638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.862492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-28T21:45:07.339717Z","caller":"traceutil/trace.go:171","msg":"trace[331288098] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:72; }","duration":"261.986436ms","start":"2026-05-28T21:45:07.077715Z","end":"2026-05-28T21:45:07.339702Z","steps":["trace[331288098] 'agreement among raft nodes before linearized reading' (duration: 261.804581ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:07.339806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.815118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-28T21:45:07.339952Z","caller":"traceutil/trace.go:171","msg":"trace[1418666533] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:72; }","duration":"229.068424ms","start":"2026-05-28T21:45:07.110856Z","end":"2026-05-28T21:45:07.339924Z","steps":["trace[1418666533] 'agreement among raft nodes before linearized reading' (duration: 228.764777ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:07.937798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.044107ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-28T21:45:07.937936Z","caller":"traceutil/trace.go:171","msg":"trace[2081197609] linearizableReadLoop","detail":"{readStateIndex:79; appliedIndex:78; }","duration":"387.82265ms","start":"2026-05-28T21:45:07.550093Z","end":"2026-05-28T21:45:07.937916Z","steps":["trace[2081197609] 'read index received' (duration: 99.893782ms)","trace[2081197609] 'applied index is now lower than readState.Index' (duration: 287.927318ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:45:07.938215Z","caller":"traceutil/trace.go:171","msg":"trace[379774391] transaction","detail":"{read_only:false; response_revision:75; number_of_response:1; }","duration":"525.358237ms","start":"2026-05-28T21:45:07.412607Z","end":"2026-05-28T21:45:07.937965Z","steps":["trace[379774391] 'process raft request' (duration: 237.48231ms)","trace[379774391] 'compare' (duration: 286.832692ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:45:07.938397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:07.412592Z","time spent":"525.720185ms","remote":"127.0.0.1:47962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":621,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-28T21:45:07.938591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.352342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-28T21:45:07.938675Z","caller":"traceutil/trace.go:171","msg":"trace[1543492141] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:75; }","duration":"388.578908ms","start":"2026-05-28T21:45:07.55008Z","end":"2026-05-28T21:45:07.938659Z","steps":["trace[1543492141] 'agreement among raft nodes before linearized reading' (duration: 387.890091ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:07.938729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:07.550035Z","time spent":"388.6821ms","remote":"127.0.0.1:47760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"info","ts":"2026-05-28T21:45:26.820172Z","caller":"traceutil/trace.go:171","msg":"trace[457167334] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"163.99312ms","start":"2026-05-28T21:45:26.656155Z","end":"2026-05-28T21:45:26.820148Z","steps":["trace[457167334] 'process raft request' (duration: 64.717625ms)","trace[457167334] 'compare' (duration: 99.166163ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:45:41.087738Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12602371863516974659,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-28T21:45:41.163568Z","caller":"traceutil/trace.go:171","msg":"trace[1951417997] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"630.804663ms","start":"2026-05-28T21:45:40.532736Z","end":"2026-05-28T21:45:41.16354Z","steps":["trace[1951417997] 'process raft request' (duration: 630.663689ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:41.163888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:40.532714Z","time spent":"630.927305ms","remote":"127.0.0.1:47876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:45:41.166564Z","caller":"traceutil/trace.go:171","msg":"trace[1736209736] linearizableReadLoop","detail":"{readStateIndex:500; appliedIndex:499; }","duration":"579.065193ms","start":"2026-05-28T21:45:40.587478Z","end":"2026-05-28T21:45:41.166544Z","steps":["trace[1736209736] 'read index received' (duration: 576.229924ms)","trace[1736209736] 'applied index is now lower than readState.Index' (duration: 2.834408ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:45:41.166771Z","caller":"traceutil/trace.go:171","msg":"trace[927903152] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"365.503655ms","start":"2026-05-28T21:45:40.801231Z","end":"2026-05-28T21:45:41.166734Z","steps":["trace[927903152] 'process raft request' (duration: 365.209988ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:41.166859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.378001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:45:41.166899Z","caller":"traceutil/trace.go:171","msg":"trace[1930145300] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:483; }","duration":"579.458513ms","start":"2026-05-28T21:45:40.587429Z","end":"2026-05-28T21:45:41.166887Z","steps":["trace[1930145300] 'agreement among raft nodes before linearized reading' (duration: 579.390091ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:41.166945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:40.587409Z","time spent":"579.528635ms","remote":"127.0.0.1:47730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-05-28T21:45:41.166962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:40.801207Z","time spent":"365.66228ms","remote":"127.0.0.1:47876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T21:45:41.167375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.146753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-28T21:45:41.167424Z","caller":"traceutil/trace.go:171","msg":"trace[408791977] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:483; }","duration":"129.232955ms","start":"2026-05-28T21:45:41.038179Z","end":"2026-05-28T21:45:41.167412Z","steps":["trace[408791977] 'agreement among raft nodes before linearized reading' (duration: 129.053071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:41.167446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.016733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-28T21:45:41.167489Z","caller":"traceutil/trace.go:171","msg":"trace[1630994180] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:483; }","duration":"215.123826ms","start":"2026-05-28T21:45:40.952353Z","end":"2026-05-28T21:45:41.167477Z","steps":["trace[1630994180] 'agreement among raft nodes before linearized reading' (duration: 215.020323ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:42.008658Z","caller":"traceutil/trace.go:171","msg":"trace[1946443690] linearizableReadLoop","detail":"{readStateIndex:560; appliedIndex:558; }","duration":"144.425796ms","start":"2026-05-28T21:45:41.864192Z","end":"2026-05-28T21:45:42.008618Z","steps":["trace[1946443690] 'read index received' (duration: 4.48679ms)","trace[1946443690] 'applied index is now lower than readState.Index' (duration: 139.938256ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:45:42.008716Z","caller":"traceutil/trace.go:171","msg":"trace[1227012854] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"160.799383ms","start":"2026-05-28T21:45:41.847862Z","end":"2026-05-28T21:45:42.008661Z","steps":["trace[1227012854] 'process raft request' (duration: 139.242558ms)","trace[1227012854] 'compare' (duration: 21.209517ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:45:42.008779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.572369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:45:42.008805Z","caller":"traceutil/trace.go:171","msg":"trace[1233526179] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:537; }","duration":"144.639011ms","start":"2026-05-28T21:45:41.864159Z","end":"2026-05-28T21:45:42.008798Z","steps":["trace[1233526179] 'agreement among raft nodes before linearized reading' (duration: 144.559959ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:42.008938Z","caller":"traceutil/trace.go:171","msg":"trace[681026430] transaction","detail":"{read_only:false; number_of_response:1; response_revision:537; }","duration":"148.062053ms","start":"2026-05-28T21:45:41.860866Z","end":"2026-05-28T21:45:42.008928Z","steps":["trace[681026430] 'process raft request' (duration: 147.665904ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:42.176514Z","caller":"traceutil/trace.go:171","msg":"trace[360736417] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"113.242576ms","start":"2026-05-28T21:45:42.063252Z","end":"2026-05-28T21:45:42.176495Z","steps":["trace[360736417] 'process raft request' (duration: 113.140353ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:43.090777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.721951ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:45:43.090873Z","caller":"traceutil/trace.go:171","msg":"trace[643370493] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:541; }","duration":"266.814973ms","start":"2026-05-28T21:45:42.824036Z","end":"2026-05-28T21:45:43.090851Z","steps":["trace[643370493] 'range keys from in-memory index tree' (duration: 266.711051ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:43.091101Z","caller":"traceutil/trace.go:171","msg":"trace[1650879396] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"853.144695ms","start":"2026-05-28T21:45:42.237947Z","end":"2026-05-28T21:45:43.091091Z","steps":["trace[1650879396] 'process raft request' (duration: 848.056622ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:43.09119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:42.237929Z","time spent":"853.220276ms","remote":"127.0.0.1:47700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":73770,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-28T21:45:43.092002Z","caller":"traceutil/trace.go:171","msg":"trace[2021783741] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"834.526792ms","start":"2026-05-28T21:45:42.25744Z","end":"2026-05-28T21:45:43.091966Z","steps":["trace[2021783741] 'process raft request' (duration: 834.195404ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:43.092212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:42.257417Z","time spent":"834.665776ms","remote":"127.0.0.1:47700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":98698,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:45:43.092022Z","caller":"traceutil/trace.go:171","msg":"trace[1807679355] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"804.552853ms","start":"2026-05-28T21:45:42.28744Z","end":"2026-05-28T21:45:43.091992Z","steps":["trace[1807679355] 'process raft request' (duration: 804.44169ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:43.092362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:42.287408Z","time spent":"804.86397ms","remote":"127.0.0.1:47700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151212,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:45:43.38229Z","caller":"traceutil/trace.go:171","msg":"trace[1662754893] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:570; }","duration":"203.592534ms","start":"2026-05-28T21:45:43.178681Z","end":"2026-05-28T21:45:43.382273Z","steps":["trace[1662754893] 'read index received' (duration: 194.592645ms)","trace[1662754893] 'applied index is now lower than readState.Index' (duration: 8.999239ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:45:43.382596Z","caller":"traceutil/trace.go:171","msg":"trace[71918728] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"191.989622ms","start":"2026-05-28T21:45:43.190574Z","end":"2026-05-28T21:45:43.382563Z","steps":["trace[71918728] 'process raft request' (duration: 191.671014ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:43.382902Z","caller":"traceutil/trace.go:171","msg":"trace[1683300105] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"212.8049ms","start":"2026-05-28T21:45:43.170083Z","end":"2026-05-28T21:45:43.382887Z","steps":["trace[1683300105] 'process raft request' (duration: 203.165165ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:43.382647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.940943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-28T21:45:43.382689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.833241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-28T21:45:43.383383Z","caller":"traceutil/trace.go:171","msg":"trace[477861712] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"205.42332ms","start":"2026-05-28T21:45:43.177808Z","end":"2026-05-28T21:45:43.383231Z","steps":["trace[477861712] 'process raft request' (duration: 204.398454ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:43.383608Z","caller":"traceutil/trace.go:171","msg":"trace[1252052912] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:550; }","duration":"204.630801ms","start":"2026-05-28T21:45:43.17862Z","end":"2026-05-28T21:45:43.383251Z","steps":["trace[1252052912] 'agreement among raft nodes before linearized reading' (duration: 203.976864ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:43.383769Z","caller":"traceutil/trace.go:171","msg":"trace[5118025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:550; }","duration":"204.431176ms","start":"2026-05-28T21:45:43.178834Z","end":"2026-05-28T21:45:43.383265Z","steps":["trace[5118025] 'agreement among raft nodes before linearized reading' (duration: 203.839702ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:47.404644Z","caller":"traceutil/trace.go:171","msg":"trace[1028861977] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"105.857717ms","start":"2026-05-28T21:45:47.298753Z","end":"2026-05-28T21:45:47.404611Z","steps":["trace[1028861977] 'process raft request' (duration: 105.56546ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:47.565853Z","caller":"traceutil/trace.go:171","msg":"trace[1888593553] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"109.27226ms","start":"2026-05-28T21:45:47.456558Z","end":"2026-05-28T21:45:47.56583Z","steps":["trace[1888593553] 'process raft request' (duration: 109.224349ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:47.565954Z","caller":"traceutil/trace.go:171","msg":"trace[1007601219] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"109.690619ms","start":"2026-05-28T21:45:47.456202Z","end":"2026-05-28T21:45:47.565893Z","steps":["trace[1007601219] 'process raft request' (duration: 62.686005ms)","trace[1007601219] 'compare' (duration: 46.782829ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:45:47.76058Z","caller":"traceutil/trace.go:171","msg":"trace[2084253413] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"107.920016ms","start":"2026-05-28T21:45:47.652632Z","end":"2026-05-28T21:45:47.760552Z","steps":["trace[2084253413] 'process raft request' (duration: 105.22271ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:48.333811Z","caller":"traceutil/trace.go:171","msg":"trace[839552815] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"568.705952ms","start":"2026-05-28T21:45:47.765081Z","end":"2026-05-28T21:45:48.333787Z","steps":["trace[839552815] 'process raft request' (duration: 568.573519ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:48.333941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:47.765059Z","time spent":"568.822864ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":182,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-28T21:45:48.370268Z","caller":"traceutil/trace.go:171","msg":"trace[1874703034] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"604.298758ms","start":"2026-05-28T21:45:47.765938Z","end":"2026-05-28T21:45:48.370236Z","steps":["trace[1874703034] 'process raft request' (duration: 604.008791ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:48.370411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:47.765917Z","time spent":"604.425312ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1749,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-28T21:45:48.370906Z","caller":"traceutil/trace.go:171","msg":"trace[77673055] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:603; }","duration":"509.02281ms","start":"2026-05-28T21:45:47.861861Z","end":"2026-05-28T21:45:48.370884Z","steps":["trace[77673055] 'read index received' (duration: 471.878066ms)","trace[77673055] 'applied index is now lower than readState.Index' (duration: 37.143914ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:45:48.371593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.717396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:45:48.371648Z","caller":"traceutil/trace.go:171","msg":"trace[251282907] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:582; }","duration":"509.813358ms","start":"2026-05-28T21:45:47.86181Z","end":"2026-05-28T21:45:48.371624Z","steps":["trace[251282907] 'agreement among raft nodes before linearized reading' (duration: 509.152642ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:48.371678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:47.861785Z","time spent":"509.88649ms","remote":"127.0.0.1:47664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-28T21:45:48.371956Z","caller":"traceutil/trace.go:171","msg":"trace[1283798865] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"219.614315ms","start":"2026-05-28T21:45:48.152003Z","end":"2026-05-28T21:45:48.371617Z","steps":["trace[1283798865] 'process raft request' (duration: 218.378996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:49.594777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.166552494s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:45:49.594877Z","caller":"traceutil/trace.go:171","msg":"trace[381204457] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:582; }","duration":"1.166669007s","start":"2026-05-28T21:45:48.428186Z","end":"2026-05-28T21:45:49.594855Z","steps":["trace[381204457] 'range keys from in-memory index tree' (duration: 1.1663785s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:49.594931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:48.428123Z","time spent":"1.16679502s","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-05-28T21:45:49.595104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.233774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-28T21:45:49.595178Z","caller":"traceutil/trace.go:171","msg":"trace[587359511] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:582; }","duration":"140.350307ms","start":"2026-05-28T21:45:49.454809Z","end":"2026-05-28T21:45:49.595159Z","steps":["trace[587359511] 'range keys from in-memory index tree' (duration: 140.097921ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:49.595381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.775317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:45:49.595454Z","caller":"traceutil/trace.go:171","msg":"trace[303706640] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:582; }","duration":"730.922121ms","start":"2026-05-28T21:45:48.86451Z","end":"2026-05-28T21:45:49.595432Z","steps":["trace[303706640] 'range keys from in-memory index tree' (duration: 730.679814ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:49.595499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:48.864486Z","time spent":"731.001122ms","remote":"127.0.0.1:47656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-28T21:45:49.595502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"981.756576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.43\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-28T21:45:49.595573Z","caller":"traceutil/trace.go:171","msg":"trace[1943085840] range","detail":"{range_begin:/registry/masterleases/199.19.213.43; range_end:; response_count:1; response_revision:582; }","duration":"981.974321ms","start":"2026-05-28T21:45:48.613578Z","end":"2026-05-28T21:45:49.595552Z","steps":["trace[1943085840] 'range keys from in-memory index tree' (duration: 981.647924ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:49.595622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:48.613559Z","time spent":"982.047703ms","remote":"127.0.0.1:47690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.43\" "} {"level":"warn","ts":"2026-05-28T21:45:49.595748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.656896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-28T21:45:49.595796Z","caller":"traceutil/trace.go:171","msg":"trace[1803341976] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:582; }","duration":"123.453655ms","start":"2026-05-28T21:45:49.472329Z","end":"2026-05-28T21:45:49.595783Z","steps":["trace[1803341976] 'range keys from in-memory index tree' (duration: 122.481862ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:52.624455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.04577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-h87j6\" ","response":"range_response_count:1 size:17520"} {"level":"warn","ts":"2026-05-28T21:45:52.624479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.434443ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-28T21:45:52.624548Z","caller":"traceutil/trace.go:171","msg":"trace[1656296988] range","detail":"{range_begin:/registry/pods/kube-system/cilium-h87j6; range_end:; response_count:1; response_revision:610; }","duration":"317.174643ms","start":"2026-05-28T21:45:52.307351Z","end":"2026-05-28T21:45:52.624526Z","steps":["trace[1656296988] 'range keys from in-memory index tree' (duration: 316.887955ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:52.624592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:52.30734Z","time spent":"317.243245ms","remote":"127.0.0.1:47800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17544,"request content":"key:\"/registry/pods/kube-system/cilium-h87j6\" "} {"level":"info","ts":"2026-05-28T21:45:52.624765Z","caller":"traceutil/trace.go:171","msg":"trace[339003400] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"315.564933ms","start":"2026-05-28T21:45:52.309151Z","end":"2026-05-28T21:45:52.624716Z","steps":["trace[339003400] 'process raft request' (duration: 203.798732ms)","trace[339003400] 'compare' (duration: 111.292849ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:45:52.624952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:45:52.309142Z","time spent":"315.721087ms","remote":"127.0.0.1:47720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":765,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-28T21:46:05.290584Z","caller":"traceutil/trace.go:171","msg":"trace[248913930] linearizableReadLoop","detail":"{readStateIndex:786; appliedIndex:785; }","duration":"426.41321ms","start":"2026-05-28T21:46:04.864149Z","end":"2026-05-28T21:46:05.290562Z","steps":["trace[248913930] 'read index received' (duration: 426.333519ms)","trace[248913930] 'applied index is now lower than readState.Index' (duration: 78.551µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:46:05.290708Z","caller":"traceutil/trace.go:171","msg":"trace[1147763684] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"905.065874ms","start":"2026-05-28T21:46:04.385607Z","end":"2026-05-28T21:46:05.290673Z","steps":["trace[1147763684] 'process raft request' (duration: 904.841859ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:46:05.290801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.628115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:46:05.290895Z","caller":"traceutil/trace.go:171","msg":"trace[480463240] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:758; }","duration":"426.773669ms","start":"2026-05-28T21:46:04.864101Z","end":"2026-05-28T21:46:05.290874Z","steps":["trace[480463240] 'agreement among raft nodes before linearized reading' (duration: 426.596655ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:46:05.290944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:46:04.86408Z","time spent":"426.856151ms","remote":"127.0.0.1:47656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-28T21:46:05.29103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:46:04.385588Z","time spent":"905.237278ms","remote":"127.0.0.1:47800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:46:38.037173Z","caller":"traceutil/trace.go:171","msg":"trace[941778546] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"141.110085ms","start":"2026-05-28T21:46:37.896036Z","end":"2026-05-28T21:46:38.037146Z","steps":["trace[941778546] 'process raft request' (duration: 141.014673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:46:38.825704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.285872ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T21:46:38.825824Z","caller":"traceutil/trace.go:171","msg":"trace[1061565627] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"177.619705ms","start":"2026-05-28T21:46:38.648185Z","end":"2026-05-28T21:46:38.825805Z","steps":["trace[1061565627] 'process raft request' (duration: 56.147018ms)","trace[1061565627] 'compare' (duration: 121.149909ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:46:39.885966Z","caller":"traceutil/trace.go:171","msg":"trace[422535551] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"142.875959ms","start":"2026-05-28T21:46:39.743061Z","end":"2026-05-28T21:46:39.885937Z","steps":["trace[422535551] 'process raft request' (duration: 142.700855ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:46:39.886267Z","caller":"traceutil/trace.go:171","msg":"trace[146249356] linearizableReadLoop","detail":"{readStateIndex:1009; appliedIndex:1009; }","duration":"137.290923ms","start":"2026-05-28T21:46:39.748905Z","end":"2026-05-28T21:46:39.886195Z","steps":["trace[146249356] 'read index received' (duration: 137.280433ms)","trace[146249356] 'applied index is now lower than readState.Index' (duration: 8.66µs)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:46:39.886679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.723814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-28T21:46:39.886733Z","caller":"traceutil/trace.go:171","msg":"trace[14349611] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:970; }","duration":"137.843907ms","start":"2026-05-28T21:46:39.748876Z","end":"2026-05-28T21:46:39.88672Z","steps":["trace[14349611] 'agreement among raft nodes before linearized reading' (duration: 137.660962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:46:40.997889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"980.053339ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T21:46:40.998007Z","caller":"traceutil/trace.go:171","msg":"trace[1743954908] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"1.106723693s","start":"2026-05-28T21:46:39.891263Z","end":"2026-05-28T21:46:40.997986Z","steps":["trace[1743954908] 'process raft request' (duration: 126.499509ms)","trace[1743954908] 'compare' (duration: 979.689591ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:46:40.998078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:46:39.891237Z","time spent":"1.106812815s","remote":"127.0.0.1:47876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T21:46:41.598225Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.580479348s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-28T21:46:42.058039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.377124ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T21:46:42.058192Z","caller":"traceutil/trace.go:171","msg":"trace[2034749017] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"166.510974ms","start":"2026-05-28T21:46:41.891662Z","end":"2026-05-28T21:46:42.058173Z","steps":["trace[2034749017] 'process raft request' (duration: 166.451083ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:46:42.058596Z","caller":"traceutil/trace.go:171","msg":"trace[1795086129] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"2.166806991s","start":"2026-05-28T21:46:39.891781Z","end":"2026-05-28T21:46:42.058588Z","steps":["trace[1795086129] 'process raft request' (duration: 1.706798492s)","trace[1795086129] 'compare' (duration: 459.040226ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:46:42.058664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:46:39.891763Z","time spent":"2.166849322s","remote":"127.0.0.1:47876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:46:42.058752Z","caller":"traceutil/trace.go:171","msg":"trace[359805487] linearizableReadLoop","detail":"{readStateIndex:1011; appliedIndex:1009; }","duration":"2.16390362s","start":"2026-05-28T21:46:39.894842Z","end":"2026-05-28T21:46:42.058746Z","steps":["trace[359805487] 'read index received' (duration: 122.914082ms)","trace[359805487] 'applied index is now lower than readState.Index' (duration: 2.040988848s)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:46:42.05886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.164018212s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-28T21:46:42.058878Z","caller":"traceutil/trace.go:171","msg":"trace[500942100] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:973; }","duration":"2.164053603s","start":"2026-05-28T21:46:39.894819Z","end":"2026-05-28T21:46:42.058872Z","steps":["trace[500942100] 'agreement among raft nodes before linearized reading' (duration: 2.163976061s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:46:42.058898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:46:39.894811Z","time spent":"2.164080444s","remote":"127.0.0.1:47876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-28T21:46:42.058973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.088747791s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-28T21:46:42.058988Z","caller":"traceutil/trace.go:171","msg":"trace[593996032] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:973; }","duration":"2.088792441s","start":"2026-05-28T21:46:39.97019Z","end":"2026-05-28T21:46:42.058983Z","steps":["trace[593996032] 'agreement among raft nodes before linearized reading' (duration: 2.0887494s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:46:42.059003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:46:39.970174Z","time spent":"2.088825623s","remote":"127.0.0.1:47876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-28T21:46:42.059072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.019266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-28T21:46:42.059085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.018680475s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-28T21:46:42.059103Z","caller":"traceutil/trace.go:171","msg":"trace[2061010430] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:973; }","duration":"197.087527ms","start":"2026-05-28T21:46:41.862006Z","end":"2026-05-28T21:46:42.059094Z","steps":["trace[2061010430] 'agreement among raft nodes before linearized reading' (duration: 197.027506ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:46:42.059114Z","caller":"traceutil/trace.go:171","msg":"trace[204004048] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:973; }","duration":"2.018734666s","start":"2026-05-28T21:46:40.040367Z","end":"2026-05-28T21:46:42.059102Z","steps":["trace[204004048] 'agreement among raft nodes before linearized reading' (duration: 2.018674165s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:46:42.059136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:46:40.040352Z","time spent":"2.018778927s","remote":"127.0.0.1:47876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-28T21:47:03.203893Z","caller":"traceutil/trace.go:171","msg":"trace[497521486] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"173.463252ms","start":"2026-05-28T21:47:03.030408Z","end":"2026-05-28T21:47:03.203871Z","steps":["trace[497521486] 'process raft request' (duration: 173.315218ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:03.562405Z","caller":"traceutil/trace.go:171","msg":"trace[809146353] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"354.178612ms","start":"2026-05-28T21:47:03.208199Z","end":"2026-05-28T21:47:03.562377Z","steps":["trace[809146353] 'process raft request' (duration: 351.106516ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:03.562562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:03.208177Z","time spent":"354.298874ms","remote":"127.0.0.1:47876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:47:06.379274Z","caller":"traceutil/trace.go:171","msg":"trace[114968051] linearizableReadLoop","detail":"{readStateIndex:1095; appliedIndex:1094; }","duration":"143.296078ms","start":"2026-05-28T21:47:06.235957Z","end":"2026-05-28T21:47:06.379253Z","steps":["trace[114968051] 'read index received' (duration: 143.135214ms)","trace[114968051] 'applied index is now lower than readState.Index' (duration: 159.994µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:47:06.379325Z","caller":"traceutil/trace.go:171","msg":"trace[812955553] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"186.413951ms","start":"2026-05-28T21:47:06.192891Z","end":"2026-05-28T21:47:06.379305Z","steps":["trace[812955553] 'process raft request' (duration: 186.230377ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:06.379527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.553404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-28T21:47:06.379609Z","caller":"traceutil/trace.go:171","msg":"trace[290887256] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1051; }","duration":"143.682977ms","start":"2026-05-28T21:47:06.235905Z","end":"2026-05-28T21:47:06.379588Z","steps":["trace[290887256] 'agreement among raft nodes before linearized reading' (duration: 143.451172ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:06.379685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.651267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-28T21:47:06.379729Z","caller":"traceutil/trace.go:171","msg":"trace[404921927] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1051; }","duration":"107.749679ms","start":"2026-05-28T21:47:06.271968Z","end":"2026-05-28T21:47:06.379718Z","steps":["trace[404921927] 'agreement among raft nodes before linearized reading' (duration: 107.590766ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:06.379828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.612609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-28T21:47:06.379976Z","caller":"traceutil/trace.go:171","msg":"trace[1815901586] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1051; }","duration":"133.800082ms","start":"2026-05-28T21:47:06.246158Z","end":"2026-05-28T21:47:06.379958Z","steps":["trace[1815901586] 'agreement among raft nodes before linearized reading' (duration: 133.397462ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:06.379837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.395517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-28T21:47:06.380276Z","caller":"traceutil/trace.go:171","msg":"trace[252243806] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1051; }","duration":"117.879229ms","start":"2026-05-28T21:47:06.262364Z","end":"2026-05-28T21:47:06.380243Z","steps":["trace[252243806] 'agreement among raft nodes before linearized reading' (duration: 117.276425ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:06.500797Z","caller":"traceutil/trace.go:171","msg":"trace[1142443530] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"114.228859ms","start":"2026-05-28T21:47:06.386547Z","end":"2026-05-28T21:47:06.500776Z","steps":["trace[1142443530] 'process raft request' (duration: 105.796051ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:06.508424Z","caller":"traceutil/trace.go:171","msg":"trace[156348394] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"121.529411ms","start":"2026-05-28T21:47:06.38687Z","end":"2026-05-28T21:47:06.5084Z","steps":["trace[156348394] 'process raft request' (duration: 121.48884ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:06.50852Z","caller":"traceutil/trace.go:171","msg":"trace[1053470604] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"121.735905ms","start":"2026-05-28T21:47:06.386763Z","end":"2026-05-28T21:47:06.508499Z","steps":["trace[1053470604] 'process raft request' (duration: 121.460898ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:06.508569Z","caller":"traceutil/trace.go:171","msg":"trace[1098573013] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"121.782356ms","start":"2026-05-28T21:47:06.386764Z","end":"2026-05-28T21:47:06.508547Z","steps":["trace[1098573013] 'process raft request' (duration: 121.560641ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:20.950707Z","caller":"traceutil/trace.go:171","msg":"trace[1830268377] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"528.515876ms","start":"2026-05-28T21:47:20.422161Z","end":"2026-05-28T21:47:20.950677Z","steps":["trace[1830268377] 'process raft request' (duration: 528.313171ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:20.950836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:20.422142Z","time spent":"528.629638ms","remote":"127.0.0.1:47876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T21:47:20.950938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.877155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-28T21:47:20.95102Z","caller":"traceutil/trace.go:171","msg":"trace[300783723] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1149; }","duration":"396.958507ms","start":"2026-05-28T21:47:20.554027Z","end":"2026-05-28T21:47:20.950986Z","steps":["trace[300783723] 'agreement among raft nodes before linearized reading' (duration: 396.739822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:20.951055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:20.553975Z","time spent":"397.072881ms","remote":"127.0.0.1:47876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-28T21:47:20.950629Z","caller":"traceutil/trace.go:171","msg":"trace[1502073090] linearizableReadLoop","detail":"{readStateIndex:1198; appliedIndex:1197; }","duration":"396.570508ms","start":"2026-05-28T21:47:20.554033Z","end":"2026-05-28T21:47:20.950604Z","steps":["trace[1502073090] 'read index received' (duration: 396.360243ms)","trace[1502073090] 'applied index is now lower than readState.Index' (duration: 209.305µs)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:47:20.953261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.245925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-28T21:47:20.95331Z","caller":"traceutil/trace.go:171","msg":"trace[1266904883] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1149; }","duration":"192.297986ms","start":"2026-05-28T21:47:20.760999Z","end":"2026-05-28T21:47:20.953297Z","steps":["trace[1266904883] 'agreement among raft nodes before linearized reading' (duration: 192.211674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:20.953381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.31169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-28T21:47:20.953405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.639269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-28T21:47:20.953443Z","caller":"traceutil/trace.go:171","msg":"trace[1917519972] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1149; }","duration":"385.412803ms","start":"2026-05-28T21:47:20.568002Z","end":"2026-05-28T21:47:20.953415Z","steps":["trace[1917519972] 'agreement among raft nodes before linearized reading' (duration: 385.217008ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:20.953488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:20.567983Z","time spent":"385.497356ms","remote":"127.0.0.1:47876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-28T21:47:20.953485Z","caller":"traceutil/trace.go:171","msg":"trace[795377511] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1149; }","duration":"381.748702ms","start":"2026-05-28T21:47:20.571716Z","end":"2026-05-28T21:47:20.953465Z","steps":["trace[795377511] 'agreement among raft nodes before linearized reading' (duration: 381.509997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:20.953533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:20.571705Z","time spent":"381.818754ms","remote":"127.0.0.1:47876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-28T21:47:20.95371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.840284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-28T21:47:20.953739Z","caller":"traceutil/trace.go:171","msg":"trace[420981680] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1149; }","duration":"383.886525ms","start":"2026-05-28T21:47:20.569843Z","end":"2026-05-28T21:47:20.95373Z","steps":["trace[420981680] 'agreement among raft nodes before linearized reading' (duration: 383.797173ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:20.953765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:20.569834Z","time spent":"383.925347ms","remote":"127.0.0.1:47876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-28T21:47:21.306325Z","caller":"traceutil/trace.go:171","msg":"trace[220581912] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"350.798938ms","start":"2026-05-28T21:47:20.955499Z","end":"2026-05-28T21:47:21.306298Z","steps":["trace[220581912] 'process raft request' (duration: 319.546717ms)","trace[220581912] 'compare' (duration: 30.891242ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:47:21.306442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:20.95547Z","time spent":"350.910081ms","remote":"127.0.0.1:47876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:47:21.311123Z","caller":"traceutil/trace.go:171","msg":"trace[1203704212] transaction","detail":"{read_only:false; response_revision:1151; number_of_response:1; }","duration":"353.818884ms","start":"2026-05-28T21:47:20.957284Z","end":"2026-05-28T21:47:21.311103Z","steps":["trace[1203704212] 'process raft request' (duration: 353.690161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:21.311231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:20.957266Z","time spent":"353.908296ms","remote":"127.0.0.1:47876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:47:21.311371Z","caller":"traceutil/trace.go:171","msg":"trace[1775630645] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"353.994938ms","start":"2026-05-28T21:47:20.957351Z","end":"2026-05-28T21:47:21.311346Z","steps":["trace[1775630645] 'process raft request' (duration: 353.715871ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:21.311502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:20.957345Z","time spent":"354.09259ms","remote":"127.0.0.1:47876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:47:21.311581Z","caller":"traceutil/trace.go:171","msg":"trace[224241557] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"353.644078ms","start":"2026-05-28T21:47:20.957928Z","end":"2026-05-28T21:47:21.311572Z","steps":["trace[224241557] 'process raft request' (duration: 353.353341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:21.312122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:20.957911Z","time spent":"354.177081ms","remote":"127.0.0.1:47876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:47:34.048785Z","caller":"traceutil/trace.go:171","msg":"trace[1800784640] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"136.061829ms","start":"2026-05-28T21:47:33.912701Z","end":"2026-05-28T21:47:34.048762Z","steps":["trace[1800784640] 'process raft request' (duration: 135.958206ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:37.773187Z","caller":"traceutil/trace.go:171","msg":"trace[765644335] linearizableReadLoop","detail":"{readStateIndex:1272; appliedIndex:1271; }","duration":"382.07613ms","start":"2026-05-28T21:47:37.391078Z","end":"2026-05-28T21:47:37.773154Z","steps":["trace[765644335] 'read index received' (duration: 381.764842ms)","trace[765644335] 'applied index is now lower than readState.Index' (duration: 309.658µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:47:37.773314Z","caller":"traceutil/trace.go:171","msg":"trace[1210420396] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"434.939745ms","start":"2026-05-28T21:47:37.338339Z","end":"2026-05-28T21:47:37.773279Z","steps":["trace[1210420396] 'process raft request' (duration: 434.563695ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:37.773469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:37.338326Z","time spent":"435.057747ms","remote":"127.0.0.1:47786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5456,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T21:47:37.773552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.505383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-28T21:47:37.7736Z","caller":"traceutil/trace.go:171","msg":"trace[1269706266] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1220; }","duration":"374.598795ms","start":"2026-05-28T21:47:37.398984Z","end":"2026-05-28T21:47:37.773583Z","steps":["trace[1269706266] 'agreement among raft nodes before linearized reading' (duration: 374.37659ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:37.773645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:37.398978Z","time spent":"374.657116ms","remote":"127.0.0.1:47876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-28T21:47:37.773645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.336476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-28T21:47:37.773732Z","caller":"traceutil/trace.go:171","msg":"trace[1440649953] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1220; }","duration":"369.431618ms","start":"2026-05-28T21:47:37.404269Z","end":"2026-05-28T21:47:37.7737Z","steps":["trace[1440649953] 'agreement among raft nodes before linearized reading' (duration: 369.172902ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:37.773775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:37.404263Z","time spent":"369.50209ms","remote":"127.0.0.1:47876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-28T21:47:37.773763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.355005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-28T21:47:37.773895Z","caller":"traceutil/trace.go:171","msg":"trace[495631996] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1220; }","duration":"373.55307ms","start":"2026-05-28T21:47:37.400316Z","end":"2026-05-28T21:47:37.773869Z","steps":["trace[495631996] 'agreement among raft nodes before linearized reading' (duration: 373.198111ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:37.773981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:37.400305Z","time spent":"373.624811ms","remote":"127.0.0.1:47876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-28T21:47:37.774308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.217318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-28T21:47:37.774359Z","caller":"traceutil/trace.go:171","msg":"trace[2024175650] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1220; }","duration":"383.310981ms","start":"2026-05-28T21:47:37.391035Z","end":"2026-05-28T21:47:37.774346Z","steps":["trace[2024175650] 'agreement among raft nodes before linearized reading' (duration: 382.92356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:37.774392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:37.391003Z","time spent":"383.382073ms","remote":"127.0.0.1:47876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-28T21:47:38.105911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.320378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-05-28T21:47:38.106229Z","caller":"traceutil/trace.go:171","msg":"trace[1958125305] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:1220; }","duration":"327.644946ms","start":"2026-05-28T21:47:37.778565Z","end":"2026-05-28T21:47:38.10621Z","steps":["trace[1958125305] 'range keys from in-memory index tree' (duration: 327.166775ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:38.106351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:37.778458Z","time spent":"327.879222ms","remote":"127.0.0.1:48006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":688,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-05-28T21:47:38.106549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.415275ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T21:47:38.106925Z","caller":"traceutil/trace.go:171","msg":"trace[1446721430] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"326.864067ms","start":"2026-05-28T21:47:37.780018Z","end":"2026-05-28T21:47:38.106882Z","steps":["trace[1446721430] 'process raft request' (duration: 119.98909ms)","trace[1446721430] 'compare' (duration: 206.134729ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:47:38.10698Z","caller":"traceutil/trace.go:171","msg":"trace[888103115] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"326.949859ms","start":"2026-05-28T21:47:37.780014Z","end":"2026-05-28T21:47:38.106964Z","steps":["trace[888103115] 'process raft request' (duration: 326.778284ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:38.107034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:37.78Z","time spent":"326.987039ms","remote":"127.0.0.1:47876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T21:47:38.107084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:37.779987Z","time spent":"327.055261ms","remote":"127.0.0.1:47876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:47:38.107966Z","caller":"traceutil/trace.go:171","msg":"trace[1892566627] linearizableReadLoop","detail":"{readStateIndex:1277; appliedIndex:1272; }","duration":"243.721016ms","start":"2026-05-28T21:47:37.86423Z","end":"2026-05-28T21:47:38.107951Z","steps":["trace[1892566627] 'read index received' (duration: 35.872705ms)","trace[1892566627] 'applied index is now lower than readState.Index' (duration: 207.846861ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:47:38.108068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.846888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:47:38.108103Z","caller":"traceutil/trace.go:171","msg":"trace[780840088] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1225; }","duration":"243.958301ms","start":"2026-05-28T21:47:37.864135Z","end":"2026-05-28T21:47:38.108094Z","steps":["trace[780840088] 'agreement among raft nodes before linearized reading' (duration: 243.875349ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:38.108352Z","caller":"traceutil/trace.go:171","msg":"trace[675833929] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"328.116228ms","start":"2026-05-28T21:47:37.780224Z","end":"2026-05-28T21:47:38.10834Z","steps":["trace[675833929] 'process raft request' (duration: 327.495163ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:38.108431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:37.780211Z","time spent":"328.175169ms","remote":"127.0.0.1:47876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:47:38.108672Z","caller":"traceutil/trace.go:171","msg":"trace[568810067] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"328.440586ms","start":"2026-05-28T21:47:37.780207Z","end":"2026-05-28T21:47:38.108647Z","steps":["trace[568810067] 'process raft request' (duration: 327.610815ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:38.108662Z","caller":"traceutil/trace.go:171","msg":"trace[1376624524] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"328.271062ms","start":"2026-05-28T21:47:37.780349Z","end":"2026-05-28T21:47:38.10862Z","steps":["trace[1376624524] 'process raft request' (duration: 327.517034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:38.108763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:37.780197Z","time spent":"328.522868ms","remote":"127.0.0.1:47876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T21:47:38.108847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:37.780318Z","time spent":"328.422546ms","remote":"127.0.0.1:47876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:47:39.409453Z","caller":"traceutil/trace.go:171","msg":"trace[1019419122] linearizableReadLoop","detail":"{readStateIndex:1281; appliedIndex:1279; }","duration":"667.087594ms","start":"2026-05-28T21:47:38.742343Z","end":"2026-05-28T21:47:39.409431Z","steps":["trace[1019419122] 'read index received' (duration: 104.55324ms)","trace[1019419122] 'applied index is now lower than readState.Index' (duration: 562.533414ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:47:39.409488Z","caller":"traceutil/trace.go:171","msg":"trace[528717424] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"759.778013ms","start":"2026-05-28T21:47:38.649677Z","end":"2026-05-28T21:47:39.409455Z","steps":["trace[528717424] 'process raft request' (duration: 759.597038ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:39.409606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.224358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:47:39.409636Z","caller":"traceutil/trace.go:171","msg":"trace[1896271410] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1228; }","duration":"667.3175ms","start":"2026-05-28T21:47:38.742311Z","end":"2026-05-28T21:47:39.409629Z","steps":["trace[1896271410] 'agreement among raft nodes before linearized reading' (duration: 667.209907ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:39.409628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:38.649647Z","time spent":"759.909026ms","remote":"127.0.0.1:47720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-28T21:47:39.409664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:38.742296Z","time spent":"667.362652ms","remote":"127.0.0.1:47876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-28T21:47:39.416202Z","caller":"traceutil/trace.go:171","msg":"trace[1478525912] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"567.97953ms","start":"2026-05-28T21:47:38.848196Z","end":"2026-05-28T21:47:39.416175Z","steps":["trace[1478525912] 'process raft request' (duration: 567.716593ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:39.416175Z","caller":"traceutil/trace.go:171","msg":"trace[145658803] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"394.765084ms","start":"2026-05-28T21:47:39.02138Z","end":"2026-05-28T21:47:39.416145Z","steps":["trace[145658803] 'process raft request' (duration: 394.707453ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:39.416336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:38.848171Z","time spent":"568.085352ms","remote":"127.0.0.1:47690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T21:47:39.416377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:47:39.021356Z","time spent":"394.913087ms","remote":"127.0.0.1:47876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:47:40.283507Z","caller":"traceutil/trace.go:171","msg":"trace[510605930] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"167.425912ms","start":"2026-05-28T21:47:40.116057Z","end":"2026-05-28T21:47:40.283483Z","steps":["trace[510605930] 'process raft request' (duration: 110.923587ms)","trace[510605930] 'compare' (duration: 56.32391ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:47:40.283643Z","caller":"traceutil/trace.go:171","msg":"trace[323951895] linearizableReadLoop","detail":"{readStateIndex:1292; appliedIndex:1289; }","duration":"165.010753ms","start":"2026-05-28T21:47:40.118606Z","end":"2026-05-28T21:47:40.283617Z","steps":["trace[323951895] 'read index received' (duration: 4.955483ms)","trace[323951895] 'applied index is now lower than readState.Index' (duration: 160.05391ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:47:40.283943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.649673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-28T21:47:40.283991Z","caller":"traceutil/trace.go:171","msg":"trace[1947447452] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1239; }","duration":"164.720715ms","start":"2026-05-28T21:47:40.119259Z","end":"2026-05-28T21:47:40.283979Z","steps":["trace[1947447452] 'agreement among raft nodes before linearized reading' (duration: 164.597232ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:40.283995Z","caller":"traceutil/trace.go:171","msg":"trace[961791284] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"166.702514ms","start":"2026-05-28T21:47:40.117277Z","end":"2026-05-28T21:47:40.283979Z","steps":["trace[961791284] 'process raft request' (duration: 166.173011ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:40.283945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.325691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-28T21:47:40.284072Z","caller":"traceutil/trace.go:171","msg":"trace[832117437] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1239; }","duration":"165.486074ms","start":"2026-05-28T21:47:40.118578Z","end":"2026-05-28T21:47:40.284064Z","steps":["trace[832117437] 'agreement among raft nodes before linearized reading' (duration: 165.229178ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:51.644685Z","caller":"traceutil/trace.go:171","msg":"trace[1476007722] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"108.677123ms","start":"2026-05-28T21:47:51.535986Z","end":"2026-05-28T21:47:51.644663Z","steps":["trace[1476007722] 'process raft request' (duration: 108.477998ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:52.075088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.009388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-28T21:47:52.075086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.031409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-28T21:47:52.075166Z","caller":"traceutil/trace.go:171","msg":"trace[1988869392] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1317; }","duration":"211.117062ms","start":"2026-05-28T21:47:51.864026Z","end":"2026-05-28T21:47:52.075143Z","steps":["trace[1988869392] 'range keys from in-memory index tree' (duration: 210.936686ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:52.075198Z","caller":"traceutil/trace.go:171","msg":"trace[1215870725] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1317; }","duration":"224.188944ms","start":"2026-05-28T21:47:51.85099Z","end":"2026-05-28T21:47:52.075179Z","steps":["trace[1215870725] 'range keys from in-memory index tree' (duration: 223.830873ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:52.193184Z","caller":"traceutil/trace.go:171","msg":"trace[1108145409] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"114.081336ms","start":"2026-05-28T21:47:52.079066Z","end":"2026-05-28T21:47:52.193147Z","steps":["trace[1108145409] 'process raft request' (duration: 113.890702ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:49:13.916755Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12602371863516977412,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-28T21:49:13.952497Z","caller":"traceutil/trace.go:171","msg":"trace[274505627] transaction","detail":"{read_only:false; response_revision:1693; number_of_response:1; }","duration":"550.232449ms","start":"2026-05-28T21:49:13.402232Z","end":"2026-05-28T21:49:13.952465Z","steps":["trace[274505627] 'process raft request' (duration: 550.037504ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:49:13.952946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:49:13.402207Z","time spent":"550.389792ms","remote":"127.0.0.1:47876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:49:13.957719Z","caller":"traceutil/trace.go:171","msg":"trace[1579466560] linearizableReadLoop","detail":"{readStateIndex:1765; appliedIndex:1763; }","duration":"541.736484ms","start":"2026-05-28T21:49:13.415969Z","end":"2026-05-28T21:49:13.957706Z","steps":["trace[1579466560] 'read index received' (duration: 536.307617ms)","trace[1579466560] 'applied index is now lower than readState.Index' (duration: 5.427557ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:49:13.957987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.631463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-28T21:49:13.958053Z","caller":"traceutil/trace.go:171","msg":"trace[1363333005] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1693; }","duration":"477.739706ms","start":"2026-05-28T21:49:13.480299Z","end":"2026-05-28T21:49:13.958039Z","steps":["trace[1363333005] 'agreement among raft nodes before linearized reading' (duration: 477.547352ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:49:13.958051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.939139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-05-28T21:49:13.958083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:49:13.480268Z","time spent":"477.807398ms","remote":"127.0.0.1:47720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":139,"response size":32,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-05-28T21:49:13.958084Z","caller":"traceutil/trace.go:171","msg":"trace[1598431459] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1693; }","duration":"542.135574ms","start":"2026-05-28T21:49:13.41594Z","end":"2026-05-28T21:49:13.958075Z","steps":["trace[1598431459] 'agreement among raft nodes before linearized reading' (duration: 541.856077ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:49:13.958253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:49:13.415927Z","time spent":"542.238117ms","remote":"127.0.0.1:47876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "}