{"level":"warn","ts":"2026-04-14T00:46:52.177679Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-14T00:46:52.17777Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.77:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.77:2380","--initial-cluster=instance=https://199.204.45.77:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.77:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.77: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-04-14T00:46:52.177835Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-14T00:46:52.177848Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-04-14T00:46:52.177868Z","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-04-14T00:46:52.178512Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"]} {"level":"info","ts":"2026-04-14T00:46:52.178578Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.77:2380"],"listen-peer-urls":["https://199.204.45.77:2380"],"advertise-client-urls":["https://199.204.45.77:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.77: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-04-14T00:46:52.185976Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.220809ms"} {"level":"info","ts":"2026-04-14T00:46:52.192703Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9133559b3198d4ec","cluster-id":"60f1aee33b0786f5"} {"level":"info","ts":"2026-04-14T00:46:52.192784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=()"} {"level":"info","ts":"2026-04-14T00:46:52.192803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became follower at term 0"} {"level":"info","ts":"2026-04-14T00:46:52.192818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9133559b3198d4ec [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-14T00:46:52.192829Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became follower at term 1"} {"level":"info","ts":"2026-04-14T00:46:52.192866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=(10462800484352185580)"} {"level":"warn","ts":"2026-04-14T00:46:52.201608Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-14T00:46:52.203342Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-14T00:46:52.205059Z","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-04-14T00:46:52.20852Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9133559b3198d4ec","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-14T00:46:52.20878Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9133559b3198d4ec","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-14T00:46:52.210008Z","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-04-14T00:46:52.210119Z","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-04-14T00:46:52.210192Z","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-04-14T00:46:52.210844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=(10462800484352185580)"} {"level":"info","ts":"2026-04-14T00:46:52.210985Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"60f1aee33b0786f5","local-member-id":"9133559b3198d4ec","added-peer-id":"9133559b3198d4ec","added-peer-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-04-14T00:46:52.214188Z","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-04-14T00:46:52.214412Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9133559b3198d4ec","initial-advertise-peer-urls":["https://199.204.45.77:2380"],"listen-peer-urls":["https://199.204.45.77:2380"],"advertise-client-urls":["https://199.204.45.77:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-14T00:46:52.214441Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-14T00:46:52.214782Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.77:2380"} {"level":"info","ts":"2026-04-14T00:46:52.21485Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.77:2380"} {"level":"info","ts":"2026-04-14T00:46:52.993579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec is starting a new election at term 1"} {"level":"info","ts":"2026-04-14T00:46:52.993654Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became pre-candidate at term 1"} {"level":"info","ts":"2026-04-14T00:46:52.993689Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec received MsgPreVoteResp from 9133559b3198d4ec at term 1"} {"level":"info","ts":"2026-04-14T00:46:52.993708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became candidate at term 2"} {"level":"info","ts":"2026-04-14T00:46:52.993719Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec received MsgVoteResp from 9133559b3198d4ec at term 2"} {"level":"info","ts":"2026-04-14T00:46:52.993734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became leader at term 2"} {"level":"info","ts":"2026-04-14T00:46:52.993751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9133559b3198d4ec elected leader 9133559b3198d4ec at term 2"} {"level":"info","ts":"2026-04-14T00:46:52.994856Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9133559b3198d4ec","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.77:2379]}","request-path":"/0/members/9133559b3198d4ec/attributes","cluster-id":"60f1aee33b0786f5","publish-timeout":"7s"} {"level":"info","ts":"2026-04-14T00:46:52.994914Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-14T00:46:52.994982Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-14T00:46:52.99544Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T00:46:52.995599Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-14T00:46:52.999614Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-14T00:46:53.00007Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"60f1aee33b0786f5","local-member-id":"9133559b3198d4ec","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T00:46:53.000687Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T00:46:53.000801Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T00:46:53.001466Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-14T00:46:53.00212Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.77:2379"} {"level":"info","ts":"2026-04-14T00:46:54.436917Z","caller":"traceutil/trace.go:171","msg":"trace[79026261] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"162.785544ms","start":"2026-04-14T00:46:54.27411Z","end":"2026-04-14T00:46:54.436896Z","steps":["trace[79026261] 'read index received' (duration: 162.663891ms)","trace[79026261] 'applied index is now lower than readState.Index' (duration: 120.513µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T00:46:54.437104Z","caller":"traceutil/trace.go:171","msg":"trace[1149602057] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"166.838315ms","start":"2026-04-14T00:46:54.270241Z","end":"2026-04-14T00:46:54.437079Z","steps":["trace[1149602057] 'process raft request' (duration: 166.479986ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:46:54.437489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.146583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-14T00:46:54.437548Z","caller":"traceutil/trace.go:171","msg":"trace[66180265] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2; }","duration":"163.43851ms","start":"2026-04-14T00:46:54.274097Z","end":"2026-04-14T00:46:54.437536Z","steps":["trace[66180265] 'agreement among raft nodes before linearized reading' (duration: 162.931877ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T00:46:54.780919Z","caller":"traceutil/trace.go:171","msg":"trace[1465560547] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"454.647505ms","start":"2026-04-14T00:46:54.32625Z","end":"2026-04-14T00:46:54.780898Z","steps":["trace[1465560547] 'process raft request' (duration: 454.531572ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T00:46:54.780907Z","caller":"traceutil/trace.go:171","msg":"trace[88148280] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"506.668887ms","start":"2026-04-14T00:46:54.274189Z","end":"2026-04-14T00:46:54.780858Z","steps":["trace[88148280] 'process raft request' (duration: 506.467702ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T00:46:54.781054Z","caller":"traceutil/trace.go:171","msg":"trace[2101454584] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:5; }","duration":"344.044368ms","start":"2026-04-14T00:46:54.436994Z","end":"2026-04-14T00:46:54.781039Z","steps":["trace[2101454584] 'read index received' (duration: 343.610057ms)","trace[2101454584] 'applied index is now lower than readState.Index' (duration: 433.631µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T00:46:54.781196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.431226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3170"} {"level":"info","ts":"2026-04-14T00:46:54.781243Z","caller":"traceutil/trace.go:171","msg":"trace[1910116922] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:11; }","duration":"505.497109ms","start":"2026-04-14T00:46:54.275736Z","end":"2026-04-14T00:46:54.781233Z","steps":["trace[1910116922] 'agreement among raft nodes before linearized reading' (duration: 505.351205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:46:54.781273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:46:54.275731Z","time spent":"505.534539ms","remote":"127.0.0.1:39466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3193,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-14T00:46:54.781295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:46:54.326229Z","time spent":"454.755337ms","remote":"127.0.0.1:39816","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-04-14T00:46:54.781489Z","caller":"traceutil/trace.go:171","msg":"trace[1089991161] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"415.637866ms","start":"2026-04-14T00:46:54.36584Z","end":"2026-04-14T00:46:54.781478Z","steps":["trace[1089991161] 'process raft request' (duration: 415.127673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:46:54.781554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:46:54.365822Z","time spent":"415.695577ms","remote":"127.0.0.1:39562","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-04-14T00:46:54.781596Z","caller":"traceutil/trace.go:171","msg":"trace[1492456703] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"455.309871ms","start":"2026-04-14T00:46:54.326273Z","end":"2026-04-14T00:46:54.781583Z","steps":["trace[1492456703] 'process raft request' (duration: 454.539152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:46:54.781654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:46:54.326242Z","time spent":"455.381743ms","remote":"127.0.0.1:39816","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-04-14T00:46:54.781657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.768297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-14T00:46:54.781698Z","caller":"traceutil/trace.go:171","msg":"trace[1802661994] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"363.85589ms","start":"2026-04-14T00:46:54.417831Z","end":"2026-04-14T00:46:54.781687Z","steps":["trace[1802661994] 'agreement among raft nodes before linearized reading' (duration: 363.803809ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T00:46:54.781717Z","caller":"traceutil/trace.go:171","msg":"trace[871678976] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"455.349712ms","start":"2026-04-14T00:46:54.326361Z","end":"2026-04-14T00:46:54.781711Z","steps":["trace[871678976] 'process raft request' (duration: 454.471861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:46:54.781739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:46:54.417807Z","time spent":"363.924371ms","remote":"127.0.0.1:39404","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-04-14T00:46:54.781752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:46:54.326337Z","time spent":"455.397453ms","remote":"127.0.0.1:39816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T00:46:54.781847Z","caller":"traceutil/trace.go:171","msg":"trace[60241230] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"455.315852ms","start":"2026-04-14T00:46:54.326525Z","end":"2026-04-14T00:46:54.781841Z","steps":["trace[60241230] 'process raft request' (duration: 454.329437ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:46:54.781881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:46:54.326516Z","time spent":"455.352002ms","remote":"127.0.0.1:39816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T00:46:54.781886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.005794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-14T00:46:54.781942Z","caller":"traceutil/trace.go:171","msg":"trace[637078556] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"364.058144ms","start":"2026-04-14T00:46:54.417845Z","end":"2026-04-14T00:46:54.781903Z","steps":["trace[637078556] 'agreement among raft nodes before linearized reading' (duration: 364.020923ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T00:46:54.781962Z","caller":"traceutil/trace.go:171","msg":"trace[2076301664] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"455.194699ms","start":"2026-04-14T00:46:54.326761Z","end":"2026-04-14T00:46:54.781956Z","steps":["trace[2076301664] 'process raft request' (duration: 454.114782ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:46:54.781976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:46:54.417826Z","time spent":"364.143877ms","remote":"127.0.0.1:39404","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-04-14T00:46:54.781992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:46:54.326733Z","time spent":"455.24483ms","remote":"127.0.0.1:39736","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-04-14T00:46:54.782067Z","caller":"traceutil/trace.go:171","msg":"trace[1683719262] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"433.278384ms","start":"2026-04-14T00:46:54.348765Z","end":"2026-04-14T00:46:54.782044Z","steps":["trace[1683719262] 'process raft request' (duration: 432.172526ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:46:54.782157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.66503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.77\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-14T00:46:54.78219Z","caller":"traceutil/trace.go:171","msg":"trace[1695708295] range","detail":"{range_begin:/registry/masterleases/199.204.45.77; range_end:; response_count:0; response_revision:11; }","duration":"457.738252ms","start":"2026-04-14T00:46:54.324443Z","end":"2026-04-14T00:46:54.782181Z","steps":["trace[1695708295] 'agreement among raft nodes before linearized reading' (duration: 457.700041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:46:54.782175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.340477ms","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":"info","ts":"2026-04-14T00:46:54.782212Z","caller":"traceutil/trace.go:171","msg":"trace[274257925] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"455.365153ms","start":"2026-04-14T00:46:54.32682Z","end":"2026-04-14T00:46:54.782185Z","steps":["trace[274257925] 'process raft request' (duration: 454.084811ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:46:54.782286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.438293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-14T00:46:54.782311Z","caller":"traceutil/trace.go:171","msg":"trace[1633600444] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"405.515264ms","start":"2026-04-14T00:46:54.376788Z","end":"2026-04-14T00:46:54.782303Z","steps":["trace[1633600444] 'agreement among raft nodes before linearized reading' (duration: 405.455263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:46:54.782337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:46:54.376768Z","time spent":"405.563486ms","remote":"127.0.0.1:39404","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-04-14T00:46:54.782327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:46:54.326804Z","time spent":"455.487546ms","remote":"127.0.0.1:39816","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-04-14T00:46:54.782332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.280914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-14T00:46:54.782406Z","caller":"traceutil/trace.go:171","msg":"trace[71141449] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:11; }","duration":"369.448308ms","start":"2026-04-14T00:46:54.412942Z","end":"2026-04-14T00:46:54.78239Z","steps":["trace[71141449] 'agreement among raft nodes before linearized reading' (duration: 369.309085ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:46:54.782445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:46:54.412926Z","time spent":"369.511949ms","remote":"127.0.0.1:39404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/default\" "} {"level":"info","ts":"2026-04-14T00:46:54.782302Z","caller":"traceutil/trace.go:171","msg":"trace[1086905183] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"456.514331ms","start":"2026-04-14T00:46:54.325758Z","end":"2026-04-14T00:46:54.782272Z","steps":["trace[1086905183] 'agreement among raft nodes before linearized reading' (duration: 456.209894ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:46:54.782568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:46:54.325746Z","time spent":"456.806078ms","remote":"127.0.0.1:39370","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-04-14T00:46:54.7826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.456319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-14T00:46:54.782624Z","caller":"traceutil/trace.go:171","msg":"trace[1633276208] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"456.525622ms","start":"2026-04-14T00:46:54.326092Z","end":"2026-04-14T00:46:54.782617Z","steps":["trace[1633276208] 'agreement among raft nodes before linearized reading' (duration: 456.484861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:46:54.782643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:46:54.326071Z","time spent":"456.567872ms","remote":"127.0.0.1:39404","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-04-14T00:46:54.782221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:46:54.32442Z","time spent":"457.795623ms","remote":"127.0.0.1:39320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.204.45.77\" "} {"level":"warn","ts":"2026-04-14T00:46:54.782699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:46:54.348746Z","time spent":"433.411688ms","remote":"127.0.0.1:39466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T00:46:54.782126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.879041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-14T00:46:54.782899Z","caller":"traceutil/trace.go:171","msg":"trace[394237941] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"457.65237ms","start":"2026-04-14T00:46:54.32524Z","end":"2026-04-14T00:46:54.782892Z","steps":["trace[394237941] 'agreement among raft nodes before linearized reading' (duration: 456.86021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:46:54.78321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:46:54.27418Z","time spent":"506.967164ms","remote":"127.0.0.1:39356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T00:46:54.782923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:46:54.325199Z","time spent":"457.717471ms","remote":"127.0.0.1:39404","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-04-14T00:46:55.130325Z","caller":"traceutil/trace.go:171","msg":"trace[1706200388] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"344.739384ms","start":"2026-04-14T00:46:54.78556Z","end":"2026-04-14T00:46:55.130299Z","steps":["trace[1706200388] 'process raft request' (duration: 342.986901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:46:55.13044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:46:54.785545Z","time spent":"344.842307ms","remote":"127.0.0.1:39736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T00:46:55.1336Z","caller":"traceutil/trace.go:171","msg":"trace[1131000926] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"346.710524ms","start":"2026-04-14T00:46:54.78686Z","end":"2026-04-14T00:46:55.13357Z","steps":["trace[1131000926] 'process raft request' (duration: 346.429917ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T00:46:55.133618Z","caller":"traceutil/trace.go:171","msg":"trace[217778348] transaction","detail":"{read_only:false; number_of_response:0; response_revision:14; }","duration":"345.365011ms","start":"2026-04-14T00:46:54.788235Z","end":"2026-04-14T00:46:55.1336Z","steps":["trace[217778348] 'process raft request' (duration: 345.226217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:46:55.133722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:46:54.78684Z","time spent":"346.821246ms","remote":"127.0.0.1:39686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":651,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T00:46:55.133863Z","caller":"traceutil/trace.go:171","msg":"trace[453579067] linearizableReadLoop","detail":"{readStateIndex:22; appliedIndex:14; }","duration":"343.508484ms","start":"2026-04-14T00:46:54.790338Z","end":"2026-04-14T00:46:55.133846Z","steps":["trace[453579067] 'read index received' (duration: 338.220122ms)","trace[453579067] 'applied index is now lower than readState.Index' (duration: 5.287342ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T00:46:55.134015Z","caller":"traceutil/trace.go:171","msg":"trace[1808225109] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"343.225737ms","start":"2026-04-14T00:46:54.790778Z","end":"2026-04-14T00:46:55.134003Z","steps":["trace[1808225109] 'process raft request' (duration: 342.872778ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:46:55.13409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:46:54.79051Z","time spent":"343.538745ms","remote":"127.0.0.1:39816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T00:46:55.134067Z","caller":"traceutil/trace.go:171","msg":"trace[218549268] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"345.76164ms","start":"2026-04-14T00:46:54.788264Z","end":"2026-04-14T00:46:55.134025Z","steps":["trace[218549268] 'process raft request' (duration: 345.162925ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:46:55.133934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:46:54.78821Z","time spent":"345.473333ms","remote":"127.0.0.1:39404","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T00:46:55.134232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:46:54.788253Z","time spent":"345.917734ms","remote":"127.0.0.1:39404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T00:46:55.134276Z","caller":"traceutil/trace.go:171","msg":"trace[2031595329] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"345.857392ms","start":"2026-04-14T00:46:54.788409Z","end":"2026-04-14T00:46:55.134266Z","steps":["trace[2031595329] 'process raft request' (duration: 345.072323ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:46:55.134333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:46:54.788395Z","time spent":"345.916254ms","remote":"127.0.0.1:39816","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-04-14T00:46:55.134449Z","caller":"traceutil/trace.go:171","msg":"trace[91386755] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"344.979961ms","start":"2026-04-14T00:46:54.789461Z","end":"2026-04-14T00:46:55.134441Z","steps":["trace[91386755] 'process raft request' (duration: 344.086468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:46:55.134502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:46:54.789452Z","time spent":"345.024191ms","remote":"127.0.0.1:39816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T00:46:55.134573Z","caller":"traceutil/trace.go:171","msg":"trace[577046050] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"345.013612ms","start":"2026-04-14T00:46:54.789552Z","end":"2026-04-14T00:46:55.134565Z","steps":["trace[577046050] 'process raft request' (duration: 344.028197ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:46:55.134616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:46:54.789547Z","time spent":"345.049632ms","remote":"127.0.0.1:39816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T00:46:55.134639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.289013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-lq5pr\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-04-14T00:46:55.13469Z","caller":"traceutil/trace.go:171","msg":"trace[1194228063] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-lq5pr; range_end:; response_count:1; response_revision:20; }","duration":"344.366165ms","start":"2026-04-14T00:46:54.790311Z","end":"2026-04-14T00:46:55.134678Z","steps":["trace[1194228063] 'agreement among raft nodes before linearized reading' (duration: 344.280953ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:46:55.135004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:46:54.7903Z","time spent":"344.687363ms","remote":"127.0.0.1:39562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":884,"request content":"key:\"/registry/certificatesigningrequests/csr-lq5pr\" "} {"level":"warn","ts":"2026-04-14T00:46:55.135029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.014425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-04-14T00:46:55.135114Z","caller":"traceutil/trace.go:171","msg":"trace[607803743] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"290.112748ms","start":"2026-04-14T00:46:54.844991Z","end":"2026-04-14T00:46:55.135103Z","steps":["trace[607803743] 'agreement among raft nodes before linearized reading' (duration: 289.946404ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T00:46:55.13471Z","caller":"traceutil/trace.go:171","msg":"trace[898778431] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"341.145345ms","start":"2026-04-14T00:46:54.793552Z","end":"2026-04-14T00:46:55.134698Z","steps":["trace[898778431] 'process raft request' (duration: 340.182471ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T00:46:55.134738Z","caller":"traceutil/trace.go:171","msg":"trace[854452379] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"345.144725ms","start":"2026-04-14T00:46:54.789585Z","end":"2026-04-14T00:46:55.134729Z","steps":["trace[854452379] 'process raft request' (duration: 344.024387ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:46:55.135299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:46:54.789581Z","time spent":"345.685028ms","remote":"127.0.0.1:39816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T00:46:55.13492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.479514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-14T00:46:55.135451Z","caller":"traceutil/trace.go:171","msg":"trace[1192852730] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:20; }","duration":"146.046778ms","start":"2026-04-14T00:46:54.989392Z","end":"2026-04-14T00:46:55.135439Z","steps":["trace[1192852730] 'agreement among raft nodes before linearized reading' (duration: 145.493825ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:46:55.134863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.012159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-14T00:46:55.135815Z","caller":"traceutil/trace.go:171","msg":"trace[844433839] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:20; }","duration":"296.004303ms","start":"2026-04-14T00:46:54.8398Z","end":"2026-04-14T00:46:55.135805Z","steps":["trace[844433839] 'agreement among raft nodes before linearized reading' (duration: 295.020869ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:46:55.136078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:46:54.793539Z","time spent":"341.694399ms","remote":"127.0.0.1:39466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3478,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T00:47:21.319262Z","caller":"traceutil/trace.go:171","msg":"trace[543887983] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"202.856987ms","start":"2026-04-14T00:47:21.11638Z","end":"2026-04-14T00:47:21.319237Z","steps":["trace[543887983] 'process raft request' (duration: 202.743864ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T00:47:21.319306Z","caller":"traceutil/trace.go:171","msg":"trace[747894525] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"217.043069ms","start":"2026-04-14T00:47:21.102234Z","end":"2026-04-14T00:47:21.319277Z","steps":["trace[747894525] 'process raft request' (duration: 216.504206ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T00:47:21.319509Z","caller":"traceutil/trace.go:171","msg":"trace[612495245] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"234.205923ms","start":"2026-04-14T00:47:21.085291Z","end":"2026-04-14T00:47:21.319496Z","steps":["trace[612495245] 'process raft request' (duration: 186.755198ms)","trace[612495245] 'compare' (duration: 46.352688ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T00:47:21.319475Z","caller":"traceutil/trace.go:171","msg":"trace[1864167533] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"217.188082ms","start":"2026-04-14T00:47:21.102276Z","end":"2026-04-14T00:47:21.319464Z","steps":["trace[1864167533] 'process raft request' (duration: 216.557227ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T00:47:21.319743Z","caller":"traceutil/trace.go:171","msg":"trace[1498266939] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"210.953201ms","start":"2026-04-14T00:47:21.108772Z","end":"2026-04-14T00:47:21.319726Z","steps":["trace[1498266939] 'process raft request' (duration: 210.234374ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T00:47:21.319746Z","caller":"traceutil/trace.go:171","msg":"trace[1286087149] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"219.807846ms","start":"2026-04-14T00:47:21.09988Z","end":"2026-04-14T00:47:21.319688Z","steps":["trace[1286087149] 'process raft request' (duration: 218.711819ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T00:47:21.319943Z","caller":"traceutil/trace.go:171","msg":"trace[848702596] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"216.70096ms","start":"2026-04-14T00:47:21.103225Z","end":"2026-04-14T00:47:21.319926Z","steps":["trace[848702596] 'process raft request' (duration: 215.648055ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T00:47:21.319979Z","caller":"traceutil/trace.go:171","msg":"trace[1275218924] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"233.563017ms","start":"2026-04-14T00:47:21.086406Z","end":"2026-04-14T00:47:21.319969Z","steps":["trace[1275218924] 'process raft request' (duration: 232.129803ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T00:47:21.319528Z","caller":"traceutil/trace.go:171","msg":"trace[1838739217] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"212.808367ms","start":"2026-04-14T00:47:21.106689Z","end":"2026-04-14T00:47:21.319497Z","steps":["trace[1838739217] 'process raft request' (duration: 212.233503ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T00:47:21.327532Z","caller":"traceutil/trace.go:171","msg":"trace[749793788] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"186.529413ms","start":"2026-04-14T00:47:21.140929Z","end":"2026-04-14T00:47:21.327458Z","steps":["trace[749793788] 'process raft request' (duration: 186.095243ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T00:47:21.327981Z","caller":"traceutil/trace.go:171","msg":"trace[1609399034] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"183.926869ms","start":"2026-04-14T00:47:21.14404Z","end":"2026-04-14T00:47:21.327967Z","steps":["trace[1609399034] 'process raft request' (duration: 183.098049ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T00:47:21.328192Z","caller":"traceutil/trace.go:171","msg":"trace[1394513914] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"171.778347ms","start":"2026-04-14T00:47:21.156404Z","end":"2026-04-14T00:47:21.328182Z","steps":["trace[1394513914] 'process raft request' (duration: 170.862744ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:47:27.393253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.901748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T00:47:27.393323Z","caller":"traceutil/trace.go:171","msg":"trace[1371275532] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:615; }","duration":"133.00291ms","start":"2026-04-14T00:47:27.260303Z","end":"2026-04-14T00:47:27.393306Z","steps":["trace[1371275532] 'range keys from in-memory index tree' (duration: 132.858007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:47:28.079838Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342811244236449031,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-14T00:47:28.116646Z","caller":"traceutil/trace.go:171","msg":"trace[1049791384] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"548.220133ms","start":"2026-04-14T00:47:27.568404Z","end":"2026-04-14T00:47:28.116624Z","steps":["trace[1049791384] 'process raft request' (duration: 548.1033ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:47:28.116789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:47:27.56839Z","time spent":"548.317996ms","remote":"127.0.0.1:39478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T00:47:28.311301Z","caller":"traceutil/trace.go:171","msg":"trace[136362706] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"528.723402ms","start":"2026-04-14T00:47:27.782551Z","end":"2026-04-14T00:47:28.311275Z","steps":["trace[136362706] 'process raft request' (duration: 528.446466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:47:28.311454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:47:27.782502Z","time spent":"528.875866ms","remote":"127.0.0.1:39572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T00:47:28.312903Z","caller":"traceutil/trace.go:171","msg":"trace[1412845679] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"513.556947ms","start":"2026-04-14T00:47:27.79931Z","end":"2026-04-14T00:47:28.312867Z","steps":["trace[1412845679] 'process raft request' (duration: 512.04096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:47:28.313077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:47:27.799279Z","time spent":"513.71463ms","remote":"127.0.0.1:39386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T00:47:28.313163Z","caller":"traceutil/trace.go:171","msg":"trace[867752866] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:637; }","duration":"733.542997ms","start":"2026-04-14T00:47:27.579583Z","end":"2026-04-14T00:47:28.313126Z","steps":["trace[867752866] 'read index received' (duration: 537.004613ms)","trace[867752866] 'applied index is now lower than readState.Index' (duration: 196.535804ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T00:47:28.313315Z","caller":"traceutil/trace.go:171","msg":"trace[1049464186] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"314.904821ms","start":"2026-04-14T00:47:27.998384Z","end":"2026-04-14T00:47:28.313289Z","steps":["trace[1049464186] 'process raft request' (duration: 314.398219ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:47:28.313424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:47:27.998365Z","time spent":"314.989523ms","remote":"127.0.0.1:39466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4538,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T00:47:28.313557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.972617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-14T00:47:28.313559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.250132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-14T00:47:28.313614Z","caller":"traceutil/trace.go:171","msg":"trace[1638818510] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:619; }","duration":"734.070799ms","start":"2026-04-14T00:47:27.579531Z","end":"2026-04-14T00:47:28.313602Z","steps":["trace[1638818510] 'agreement among raft nodes before linearized reading' (duration: 733.966967ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T00:47:28.313632Z","caller":"traceutil/trace.go:171","msg":"trace[324754436] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:619; }","duration":"463.378266ms","start":"2026-04-14T00:47:27.850236Z","end":"2026-04-14T00:47:28.313614Z","steps":["trace[324754436] 'agreement among raft nodes before linearized reading' (duration: 463.174381ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:47:28.313649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:47:27.57951Z","time spent":"734.13126ms","remote":"127.0.0.1:39572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-14T00:47:28.313671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:47:27.850223Z","time spent":"463.440287ms","remote":"127.0.0.1:39572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-14T00:47:28.973102Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342811244236449037,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-14T00:47:29.385062Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.008164464s","expected-duration":"1s"} {"level":"info","ts":"2026-04-14T00:47:29.425415Z","caller":"traceutil/trace.go:171","msg":"trace[1879287679] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"953.074374ms","start":"2026-04-14T00:47:28.472319Z","end":"2026-04-14T00:47:29.425393Z","steps":["trace[1879287679] 'read index received' (duration: 912.894064ms)","trace[1879287679] 'applied index is now lower than readState.Index' (duration: 40.17939ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T00:47:29.425531Z","caller":"traceutil/trace.go:171","msg":"trace[528150674] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"1.048715733s","start":"2026-04-14T00:47:28.376805Z","end":"2026-04-14T00:47:29.425521Z","steps":["trace[528150674] 'process raft request' (duration: 1.008498032s)","trace[528150674] 'compare' (duration: 39.75756ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T00:47:29.425608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"953.28252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T00:47:29.425666Z","caller":"traceutil/trace.go:171","msg":"trace[516914448] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"953.372851ms","start":"2026-04-14T00:47:28.472275Z","end":"2026-04-14T00:47:29.425648Z","steps":["trace[516914448] 'agreement among raft nodes before linearized reading' (duration: 953.258599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:47:29.425697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.201152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-14T00:47:29.425707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:47:28.472258Z","time spent":"953.441864ms","remote":"127.0.0.1:39282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-14T00:47:29.425729Z","caller":"traceutil/trace.go:171","msg":"trace[540522454] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:622; }","duration":"657.267074ms","start":"2026-04-14T00:47:28.768453Z","end":"2026-04-14T00:47:29.42572Z","steps":["trace[540522454] 'agreement among raft nodes before linearized reading' (duration: 657.197322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:47:29.425713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"895.900304ms","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-04-14T00:47:29.425758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:47:28.768434Z","time spent":"657.317396ms","remote":"127.0.0.1:39572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-14T00:47:29.425782Z","caller":"traceutil/trace.go:171","msg":"trace[447207030] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:622; }","duration":"896.021087ms","start":"2026-04-14T00:47:28.529744Z","end":"2026-04-14T00:47:29.425765Z","steps":["trace[447207030] 'agreement among raft nodes before linearized reading' (duration: 895.806712ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:47:29.425834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:47:28.529716Z","time spent":"896.10968ms","remote":"127.0.0.1:39572","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-04-14T00:47:29.425613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:47:28.376787Z","time spent":"1.048775044s","remote":"127.0.0.1:39540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T00:47:30.909022Z","caller":"traceutil/trace.go:171","msg":"trace[365857944] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"196.293578ms","start":"2026-04-14T00:47:30.71269Z","end":"2026-04-14T00:47:30.908983Z","steps":["trace[365857944] 'process raft request' (duration: 196.068733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:47:44.191357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.504003ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T00:47:44.191495Z","caller":"traceutil/trace.go:171","msg":"trace[575370410] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:776; }","duration":"476.663966ms","start":"2026-04-14T00:47:43.714813Z","end":"2026-04-14T00:47:44.191477Z","steps":["trace[575370410] 'range keys from in-memory index tree' (duration: 476.483711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:47:44.191886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.363699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-kvxhl\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-04-14T00:47:44.191978Z","caller":"traceutil/trace.go:171","msg":"trace[185896018] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-kvxhl; range_end:; response_count:1; response_revision:776; }","duration":"534.498882ms","start":"2026-04-14T00:47:43.657459Z","end":"2026-04-14T00:47:44.191957Z","steps":["trace[185896018] 'range keys from in-memory index tree' (duration: 534.210285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:47:44.192017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:47:43.657438Z","time spent":"534.569253ms","remote":"127.0.0.1:39478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-kvxhl\" "} {"level":"warn","ts":"2026-04-14T00:48:19.065213Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342811244236449869,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-14T00:48:19.320981Z","caller":"traceutil/trace.go:171","msg":"trace[2134298485] linearizableReadLoop","detail":"{readStateIndex:1031; appliedIndex:1030; }","duration":"756.229259ms","start":"2026-04-14T00:48:18.56472Z","end":"2026-04-14T00:48:19.32095Z","steps":["trace[2134298485] 'read index received' (duration: 756.031264ms)","trace[2134298485] 'applied index is now lower than readState.Index' (duration: 197.055µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T00:48:19.321337Z","caller":"traceutil/trace.go:171","msg":"trace[1667824181] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"757.750205ms","start":"2026-04-14T00:48:18.563562Z","end":"2026-04-14T00:48:19.321312Z","steps":["trace[1667824181] 'process raft request' (duration: 757.248213ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:48:19.321396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.917317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-14T00:48:19.321438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.69835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-14T00:48:19.321463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:48:18.563548Z","time spent":"757.850988ms","remote":"127.0.0.1:39572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T00:48:19.321467Z","caller":"traceutil/trace.go:171","msg":"trace[2068475231] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:995; }","duration":"352.02801ms","start":"2026-04-14T00:48:18.969422Z","end":"2026-04-14T00:48:19.32145Z","steps":["trace[2068475231] 'agreement among raft nodes before linearized reading' (duration: 351.824705ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T00:48:19.321476Z","caller":"traceutil/trace.go:171","msg":"trace[817589957] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:995; }","duration":"756.761061ms","start":"2026-04-14T00:48:18.564705Z","end":"2026-04-14T00:48:19.321466Z","steps":["trace[817589957] 'agreement among raft nodes before linearized reading' (duration: 756.603948ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:48:19.321508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:48:18.969406Z","time spent":"352.094311ms","remote":"127.0.0.1:39572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-14T00:48:19.321522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:48:18.564699Z","time spent":"756.800502ms","remote":"127.0.0.1:39572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-14T00:48:41.978889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.325118ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-14T00:48:41.979015Z","caller":"traceutil/trace.go:171","msg":"trace[1704949874] linearizableReadLoop","detail":"{readStateIndex:1108; appliedIndex:1107; }","duration":"535.935089ms","start":"2026-04-14T00:48:41.443061Z","end":"2026-04-14T00:48:41.978996Z","steps":["trace[1704949874] 'read index received' (duration: 17.405316ms)","trace[1704949874] 'applied index is now lower than readState.Index' (duration: 518.528163ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T00:48:41.9792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.130964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-14T00:48:41.979262Z","caller":"traceutil/trace.go:171","msg":"trace[1610679129] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1067; }","duration":"536.229797ms","start":"2026-04-14T00:48:41.443014Z","end":"2026-04-14T00:48:41.979244Z","steps":["trace[1610679129] 'agreement among raft nodes before linearized reading' (duration: 536.041322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:48:41.979254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.403778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-14T00:48:41.979292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:48:41.442995Z","time spent":"536.290828ms","remote":"127.0.0.1:39572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-14T00:48:41.97931Z","caller":"traceutil/trace.go:171","msg":"trace[148901121] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1067; }","duration":"515.49883ms","start":"2026-04-14T00:48:41.463797Z","end":"2026-04-14T00:48:41.979296Z","steps":["trace[148901121] 'agreement among raft nodes before linearized reading' (duration: 515.296925ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:48:41.979361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:48:41.46378Z","time spent":"515.570942ms","remote":"127.0.0.1:39572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-14T00:48:41.979443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.73687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-14T00:48:41.979504Z","caller":"traceutil/trace.go:171","msg":"trace[1959509443] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1067; }","duration":"488.825493ms","start":"2026-04-14T00:48:41.490666Z","end":"2026-04-14T00:48:41.979492Z","steps":["trace[1959509443] 'agreement among raft nodes before linearized reading' (duration: 488.72679ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:48:41.979524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:48:41.490626Z","time spent":"488.893134ms","remote":"127.0.0.1:39572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-14T00:48:41.979853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.716315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-14T00:48:41.980072Z","caller":"traceutil/trace.go:171","msg":"trace[922587229] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1067; }","duration":"201.966201ms","start":"2026-04-14T00:48:41.778082Z","end":"2026-04-14T00:48:41.980048Z","steps":["trace[922587229] 'agreement among raft nodes before linearized reading' (duration: 201.029638ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T00:48:44.240828Z","caller":"traceutil/trace.go:171","msg":"trace[1008896036] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"135.447939ms","start":"2026-04-14T00:48:44.105365Z","end":"2026-04-14T00:48:44.240813Z","steps":["trace[1008896036] 'process raft request' (duration: 135.357037ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T00:48:44.241195Z","caller":"traceutil/trace.go:171","msg":"trace[636229195] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"135.733486ms","start":"2026-04-14T00:48:44.105455Z","end":"2026-04-14T00:48:44.241188Z","steps":["trace[636229195] 'process raft request' (duration: 135.338777ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T00:48:44.241206Z","caller":"traceutil/trace.go:171","msg":"trace[1989994004] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"134.943367ms","start":"2026-04-14T00:48:44.106245Z","end":"2026-04-14T00:48:44.241188Z","steps":["trace[1989994004] 'process raft request' (duration: 134.751143ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T00:48:50.398369Z","caller":"traceutil/trace.go:171","msg":"trace[892954785] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"108.822283ms","start":"2026-04-14T00:48:50.289531Z","end":"2026-04-14T00:48:50.398354Z","steps":["trace[892954785] 'process raft request' (duration: 108.69317ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:49:01.591937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.216035ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-14T00:49:01.592265Z","caller":"traceutil/trace.go:171","msg":"trace[1723690972] linearizableReadLoop","detail":"{readStateIndex:1231; appliedIndex:1230; }","duration":"172.580228ms","start":"2026-04-14T00:49:01.419648Z","end":"2026-04-14T00:49:01.592228Z","steps":["trace[1723690972] 'read index received' (duration: 36.15µs)","trace[1723690972] 'applied index is now lower than readState.Index' (duration: 172.538808ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T00:49:01.592451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.779263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T00:49:01.59248Z","caller":"traceutil/trace.go:171","msg":"trace[721907469] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1185; }","duration":"172.858085ms","start":"2026-04-14T00:49:01.419613Z","end":"2026-04-14T00:49:01.592472Z","steps":["trace[721907469] 'agreement among raft nodes before linearized reading' (duration: 172.770773ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:49:01.592527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.507332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T00:49:01.592628Z","caller":"traceutil/trace.go:171","msg":"trace[2002746718] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1185; }","duration":"120.648596ms","start":"2026-04-14T00:49:01.471947Z","end":"2026-04-14T00:49:01.592595Z","steps":["trace[2002746718] 'agreement among raft nodes before linearized reading' (duration: 120.492992ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T00:49:21.072375Z","caller":"traceutil/trace.go:171","msg":"trace[901823599] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"158.248816ms","start":"2026-04-14T00:49:20.914096Z","end":"2026-04-14T00:49:21.072345Z","steps":["trace[901823599] 'process raft request' (duration: 122.079377ms)","trace[901823599] 'compare' (duration: 36.005935ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T00:49:21.608876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.77715ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-14T00:49:21.609139Z","caller":"traceutil/trace.go:171","msg":"trace[508765516] linearizableReadLoop","detail":"{readStateIndex:1343; appliedIndex:1342; }","duration":"274.125986ms","start":"2026-04-14T00:49:21.334996Z","end":"2026-04-14T00:49:21.609122Z","steps":["trace[508765516] 'read index received' (duration: 29.121511ms)","trace[508765516] 'applied index is now lower than readState.Index' (duration: 245.003045ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T00:49:21.609381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.515311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T00:49:21.609594Z","caller":"traceutil/trace.go:171","msg":"trace[591657388] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1292; }","duration":"136.769256ms","start":"2026-04-14T00:49:21.472806Z","end":"2026-04-14T00:49:21.609575Z","steps":["trace[591657388] 'agreement among raft nodes before linearized reading' (duration: 136.50812ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:49:21.609443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.415763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-14T00:49:21.609809Z","caller":"traceutil/trace.go:171","msg":"trace[1519851183] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1292; }","duration":"274.801522ms","start":"2026-04-14T00:49:21.334953Z","end":"2026-04-14T00:49:21.609754Z","steps":["trace[1519851183] 'agreement among raft nodes before linearized reading' (duration: 274.389132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:49:22.599209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.326526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-14T00:49:22.599226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.168937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-14T00:49:22.599255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.443159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-14T00:49:22.599285Z","caller":"traceutil/trace.go:171","msg":"trace[1568477641] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1294; }","duration":"112.419619ms","start":"2026-04-14T00:49:22.486837Z","end":"2026-04-14T00:49:22.599256Z","steps":["trace[1568477641] 'range keys from in-memory index tree' (duration: 112.186362ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T00:49:22.59932Z","caller":"traceutil/trace.go:171","msg":"trace[184381017] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1294; }","duration":"120.540311ms","start":"2026-04-14T00:49:22.478763Z","end":"2026-04-14T00:49:22.599304Z","steps":["trace[184381017] 'range keys from in-memory index tree' (duration: 120.305026ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T00:49:22.599319Z","caller":"traceutil/trace.go:171","msg":"trace[1736825724] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1294; }","duration":"167.299661ms","start":"2026-04-14T00:49:22.431997Z","end":"2026-04-14T00:49:22.599296Z","steps":["trace[1736825724] 'range keys from in-memory index tree' (duration: 167.047434ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:49:22.599322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.455579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-14T00:49:22.599386Z","caller":"traceutil/trace.go:171","msg":"trace[808338075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1294; }","duration":"120.555311ms","start":"2026-04-14T00:49:22.478812Z","end":"2026-04-14T00:49:22.599367Z","steps":["trace[808338075] 'range keys from in-memory index tree' (duration: 120.177403ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:49:22.851772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.090173ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T00:49:22.852081Z","caller":"traceutil/trace.go:171","msg":"trace[1432543423] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"247.73807ms","start":"2026-04-14T00:49:22.604325Z","end":"2026-04-14T00:49:22.852063Z","steps":["trace[1432543423] 'process raft request' (duration: 247.685569ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T00:49:22.85221Z","caller":"traceutil/trace.go:171","msg":"trace[2137711853] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"248.805776ms","start":"2026-04-14T00:49:22.603375Z","end":"2026-04-14T00:49:22.852181Z","steps":["trace[2137711853] 'process raft request' (duration: 114.216651ms)","trace[2137711853] 'compare' (duration: 133.818627ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T00:49:22.85229Z","caller":"traceutil/trace.go:171","msg":"trace[1160552726] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"248.455767ms","start":"2026-04-14T00:49:22.603827Z","end":"2026-04-14T00:49:22.852283Z","steps":["trace[1160552726] 'process raft request' (duration: 248.054548ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T00:49:22.852374Z","caller":"traceutil/trace.go:171","msg":"trace[1283021486] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"248.102779ms","start":"2026-04-14T00:49:22.604266Z","end":"2026-04-14T00:49:22.852369Z","steps":["trace[1283021486] 'process raft request' (duration: 247.71081ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T00:49:27.195995Z","caller":"traceutil/trace.go:171","msg":"trace[27478500] linearizableReadLoop","detail":"{readStateIndex:1381; appliedIndex:1380; }","duration":"333.245448ms","start":"2026-04-14T00:49:26.862734Z","end":"2026-04-14T00:49:27.19598Z","steps":["trace[27478500] 'read index received' (duration: 333.156066ms)","trace[27478500] 'applied index is now lower than readState.Index' (duration: 88.352µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T00:49:27.196206Z","caller":"traceutil/trace.go:171","msg":"trace[706904133] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"493.035161ms","start":"2026-04-14T00:49:26.703134Z","end":"2026-04-14T00:49:27.196169Z","steps":["trace[706904133] 'process raft request' (duration: 492.731493ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:49:27.196328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:49:26.703112Z","time spent":"493.154424ms","remote":"127.0.0.1:39572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T00:49:27.196346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.585926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-14T00:49:27.196423Z","caller":"traceutil/trace.go:171","msg":"trace[1380806154] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1329; }","duration":"333.711839ms","start":"2026-04-14T00:49:26.862694Z","end":"2026-04-14T00:49:27.196406Z","steps":["trace[1380806154] 'agreement among raft nodes before linearized reading' (duration: 333.478514ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:49:27.196469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:49:26.862677Z","time spent":"333.783561ms","remote":"127.0.0.1:39572","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-04-14T00:49:27.696935Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342811244236450628,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-14T00:49:27.743122Z","caller":"traceutil/trace.go:171","msg":"trace[584740287] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"831.848043ms","start":"2026-04-14T00:49:26.911243Z","end":"2026-04-14T00:49:27.743091Z","steps":["trace[584740287] 'process raft request' (duration: 822.824929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:49:27.743283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:49:26.911225Z","time spent":"831.970375ms","remote":"127.0.0.1:39572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T00:49:27.743726Z","caller":"traceutil/trace.go:171","msg":"trace[2076973119] linearizableReadLoop","detail":"{readStateIndex:1383; appliedIndex:1381; }","duration":"547.662449ms","start":"2026-04-14T00:49:27.196047Z","end":"2026-04-14T00:49:27.743709Z","steps":["trace[2076973119] 'read index received' (duration: 538.03812ms)","trace[2076973119] 'applied index is now lower than readState.Index' (duration: 9.623509ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T00:49:27.743976Z","caller":"traceutil/trace.go:171","msg":"trace[2103068092] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"543.720485ms","start":"2026-04-14T00:49:27.200237Z","end":"2026-04-14T00:49:27.743958Z","steps":["trace[2103068092] 'process raft request' (duration: 543.393428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:49:27.743991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.873225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-14T00:49:27.74406Z","caller":"traceutil/trace.go:171","msg":"trace[1157776868] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1331; }","duration":"582.958466ms","start":"2026-04-14T00:49:27.161081Z","end":"2026-04-14T00:49:27.744039Z","steps":["trace[1157776868] 'agreement among raft nodes before linearized reading' (duration: 582.651459ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:49:27.744068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:49:27.200222Z","time spent":"543.792687ms","remote":"127.0.0.1:39572","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-04-14T00:49:27.744101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:49:27.161074Z","time spent":"583.018298ms","remote":"127.0.0.1:39572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-04-14T00:49:27.744155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.555251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-04-14T00:49:27.744186Z","caller":"traceutil/trace.go:171","msg":"trace[1602121005] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1331; }","duration":"669.631034ms","start":"2026-04-14T00:49:27.074547Z","end":"2026-04-14T00:49:27.744178Z","steps":["trace[1602121005] 'agreement among raft nodes before linearized reading' (duration: 669.533361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:49:27.74419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"874.799972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-14T00:49:27.744248Z","caller":"traceutil/trace.go:171","msg":"trace[900153149] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1331; }","duration":"874.899054ms","start":"2026-04-14T00:49:26.869331Z","end":"2026-04-14T00:49:27.74423Z","steps":["trace[900153149] 'agreement among raft nodes before linearized reading' (duration: 874.761651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:49:27.744297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:49:26.869309Z","time spent":"874.976606ms","remote":"127.0.0.1:39572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-14T00:49:27.744209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:49:27.074532Z","time spent":"669.671874ms","remote":"127.0.0.1:39572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-14T00:49:27.744429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"879.551214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-14T00:49:27.7445Z","caller":"traceutil/trace.go:171","msg":"trace[733588533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1331; }","duration":"880.17533ms","start":"2026-04-14T00:49:26.864294Z","end":"2026-04-14T00:49:27.74447Z","steps":["trace[733588533] 'agreement among raft nodes before linearized reading' (duration: 879.477993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:49:27.744555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"873.272055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-14T00:49:27.744655Z","caller":"traceutil/trace.go:171","msg":"trace[481508118] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1331; }","duration":"873.354458ms","start":"2026-04-14T00:49:26.871247Z","end":"2026-04-14T00:49:27.744601Z","steps":["trace[481508118] 'agreement among raft nodes before linearized reading' (duration: 872.759374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:49:27.744552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:49:26.864284Z","time spent":"880.255491ms","remote":"127.0.0.1:39572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-14T00:49:27.744709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T00:49:26.871236Z","time spent":"873.45994ms","remote":"127.0.0.1:39572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-14T00:50:58.521108Z","caller":"traceutil/trace.go:171","msg":"trace[2122087614] linearizableReadLoop","detail":"{readStateIndex:1819; appliedIndex:1817; }","duration":"141.385659ms","start":"2026-04-14T00:50:58.379701Z","end":"2026-04-14T00:50:58.521086Z","steps":["trace[2122087614] 'read index received' (duration: 46.951622ms)","trace[2122087614] 'applied index is now lower than readState.Index' (duration: 94.433037ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T00:50:58.521302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.590683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-14T00:50:58.521388Z","caller":"traceutil/trace.go:171","msg":"trace[1285829111] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1749; }","duration":"141.724887ms","start":"2026-04-14T00:50:58.379649Z","end":"2026-04-14T00:50:58.521374Z","steps":["trace[1285829111] 'agreement among raft nodes before linearized reading' (duration: 141.541982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:50:58.521387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.398405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-14T00:50:58.52146Z","caller":"traceutil/trace.go:171","msg":"trace[217657104] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1749; }","duration":"140.506297ms","start":"2026-04-14T00:50:58.380935Z","end":"2026-04-14T00:50:58.521441Z","steps":["trace[217657104] 'agreement among raft nodes before linearized reading' (duration: 140.322373ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T00:50:58.521623Z","caller":"traceutil/trace.go:171","msg":"trace[638545117] transaction","detail":"{read_only:false; response_revision:1749; number_of_response:1; }","duration":"143.053328ms","start":"2026-04-14T00:50:58.378116Z","end":"2026-04-14T00:50:58.521169Z","steps":["trace[638545117] 'process raft request' (duration: 93.048904ms)","trace[638545117] 'compare' (duration: 49.84825ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T00:50:58.523507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.464332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-04-14T00:50:58.523629Z","caller":"traceutil/trace.go:171","msg":"trace[1407067310] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1749; }","duration":"138.424668ms","start":"2026-04-14T00:50:58.38518Z","end":"2026-04-14T00:50:58.523605Z","steps":["trace[1407067310] 'agreement among raft nodes before linearized reading' (duration: 136.253607ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T00:51:24.997957Z","caller":"traceutil/trace.go:171","msg":"trace[62260424] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"194.504028ms","start":"2026-04-14T00:51:24.80341Z","end":"2026-04-14T00:51:24.997914Z","steps":["trace[62260424] 'process raft request' (duration: 194.468627ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T00:51:24.998203Z","caller":"traceutil/trace.go:171","msg":"trace[2135515464] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"195.217254ms","start":"2026-04-14T00:51:24.802975Z","end":"2026-04-14T00:51:24.998192Z","steps":["trace[2135515464] 'process raft request' (duration: 103.14562ms)","trace[2135515464] 'compare' (duration: 91.660744ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T00:51:35.078845Z","caller":"traceutil/trace.go:171","msg":"trace[1443634302] transaction","detail":"{read_only:false; response_revision:1917; number_of_response:1; }","duration":"119.105492ms","start":"2026-04-14T00:51:34.959718Z","end":"2026-04-14T00:51:35.078823Z","steps":["trace[1443634302] 'process raft request' (duration: 118.861876ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T00:52:13.527093Z","caller":"traceutil/trace.go:171","msg":"trace[875451665] transaction","detail":"{read_only:false; response_revision:2093; number_of_response:1; }","duration":"186.452635ms","start":"2026-04-14T00:52:13.340618Z","end":"2026-04-14T00:52:13.52707Z","steps":["trace[875451665] 'process raft request' (duration: 133.565598ms)","trace[875451665] 'compare' (duration: 52.408925ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T00:52:24.812834Z","caller":"traceutil/trace.go:171","msg":"trace[786350347] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"197.260993ms","start":"2026-04-14T00:52:24.615535Z","end":"2026-04-14T00:52:24.812796Z","steps":["trace[786350347] 'process raft request' (duration: 165.666687ms)","trace[786350347] 'compare' (duration: 31.425622ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T00:52:24.812874Z","caller":"traceutil/trace.go:171","msg":"trace[844898643] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"167.665604ms","start":"2026-04-14T00:52:24.645184Z","end":"2026-04-14T00:52:24.81285Z","steps":["trace[844898643] 'process raft request' (duration: 167.594973ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:52:24.813285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.925189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/attachdetach-controller\" ","response":"range_response_count:1 size:212"} {"level":"info","ts":"2026-04-14T00:52:24.813372Z","caller":"traceutil/trace.go:171","msg":"trace[209686070] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/attachdetach-controller; range_end:; response_count:1; response_revision:2159; }","duration":"198.028251ms","start":"2026-04-14T00:52:24.615317Z","end":"2026-04-14T00:52:24.813346Z","steps":["trace[209686070] 'agreement among raft nodes before linearized reading' (duration: 197.860057ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T00:52:24.812941Z","caller":"traceutil/trace.go:171","msg":"trace[1414663525] linearizableReadLoop","detail":"{readStateIndex:2246; appliedIndex:2245; }","duration":"174.278223ms","start":"2026-04-14T00:52:24.638627Z","end":"2026-04-14T00:52:24.812905Z","steps":["trace[1414663525] 'read index received' (duration: 142.649615ms)","trace[1414663525] 'applied index is now lower than readState.Index' (duration: 31.625288ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T00:52:24.813955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.291776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1243"} {"level":"info","ts":"2026-04-14T00:52:24.814047Z","caller":"traceutil/trace.go:171","msg":"trace[132276902] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:2159; }","duration":"147.44239ms","start":"2026-04-14T00:52:24.666581Z","end":"2026-04-14T00:52:24.814024Z","steps":["trace[132276902] 'agreement among raft nodes before linearized reading' (duration: 147.163174ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T00:52:25.126284Z","caller":"traceutil/trace.go:171","msg":"trace[575678590] transaction","detail":"{read_only:false; response_revision:2161; number_of_response:1; }","duration":"275.022915ms","start":"2026-04-14T00:52:24.851237Z","end":"2026-04-14T00:52:25.12626Z","steps":["trace[575678590] 'process raft request' (duration: 214.332302ms)","trace[575678590] 'compare' (duration: 60.336314ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T00:52:25.126396Z","caller":"traceutil/trace.go:171","msg":"trace[790159766] linearizableReadLoop","detail":"{readStateIndex:2250; appliedIndex:2248; }","duration":"230.811117ms","start":"2026-04-14T00:52:24.895559Z","end":"2026-04-14T00:52:25.12637Z","steps":["trace[790159766] 'read index received' (duration: 170.144054ms)","trace[790159766] 'applied index is now lower than readState.Index' (duration: 60.665863ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T00:52:25.12662Z","caller":"traceutil/trace.go:171","msg":"trace[549401015] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"275.316862ms","start":"2026-04-14T00:52:24.851291Z","end":"2026-04-14T00:52:25.126608Z","steps":["trace[549401015] 'process raft request' (duration: 274.877582ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:52:25.126706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.138904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-04-14T00:52:25.126738Z","caller":"traceutil/trace.go:171","msg":"trace[238419937] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2162; }","duration":"231.210746ms","start":"2026-04-14T00:52:24.895517Z","end":"2026-04-14T00:52:25.126728Z","steps":["trace[238419937] 'agreement among raft nodes before linearized reading' (duration: 231.065793ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T00:52:25.137827Z","caller":"traceutil/trace.go:171","msg":"trace[504585258] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"185.152404ms","start":"2026-04-14T00:52:24.952656Z","end":"2026-04-14T00:52:25.137809Z","steps":["trace[504585258] 'process raft request' (duration: 184.97877ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T00:52:25.138003Z","caller":"traceutil/trace.go:171","msg":"trace[1158205630] transaction","detail":"{read_only:false; response_revision:2164; number_of_response:1; }","duration":"167.106252ms","start":"2026-04-14T00:52:24.97088Z","end":"2026-04-14T00:52:25.137986Z","steps":["trace[1158205630] 'process raft request' (duration: 166.885647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:52:25.138102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.189074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/default/default\" ","response":"range_response_count:1 size:171"} {"level":"info","ts":"2026-04-14T00:52:25.138258Z","caller":"traceutil/trace.go:171","msg":"trace[1911729989] range","detail":"{range_begin:/registry/serviceaccounts/default/default; range_end:; response_count:1; response_revision:2164; }","duration":"119.389229ms","start":"2026-04-14T00:52:25.018854Z","end":"2026-04-14T00:52:25.138243Z","steps":["trace[1911729989] 'agreement among raft nodes before linearized reading' (duration: 119.159114ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:52:25.138351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.469027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-14T00:52:25.138451Z","caller":"traceutil/trace.go:171","msg":"trace[1949875826] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2164; }","duration":"123.802285ms","start":"2026-04-14T00:52:25.014633Z","end":"2026-04-14T00:52:25.138436Z","steps":["trace[1949875826] 'agreement among raft nodes before linearized reading' (duration: 123.297333ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T00:52:28.341766Z","caller":"traceutil/trace.go:171","msg":"trace[1550256381] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"134.230355ms","start":"2026-04-14T00:52:28.2075Z","end":"2026-04-14T00:52:28.34173Z","steps":["trace[1550256381] 'process raft request' (duration: 134.010989ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T00:52:37.893447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.204294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-04-14T00:52:37.893517Z","caller":"traceutil/trace.go:171","msg":"trace[94259179] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2233; }","duration":"262.317857ms","start":"2026-04-14T00:52:37.631182Z","end":"2026-04-14T00:52:37.8935Z","steps":["trace[94259179] 'range keys from in-memory index tree' (duration: 262.041711ms)"],"step_count":1}