{"level":"warn","ts":"2026-05-05T11:15:45.114645Z","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-05T11:15:45.11473Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.21: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.21:2380","--initial-cluster=instance=https://199.19.213.21:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.21:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.21: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-05T11:15:45.114794Z","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-05T11:15:45.114808Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.21:2380"]} {"level":"info","ts":"2026-05-05T11:15:45.11483Z","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-05T11:15:45.115568Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.21:2379"]} {"level":"info","ts":"2026-05-05T11:15:45.115667Z","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.21:2380"],"listen-peer-urls":["https://199.19.213.21:2380"],"advertise-client-urls":["https://199.19.213.21:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.21:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.21: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-05T11:15:45.120503Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.597038ms"} {"level":"info","ts":"2026-05-05T11:15:45.147535Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c39f03fd9ad57fdc","cluster-id":"3c95a04d00bbaaa4"} {"level":"info","ts":"2026-05-05T11:15:45.147633Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T11:15:45.147659Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc became follower at term 0"} {"level":"info","ts":"2026-05-05T11:15:45.147671Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c39f03fd9ad57fdc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T11:15:45.14768Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc became follower at term 1"} {"level":"info","ts":"2026-05-05T11:15:45.147724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc switched to configuration voters=(14095989746452234204)"} {"level":"warn","ts":"2026-05-05T11:15:45.16512Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T11:15:45.16676Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T11:15:45.16816Z","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-05T11:15:45.170407Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c39f03fd9ad57fdc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T11:15:45.170667Z","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-05T11:15:45.170761Z","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-05T11:15:45.170775Z","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-05T11:15:45.170687Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c39f03fd9ad57fdc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T11:15:45.171626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc switched to configuration voters=(14095989746452234204)"} {"level":"info","ts":"2026-05-05T11:15:45.172073Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3c95a04d00bbaaa4","local-member-id":"c39f03fd9ad57fdc","added-peer-id":"c39f03fd9ad57fdc","added-peer-peer-urls":["https://199.19.213.21:2380"]} {"level":"info","ts":"2026-05-05T11:15:45.175177Z","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-05T11:15:45.175518Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c39f03fd9ad57fdc","initial-advertise-peer-urls":["https://199.19.213.21:2380"],"listen-peer-urls":["https://199.19.213.21:2380"],"advertise-client-urls":["https://199.19.213.21:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.21:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T11:15:45.175622Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T11:15:45.175674Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.21:2380"} {"level":"info","ts":"2026-05-05T11:15:45.17571Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.21:2380"} {"level":"info","ts":"2026-05-05T11:15:45.448117Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T11:15:45.448209Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T11:15:45.448506Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc received MsgPreVoteResp from c39f03fd9ad57fdc at term 1"} {"level":"info","ts":"2026-05-05T11:15:45.448568Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc became candidate at term 2"} {"level":"info","ts":"2026-05-05T11:15:45.448629Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc received MsgVoteResp from c39f03fd9ad57fdc at term 2"} {"level":"info","ts":"2026-05-05T11:15:45.448651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc became leader at term 2"} {"level":"info","ts":"2026-05-05T11:15:45.448725Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c39f03fd9ad57fdc elected leader c39f03fd9ad57fdc at term 2"} {"level":"info","ts":"2026-05-05T11:15:45.458917Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c39f03fd9ad57fdc","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.21:2379]}","request-path":"/0/members/c39f03fd9ad57fdc/attributes","cluster-id":"3c95a04d00bbaaa4","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T11:15:45.459022Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T11:15:45.459117Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T11:15:45.459236Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T11:15:45.459253Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T11:15:45.459396Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T11:15:45.460279Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3c95a04d00bbaaa4","local-member-id":"c39f03fd9ad57fdc","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T11:15:45.460353Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T11:15:45.460376Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T11:15:45.461874Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.21:2379"} {"level":"info","ts":"2026-05-05T11:15:45.462975Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T11:15:47.40867Z","caller":"traceutil/trace.go:171","msg":"trace[429998580] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"412.713307ms","start":"2026-05-05T11:15:46.995928Z","end":"2026-05-05T11:15:47.408641Z","steps":["trace[429998580] 'process raft request' (duration: 412.44861ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:15:47.409302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:15:46.995911Z","time spent":"412.856451ms","remote":"127.0.0.1:57604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T11:15:47.592723Z","caller":"traceutil/trace.go:171","msg":"trace[154976790] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"593.97634ms","start":"2026-05-05T11:15:46.99872Z","end":"2026-05-05T11:15:47.592697Z","steps":["trace[154976790] 'process raft request' (duration: 593.508547ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:15:47.592825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:15:46.998701Z","time spent":"594.078443ms","remote":"127.0.0.1:57592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T11:15:47.592831Z","caller":"traceutil/trace.go:171","msg":"trace[1232043122] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"531.246996ms","start":"2026-05-05T11:15:47.061554Z","end":"2026-05-05T11:15:47.592801Z","steps":["trace[1232043122] 'process raft request' (duration: 531.05471ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:15:47.59286Z","caller":"traceutil/trace.go:171","msg":"trace[1018934740] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"531.292867ms","start":"2026-05-05T11:15:47.061524Z","end":"2026-05-05T11:15:47.592817Z","steps":["trace[1018934740] 'process raft request' (duration: 531.01621ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:15:47.592965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:15:47.061533Z","time spent":"531.358999ms","remote":"127.0.0.1:58028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T11:15:47.592948Z","caller":"traceutil/trace.go:171","msg":"trace[1626601025] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"542.780325ms","start":"2026-05-05T11:15:47.050092Z","end":"2026-05-05T11:15:47.592873Z","steps":["trace[1626601025] 'process raft request' (duration: 542.365413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:15:47.593108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:15:47.0615Z","time spent":"531.462272ms","remote":"127.0.0.1:58028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T11:15:47.593131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:15:47.050042Z","time spent":"542.999742ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T11:15:47.59309Z","caller":"traceutil/trace.go:171","msg":"trace[1365775522] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"588.742817ms","start":"2026-05-05T11:15:47.004282Z","end":"2026-05-05T11:15:47.593025Z","steps":["trace[1365775522] 'read index received' (duration: 404.198279ms)","trace[1365775522] 'applied index is now lower than readState.Index' (duration: 184.543488ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:15:47.593309Z","caller":"traceutil/trace.go:171","msg":"trace[1672023396] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"531.150003ms","start":"2026-05-05T11:15:47.062151Z","end":"2026-05-05T11:15:47.593301Z","steps":["trace[1672023396] 'process raft request' (duration: 530.553396ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:15:47.593348Z","caller":"traceutil/trace.go:171","msg":"trace[341196348] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"521.420085ms","start":"2026-05-05T11:15:47.071915Z","end":"2026-05-05T11:15:47.593335Z","steps":["trace[341196348] 'process raft request' (duration: 521.012983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:15:47.593361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:15:47.062132Z","time spent":"531.201874ms","remote":"127.0.0.1:58028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T11:15:47.59341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:15:47.071894Z","time spent":"521.493347ms","remote":"127.0.0.1:57718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T11:15:47.593442Z","caller":"traceutil/trace.go:171","msg":"trace[97931446] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"531.144563ms","start":"2026-05-05T11:15:47.062289Z","end":"2026-05-05T11:15:47.593434Z","steps":["trace[97931446] 'process raft request' (duration: 530.445543ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:15:47.593483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:15:47.062251Z","time spent":"531.210285ms","remote":"127.0.0.1:58028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T11:15:47.593493Z","caller":"traceutil/trace.go:171","msg":"trace[427279787] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"528.380363ms","start":"2026-05-05T11:15:47.0651Z","end":"2026-05-05T11:15:47.593481Z","steps":["trace[427279787] 'process raft request' (duration: 527.714104ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:15:47.593559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:15:47.065067Z","time spent":"528.458245ms","remote":"127.0.0.1:57784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T11:15:47.593272Z","caller":"traceutil/trace.go:171","msg":"trace[544953773] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"531.655397ms","start":"2026-05-05T11:15:47.061604Z","end":"2026-05-05T11:15:47.593259Z","steps":["trace[544953773] 'process raft request' (duration: 531.039509ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:15:47.593776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:15:47.061595Z","time spent":"532.14411ms","remote":"127.0.0.1:58028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T11:15:47.593801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.19878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-05T11:15:47.593885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.733093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T11:15:47.593886Z","caller":"traceutil/trace.go:171","msg":"trace[1616681882] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"541.301854ms","start":"2026-05-05T11:15:47.052565Z","end":"2026-05-05T11:15:47.593867Z","steps":["trace[1616681882] 'agreement among raft nodes before linearized reading' (duration: 541.140298ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:15:47.593912Z","caller":"traceutil/trace.go:171","msg":"trace[1190697825] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"543.779235ms","start":"2026-05-05T11:15:47.050124Z","end":"2026-05-05T11:15:47.593904Z","steps":["trace[1190697825] 'agreement among raft nodes before linearized reading' (duration: 543.705842ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:15:47.593851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.473668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-05T11:15:47.593923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:15:47.052496Z","time spent":"541.418077ms","remote":"127.0.0.1:57620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-05-05T11:15:47.593937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:15:47.050083Z","time spent":"543.849947ms","remote":"127.0.0.1:57646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-05T11:15:47.593924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.335264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-05T11:15:47.593971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.889735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.21\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T11:15:47.593988Z","caller":"traceutil/trace.go:171","msg":"trace[980660984] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"493.428767ms","start":"2026-05-05T11:15:47.100538Z","end":"2026-05-05T11:15:47.593967Z","steps":["trace[980660984] 'agreement among raft nodes before linearized reading' (duration: 493.329884ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:15:47.593966Z","caller":"traceutil/trace.go:171","msg":"trace[1984703902] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"589.689175ms","start":"2026-05-05T11:15:47.004254Z","end":"2026-05-05T11:15:47.593943Z","steps":["trace[1984703902] 'agreement among raft nodes before linearized reading' (duration: 589.139079ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:15:47.594021Z","caller":"traceutil/trace.go:171","msg":"trace[1327470656] range","detail":"{range_begin:/registry/masterleases/199.19.213.21; range_end:; response_count:0; response_revision:11; }","duration":"545.940096ms","start":"2026-05-05T11:15:47.04807Z","end":"2026-05-05T11:15:47.59401Z","steps":["trace[1327470656] 'agreement among raft nodes before linearized reading' (duration: 545.867414ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:15:47.594036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:15:47.100522Z","time spent":"493.502569ms","remote":"127.0.0.1:57646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-05T11:15:47.594052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:15:47.047866Z","time spent":"546.179002ms","remote":"127.0.0.1:57582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.19.213.21\" "} {"level":"warn","ts":"2026-05-05T11:15:47.594077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:15:47.00424Z","time spent":"589.822129ms","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-05-05T11:15:47.594201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.004743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T11:15:47.599203Z","caller":"traceutil/trace.go:171","msg":"trace[1426492438] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"560.967654ms","start":"2026-05-05T11:15:47.038146Z","end":"2026-05-05T11:15:47.599114Z","steps":["trace[1426492438] 'agreement among raft nodes before linearized reading' (duration: 555.535469ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:15:47.599392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:15:47.038132Z","time spent":"561.238022ms","remote":"127.0.0.1:57646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-05-05T11:15:51.990373Z","caller":"traceutil/trace.go:171","msg":"trace[458611980] transaction","detail":"{read_only:false; number_of_response:0; response_revision:278; }","duration":"112.671689ms","start":"2026-05-05T11:15:51.877673Z","end":"2026-05-05T11:15:51.990344Z","steps":["trace[458611980] 'process raft request' (duration: 112.528316ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:15:51.990446Z","caller":"traceutil/trace.go:171","msg":"trace[203958995] transaction","detail":"{read_only:false; number_of_response:0; response_revision:278; }","duration":"111.841528ms","start":"2026-05-05T11:15:51.878588Z","end":"2026-05-05T11:15:51.99043Z","steps":["trace[203958995] 'process raft request' (duration: 111.701165ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:15:51.9906Z","caller":"traceutil/trace.go:171","msg":"trace[1158834133] transaction","detail":"{read_only:false; number_of_response:0; response_revision:278; }","duration":"111.319035ms","start":"2026-05-05T11:15:51.879266Z","end":"2026-05-05T11:15:51.990585Z","steps":["trace[1158834133] 'process raft request' (duration: 111.074749ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:15:52.226428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.406141ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T11:15:52.226546Z","caller":"traceutil/trace.go:171","msg":"trace[1778942948] linearizableReadLoop","detail":"{readStateIndex:293; appliedIndex:292; }","duration":"195.904918ms","start":"2026-05-05T11:15:52.030622Z","end":"2026-05-05T11:15:52.226527Z","steps":["trace[1778942948] 'read index received' (duration: 95.015315ms)","trace[1778942948] 'applied index is now lower than readState.Index' (duration: 100.888263ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:15:52.226619Z","caller":"traceutil/trace.go:171","msg":"trace[869359152] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"225.19051ms","start":"2026-05-05T11:15:52.00136Z","end":"2026-05-05T11:15:52.22655Z","steps":["trace[869359152] 'process raft request' (duration: 124.312747ms)","trace[869359152] 'compare' (duration: 100.250637ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:15:52.226701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.944093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5892"} {"level":"warn","ts":"2026-05-05T11:15:52.226776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.966457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3468"} {"level":"info","ts":"2026-05-05T11:15:52.226813Z","caller":"traceutil/trace.go:171","msg":"trace[374008090] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:280; }","duration":"192.023789ms","start":"2026-05-05T11:15:52.034778Z","end":"2026-05-05T11:15:52.226802Z","steps":["trace[374008090] 'agreement among raft nodes before linearized reading' (duration: 191.974248ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:15:52.226684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.965074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3468"} {"level":"info","ts":"2026-05-05T11:15:52.226867Z","caller":"traceutil/trace.go:171","msg":"trace[2143204671] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:280; }","duration":"235.163558ms","start":"2026-05-05T11:15:51.991689Z","end":"2026-05-05T11:15:52.226852Z","steps":["trace[2143204671] 'agreement among raft nodes before linearized reading' (duration: 234.938443ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:15:52.226775Z","caller":"traceutil/trace.go:171","msg":"trace[476839005] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:280; }","duration":"235.040666ms","start":"2026-05-05T11:15:51.991716Z","end":"2026-05-05T11:15:52.226757Z","steps":["trace[476839005] 'agreement among raft nodes before linearized reading' (duration: 234.856261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:15:52.226727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.063876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5326"} {"level":"info","ts":"2026-05-05T11:15:52.227119Z","caller":"traceutil/trace.go:171","msg":"trace[316528754] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:280; }","duration":"235.474216ms","start":"2026-05-05T11:15:51.991628Z","end":"2026-05-05T11:15:52.227103Z","steps":["trace[316528754] 'agreement among raft nodes before linearized reading' (duration: 235.073607ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:15:53.596645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.248741502s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T11:15:53.596865Z","caller":"traceutil/trace.go:171","msg":"trace[891868650] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"1.366602184s","start":"2026-05-05T11:15:52.230246Z","end":"2026-05-05T11:15:53.596848Z","steps":["trace[891868650] 'process raft request' (duration: 117.567725ms)","trace[891868650] 'compare' (duration: 1.248614709s)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:15:53.596916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:15:52.230219Z","time spent":"1.366674226s","remote":"127.0.0.1:57800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T11:15:53.596956Z","caller":"traceutil/trace.go:171","msg":"trace[1538086099] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"1.359949056s","start":"2026-05-05T11:15:52.236964Z","end":"2026-05-05T11:15:53.596913Z","steps":["trace[1538086099] 'process raft request' (duration: 1.359782961s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:15:53.597162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:15:52.236941Z","time spent":"1.3601118s","remote":"127.0.0.1:57800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T11:15:53.597353Z","caller":"traceutil/trace.go:171","msg":"trace[1897776067] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"1.35812423s","start":"2026-05-05T11:15:52.239141Z","end":"2026-05-05T11:15:53.597265Z","steps":["trace[1897776067] 'process raft request' (duration: 1.357650608s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:15:53.5978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:15:52.239119Z","time spent":"1.35852181s","remote":"127.0.0.1:57728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4645,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T11:15:53.598632Z","caller":"traceutil/trace.go:171","msg":"trace[1093324670] linearizableReadLoop","detail":"{readStateIndex:295; appliedIndex:293; }","duration":"1.361452904s","start":"2026-05-05T11:15:52.237077Z","end":"2026-05-05T11:15:53.598529Z","steps":["trace[1093324670] 'read index received' (duration: 110.780434ms)","trace[1093324670] 'applied index is now lower than readState.Index' (duration: 1.25067059s)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:15:53.59875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.361662849s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T11:15:53.599889Z","caller":"traceutil/trace.go:171","msg":"trace[154058358] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:0; response_revision:283; }","duration":"1.362838548s","start":"2026-05-05T11:15:52.237033Z","end":"2026-05-05T11:15:53.599871Z","steps":["trace[154058358] 'agreement among raft nodes before linearized reading' (duration: 1.361668309s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:15:53.600908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:15:52.237018Z","time spent":"1.362904149s","remote":"127.0.0.1:57742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" "} {"level":"warn","ts":"2026-05-05T11:15:53.602124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.088172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-05T11:15:53.602118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.329307ms","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-05T11:15:53.602178Z","caller":"traceutil/trace.go:171","msg":"trace[1336076304] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:283; }","duration":"760.194525ms","start":"2026-05-05T11:15:52.841972Z","end":"2026-05-05T11:15:53.602166Z","steps":["trace[1336076304] 'agreement among raft nodes before linearized reading' (duration: 760.087162ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:15:53.60221Z","caller":"traceutil/trace.go:171","msg":"trace[73113867] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:283; }","duration":"324.4743ms","start":"2026-05-05T11:15:53.277719Z","end":"2026-05-05T11:15:53.602193Z","steps":["trace[73113867] 'agreement among raft nodes before linearized reading' (duration: 324.300156ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:15:53.602254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:15:53.277692Z","time spent":"324.549402ms","remote":"127.0.0.1:57800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T11:15:53.602428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:15:52.84194Z","time spent":"760.263627ms","remote":"127.0.0.1:57800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T11:15:54.856918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.036225ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T11:15:54.857862Z","caller":"traceutil/trace.go:171","msg":"trace[1699081549] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"1.250583844s","start":"2026-05-05T11:15:53.607178Z","end":"2026-05-05T11:15:54.857762Z","steps":["trace[1699081549] 'process raft request' (duration: 527.513914ms)","trace[1699081549] 'compare' (duration: 721.80916ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:15:54.857995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:15:53.607161Z","time spent":"1.25079207s","remote":"127.0.0.1:57800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T11:15:55.496274Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.361536235s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-05T11:15:55.759541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.969864ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T11:15:55.759823Z","caller":"traceutil/trace.go:171","msg":"trace[2030006642] linearizableReadLoop","detail":"{readStateIndex:300; appliedIndex:297; }","duration":"155.004926ms","start":"2026-05-05T11:15:55.604787Z","end":"2026-05-05T11:15:55.759792Z","steps":["trace[2030006642] 'read index received' (duration: 77.752µs)","trace[2030006642] 'applied index is now lower than readState.Index' (duration: 154.925374ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:15:55.759987Z","caller":"traceutil/trace.go:171","msg":"trace[567556507] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"2.150604898s","start":"2026-05-05T11:15:53.609344Z","end":"2026-05-05T11:15:55.759949Z","steps":["trace[567556507] 'process raft request' (duration: 2.150395442s)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:15:55.75997Z","caller":"traceutil/trace.go:171","msg":"trace[2123782733] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"2.151906641s","start":"2026-05-05T11:15:53.608021Z","end":"2026-05-05T11:15:55.759928Z","steps":["trace[2123782733] 'process raft request' (duration: 1.888460804s)","trace[2123782733] 'compare' (duration: 262.804441ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:15:55.760086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.340762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-05T11:15:55.760114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:15:53.609328Z","time spent":"2.15072565s","remote":"127.0.0.1:57742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":192,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T11:15:55.760119Z","caller":"traceutil/trace.go:171","msg":"trace[1930241741] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:287; }","duration":"154.398004ms","start":"2026-05-05T11:15:55.60571Z","end":"2026-05-05T11:15:55.760108Z","steps":["trace[1930241741] 'agreement among raft nodes before linearized reading' (duration: 154.347923ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:15:55.760166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:15:53.608009Z","time spent":"2.152057544s","remote":"127.0.0.1:57800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T11:15:55.760134Z","caller":"traceutil/trace.go:171","msg":"trace[581786924] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"2.151834589s","start":"2026-05-05T11:15:53.608188Z","end":"2026-05-05T11:15:55.760022Z","steps":["trace[581786924] 'process raft request' (duration: 2.15147943s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:15:55.760014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.238313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-05-05T11:15:55.760392Z","caller":"traceutil/trace.go:171","msg":"trace[297193002] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:287; }","duration":"155.659073ms","start":"2026-05-05T11:15:55.604718Z","end":"2026-05-05T11:15:55.760377Z","steps":["trace[297193002] 'agreement among raft nodes before linearized reading' (duration: 155.231253ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:15:55.760685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:15:53.608182Z","time spent":"2.152201737s","remote":"127.0.0.1:57728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7087,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T11:15:56.084052Z","caller":"traceutil/trace.go:171","msg":"trace[565756282] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"317.414937ms","start":"2026-05-05T11:15:55.765519Z","end":"2026-05-05T11:15:56.082934Z","steps":["trace[565756282] 'process raft request' (duration: 267.620818ms)","trace[565756282] 'compare' (duration: 49.259027ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:15:56.084643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:15:55.765512Z","time spent":"318.841ms","remote":"127.0.0.1:57800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T11:15:56.084698Z","caller":"traceutil/trace.go:171","msg":"trace[609983793] linearizableReadLoop","detail":"{readStateIndex:302; appliedIndex:300; }","duration":"317.58864ms","start":"2026-05-05T11:15:55.767029Z","end":"2026-05-05T11:15:56.084617Z","steps":["trace[609983793] 'read index received' (duration: 89.489911ms)","trace[609983793] 'applied index is now lower than readState.Index' (duration: 228.096409ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:15:56.085615Z","caller":"traceutil/trace.go:171","msg":"trace[160743500] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"313.587516ms","start":"2026-05-05T11:15:55.772001Z","end":"2026-05-05T11:15:56.085588Z","steps":["trace[160743500] 'process raft request' (duration: 310.623268ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:15:56.085886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:15:55.771987Z","time spent":"313.679328ms","remote":"127.0.0.1:57728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4307,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T11:15:56.086775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.644697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-05-05T11:15:56.087198Z","caller":"traceutil/trace.go:171","msg":"trace[409667963] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:290; }","duration":"320.14623ms","start":"2026-05-05T11:15:55.767014Z","end":"2026-05-05T11:15:56.08716Z","steps":["trace[409667963] 'agreement among raft nodes before linearized reading' (duration: 317.770645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:15:56.088833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:15:55.767008Z","time spent":"320.237951ms","remote":"127.0.0.1:57742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-05-05T11:15:56.876089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.470747ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T11:15:56.87649Z","caller":"traceutil/trace.go:171","msg":"trace[475774613] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"213.296669ms","start":"2026-05-05T11:15:56.663096Z","end":"2026-05-05T11:15:56.876393Z","steps":["trace[475774613] 'process raft request' (duration: 89.402912ms)","trace[475774613] 'compare' (duration: 123.209971ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:15:56.876653Z","caller":"traceutil/trace.go:171","msg":"trace[1929170053] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"211.944298ms","start":"2026-05-05T11:15:56.664677Z","end":"2026-05-05T11:15:56.876622Z","steps":["trace[1929170053] 'process raft request' (duration: 211.553599ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:15:56.877301Z","caller":"traceutil/trace.go:171","msg":"trace[1819858348] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"206.312997ms","start":"2026-05-05T11:15:56.670859Z","end":"2026-05-05T11:15:56.877172Z","steps":["trace[1819858348] 'process raft request' (duration: 205.435407ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:15:56.877376Z","caller":"traceutil/trace.go:171","msg":"trace[973109487] linearizableReadLoop","detail":"{readStateIndex:326; appliedIndex:324; }","duration":"212.596082ms","start":"2026-05-05T11:15:56.664765Z","end":"2026-05-05T11:15:56.877361Z","steps":["trace[973109487] 'read index received' (duration: 87.745663ms)","trace[973109487] 'applied index is now lower than readState.Index' (duration: 124.848959ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:15:56.877791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.111903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-05-05T11:15:56.877874Z","caller":"traceutil/trace.go:171","msg":"trace[1778507266] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:314; }","duration":"183.244886ms","start":"2026-05-05T11:15:56.694602Z","end":"2026-05-05T11:15:56.877846Z","steps":["trace[1778507266] 'agreement among raft nodes before linearized reading' (duration: 183.081892ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:15:56.87805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.118663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-05-05T11:15:56.87811Z","caller":"traceutil/trace.go:171","msg":"trace[1760310714] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:314; }","duration":"134.217806ms","start":"2026-05-05T11:15:56.743877Z","end":"2026-05-05T11:15:56.878095Z","steps":["trace[1760310714] 'agreement among raft nodes before linearized reading' (duration: 134.102763ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:15:56.878479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.702328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:1 size:3475"} {"level":"info","ts":"2026-05-05T11:15:56.878544Z","caller":"traceutil/trace.go:171","msg":"trace[301795516] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:1; response_revision:314; }","duration":"213.80862ms","start":"2026-05-05T11:15:56.664716Z","end":"2026-05-05T11:15:56.878524Z","steps":["trace[301795516] 'agreement among raft nodes before linearized reading' (duration: 212.829067ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:15:57.084475Z","caller":"traceutil/trace.go:171","msg":"trace[1955075905] linearizableReadLoop","detail":"{readStateIndex:342; appliedIndex:341; }","duration":"141.157303ms","start":"2026-05-05T11:15:56.943288Z","end":"2026-05-05T11:15:57.084446Z","steps":["trace[1955075905] 'read index received' (duration: 60.170708ms)","trace[1955075905] 'applied index is now lower than readState.Index' (duration: 80.985355ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:15:57.084611Z","caller":"traceutil/trace.go:171","msg":"trace[959128938] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"155.611567ms","start":"2026-05-05T11:15:56.928931Z","end":"2026-05-05T11:15:57.084543Z","steps":["trace[959128938] 'process raft request' (duration: 74.513648ms)","trace[959128938] 'compare' (duration: 80.841243ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:15:57.084814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.493471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-05T11:15:57.084893Z","caller":"traceutil/trace.go:171","msg":"trace[563305102] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:329; }","duration":"141.623663ms","start":"2026-05-05T11:15:56.94325Z","end":"2026-05-05T11:15:57.084873Z","steps":["trace[563305102] 'agreement among raft nodes before linearized reading' (duration: 141.339527ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:15:57.122372Z","caller":"traceutil/trace.go:171","msg":"trace[919396079] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"174.702847ms","start":"2026-05-05T11:15:56.947657Z","end":"2026-05-05T11:15:57.12236Z","steps":["trace[919396079] 'process raft request' (duration: 174.519702ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:15:57.122449Z","caller":"traceutil/trace.go:171","msg":"trace[1431114475] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"169.052677ms","start":"2026-05-05T11:15:56.953361Z","end":"2026-05-05T11:15:57.122414Z","steps":["trace[1431114475] 'process raft request' (duration: 168.910914ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:15:57.122509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.883004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3706"} {"level":"info","ts":"2026-05-05T11:15:57.122566Z","caller":"traceutil/trace.go:171","msg":"trace[477533596] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:331; }","duration":"175.988577ms","start":"2026-05-05T11:15:56.946563Z","end":"2026-05-05T11:15:57.122551Z","steps":["trace[477533596] 'agreement among raft nodes before linearized reading' (duration: 175.844364ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:15:57.232676Z","caller":"traceutil/trace.go:171","msg":"trace[224958487] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"143.75931ms","start":"2026-05-05T11:15:57.088862Z","end":"2026-05-05T11:15:57.232622Z","steps":["trace[224958487] 'process raft request' (duration: 98.281435ms)","trace[224958487] 'compare' (duration: 45.175369ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:15:57.232756Z","caller":"traceutil/trace.go:171","msg":"trace[695480896] linearizableReadLoop","detail":"{readStateIndex:345; appliedIndex:344; }","duration":"110.384047ms","start":"2026-05-05T11:15:57.122323Z","end":"2026-05-05T11:15:57.232707Z","steps":["trace[695480896] 'read index received' (duration: 64.845511ms)","trace[695480896] 'applied index is now lower than readState.Index' (duration: 45.533976ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:15:57.23287Z","caller":"traceutil/trace.go:171","msg":"trace[2004419708] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"101.60647ms","start":"2026-05-05T11:15:57.131231Z","end":"2026-05-05T11:15:57.232837Z","steps":["trace[2004419708] 'process raft request' (duration: 101.428106ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:15:57.232947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.141679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-05T11:15:57.233046Z","caller":"traceutil/trace.go:171","msg":"trace[1534736495] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:334; }","duration":"144.276661ms","start":"2026-05-05T11:15:57.08875Z","end":"2026-05-05T11:15:57.233027Z","steps":["trace[1534736495] 'agreement among raft nodes before linearized reading' (duration: 144.053346ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:15:57.233049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.993771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-05-05T11:15:57.2331Z","caller":"traceutil/trace.go:171","msg":"trace[1022213688] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:334; }","duration":"146.091033ms","start":"2026-05-05T11:15:57.086996Z","end":"2026-05-05T11:15:57.233088Z","steps":["trace[1022213688] 'agreement among raft nodes before linearized reading' (duration: 145.993761ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:15:57.232947Z","caller":"traceutil/trace.go:171","msg":"trace[1170687981] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"106.908659ms","start":"2026-05-05T11:15:57.126025Z","end":"2026-05-05T11:15:57.232934Z","steps":["trace[1170687981] 'process raft request' (duration: 106.535101ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:15:58.009083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.351445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-05T11:15:58.009168Z","caller":"traceutil/trace.go:171","msg":"trace[1478033837] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:346; }","duration":"146.491179ms","start":"2026-05-05T11:15:57.862658Z","end":"2026-05-05T11:15:58.009149Z","steps":["trace[1478033837] 'range keys from in-memory index tree' (duration: 146.205713ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:16:11.238565Z","caller":"traceutil/trace.go:171","msg":"trace[1068852377] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"120.113654ms","start":"2026-05-05T11:16:11.118412Z","end":"2026-05-05T11:16:11.238526Z","steps":["trace[1068852377] 'process raft request' (duration: 86.945458ms)","trace[1068852377] 'compare' (duration: 33.024864ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:16:20.451908Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9213412625531240517,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T11:16:20.952442Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9213412625531240517,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T11:16:21.122364Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.310760734s","expected-duration":"1s"} {"level":"info","ts":"2026-05-05T11:16:21.123011Z","caller":"traceutil/trace.go:171","msg":"trace[1348536430] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"1.311746798s","start":"2026-05-05T11:16:19.811233Z","end":"2026-05-05T11:16:21.12298Z","steps":["trace[1348536430] 'process raft request' (duration: 1.311412953s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:16:21.123205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:16:19.811212Z","time spent":"1.31188147s","remote":"127.0.0.1:57586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80679,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T11:16:21.867593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.28682ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T11:16:21.868093Z","caller":"traceutil/trace.go:171","msg":"trace[858372206] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:540; }","duration":"1.916688018s","start":"2026-05-05T11:16:19.951385Z","end":"2026-05-05T11:16:21.868073Z","steps":["trace[858372206] 'read index received' (duration: 1.171372389s)","trace[858372206] 'applied index is now lower than readState.Index' (duration: 745.314709ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:16:21.868175Z","caller":"traceutil/trace.go:171","msg":"trace[166340779] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"2.030957071s","start":"2026-05-05T11:16:19.837209Z","end":"2026-05-05T11:16:21.868166Z","steps":["trace[166340779] 'process raft request' (duration: 2.030756488s)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:16:21.86821Z","caller":"traceutil/trace.go:171","msg":"trace[991657845] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"2.047699813s","start":"2026-05-05T11:16:19.82049Z","end":"2026-05-05T11:16:21.86819Z","steps":["trace[991657845] 'process raft request' (duration: 2.047261447s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:16:21.868263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:16:19.837184Z","time spent":"2.031017091s","remote":"127.0.0.1:57586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T11:16:21.868375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:16:19.82047Z","time spent":"2.047813044s","remote":"127.0.0.1:57586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59616,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T11:16:21.868491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.917106294s","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-05T11:16:21.868488Z","caller":"traceutil/trace.go:171","msg":"trace[595613940] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"2.051798762s","start":"2026-05-05T11:16:19.816292Z","end":"2026-05-05T11:16:21.868091Z","steps":["trace[595613940] 'process raft request' (duration: 1.945928863s)","trace[595613940] 'compare' (duration: 105.127988ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:16:21.868527Z","caller":"traceutil/trace.go:171","msg":"trace[1736413972] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:522; }","duration":"1.917174895s","start":"2026-05-05T11:16:19.951342Z","end":"2026-05-05T11:16:21.868516Z","steps":["trace[1736413972] 'agreement among raft nodes before linearized reading' (duration: 1.917099624s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:16:21.868557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:16:19.951325Z","time spent":"1.917226137s","remote":"127.0.0.1:57800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T11:16:21.868553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:16:19.816272Z","time spent":"2.052254578s","remote":"127.0.0.1:57586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T11:16:22.018303Z","caller":"traceutil/trace.go:171","msg":"trace[1606262252] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"877.447822ms","start":"2026-05-05T11:16:21.140831Z","end":"2026-05-05T11:16:22.018279Z","steps":["trace[1606262252] 'process raft request' (duration: 868.323037ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:16:22.01844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:16:21.140806Z","time spent":"877.563063ms","remote":"127.0.0.1:57586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29546,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T11:16:22.018587Z","caller":"traceutil/trace.go:171","msg":"trace[995017131] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"575.19966ms","start":"2026-05-05T11:16:21.443373Z","end":"2026-05-05T11:16:22.018572Z","steps":["trace[995017131] 'process raft request' (duration: 574.862375ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:16:22.018619Z","caller":"traceutil/trace.go:171","msg":"trace[1518353441] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:544; }","duration":"150.467138ms","start":"2026-05-05T11:16:21.868131Z","end":"2026-05-05T11:16:22.018599Z","steps":["trace[1518353441] 'read index received' (duration: 140.931868ms)","trace[1518353441] 'applied index is now lower than readState.Index' (duration: 9.53419ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:16:22.018714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:16:21.44334Z","time spent":"575.345462ms","remote":"127.0.0.1:57800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T11:16:22.018852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.348274398s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T11:16:22.018883Z","caller":"traceutil/trace.go:171","msg":"trace[212061221] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:525; }","duration":"1.348332129s","start":"2026-05-05T11:16:20.670541Z","end":"2026-05-05T11:16:22.018873Z","steps":["trace[212061221] 'agreement among raft nodes before linearized reading' (duration: 1.348093156s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:16:22.018903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:16:20.670525Z","time spent":"1.348373259s","remote":"127.0.0.1:57562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T11:16:22.018956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"917.014463ms","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-05T11:16:22.019069Z","caller":"traceutil/trace.go:171","msg":"trace[1766789924] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"145.962654ms","start":"2026-05-05T11:16:21.873097Z","end":"2026-05-05T11:16:22.01906Z","steps":["trace[1766789924] 'process raft request' (duration: 145.408807ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:16:22.019084Z","caller":"traceutil/trace.go:171","msg":"trace[969986209] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:525; }","duration":"917.211376ms","start":"2026-05-05T11:16:21.10184Z","end":"2026-05-05T11:16:22.019052Z","steps":["trace[969986209] 'agreement among raft nodes before linearized reading' (duration: 916.814001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:16:22.019073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.674632205s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-05T11:16:22.019143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:16:21.101813Z","time spent":"917.317098ms","remote":"127.0.0.1:57800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-05T11:16:22.019147Z","caller":"traceutil/trace.go:171","msg":"trace[726308401] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:525; }","duration":"1.674747397s","start":"2026-05-05T11:16:20.344387Z","end":"2026-05-05T11:16:22.019134Z","steps":["trace[726308401] 'agreement among raft nodes before linearized reading' (duration: 1.674277811s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:16:22.019229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:16:20.344364Z","time spent":"1.674854229s","remote":"127.0.0.1:57800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T11:16:22.019211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.722871161s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-05T11:16:22.019364Z","caller":"traceutil/trace.go:171","msg":"trace[1316172744] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"141.719505ms","start":"2026-05-05T11:16:21.877635Z","end":"2026-05-05T11:16:22.019354Z","steps":["trace[1316172744] 'process raft request' (duration: 141.104007ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:16:22.019603Z","caller":"traceutil/trace.go:171","msg":"trace[1639640250] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"136.867707ms","start":"2026-05-05T11:16:21.882721Z","end":"2026-05-05T11:16:22.019589Z","steps":["trace[1639640250] 'process raft request' (duration: 136.596204ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:16:22.020543Z","caller":"traceutil/trace.go:171","msg":"trace[1046623192] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:525; }","duration":"1.724332781s","start":"2026-05-05T11:16:20.296143Z","end":"2026-05-05T11:16:22.020475Z","steps":["trace[1046623192] 'agreement among raft nodes before linearized reading' (duration: 1.722551907s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:16:22.020691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:16:20.296121Z","time spent":"1.724546574s","remote":"127.0.0.1:57800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-05T11:16:28.049662Z","caller":"traceutil/trace.go:171","msg":"trace[1084951098] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:603; }","duration":"126.034527ms","start":"2026-05-05T11:16:27.923563Z","end":"2026-05-05T11:16:28.049598Z","steps":["trace[1084951098] 'read index received' (duration: 125.598551ms)","trace[1084951098] 'applied index is now lower than readState.Index' (duration: 434.016µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:16:28.049901Z","caller":"traceutil/trace.go:171","msg":"trace[1753096223] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"134.17723ms","start":"2026-05-05T11:16:27.915686Z","end":"2026-05-05T11:16:28.049864Z","steps":["trace[1753096223] 'process raft request' (duration: 133.617863ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:16:28.049987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.394531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T11:16:28.050086Z","caller":"traceutil/trace.go:171","msg":"trace[669796845] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:581; }","duration":"126.555133ms","start":"2026-05-05T11:16:27.923512Z","end":"2026-05-05T11:16:28.050067Z","steps":["trace[669796845] 'agreement among raft nodes before linearized reading' (duration: 126.340391ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:16:28.050578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.199589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T11:16:28.05068Z","caller":"traceutil/trace.go:171","msg":"trace[636172959] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:581; }","duration":"126.32535ms","start":"2026-05-05T11:16:27.924298Z","end":"2026-05-05T11:16:28.050624Z","steps":["trace[636172959] 'agreement among raft nodes before linearized reading' (duration: 126.207499ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:16:28.050676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.950459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T11:16:28.05074Z","caller":"traceutil/trace.go:171","msg":"trace[367345172] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:581; }","duration":"127.054879ms","start":"2026-05-05T11:16:27.923669Z","end":"2026-05-05T11:16:28.050724Z","steps":["trace[367345172] 'agreement among raft nodes before linearized reading' (duration: 126.373851ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:16:29.433871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.517456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-955vl\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-05T11:16:29.433962Z","caller":"traceutil/trace.go:171","msg":"trace[1900198449] range","detail":"{range_begin:/registry/pods/kube-system/cilium-955vl; range_end:; response_count:1; response_revision:597; }","duration":"453.642257ms","start":"2026-05-05T11:16:28.980298Z","end":"2026-05-05T11:16:29.43394Z","steps":["trace[1900198449] 'range keys from in-memory index tree' (duration: 453.413644ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:16:29.434003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:16:28.98028Z","time spent":"453.714338ms","remote":"127.0.0.1:57728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-955vl\" "} {"level":"info","ts":"2026-05-05T11:16:29.592825Z","caller":"traceutil/trace.go:171","msg":"trace[398805911] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"140.600227ms","start":"2026-05-05T11:16:29.452194Z","end":"2026-05-05T11:16:29.592795Z","steps":["trace[398805911] 'process raft request' (duration: 140.349324ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:16:31.382925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.247366349s","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-05T11:16:31.38302Z","caller":"traceutil/trace.go:171","msg":"trace[345501970] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:598; }","duration":"1.24751099s","start":"2026-05-05T11:16:30.135487Z","end":"2026-05-05T11:16:31.382998Z","steps":["trace[345501970] 'range keys from in-memory index tree' (duration: 1.247189247s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:16:31.383075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:16:30.135467Z","time spent":"1.247583721s","remote":"127.0.0.1:57800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T11:16:31.383076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.713832655s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T11:16:31.383186Z","caller":"traceutil/trace.go:171","msg":"trace[1181608003] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:598; }","duration":"1.713985016s","start":"2026-05-05T11:16:29.669176Z","end":"2026-05-05T11:16:31.383161Z","steps":["trace[1181608003] 'range keys from in-memory index tree' (duration: 1.713708243s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:16:31.383228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:16:29.669158Z","time spent":"1.714061358s","remote":"127.0.0.1:57562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T11:16:31.383353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.211675347s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-05T11:16:31.383389Z","caller":"traceutil/trace.go:171","msg":"trace[8120638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:598; }","duration":"1.211747859s","start":"2026-05-05T11:16:30.171631Z","end":"2026-05-05T11:16:31.383379Z","steps":["trace[8120638] 'range keys from in-memory index tree' (duration: 1.211494864s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:16:31.383416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:16:30.171607Z","time spent":"1.211802999s","remote":"127.0.0.1:57800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T11:16:31.383519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.197142751s","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-05T11:16:31.383616Z","caller":"traceutil/trace.go:171","msg":"trace[1413821690] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:598; }","duration":"1.197365493s","start":"2026-05-05T11:16:30.186227Z","end":"2026-05-05T11:16:31.383592Z","steps":["trace[1413821690] 'range keys from in-memory index tree' (duration: 1.196927058s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:16:31.38371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:16:30.186212Z","time spent":"1.197441515s","remote":"127.0.0.1:57800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T11:16:31.383738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.198892972s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-05-05T11:16:31.383784Z","caller":"traceutil/trace.go:171","msg":"trace[660687304] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:598; }","duration":"1.199038804s","start":"2026-05-05T11:16:30.184732Z","end":"2026-05-05T11:16:31.383771Z","steps":["trace[660687304] 'range keys from in-memory index tree' (duration: 1.198770611s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:16:31.383821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:16:30.184718Z","time spent":"1.199095185s","remote":"127.0.0.1:57800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-05T11:16:33.432999Z","caller":"traceutil/trace.go:171","msg":"trace[930796796] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"274.290639ms","start":"2026-05-05T11:16:33.158669Z","end":"2026-05-05T11:16:33.43296Z","steps":["trace[930796796] 'process raft request' (duration: 273.808942ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:16:36.383344Z","caller":"traceutil/trace.go:171","msg":"trace[1448366287] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"201.070096ms","start":"2026-05-05T11:16:36.182231Z","end":"2026-05-05T11:16:36.383301Z","steps":["trace[1448366287] 'process raft request' (duration: 200.845754ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:16:47.488812Z","caller":"traceutil/trace.go:171","msg":"trace[1770176435] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"107.202055ms","start":"2026-05-05T11:16:47.381571Z","end":"2026-05-05T11:16:47.488773Z","steps":["trace[1770176435] 'process raft request' (duration: 106.900702ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:16:47.673277Z","caller":"traceutil/trace.go:171","msg":"trace[315421202] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"102.154635ms","start":"2026-05-05T11:16:47.571094Z","end":"2026-05-05T11:16:47.673248Z","steps":["trace[315421202] 'process raft request' (duration: 102.017683ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:16:47.67332Z","caller":"traceutil/trace.go:171","msg":"trace[212907053] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"102.400518ms","start":"2026-05-05T11:16:47.570869Z","end":"2026-05-05T11:16:47.673269Z","steps":["trace[212907053] 'process raft request' (duration: 101.137186ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:16:47.673566Z","caller":"traceutil/trace.go:171","msg":"trace[2031052490] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"101.799822ms","start":"2026-05-05T11:16:47.571548Z","end":"2026-05-05T11:16:47.673348Z","steps":["trace[2031052490] 'process raft request' (duration: 101.60676ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:16:48.261927Z","caller":"traceutil/trace.go:171","msg":"trace[1964207064] linearizableReadLoop","detail":"{readStateIndex:788; appliedIndex:787; }","duration":"120.043298ms","start":"2026-05-05T11:16:48.141855Z","end":"2026-05-05T11:16:48.261898Z","steps":["trace[1964207064] 'read index received' (duration: 119.757836ms)","trace[1964207064] 'applied index is now lower than readState.Index' (duration: 284.252µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:16:48.261979Z","caller":"traceutil/trace.go:171","msg":"trace[75227589] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"120.807405ms","start":"2026-05-05T11:16:48.14115Z","end":"2026-05-05T11:16:48.261958Z","steps":["trace[75227589] 'process raft request' (duration: 120.558713ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:16:48.262083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.20801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T11:16:48.262123Z","caller":"traceutil/trace.go:171","msg":"trace[640583399] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:760; }","duration":"120.31091ms","start":"2026-05-05T11:16:48.141802Z","end":"2026-05-05T11:16:48.262113Z","steps":["trace[640583399] 'agreement among raft nodes before linearized reading' (duration: 120.211299ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:16:59.469803Z","caller":"traceutil/trace.go:171","msg":"trace[2107953889] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"106.148944ms","start":"2026-05-05T11:16:59.363624Z","end":"2026-05-05T11:16:59.469773Z","steps":["trace[2107953889] 'process raft request' (duration: 105.983603ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:17:21.171073Z","caller":"traceutil/trace.go:171","msg":"trace[1863859271] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"120.894006ms","start":"2026-05-05T11:17:21.05015Z","end":"2026-05-05T11:17:21.171044Z","steps":["trace[1863859271] 'process raft request' (duration: 119.889819ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:17:32.175918Z","caller":"traceutil/trace.go:171","msg":"trace[544639110] linearizableReadLoop","detail":"{readStateIndex:1081; appliedIndex:1080; }","duration":"103.708442ms","start":"2026-05-05T11:17:32.072192Z","end":"2026-05-05T11:17:32.1759Z","steps":["trace[544639110] 'read index received' (duration: 103.548295ms)","trace[544639110] 'applied index is now lower than readState.Index' (duration: 159.557µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:17:32.176024Z","caller":"traceutil/trace.go:171","msg":"trace[657130698] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"106.519733ms","start":"2026-05-05T11:17:32.06947Z","end":"2026-05-05T11:17:32.175989Z","steps":["trace[657130698] 'process raft request' (duration: 106.315354ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:17:32.176101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.905241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-05T11:17:32.176133Z","caller":"traceutil/trace.go:171","msg":"trace[1215565894] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1041; }","duration":"104.001875ms","start":"2026-05-05T11:17:32.072123Z","end":"2026-05-05T11:17:32.176125Z","steps":["trace[1215565894] 'agreement among raft nodes before linearized reading' (duration: 103.847448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:18:17.859171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.012741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T11:18:17.859304Z","caller":"traceutil/trace.go:171","msg":"trace[1646691732] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1188; }","duration":"187.205506ms","start":"2026-05-05T11:18:17.672063Z","end":"2026-05-05T11:18:17.859268Z","steps":["trace[1646691732] 'range keys from in-memory index tree' (duration: 186.92903ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:18:21.755363Z","caller":"traceutil/trace.go:171","msg":"trace[1962647490] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"134.969693ms","start":"2026-05-05T11:18:21.620346Z","end":"2026-05-05T11:18:21.755315Z","steps":["trace[1962647490] 'process raft request' (duration: 134.710857ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:18:27.285772Z","caller":"traceutil/trace.go:171","msg":"trace[2115690355] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"125.405629ms","start":"2026-05-05T11:18:27.16034Z","end":"2026-05-05T11:18:27.285746Z","steps":["trace[2115690355] 'process raft request' (duration: 125.260685ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:18:43.418468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.694198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-05T11:18:43.418555Z","caller":"traceutil/trace.go:171","msg":"trace[1596616789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1311; }","duration":"420.82464ms","start":"2026-05-05T11:18:42.997713Z","end":"2026-05-05T11:18:43.418538Z","steps":["trace[1596616789] 'range keys from in-memory index tree' (duration: 420.481754ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:18:43.418598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:18:42.997673Z","time spent":"420.916382ms","remote":"127.0.0.1:57800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}