{"level":"warn","ts":"2026-05-14T13:13:12.494324Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-14T13:13:12.494441Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.140: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.140:2380","--initial-cluster=instance=https://199.204.45.140:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.140:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.140:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-14T13:13:12.494511Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-14T13:13:12.494521Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-05-14T13:13:12.494539Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-14T13:13:12.495225Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"]} {"level":"info","ts":"2026-05-14T13:13:12.495331Z","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.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.140:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-14T13:13:12.5024Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.700162ms"} {"level":"info","ts":"2026-05-14T13:13:12.508243Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"35b2766da46a76bb","cluster-id":"f5fd3aeb8d9f53dc"} {"level":"info","ts":"2026-05-14T13:13:12.508321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=()"} {"level":"info","ts":"2026-05-14T13:13:12.508346Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 0"} {"level":"info","ts":"2026-05-14T13:13:12.50836Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 35b2766da46a76bb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-14T13:13:12.508435Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 1"} {"level":"info","ts":"2026-05-14T13:13:12.508536Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"warn","ts":"2026-05-14T13:13:12.517385Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-14T13:13:12.519227Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-14T13:13:12.520453Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-14T13:13:12.523474Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"35b2766da46a76bb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-14T13:13:12.523895Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"35b2766da46a76bb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-14T13:13:12.523859Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-14T13:13:12.524426Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-14T13:13:12.524456Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-14T13:13:12.524674Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"info","ts":"2026-05-14T13:13:12.525083Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","added-peer-id":"35b2766da46a76bb","added-peer-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-05-14T13:13:12.5273Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-14T13:13:12.527343Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-05-14T13:13:12.527382Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-05-14T13:13:12.528144Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"35b2766da46a76bb","initial-advertise-peer-urls":["https://199.204.45.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-14T13:13:12.528192Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-14T13:13:12.609194Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb is starting a new election at term 1"} {"level":"info","ts":"2026-05-14T13:13:12.609245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became pre-candidate at term 1"} {"level":"info","ts":"2026-05-14T13:13:12.609272Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgPreVoteResp from 35b2766da46a76bb at term 1"} {"level":"info","ts":"2026-05-14T13:13:12.60929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became candidate at term 2"} {"level":"info","ts":"2026-05-14T13:13:12.609301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgVoteResp from 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-05-14T13:13:12.609317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became leader at term 2"} {"level":"info","ts":"2026-05-14T13:13:12.609329Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 35b2766da46a76bb elected leader 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-05-14T13:13:12.611434Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"35b2766da46a76bb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.140:2379]}","request-path":"/0/members/35b2766da46a76bb/attributes","cluster-id":"f5fd3aeb8d9f53dc","publish-timeout":"7s"} {"level":"info","ts":"2026-05-14T13:13:12.6115Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-14T13:13:12.611441Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T13:13:12.611601Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-14T13:13:12.611979Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-14T13:13:12.612012Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-14T13:13:12.613186Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T13:13:12.613421Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T13:13:12.61347Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T13:13:12.61557Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.140:2379"} {"level":"info","ts":"2026-05-14T13:13:12.615717Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-14T13:13:15.142485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"996.747138ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-05-14T13:13:15.142652Z","caller":"traceutil/trace.go:171","msg":"trace[1024870948] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"1.076804065s","start":"2026-05-14T13:13:14.065799Z","end":"2026-05-14T13:13:15.142603Z","steps":["trace[1024870948] 'process raft request' (duration: 79.395683ms)","trace[1024870948] 'compare' (duration: 996.621668ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:13:15.1428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:14.065779Z","time spent":"1.076952765s","remote":"127.0.0.1:47990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T13:13:15.142826Z","caller":"traceutil/trace.go:171","msg":"trace[2129027127] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"1.067516971s","start":"2026-05-14T13:13:14.075285Z","end":"2026-05-14T13:13:15.142802Z","steps":["trace[2129027127] 'process raft request' (duration: 1.067481721s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:15.142897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:14.075267Z","time spent":"1.067597952s","remote":"127.0.0.1:48076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T13:13:15.14305Z","caller":"traceutil/trace.go:171","msg":"trace[476372912] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"1.073959612s","start":"2026-05-14T13:13:14.069054Z","end":"2026-05-14T13:13:15.143014Z","steps":["trace[476372912] 'process raft request' (duration: 1.07359403s)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:13:15.143123Z","caller":"traceutil/trace.go:171","msg":"trace[134660157] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"1.074021852s","start":"2026-05-14T13:13:14.069096Z","end":"2026-05-14T13:13:15.143118Z","steps":["trace[134660157] 'process raft request' (duration: 1.07357797s)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:13:15.143145Z","caller":"traceutil/trace.go:171","msg":"trace[1611498200] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"1.073844382s","start":"2026-05-14T13:13:14.06929Z","end":"2026-05-14T13:13:15.143135Z","steps":["trace[1611498200] 'process raft request' (duration: 1.073450811s)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:13:15.143176Z","caller":"traceutil/trace.go:171","msg":"trace[1003293173] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"1.074009182s","start":"2026-05-14T13:13:14.069162Z","end":"2026-05-14T13:13:15.143171Z","steps":["trace[1003293173] 'process raft request' (duration: 1.07353862s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:15.143206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:14.069155Z","time spent":"1.074040102s","remote":"127.0.0.1:48432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T13:13:15.1432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:14.069281Z","time spent":"1.073892412s","remote":"127.0.0.1:48432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T13:13:15.143244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:14.069041Z","time spent":"1.074107902s","remote":"127.0.0.1:48432","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-05-14T13:13:15.143297Z","caller":"traceutil/trace.go:171","msg":"trace[762506746] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:5; }","duration":"1.074332894s","start":"2026-05-14T13:13:14.068926Z","end":"2026-05-14T13:13:15.143259Z","steps":["trace[762506746] 'read index received' (duration: 76.185739ms)","trace[762506746] 'applied index is now lower than readState.Index' (duration: 998.144595ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:13:15.143156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:14.069084Z","time spent":"1.074054432s","remote":"127.0.0.1:48432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T13:13:15.143098Z","caller":"traceutil/trace.go:171","msg":"trace[836468770] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"1.073911772s","start":"2026-05-14T13:13:14.069179Z","end":"2026-05-14T13:13:15.143091Z","steps":["trace[836468770] 'process raft request' (duration: 1.07354042s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:15.143589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.551892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-14T13:13:15.143633Z","caller":"traceutil/trace.go:171","msg":"trace[11778258] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:10; }","duration":"177.615552ms","start":"2026-05-14T13:13:14.966009Z","end":"2026-05-14T13:13:15.143624Z","steps":["trace[11778258] 'agreement among raft nodes before linearized reading' (duration: 177.566652ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:15.143638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"996.96073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-14T13:13:15.143672Z","caller":"traceutil/trace.go:171","msg":"trace[657098150] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"997.069601ms","start":"2026-05-14T13:13:14.146589Z","end":"2026-05-14T13:13:15.143659Z","steps":["trace[657098150] 'agreement among raft nodes before linearized reading' (duration: 997.032001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:15.143708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.064694659s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-14T13:13:15.143745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.856556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-14T13:13:15.143767Z","caller":"traceutil/trace.go:171","msg":"trace[1945796208] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10; }","duration":"137.912676ms","start":"2026-05-14T13:13:15.005848Z","end":"2026-05-14T13:13:15.143761Z","steps":["trace[1945796208] 'agreement among raft nodes before linearized reading' (duration: 137.882295ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:15.143767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.074072823s","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-05-14T13:13:15.143761Z","caller":"traceutil/trace.go:171","msg":"trace[328321520] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:10; }","duration":"1.06478485s","start":"2026-05-14T13:13:14.078962Z","end":"2026-05-14T13:13:15.143747Z","steps":["trace[328321520] 'agreement among raft nodes before linearized reading' (duration: 1.064700099s)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:13:15.143804Z","caller":"traceutil/trace.go:171","msg":"trace[1386585735] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"1.074117453s","start":"2026-05-14T13:13:14.069675Z","end":"2026-05-14T13:13:15.143792Z","steps":["trace[1386585735] 'agreement among raft nodes before linearized reading' (duration: 1.074060873s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:15.14384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:14.06967Z","time spent":"1.074162893s","remote":"127.0.0.1:48014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-05-14T13:13:15.143852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.752863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-14T13:13:15.143848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.022951743s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-14T13:13:15.143875Z","caller":"traceutil/trace.go:171","msg":"trace[350000214] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:10; }","duration":"177.812483ms","start":"2026-05-14T13:13:14.966054Z","end":"2026-05-14T13:13:15.143867Z","steps":["trace[350000214] 'agreement among raft nodes before linearized reading' (duration: 177.782973ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:13:15.14388Z","caller":"traceutil/trace.go:171","msg":"trace[791984513] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"1.023003513s","start":"2026-05-14T13:13:14.120868Z","end":"2026-05-14T13:13:15.143871Z","steps":["trace[791984513] 'agreement among raft nodes before linearized reading' (duration: 1.022958943s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:15.143915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:14.120856Z","time spent":"1.023053103s","remote":"127.0.0.1:48032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-14T13:13:15.143582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:14.069147Z","time spent":"1.074404815s","remote":"127.0.0.1:48432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T13:13:15.143712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:14.146569Z","time spent":"997.136151ms","remote":"127.0.0.1:48032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-14T13:13:15.143808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:14.078957Z","time spent":"1.06484389s","remote":"127.0.0.1:48272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-05-14T13:13:15.143526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.074595556s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-14T13:13:15.144145Z","caller":"traceutil/trace.go:171","msg":"trace[1755602018] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10; }","duration":"1.075256858s","start":"2026-05-14T13:13:14.068877Z","end":"2026-05-14T13:13:15.144134Z","steps":["trace[1755602018] 'agreement among raft nodes before linearized reading' (duration: 1.074582335s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:15.144184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:14.068863Z","time spent":"1.075311568s","remote":"127.0.0.1:47936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-14T13:13:15.143592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.074637376s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-14T13:13:15.144246Z","caller":"traceutil/trace.go:171","msg":"trace[1687067228] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"1.075311578s","start":"2026-05-14T13:13:14.068904Z","end":"2026-05-14T13:13:15.144215Z","steps":["trace[1687067228] 'agreement among raft nodes before linearized reading' (duration: 1.074627936s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:15.144295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:14.068891Z","time spent":"1.075393009s","remote":"127.0.0.1:48032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-05-14T13:13:15.143533Z","caller":"traceutil/trace.go:171","msg":"trace[1183805554] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"1.074086622s","start":"2026-05-14T13:13:14.06906Z","end":"2026-05-14T13:13:15.143147Z","steps":["trace[1183805554] 'process raft request' (duration: 1.07354153s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:15.144735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:14.069044Z","time spent":"1.075516199s","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T13:13:15.651737Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555605805828160642,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T13:13:16.420322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.765446ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-14T13:13:16.420427Z","caller":"traceutil/trace.go:171","msg":"trace[1415147751] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"1.271123454s","start":"2026-05-14T13:13:15.149286Z","end":"2026-05-14T13:13:16.42041Z","steps":["trace[1415147751] 'process raft request' (duration: 749.940953ms)","trace[1415147751] 'compare' (duration: 520.596561ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:13:16.420479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:15.149271Z","time spent":"1.271188495s","remote":"127.0.0.1:48032","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-05-14T13:13:16.425882Z","caller":"traceutil/trace.go:171","msg":"trace[2052358433] transaction","detail":"{read_only:false; number_of_response:0; response_revision:11; }","duration":"1.276563839s","start":"2026-05-14T13:13:15.149303Z","end":"2026-05-14T13:13:16.425867Z","steps":["trace[2052358433] 'process raft request' (duration: 1.276393024s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:16.425971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:15.149277Z","time spent":"1.276659402s","remote":"127.0.0.1:48032","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T13:13:16.426586Z","caller":"traceutil/trace.go:171","msg":"trace[1657322293] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"1.275594015s","start":"2026-05-14T13:13:15.150971Z","end":"2026-05-14T13:13:16.426565Z","steps":["trace[1657322293] 'process raft request' (duration: 1.27483053s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:16.426693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:15.150957Z","time spent":"1.275684668s","remote":"127.0.0.1:48354","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-05-14T13:13:16.427243Z","caller":"traceutil/trace.go:171","msg":"trace[1942844147] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"1.276194395s","start":"2026-05-14T13:13:15.151005Z","end":"2026-05-14T13:13:16.427199Z","steps":["trace[1942844147] 'process raft request' (duration: 1.275334048s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:16.427336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:15.15099Z","time spent":"1.276304719s","remote":"127.0.0.1:48432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T13:13:16.427256Z","caller":"traceutil/trace.go:171","msg":"trace[426788787] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"1.276130415s","start":"2026-05-14T13:13:15.151096Z","end":"2026-05-14T13:13:16.427226Z","steps":["trace[426788787] 'process raft request' (duration: 1.276034252s)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:13:16.42749Z","caller":"traceutil/trace.go:171","msg":"trace[757999627] linearizableReadLoop","detail":"{readStateIndex:23; appliedIndex:13; }","duration":"1.276025953s","start":"2026-05-14T13:13:15.151431Z","end":"2026-05-14T13:13:16.427457Z","steps":["trace[757999627] 'read index received' (duration: 747.809414ms)","trace[757999627] 'applied index is now lower than readState.Index' (duration: 528.215038ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:13:16.427649Z","caller":"traceutil/trace.go:171","msg":"trace[844256428] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"1.276478559s","start":"2026-05-14T13:13:15.151159Z","end":"2026-05-14T13:13:16.427637Z","steps":["trace[844256428] 'process raft request' (duration: 1.276031674s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:16.427565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:15.15109Z","time spent":"1.276408315s","remote":"127.0.0.1:48432","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-05-14T13:13:16.427835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:15.151147Z","time spent":"1.276582262s","remote":"127.0.0.1:48432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T13:13:16.427886Z","caller":"traceutil/trace.go:171","msg":"trace[291508850] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"1.274950368s","start":"2026-05-14T13:13:15.152907Z","end":"2026-05-14T13:13:16.427857Z","steps":["trace[291508850] 'process raft request' (duration: 1.274362519s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:16.428088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.276646772s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3491"} {"level":"warn","ts":"2026-05-14T13:13:16.428099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:15.152898Z","time spent":"1.275135634s","remote":"127.0.0.1:48432","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-05-14T13:13:16.428132Z","caller":"traceutil/trace.go:171","msg":"trace[699014293] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:19; }","duration":"1.276708974s","start":"2026-05-14T13:13:15.151411Z","end":"2026-05-14T13:13:16.42812Z","steps":["trace[699014293] 'agreement among raft nodes before linearized reading' (duration: 1.276626641s)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:13:16.428191Z","caller":"traceutil/trace.go:171","msg":"trace[191714848] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"1.277006886s","start":"2026-05-14T13:13:15.151164Z","end":"2026-05-14T13:13:16.428171Z","steps":["trace[191714848] 'process raft request' (duration: 1.276052515s)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:13:16.427933Z","caller":"traceutil/trace.go:171","msg":"trace[9043384] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"1.270559108s","start":"2026-05-14T13:13:15.157307Z","end":"2026-05-14T13:13:16.427867Z","steps":["trace[9043384] 'process raft request' (duration: 1.26999488s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:16.428295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:15.151153Z","time spent":"1.277094019s","remote":"127.0.0.1:48232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T13:13:16.428306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:15.157299Z","time spent":"1.27096358s","remote":"127.0.0.1:48076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3479,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T13:13:16.428394Z","caller":"traceutil/trace.go:171","msg":"trace[878879558] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"1.276427009s","start":"2026-05-14T13:13:15.151957Z","end":"2026-05-14T13:13:16.428384Z","steps":["trace[878879558] 'process raft request' (duration: 1.275291212s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:16.428457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:15.151944Z","time spent":"1.27648758s","remote":"127.0.0.1:48432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T13:13:16.428166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:15.151404Z","time spent":"1.276754815s","remote":"127.0.0.1:48076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3514,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-05-14T13:13:16.430111Z","caller":"traceutil/trace.go:171","msg":"trace[953538963] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"447.226411ms","start":"2026-05-14T13:13:15.982868Z","end":"2026-05-14T13:13:16.430094Z","steps":["trace[953538963] 'process raft request' (duration: 447.007373ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:16.430121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.278469976s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-14T13:13:16.430177Z","caller":"traceutil/trace.go:171","msg":"trace[2002146352] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:20; }","duration":"1.278589529s","start":"2026-05-14T13:13:15.151575Z","end":"2026-05-14T13:13:16.430165Z","steps":["trace[2002146352] 'agreement among raft nodes before linearized reading' (duration: 1.278383282s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:16.430215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:15.982843Z","time spent":"447.310183ms","remote":"127.0.0.1:48134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":845,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T13:13:16.430327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.078070373s","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-05-14T13:13:16.430445Z","caller":"traceutil/trace.go:171","msg":"trace[2016569053] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:20; }","duration":"1.078210506s","start":"2026-05-14T13:13:15.352166Z","end":"2026-05-14T13:13:16.430377Z","steps":["trace[2016569053] 'agreement among raft nodes before linearized reading' (duration: 1.078075512s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:16.430644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:15.151562Z","time spent":"1.279075224s","remote":"127.0.0.1:48206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/system:aggregate-to-admin\" "} {"level":"warn","ts":"2026-05-14T13:13:16.430678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:15.352142Z","time spent":"1.078524456s","remote":"127.0.0.1:48136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-05-14T13:13:16.430737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.503952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-14T13:13:16.430581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.179112714s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-14T13:13:16.430829Z","caller":"traceutil/trace.go:171","msg":"trace[1102801091] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20; }","duration":"1.179369012s","start":"2026-05-14T13:13:15.251445Z","end":"2026-05-14T13:13:16.430813Z","steps":["trace[1102801091] 'agreement among raft nodes before linearized reading' (duration: 1.179111844s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:16.43091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:15.251438Z","time spent":"1.179460165s","remote":"127.0.0.1:47936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-14T13:13:16.430582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.910061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-14T13:13:16.431042Z","caller":"traceutil/trace.go:171","msg":"trace[2034681508] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:20; }","duration":"679.369376ms","start":"2026-05-14T13:13:15.751655Z","end":"2026-05-14T13:13:16.431024Z","steps":["trace[2034681508] 'agreement among raft nodes before linearized reading' (duration: 678.87532ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:16.431276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:15.75159Z","time spent":"679.661473ms","remote":"127.0.0.1:48136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"info","ts":"2026-05-14T13:13:16.43143Z","caller":"traceutil/trace.go:171","msg":"trace[1564995551] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20; }","duration":"424.597124ms","start":"2026-05-14T13:13:16.006166Z","end":"2026-05-14T13:13:16.430763Z","steps":["trace[1564995551] 'agreement among raft nodes before linearized reading' (duration: 423.838859ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:16.431546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:16.006154Z","time spent":"425.35341ms","remote":"127.0.0.1:47940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-14T13:13:16.430605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.22494095s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-05-14T13:13:16.432021Z","caller":"traceutil/trace.go:171","msg":"trace[365029640] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"1.226379906s","start":"2026-05-14T13:13:15.205627Z","end":"2026-05-14T13:13:16.432007Z","steps":["trace[365029640] 'agreement among raft nodes before linearized reading' (duration: 1.224945509s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:16.432097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:15.205612Z","time spent":"1.226474098s","remote":"127.0.0.1:48032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-05-14T13:13:17.861103Z","caller":"traceutil/trace.go:171","msg":"trace[457718947] linearizableReadLoop","detail":"{readStateIndex:137; appliedIndex:136; }","duration":"122.658067ms","start":"2026-05-14T13:13:17.738424Z","end":"2026-05-14T13:13:17.861082Z","steps":["trace[457718947] 'read index received' (duration: 122.494871ms)","trace[457718947] 'applied index is now lower than readState.Index' (duration: 162.366µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:13:17.861241Z","caller":"traceutil/trace.go:171","msg":"trace[2025975007] transaction","detail":"{read_only:false; response_revision:133; number_of_response:1; }","duration":"123.152113ms","start":"2026-05-14T13:13:17.738047Z","end":"2026-05-14T13:13:17.861199Z","steps":["trace[2025975007] 'process raft request' (duration: 122.925905ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:17.861287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.812362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:statefulset-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:13:17.861438Z","caller":"traceutil/trace.go:171","msg":"trace[1045080261] range","detail":"{range_begin:/registry/clusterroles/system:controller:statefulset-controller; range_end:; response_count:0; response_revision:133; }","duration":"123.016339ms","start":"2026-05-14T13:13:17.738383Z","end":"2026-05-14T13:13:17.861399Z","steps":["trace[1045080261] 'agreement among raft nodes before linearized reading' (duration: 122.795472ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:13:18.145028Z","caller":"traceutil/trace.go:171","msg":"trace[2023705064] transaction","detail":"{read_only:false; response_revision:139; number_of_response:1; }","duration":"142.800325ms","start":"2026-05-14T13:13:18.002192Z","end":"2026-05-14T13:13:18.144992Z","steps":["trace[2023705064] 'process raft request' (duration: 124.915502ms)","trace[2023705064] 'compare' (duration: 17.719378ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:13:18.55716Z","caller":"traceutil/trace.go:171","msg":"trace[500072540] transaction","detail":"{read_only:false; response_revision:148; number_of_response:1; }","duration":"198.590025ms","start":"2026-05-14T13:13:18.358539Z","end":"2026-05-14T13:13:18.557129Z","steps":["trace[500072540] 'process raft request' (duration: 166.878545ms)","trace[500072540] 'compare' (duration: 31.564206ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:13:20.404925Z","caller":"traceutil/trace.go:171","msg":"trace[119169925] linearizableReadLoop","detail":"{readStateIndex:222; appliedIndex:221; }","duration":"121.670637ms","start":"2026-05-14T13:13:20.28323Z","end":"2026-05-14T13:13:20.404901Z","steps":["trace[119169925] 'read index received' (duration: 59.257046ms)","trace[119169925] 'applied index is now lower than readState.Index' (duration: 62.412451ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:13:20.404953Z","caller":"traceutil/trace.go:171","msg":"trace[1663591675] transaction","detail":"{read_only:false; response_revision:216; number_of_response:1; }","duration":"208.846292ms","start":"2026-05-14T13:13:20.196071Z","end":"2026-05-14T13:13:20.404918Z","steps":["trace[1663591675] 'process raft request' (duration: 146.488442ms)","trace[1663591675] 'compare' (duration: 62.194685ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:13:20.405068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.827502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-pq6te0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:13:20.405104Z","caller":"traceutil/trace.go:171","msg":"trace[1350748446] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-pq6te0; range_end:; response_count:0; response_revision:216; }","duration":"121.917385ms","start":"2026-05-14T13:13:20.283175Z","end":"2026-05-14T13:13:20.405093Z","steps":["trace[1350748446] 'agreement among raft nodes before linearized reading' (duration: 121.819762ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:13:22.848189Z","caller":"traceutil/trace.go:171","msg":"trace[1404562534] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"111.236004ms","start":"2026-05-14T13:13:22.736934Z","end":"2026-05-14T13:13:22.84817Z","steps":["trace[1404562534] 'process raft request' (duration: 110.855753ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:13:43.487685Z","caller":"traceutil/trace.go:171","msg":"trace[1551851975] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"112.327377ms","start":"2026-05-14T13:13:43.375328Z","end":"2026-05-14T13:13:43.487656Z","steps":["trace[1551851975] 'process raft request' (duration: 112.249735ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:13:43.487746Z","caller":"traceutil/trace.go:171","msg":"trace[1431290744] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"118.740689ms","start":"2026-05-14T13:13:43.368945Z","end":"2026-05-14T13:13:43.487685Z","steps":["trace[1431290744] 'process raft request' (duration: 49.462881ms)","trace[1431290744] 'compare' (duration: 69.000981ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:13:43.489154Z","caller":"traceutil/trace.go:171","msg":"trace[1758983538] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"100.281462ms","start":"2026-05-14T13:13:43.388844Z","end":"2026-05-14T13:13:43.489126Z","steps":["trace[1758983538] 'process raft request' (duration: 99.413842ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:13:45.084374Z","caller":"traceutil/trace.go:171","msg":"trace[206620943] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"101.039926ms","start":"2026-05-14T13:13:44.983311Z","end":"2026-05-14T13:13:45.084351Z","steps":["trace[206620943] 'process raft request' (duration: 100.908263ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:13:45.098645Z","caller":"traceutil/trace.go:171","msg":"trace[291740830] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"107.358761ms","start":"2026-05-14T13:13:44.99126Z","end":"2026-05-14T13:13:45.098619Z","steps":["trace[291740830] 'process raft request' (duration: 107.036244ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:13:45.098698Z","caller":"traceutil/trace.go:171","msg":"trace[644356464] linearizableReadLoop","detail":"{readStateIndex:537; appliedIndex:531; }","duration":"104.879132ms","start":"2026-05-14T13:13:44.993801Z","end":"2026-05-14T13:13:45.09868Z","steps":["trace[644356464] 'read index received' (duration: 90.52401ms)","trace[644356464] 'applied index is now lower than readState.Index' (duration: 14.354512ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:13:45.098874Z","caller":"traceutil/trace.go:171","msg":"trace[2007904070] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"115.404258ms","start":"2026-05-14T13:13:44.983446Z","end":"2026-05-14T13:13:45.098851Z","steps":["trace[2007904070] 'process raft request' (duration: 114.457226ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:13:45.099017Z","caller":"traceutil/trace.go:171","msg":"trace[185194684] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"111.674772ms","start":"2026-05-14T13:13:44.987333Z","end":"2026-05-14T13:13:45.099007Z","steps":["trace[185194684] 'process raft request' (duration: 110.904594ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:13:45.0992Z","caller":"traceutil/trace.go:171","msg":"trace[1799151421] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"103.705075ms","start":"2026-05-14T13:13:44.995483Z","end":"2026-05-14T13:13:45.099188Z","steps":["trace[1799151421] 'process raft request' (duration: 102.914507ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:13:45.099267Z","caller":"traceutil/trace.go:171","msg":"trace[1129906557] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"109.576221ms","start":"2026-05-14T13:13:44.98968Z","end":"2026-05-14T13:13:45.099256Z","steps":["trace[1129906557] 'process raft request' (duration: 108.595329ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:13:45.099367Z","caller":"traceutil/trace.go:171","msg":"trace[188811935] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"105.702721ms","start":"2026-05-14T13:13:44.993186Z","end":"2026-05-14T13:13:45.098888Z","steps":["trace[188811935] 'process raft request' (duration: 105.138688ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:45.099587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.769253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-14T13:13:45.099607Z","caller":"traceutil/trace.go:171","msg":"trace[188821393] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:522; }","duration":"105.819154ms","start":"2026-05-14T13:13:44.993781Z","end":"2026-05-14T13:13:45.0996Z","steps":["trace[188821393] 'agreement among raft nodes before linearized reading' (duration: 105.742252ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:45.105922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.544489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-14T13:13:45.105984Z","caller":"traceutil/trace.go:171","msg":"trace[1989875006] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:524; }","duration":"106.648852ms","start":"2026-05-14T13:13:44.999321Z","end":"2026-05-14T13:13:45.10597Z","steps":["trace[1989875006] 'agreement among raft nodes before linearized reading' (duration: 106.527839ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:46.12763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.059259ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:13:46.127752Z","caller":"traceutil/trace.go:171","msg":"trace[919899349] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:544; }","duration":"364.191282ms","start":"2026-05-14T13:13:45.763543Z","end":"2026-05-14T13:13:46.127734Z","steps":["trace[919899349] 'range keys from in-memory index tree' (duration: 364.045659ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:46.128439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.727579ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T13:13:46.128597Z","caller":"traceutil/trace.go:171","msg":"trace[835429835] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"887.659764ms","start":"2026-05-14T13:13:45.240906Z","end":"2026-05-14T13:13:46.128566Z","steps":["trace[835429835] 'process raft request' (duration: 441.671678ms)","trace[835429835] 'compare' (duration: 444.922422ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:13:46.128676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:45.240884Z","time spent":"887.759227ms","remote":"127.0.0.1:47980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29452,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T13:13:46.207677Z","caller":"traceutil/trace.go:171","msg":"trace[651580243] linearizableReadLoop","detail":"{readStateIndex:575; appliedIndex:572; }","duration":"947.755997ms","start":"2026-05-14T13:13:45.259895Z","end":"2026-05-14T13:13:46.207651Z","steps":["trace[651580243] 'read index received' (duration: 422.7034ms)","trace[651580243] 'applied index is now lower than readState.Index' (duration: 525.051777ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:13:46.207714Z","caller":"traceutil/trace.go:171","msg":"trace[1988290289] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"963.131363ms","start":"2026-05-14T13:13:45.24455Z","end":"2026-05-14T13:13:46.207681Z","steps":["trace[1988290289] 'process raft request' (duration: 962.738194ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:13:46.207777Z","caller":"traceutil/trace.go:171","msg":"trace[353551130] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"959.474048ms","start":"2026-05-14T13:13:45.248266Z","end":"2026-05-14T13:13:46.20774Z","steps":["trace[353551130] 'process raft request' (duration: 959.311424ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:46.207881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"947.961391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-14T13:13:46.207926Z","caller":"traceutil/trace.go:171","msg":"trace[632362428] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:547; }","duration":"948.052134ms","start":"2026-05-14T13:13:45.259862Z","end":"2026-05-14T13:13:46.207915Z","steps":["trace[632362428] 'agreement among raft nodes before linearized reading' (duration: 947.90172ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:46.207961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:45.259851Z","time spent":"948.101935ms","remote":"127.0.0.1:48136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-14T13:13:46.20795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:45.244541Z","time spent":"963.255206ms","remote":"127.0.0.1:47980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23066,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T13:13:46.208062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:45.248254Z","time spent":"959.555189ms","remote":"127.0.0.1:47980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T13:13:46.415002Z","caller":"traceutil/trace.go:171","msg":"trace[1104341861] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"265.089503ms","start":"2026-05-14T13:13:46.149865Z","end":"2026-05-14T13:13:46.414955Z","steps":["trace[1104341861] 'process raft request' (duration: 248.614175ms)","trace[1104341861] 'compare' (duration: 16.312784ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:13:46.422303Z","caller":"traceutil/trace.go:171","msg":"trace[1227423660] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"210.185206ms","start":"2026-05-14T13:13:46.212083Z","end":"2026-05-14T13:13:46.422268Z","steps":["trace[1227423660] 'process raft request' (duration: 209.94738ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:13:46.422408Z","caller":"traceutil/trace.go:171","msg":"trace[1490658406] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"200.068114ms","start":"2026-05-14T13:13:46.222303Z","end":"2026-05-14T13:13:46.422371Z","steps":["trace[1490658406] 'process raft request' (duration: 199.828619ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:13:46.42254Z","caller":"traceutil/trace.go:171","msg":"trace[2127842008] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"156.267479ms","start":"2026-05-14T13:13:46.266253Z","end":"2026-05-14T13:13:46.42252Z","steps":["trace[2127842008] 'process raft request' (duration: 155.941042ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:13:46.423759Z","caller":"traceutil/trace.go:171","msg":"trace[163978393] linearizableReadLoop","detail":"{readStateIndex:578; appliedIndex:575; }","duration":"158.128953ms","start":"2026-05-14T13:13:46.265613Z","end":"2026-05-14T13:13:46.423742Z","steps":["trace[163978393] 'read index received' (duration: 132.888125ms)","trace[163978393] 'applied index is now lower than readState.Index' (duration: 25.240028ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:13:46.424221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.587384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-14T13:13:46.424273Z","caller":"traceutil/trace.go:171","msg":"trace[1130820417] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:552; }","duration":"158.686937ms","start":"2026-05-14T13:13:46.26557Z","end":"2026-05-14T13:13:46.424257Z","steps":["trace[1130820417] 'agreement among raft nodes before linearized reading' (duration: 158.235986ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:13:46.424498Z","caller":"traceutil/trace.go:171","msg":"trace[102053317] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"155.482282ms","start":"2026-05-14T13:13:46.269004Z","end":"2026-05-14T13:13:46.424486Z","steps":["trace[102053317] 'process raft request' (duration: 153.473855ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:13:47.285933Z","caller":"traceutil/trace.go:171","msg":"trace[289184988] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"162.276438ms","start":"2026-05-14T13:13:47.123623Z","end":"2026-05-14T13:13:47.2859Z","steps":["trace[289184988] 'read index received' (duration: 161.798927ms)","trace[289184988] 'applied index is now lower than readState.Index' (duration: 476.601µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:13:47.286511Z","caller":"traceutil/trace.go:171","msg":"trace[1430372762] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"236.376886ms","start":"2026-05-14T13:13:47.050094Z","end":"2026-05-14T13:13:47.286471Z","steps":["trace[1430372762] 'process raft request' (duration: 235.480127ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:47.286843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.201599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-14T13:13:47.286892Z","caller":"traceutil/trace.go:171","msg":"trace[1815244829] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:583; }","duration":"163.329772ms","start":"2026-05-14T13:13:47.123548Z","end":"2026-05-14T13:13:47.286878Z","steps":["trace[1815244829] 'agreement among raft nodes before linearized reading' (duration: 163.168289ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:47.287267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.72805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-14T13:13:47.287311Z","caller":"traceutil/trace.go:171","msg":"trace[12337406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:583; }","duration":"148.811192ms","start":"2026-05-14T13:13:47.138489Z","end":"2026-05-14T13:13:47.2873Z","steps":["trace[12337406] 'agreement among raft nodes before linearized reading' (duration: 148.671649ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:13:52.480545Z","caller":"traceutil/trace.go:171","msg":"trace[227207409] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"993.979641ms","start":"2026-05-14T13:13:51.486536Z","end":"2026-05-14T13:13:52.480516Z","steps":["trace[227207409] 'process raft request' (duration: 993.298817ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:52.480728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:51.486503Z","time spent":"994.125294ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036225,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T13:13:52.799065Z","caller":"traceutil/trace.go:171","msg":"trace[2043283988] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"186.243055ms","start":"2026-05-14T13:13:52.612794Z","end":"2026-05-14T13:13:52.799037Z","steps":["trace[2043283988] 'process raft request' (duration: 164.697211ms)","trace[2043283988] 'compare' (duration: 21.397801ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:13:53.718564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.829078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-14T13:13:53.718658Z","caller":"traceutil/trace.go:171","msg":"trace[1019215755] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:622; }","duration":"391.979012ms","start":"2026-05-14T13:13:53.326658Z","end":"2026-05-14T13:13:53.718637Z","steps":["trace[1019215755] 'range keys from in-memory index tree' (duration: 391.559872ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:53.718701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:53.326635Z","time spent":"392.056253ms","remote":"127.0.0.1:48136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-14T13:13:53.718893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.002522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-14T13:13:53.719006Z","caller":"traceutil/trace.go:171","msg":"trace[108370880] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:622; }","duration":"392.213466ms","start":"2026-05-14T13:13:53.326773Z","end":"2026-05-14T13:13:53.718986Z","steps":["trace[108370880] 'range keys from in-memory index tree' (duration: 391.848198ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:53.719081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:53.326762Z","time spent":"392.301829ms","remote":"127.0.0.1:48136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-14T13:13:53.901495Z","caller":"traceutil/trace.go:171","msg":"trace[2112876616] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:651; }","duration":"197.716617ms","start":"2026-05-14T13:13:53.703752Z","end":"2026-05-14T13:13:53.901468Z","steps":["trace[2112876616] 'read index received' (duration: 196.986532ms)","trace[2112876616] 'applied index is now lower than readState.Index' (duration: 728.925µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:13:53.901589Z","caller":"traceutil/trace.go:171","msg":"trace[347423303] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"410.30637ms","start":"2026-05-14T13:13:53.49125Z","end":"2026-05-14T13:13:53.901557Z","steps":["trace[347423303] 'process raft request' (duration: 409.571445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:53.901753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:53.491223Z","time spent":"410.432243ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T13:13:53.901764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.014033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gtpcx\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-14T13:13:53.90181Z","caller":"traceutil/trace.go:171","msg":"trace[2016857295] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gtpcx; range_end:; response_count:1; response_revision:623; }","duration":"198.112315ms","start":"2026-05-14T13:13:53.703683Z","end":"2026-05-14T13:13:53.901795Z","steps":["trace[2016857295] 'agreement among raft nodes before linearized reading' (duration: 197.939272ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:13:53.950629Z","caller":"traceutil/trace.go:171","msg":"trace[1933813174] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"227.014ms","start":"2026-05-14T13:13:53.723551Z","end":"2026-05-14T13:13:53.950565Z","steps":["trace[1933813174] 'process raft request' (duration: 197.661266ms)","trace[1933813174] 'compare' (duration: 29.109839ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:13:53.95079Z","caller":"traceutil/trace.go:171","msg":"trace[781751637] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"227.101522ms","start":"2026-05-14T13:13:53.723667Z","end":"2026-05-14T13:13:53.950769Z","steps":["trace[781751637] 'process raft request' (duration: 226.824617ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:55.910147Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.24605346s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-14T13:13:55.91316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.320443ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:13:55.913344Z","caller":"traceutil/trace.go:171","msg":"trace[891277331] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:625; }","duration":"149.518157ms","start":"2026-05-14T13:13:55.763798Z","end":"2026-05-14T13:13:55.913316Z","steps":["trace[891277331] 'range keys from in-memory index tree' (duration: 149.289032ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:55.914478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.250314809s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T13:13:55.91497Z","caller":"traceutil/trace.go:171","msg":"trace[364245570] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"1.957946584s","start":"2026-05-14T13:13:53.956978Z","end":"2026-05-14T13:13:55.914925Z","steps":["trace[364245570] 'process raft request' (duration: 1.957606257s)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:13:55.914962Z","caller":"traceutil/trace.go:171","msg":"trace[589319514] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"1.999607161s","start":"2026-05-14T13:13:53.915292Z","end":"2026-05-14T13:13:55.914899Z","steps":["trace[589319514] 'process raft request' (duration: 748.768751ms)","trace[589319514] 'compare' (duration: 1.249008222s)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:13:55.91507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:53.956955Z","time spent":"1.958062836s","remote":"127.0.0.1:48416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T13:13:55.915227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:53.91527Z","time spent":"1.999870717s","remote":"127.0.0.1:48092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T13:13:55.9249Z","caller":"traceutil/trace.go:171","msg":"trace[977965127] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:654; }","duration":"1.421723147s","start":"2026-05-14T13:13:54.503137Z","end":"2026-05-14T13:13:55.92486Z","steps":["trace[977965127] 'read index received' (duration: 160.93683ms)","trace[977965127] 'applied index is now lower than readState.Index' (duration: 1.260785387s)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:13:55.925225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.422078944s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-14T13:13:55.92526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.411322748s","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-05-14T13:13:55.925294Z","caller":"traceutil/trace.go:171","msg":"trace[507795272] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:627; }","duration":"1.411375669s","start":"2026-05-14T13:13:54.513909Z","end":"2026-05-14T13:13:55.925285Z","steps":["trace[507795272] 'agreement among raft nodes before linearized reading' (duration: 1.411185315s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:55.925321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:54.513903Z","time spent":"1.411412441s","remote":"127.0.0.1:48136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-14T13:13:55.925359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.924276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:13:55.925285Z","caller":"traceutil/trace.go:171","msg":"trace[1800805148] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:627; }","duration":"1.422192017s","start":"2026-05-14T13:13:54.50308Z","end":"2026-05-14T13:13:55.925272Z","steps":["trace[1800805148] 'agreement among raft nodes before linearized reading' (duration: 1.421989183s)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:13:55.925405Z","caller":"traceutil/trace.go:171","msg":"trace[343059112] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:627; }","duration":"243.997697ms","start":"2026-05-14T13:13:55.681393Z","end":"2026-05-14T13:13:55.92539Z","steps":["trace[343059112] 'agreement among raft nodes before linearized reading' (duration: 243.924315ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:55.925421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:54.50306Z","time spent":"1.42235253s","remote":"127.0.0.1:48136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-14T13:13:55.92549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.581159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T13:13:55.925492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.254046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:13:55.92552Z","caller":"traceutil/trace.go:171","msg":"trace[1344713986] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:627; }","duration":"244.63713ms","start":"2026-05-14T13:13:55.680874Z","end":"2026-05-14T13:13:55.925511Z","steps":["trace[1344713986] 'agreement among raft nodes before linearized reading' (duration: 244.59307ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:13:55.925548Z","caller":"traceutil/trace.go:171","msg":"trace[1955458650] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:627; }","duration":"142.336438ms","start":"2026-05-14T13:13:55.783198Z","end":"2026-05-14T13:13:55.925534Z","steps":["trace[1955458650] 'agreement among raft nodes before linearized reading' (duration: 142.252506ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:55.92556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.021821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:13:55.925583Z","caller":"traceutil/trace.go:171","msg":"trace[650451348] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:627; }","duration":"143.059952ms","start":"2026-05-14T13:13:55.782516Z","end":"2026-05-14T13:13:55.925576Z","steps":["trace[650451348] 'agreement among raft nodes before linearized reading' (duration: 143.022521ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:55.925625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.782133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:13:55.92565Z","caller":"traceutil/trace.go:171","msg":"trace[813476489] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:627; }","duration":"244.837484ms","start":"2026-05-14T13:13:55.680806Z","end":"2026-05-14T13:13:55.925643Z","steps":["trace[813476489] 'agreement among raft nodes before linearized reading' (duration: 244.799503ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:55.925652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.934174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T13:13:55.92566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.653899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:13:55.925678Z","caller":"traceutil/trace.go:171","msg":"trace[1754064744] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:627; }","duration":"124.986817ms","start":"2026-05-14T13:13:55.800685Z","end":"2026-05-14T13:13:55.925672Z","steps":["trace[1754064744] 'agreement among raft nodes before linearized reading' (duration: 124.947956ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:55.925687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.183326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:13:55.925707Z","caller":"traceutil/trace.go:171","msg":"trace[222618554] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:627; }","duration":"143.230446ms","start":"2026-05-14T13:13:55.782471Z","end":"2026-05-14T13:13:55.925702Z","steps":["trace[222618554] 'agreement among raft nodes before linearized reading' (duration: 143.198096ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:13:55.925697Z","caller":"traceutil/trace.go:171","msg":"trace[270432183] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:627; }","duration":"124.71168ms","start":"2026-05-14T13:13:55.800972Z","end":"2026-05-14T13:13:55.925684Z","steps":["trace[270432183] 'agreement among raft nodes before linearized reading' (duration: 124.627178ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:55.925638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.493036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T13:13:55.925744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.03887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:13:55.925765Z","caller":"traceutil/trace.go:171","msg":"trace[1701516871] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:627; }","duration":"270.08898ms","start":"2026-05-14T13:13:55.655669Z","end":"2026-05-14T13:13:55.925758Z","steps":["trace[1701516871] 'agreement among raft nodes before linearized reading' (duration: 270.05718ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:55.925794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.142149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:13:55.925777Z","caller":"traceutil/trace.go:171","msg":"trace[254050369] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:627; }","duration":"124.72606ms","start":"2026-05-14T13:13:55.801025Z","end":"2026-05-14T13:13:55.925751Z","steps":["trace[254050369] 'agreement among raft nodes before linearized reading' (duration: 124.498206ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:55.925813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.867822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:13:55.925843Z","caller":"traceutil/trace.go:171","msg":"trace[2101355650] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:627; }","duration":"229.936363ms","start":"2026-05-14T13:13:55.695898Z","end":"2026-05-14T13:13:55.925834Z","steps":["trace[2101355650] 'agreement among raft nodes before linearized reading' (duration: 229.877542ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:55.925879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.98223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:13:55.925905Z","caller":"traceutil/trace.go:171","msg":"trace[2112981115] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:627; }","duration":"271.00808ms","start":"2026-05-14T13:13:55.65489Z","end":"2026-05-14T13:13:55.925898Z","steps":["trace[2112981115] 'agreement among raft nodes before linearized reading' (duration: 270.97165ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:55.925953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.957886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T13:13:55.925947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.3293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:13:55.92598Z","caller":"traceutil/trace.go:171","msg":"trace[99344797] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:627; }","duration":"197.005338ms","start":"2026-05-14T13:13:55.728966Z","end":"2026-05-14T13:13:55.925971Z","steps":["trace[99344797] 'agreement among raft nodes before linearized reading' (duration: 196.966167ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:55.925993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.948738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:13:55.926024Z","caller":"traceutil/trace.go:171","msg":"trace[800727044] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:627; }","duration":"517.974019ms","start":"2026-05-14T13:13:55.408036Z","end":"2026-05-14T13:13:55.926011Z","steps":["trace[800727044] 'agreement among raft nodes before linearized reading' (duration: 517.938218ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:55.92607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:55.408007Z","time spent":"518.048709ms","remote":"127.0.0.1:40268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 "} {"level":"info","ts":"2026-05-14T13:13:55.925818Z","caller":"traceutil/trace.go:171","msg":"trace[393987201] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:627; }","duration":"230.18514ms","start":"2026-05-14T13:13:55.695627Z","end":"2026-05-14T13:13:55.925813Z","steps":["trace[393987201] 'agreement among raft nodes before linearized reading' (duration: 230.149949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:55.926167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.789265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:13:55.92622Z","caller":"traceutil/trace.go:171","msg":"trace[20073041] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:627; }","duration":"518.875097ms","start":"2026-05-14T13:13:55.407336Z","end":"2026-05-14T13:13:55.926211Z","steps":["trace[20073041] 'agreement among raft nodes before linearized reading' (duration: 518.804975ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:55.926233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.052936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:13:55.925996Z","caller":"traceutil/trace.go:171","msg":"trace[1455655859] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:627; }","duration":"464.394432ms","start":"2026-05-14T13:13:55.461592Z","end":"2026-05-14T13:13:55.925987Z","steps":["trace[1455655859] 'agreement among raft nodes before linearized reading' (duration: 464.329211ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:55.926255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.021805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T13:13:55.926254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:55.407323Z","time spent":"518.923327ms","remote":"127.0.0.1:40268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-14T13:13:55.92629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:55.461585Z","time spent":"464.693098ms","remote":"127.0.0.1:40274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-14T13:13:55.926312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"889.602835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T13:13:55.926329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.714565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:13:55.926369Z","caller":"traceutil/trace.go:171","msg":"trace[994108603] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:627; }","duration":"377.753686ms","start":"2026-05-14T13:13:55.548604Z","end":"2026-05-14T13:13:55.926358Z","steps":["trace[994108603] 'agreement among raft nodes before linearized reading' (duration: 377.704665ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:55.926337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.538006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T13:13:55.926067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.192935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T13:13:55.926406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:55.548574Z","time spent":"377.821936ms","remote":"127.0.0.1:40290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 "} {"level":"info","ts":"2026-05-14T13:13:55.926432Z","caller":"traceutil/trace.go:171","msg":"trace[691168779] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:627; }","duration":"419.622017ms","start":"2026-05-14T13:13:55.506778Z","end":"2026-05-14T13:13:55.9264Z","steps":["trace[691168779] 'agreement among raft nodes before linearized reading' (duration: 419.536606ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:13:55.92629Z","caller":"traceutil/trace.go:171","msg":"trace[501442391] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:627; }","duration":"419.081517ms","start":"2026-05-14T13:13:55.507198Z","end":"2026-05-14T13:13:55.92628Z","steps":["trace[501442391] 'agreement among raft nodes before linearized reading' (duration: 419.032216ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:55.926458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:55.506766Z","time spent":"419.683948ms","remote":"127.0.0.1:40278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 "} {"level":"warn","ts":"2026-05-14T13:13:55.926526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.011622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T13:13:55.926081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.744593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T13:13:55.926557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.046681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:13:55.926554Z","caller":"traceutil/trace.go:171","msg":"trace[940664075] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:627; }","duration":"463.055043ms","start":"2026-05-14T13:13:55.463492Z","end":"2026-05-14T13:13:55.926547Z","steps":["trace[940664075] 'agreement among raft nodes before linearized reading' (duration: 463.013423ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:13:55.92659Z","caller":"traceutil/trace.go:171","msg":"trace[1028163162] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:627; }","duration":"378.103072ms","start":"2026-05-14T13:13:55.548479Z","end":"2026-05-14T13:13:55.926582Z","steps":["trace[1028163162] 'agreement among raft nodes before linearized reading' (duration: 378.055221ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:55.926626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:55.548465Z","time spent":"378.152684ms","remote":"127.0.0.1:40290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"info","ts":"2026-05-14T13:13:55.926413Z","caller":"traceutil/trace.go:171","msg":"trace[1607345311] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:627; }","duration":"271.566151ms","start":"2026-05-14T13:13:55.654839Z","end":"2026-05-14T13:13:55.926405Z","steps":["trace[1607345311] 'agreement among raft nodes before linearized reading' (duration: 271.170864ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:55.926371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.181544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:13:55.926338Z","caller":"traceutil/trace.go:171","msg":"trace[1571368914] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"889.657937ms","start":"2026-05-14T13:13:55.036673Z","end":"2026-05-14T13:13:55.92633Z","steps":["trace[1571368914] 'agreement among raft nodes before linearized reading' (duration: 889.615146ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:55.926822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:55.036656Z","time spent":"890.156788ms","remote":"127.0.0.1:47940","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-05-14T13:13:55.92658Z","caller":"traceutil/trace.go:171","msg":"trace[587010815] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:627; }","duration":"198.241533ms","start":"2026-05-14T13:13:55.72833Z","end":"2026-05-14T13:13:55.926571Z","steps":["trace[587010815] 'agreement among raft nodes before linearized reading' (duration: 197.734193ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:55.926887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.983744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T13:13:55.926704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:55.463483Z","time spent":"463.130885ms","remote":"127.0.0.1:40274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 "} {"level":"info","ts":"2026-05-14T13:13:55.926927Z","caller":"traceutil/trace.go:171","msg":"trace[259315595] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:627; }","duration":"465.065266ms","start":"2026-05-14T13:13:55.461854Z","end":"2026-05-14T13:13:55.92692Z","steps":["trace[259315595] 'agreement among raft nodes before linearized reading' (duration: 464.977904ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:55.926967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:55.461823Z","time spent":"465.137598ms","remote":"127.0.0.1:40274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 "} {"level":"warn","ts":"2026-05-14T13:13:55.926466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:55.507183Z","time spent":"419.275831ms","remote":"127.0.0.1:40278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"info","ts":"2026-05-14T13:13:55.926257Z","caller":"traceutil/trace.go:171","msg":"trace[1195659945] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:627; }","duration":"230.091666ms","start":"2026-05-14T13:13:55.69616Z","end":"2026-05-14T13:13:55.926251Z","steps":["trace[1195659945] 'agreement among raft nodes before linearized reading' (duration: 230.053856ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:55.926219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.290366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T13:13:55.92611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.887695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:13:55.927259Z","caller":"traceutil/trace.go:171","msg":"trace[727523693] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:627; }","duration":"378.350527ms","start":"2026-05-14T13:13:55.548897Z","end":"2026-05-14T13:13:55.927248Z","steps":["trace[727523693] 'agreement among raft nodes before linearized reading' (duration: 377.297345ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:13:55.927269Z","caller":"traceutil/trace.go:171","msg":"trace[177054697] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:627; }","duration":"199.05559ms","start":"2026-05-14T13:13:55.728205Z","end":"2026-05-14T13:13:55.92726Z","steps":["trace[177054697] 'agreement among raft nodes before linearized reading' (duration: 197.890025ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:55.927293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:55.548886Z","time spent":"378.397348ms","remote":"127.0.0.1:40278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 "} {"level":"info","ts":"2026-05-14T13:13:55.926928Z","caller":"traceutil/trace.go:171","msg":"trace[120415724] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:627; }","duration":"519.725495ms","start":"2026-05-14T13:13:55.407161Z","end":"2026-05-14T13:13:55.926886Z","steps":["trace[120415724] 'agreement among raft nodes before linearized reading' (duration: 519.193503ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:55.929213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:55.407146Z","time spent":"522.051264ms","remote":"127.0.0.1:40268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 "} {"level":"info","ts":"2026-05-14T13:13:57.273703Z","caller":"traceutil/trace.go:171","msg":"trace[279960240] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:672; }","duration":"237.4036ms","start":"2026-05-14T13:13:57.036258Z","end":"2026-05-14T13:13:57.273661Z","steps":["trace[279960240] 'read index received' (duration: 234.785066ms)","trace[279960240] 'applied index is now lower than readState.Index' (duration: 2.617134ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:13:57.273855Z","caller":"traceutil/trace.go:171","msg":"trace[1679643217] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"425.631227ms","start":"2026-05-14T13:13:56.84818Z","end":"2026-05-14T13:13:57.273811Z","steps":["trace[1679643217] 'process raft request' (duration: 422.958993ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:57.273937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.647904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:13:57.273983Z","caller":"traceutil/trace.go:171","msg":"trace[1356847172] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:643; }","duration":"237.757487ms","start":"2026-05-14T13:13:57.036211Z","end":"2026-05-14T13:13:57.273969Z","steps":["trace[1356847172] 'agreement among raft nodes before linearized reading' (duration: 237.592803ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:57.27405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:13:56.848153Z","time spent":"425.79053ms","remote":"127.0.0.1:48136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T13:13:58.055487Z","caller":"traceutil/trace.go:171","msg":"trace[1896716514] linearizableReadLoop","detail":"{readStateIndex:694; appliedIndex:693; }","duration":"113.881976ms","start":"2026-05-14T13:13:57.941568Z","end":"2026-05-14T13:13:58.05545Z","steps":["trace[1896716514] 'read index received' (duration: 113.597491ms)","trace[1896716514] 'applied index is now lower than readState.Index' (duration: 282.875µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:13:58.055536Z","caller":"traceutil/trace.go:171","msg":"trace[354811130] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"189.759236ms","start":"2026-05-14T13:13:57.865742Z","end":"2026-05-14T13:13:58.055502Z","steps":["trace[354811130] 'process raft request' (duration: 189.494101ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:58.055915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.172903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-14T13:13:58.056056Z","caller":"traceutil/trace.go:171","msg":"trace[139911509] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:664; }","duration":"113.328135ms","start":"2026-05-14T13:13:57.942649Z","end":"2026-05-14T13:13:58.055977Z","steps":["trace[139911509] 'agreement among raft nodes before linearized reading' (duration: 113.047759ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:13:58.05605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.451817ms","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-05-14T13:13:58.056171Z","caller":"traceutil/trace.go:171","msg":"trace[2069904028] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:664; }","duration":"114.601081ms","start":"2026-05-14T13:13:57.941513Z","end":"2026-05-14T13:13:58.056114Z","steps":["trace[2069904028] 'agreement among raft nodes before linearized reading' (duration: 114.134482ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:13:59.948552Z","caller":"traceutil/trace.go:171","msg":"trace[17725472] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"207.435633ms","start":"2026-05-14T13:13:59.741094Z","end":"2026-05-14T13:13:59.94853Z","steps":["trace[17725472] 'process raft request' (duration: 207.30414ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:14:00.018391Z","caller":"traceutil/trace.go:171","msg":"trace[1173819527] linearizableReadLoop","detail":"{readStateIndex:725; appliedIndex:723; }","duration":"264.637656ms","start":"2026-05-14T13:13:59.753724Z","end":"2026-05-14T13:14:00.018362Z","steps":["trace[1173819527] 'read index received' (duration: 194.730498ms)","trace[1173819527] 'applied index is now lower than readState.Index' (duration: 69.906308ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:14:00.018506Z","caller":"traceutil/trace.go:171","msg":"trace[1219062975] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"266.982244ms","start":"2026-05-14T13:13:59.751507Z","end":"2026-05-14T13:14:00.018489Z","steps":["trace[1219062975] 'process raft request' (duration: 266.737589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:14:00.018526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.77978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:14:00.018691Z","caller":"traceutil/trace.go:171","msg":"trace[389743301] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:694; }","duration":"264.987523ms","start":"2026-05-14T13:13:59.753691Z","end":"2026-05-14T13:14:00.018679Z","steps":["trace[389743301] 'agreement among raft nodes before linearized reading' (duration: 264.78338ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:14:00.018713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.700505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-05-14T13:14:00.018779Z","caller":"traceutil/trace.go:171","msg":"trace[1485468308] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:694; }","duration":"154.833438ms","start":"2026-05-14T13:13:59.863931Z","end":"2026-05-14T13:14:00.018765Z","steps":["trace[1485468308] 'agreement among raft nodes before linearized reading' (duration: 154.619604ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:14:00.418898Z","caller":"traceutil/trace.go:171","msg":"trace[1599041496] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"268.097904ms","start":"2026-05-14T13:14:00.150776Z","end":"2026-05-14T13:14:00.418874Z","steps":["trace[1599041496] 'process raft request' (duration: 240.729331ms)","trace[1599041496] 'compare' (duration: 27.103208ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:14:00.418889Z","caller":"traceutil/trace.go:171","msg":"trace[1295525137] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"267.644115ms","start":"2026-05-14T13:14:00.151195Z","end":"2026-05-14T13:14:00.418863Z","steps":["trace[1295525137] 'process raft request' (duration: 267.546723ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:14:00.419168Z","caller":"traceutil/trace.go:171","msg":"trace[425031677] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"265.90009ms","start":"2026-05-14T13:14:00.153235Z","end":"2026-05-14T13:14:00.419135Z","steps":["trace[425031677] 'process raft request' (duration: 265.582464ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:14:00.419241Z","caller":"traceutil/trace.go:171","msg":"trace[711239612] linearizableReadLoop","detail":"{readStateIndex:729; appliedIndex:726; }","duration":"155.016403ms","start":"2026-05-14T13:14:00.2642Z","end":"2026-05-14T13:14:00.419217Z","steps":["trace[711239612] 'read index received' (duration: 127.255822ms)","trace[711239612] 'applied index is now lower than readState.Index' (duration: 27.757501ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:14:00.419598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.379539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-05-14T13:14:00.419664Z","caller":"traceutil/trace.go:171","msg":"trace[1605923447] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:698; }","duration":"155.491732ms","start":"2026-05-14T13:14:00.264153Z","end":"2026-05-14T13:14:00.419645Z","steps":["trace[1605923447] 'agreement among raft nodes before linearized reading' (duration: 155.224967ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:14:01.054693Z","caller":"traceutil/trace.go:171","msg":"trace[1412250126] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"165.065833ms","start":"2026-05-14T13:14:00.889602Z","end":"2026-05-14T13:14:01.054668Z","steps":["trace[1412250126] 'process raft request' (duration: 164.89821ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:14:08.714197Z","caller":"traceutil/trace.go:171","msg":"trace[1184856066] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"244.409033ms","start":"2026-05-14T13:14:08.469764Z","end":"2026-05-14T13:14:08.714173Z","steps":["trace[1184856066] 'process raft request' (duration: 244.245869ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:14:08.717291Z","caller":"traceutil/trace.go:171","msg":"trace[121408838] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"247.204905ms","start":"2026-05-14T13:14:08.470069Z","end":"2026-05-14T13:14:08.717274Z","steps":["trace[121408838] 'process raft request' (duration: 247.025231ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:14:08.71814Z","caller":"traceutil/trace.go:171","msg":"trace[1170060884] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"243.805462ms","start":"2026-05-14T13:14:08.474317Z","end":"2026-05-14T13:14:08.718123Z","steps":["trace[1170060884] 'process raft request' (duration: 242.896325ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:14:12.080657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.046979769s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:14:12.08074Z","caller":"traceutil/trace.go:171","msg":"trace[1550350377] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:791; }","duration":"1.047115281s","start":"2026-05-14T13:14:11.033606Z","end":"2026-05-14T13:14:12.080721Z","steps":["trace[1550350377] 'range keys from in-memory index tree' (duration: 1.046845456s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:14:12.08074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.321563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-14T13:14:12.080834Z","caller":"traceutil/trace.go:171","msg":"trace[736434896] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:791; }","duration":"342.473165ms","start":"2026-05-14T13:14:11.738342Z","end":"2026-05-14T13:14:12.080815Z","steps":["trace[736434896] 'range keys from in-memory index tree' (duration: 342.114798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:14:12.080884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:14:11.738313Z","time spent":"342.557267ms","remote":"127.0.0.1:48136","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-05-14T13:14:12.08078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:14:11.033586Z","time spent":"1.047185153s","remote":"127.0.0.1:47940","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-05-14T13:14:12.168697Z","caller":"traceutil/trace.go:171","msg":"trace[1465302840] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"250.471861ms","start":"2026-05-14T13:14:11.918187Z","end":"2026-05-14T13:14:12.168659Z","steps":["trace[1465302840] 'process raft request' (duration: 250.209556ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:14:39.108747Z","caller":"traceutil/trace.go:171","msg":"trace[636529381] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"200.497713ms","start":"2026-05-14T13:14:38.908224Z","end":"2026-05-14T13:14:39.108721Z","steps":["trace[636529381] 'process raft request' (duration: 200.333721ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:14:39.117837Z","caller":"traceutil/trace.go:171","msg":"trace[1698521065] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"130.240485ms","start":"2026-05-14T13:14:38.987539Z","end":"2026-05-14T13:14:39.11778Z","steps":["trace[1698521065] 'process raft request' (duration: 130.076283ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:15:17.465635Z","caller":"traceutil/trace.go:171","msg":"trace[156489044] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"123.894456ms","start":"2026-05-14T13:15:17.341696Z","end":"2026-05-14T13:15:17.465591Z","steps":["trace[156489044] 'process raft request' (duration: 123.689714ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:15:35.417066Z","caller":"traceutil/trace.go:171","msg":"trace[319947035] linearizableReadLoop","detail":"{readStateIndex:1256; appliedIndex:1255; }","duration":"137.819875ms","start":"2026-05-14T13:15:35.279227Z","end":"2026-05-14T13:15:35.417047Z","steps":["trace[319947035] 'read index received' (duration: 137.651972ms)","trace[319947035] 'applied index is now lower than readState.Index' (duration: 167.093µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:15:35.41711Z","caller":"traceutil/trace.go:171","msg":"trace[638307398] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"140.28549ms","start":"2026-05-14T13:15:35.276799Z","end":"2026-05-14T13:15:35.417084Z","steps":["trace[638307398] 'process raft request' (duration: 140.120918ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:15:35.417436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.189568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329956"} {"level":"info","ts":"2026-05-14T13:15:35.417525Z","caller":"traceutil/trace.go:171","msg":"trace[1060627324] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:1202; }","duration":"138.325439ms","start":"2026-05-14T13:15:35.279181Z","end":"2026-05-14T13:15:35.417506Z","steps":["trace[1060627324] 'agreement among raft nodes before linearized reading' (duration: 137.967766ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:15:51.260812Z","caller":"traceutil/trace.go:171","msg":"trace[1927912823] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"110.930284ms","start":"2026-05-14T13:15:51.149856Z","end":"2026-05-14T13:15:51.260786Z","steps":["trace[1927912823] 'process raft request' (duration: 110.775062ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:16:05.65571Z","caller":"traceutil/trace.go:171","msg":"trace[690771882] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"328.169693ms","start":"2026-05-14T13:16:05.327507Z","end":"2026-05-14T13:16:05.655676Z","steps":["trace[690771882] 'process raft request' (duration: 318.484736ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:05.655882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:05.327476Z","time spent":"328.321485ms","remote":"127.0.0.1:48136","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-05-14T13:16:17.209928Z","caller":"traceutil/trace.go:171","msg":"trace[695750629] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"105.745656ms","start":"2026-05-14T13:16:17.104163Z","end":"2026-05-14T13:16:17.209908Z","steps":["trace[695750629] 'process raft request' (duration: 105.530855ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:16:20.102112Z","caller":"traceutil/trace.go:171","msg":"trace[1546420982] linearizableReadLoop","detail":"{readStateIndex:1459; appliedIndex:1458; }","duration":"134.365788ms","start":"2026-05-14T13:16:19.967709Z","end":"2026-05-14T13:16:20.102075Z","steps":["trace[1546420982] 'read index received' (duration: 73.084565ms)","trace[1546420982] 'applied index is now lower than readState.Index' (duration: 61.276283ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:16:20.102151Z","caller":"traceutil/trace.go:171","msg":"trace[164163254] transaction","detail":"{read_only:false; response_revision:1394; number_of_response:1; }","duration":"134.967415ms","start":"2026-05-14T13:16:19.967147Z","end":"2026-05-14T13:16:20.102115Z","steps":["trace[164163254] 'process raft request' (duration: 73.652381ms)","trace[164163254] 'compare' (duration: 61.120792ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:16:20.102345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.61944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node\" ","response":"range_response_count:1 size:1486"} {"level":"info","ts":"2026-05-14T13:16:20.102451Z","caller":"traceutil/trace.go:171","msg":"trace[198368302] range","detail":"{range_begin:/registry/clusterroles/system:node; range_end:; response_count:1; response_revision:1394; }","duration":"134.761702ms","start":"2026-05-14T13:16:19.96765Z","end":"2026-05-14T13:16:20.102412Z","steps":["trace[198368302] 'agreement among raft nodes before linearized reading' (duration: 134.55958ms)"],"step_count":1}