{"level":"warn","ts":"2026-06-16T00:24:32.153452Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-16T00:24:32.153549Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.23: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.23:2380","--initial-cluster=instance=https://199.204.45.23:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.23:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.23: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-06-16T00:24:32.153643Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-16T00:24:32.153666Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-06-16T00:24:32.153694Z","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-06-16T00:24:32.154755Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"]} {"level":"info","ts":"2026-06-16T00:24:32.154915Z","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.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.23: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-06-16T00:24:32.157718Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"1.811623ms"} {"level":"info","ts":"2026-06-16T00:24:32.164471Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"51a5c416a4d98c86","cluster-id":"ade2045974c19907"} {"level":"info","ts":"2026-06-16T00:24:32.164545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=()"} {"level":"info","ts":"2026-06-16T00:24:32.164565Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 0"} {"level":"info","ts":"2026-06-16T00:24:32.164578Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 51a5c416a4d98c86 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-16T00:24:32.164589Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 1"} {"level":"info","ts":"2026-06-16T00:24:32.16463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"warn","ts":"2026-06-16T00:24:32.171338Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-16T00:24:32.172826Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-16T00:24:32.173957Z","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-06-16T00:24:32.177052Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"51a5c416a4d98c86","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-16T00:24:32.177942Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"51a5c416a4d98c86","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-16T00:24:32.17821Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"info","ts":"2026-06-16T00:24:32.178292Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","added-peer-id":"51a5c416a4d98c86","added-peer-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-06-16T00:24:32.178364Z","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-06-16T00:24:32.178391Z","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-06-16T00:24:32.178402Z","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-06-16T00:24:32.183436Z","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-06-16T00:24:32.183788Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-06-16T00:24:32.183864Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-06-16T00:24:32.184523Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"51a5c416a4d98c86","initial-advertise-peer-urls":["https://199.204.45.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-16T00:24:32.184571Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-16T00:24:32.865132Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 is starting a new election at term 1"} {"level":"info","ts":"2026-06-16T00:24:32.86526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-16T00:24:32.865292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgPreVoteResp from 51a5c416a4d98c86 at term 1"} {"level":"info","ts":"2026-06-16T00:24:32.86555Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became candidate at term 2"} {"level":"info","ts":"2026-06-16T00:24:32.865575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgVoteResp from 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-06-16T00:24:32.86564Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became leader at term 2"} {"level":"info","ts":"2026-06-16T00:24:32.865657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 51a5c416a4d98c86 elected leader 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-06-16T00:24:32.866864Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T00:24:32.86808Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"51a5c416a4d98c86","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.23:2379]}","request-path":"/0/members/51a5c416a4d98c86/attributes","cluster-id":"ade2045974c19907","publish-timeout":"7s"} {"level":"info","ts":"2026-06-16T00:24:32.868154Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-16T00:24:32.868481Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-16T00:24:32.869008Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T00:24:32.869227Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T00:24:32.869345Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T00:24:32.871405Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-16T00:24:32.871434Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-16T00:24:32.873445Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.23:2379"} {"level":"info","ts":"2026-06-16T00:24:32.877544Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-06-16T00:24:34.65026Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125955418997302390,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-16T00:24:34.651801Z","caller":"traceutil/trace.go:171","msg":"trace[418885754] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"503.099548ms","start":"2026-06-16T00:24:34.148675Z","end":"2026-06-16T00:24:34.651775Z","steps":["trace[418885754] 'process raft request' (duration: 502.872863ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:24:34.652438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:24:34.148664Z","time spent":"503.19937ms","remote":"127.0.0.1:46582","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-06-16T00:24:35.329342Z","caller":"traceutil/trace.go:171","msg":"trace[1927168357] linearizableReadLoop","detail":"{readStateIndex:13; appliedIndex:4; }","duration":"1.179832264s","start":"2026-06-16T00:24:34.149471Z","end":"2026-06-16T00:24:35.329304Z","steps":["trace[1927168357] 'read index received' (duration: 502.189126ms)","trace[1927168357] 'applied index is now lower than readState.Index' (duration: 677.641828ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:24:35.329863Z","caller":"traceutil/trace.go:171","msg":"trace[2115560174] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"1.08811498s","start":"2026-06-16T00:24:34.241718Z","end":"2026-06-16T00:24:35.329833Z","steps":["trace[2115560174] 'process raft request' (duration: 1.087539826s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:24:35.330022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:24:34.241691Z","time spent":"1.088235853s","remote":"127.0.0.1:46816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-16T00:24:35.330026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.18049173s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-16T00:24:35.330117Z","caller":"traceutil/trace.go:171","msg":"trace[583277846] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:0; response_revision:10; }","duration":"1.180640584s","start":"2026-06-16T00:24:34.149456Z","end":"2026-06-16T00:24:35.330097Z","steps":["trace[583277846] 'agreement among raft nodes before linearized reading' (duration: 1.180394478s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:24:35.330156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:24:34.149447Z","time spent":"1.180701335s","remote":"127.0.0.1:46574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-06-16T00:24:35.330278Z","caller":"traceutil/trace.go:171","msg":"trace[195822148] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"1.103316592s","start":"2026-06-16T00:24:34.22695Z","end":"2026-06-16T00:24:35.330266Z","steps":["trace[195822148] 'process raft request' (duration: 1.102222765s)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:24:35.330289Z","caller":"traceutil/trace.go:171","msg":"trace[1772328077] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"1.090019156s","start":"2026-06-16T00:24:34.240253Z","end":"2026-06-16T00:24:35.330272Z","steps":["trace[1772328077] 'process raft request' (duration: 1.0889693s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:24:35.330345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:24:34.22694Z","time spent":"1.103376523s","remote":"127.0.0.1:47020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-16T00:24:35.329656Z","caller":"traceutil/trace.go:171","msg":"trace[1845792472] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"1.103750492s","start":"2026-06-16T00:24:34.225822Z","end":"2026-06-16T00:24:35.329573Z","steps":["trace[1845792472] 'process raft request' (duration: 1.050374096s)","trace[1845792472] 'compare' (duration: 52.631677ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:24:35.330415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:24:34.240219Z","time spent":"1.090125079s","remote":"127.0.0.1:46720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-16T00:24:35.33046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.42233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-16T00:24:35.330482Z","caller":"traceutil/trace.go:171","msg":"trace[944021823] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:10; }","duration":"206.478782ms","start":"2026-06-16T00:24:35.123996Z","end":"2026-06-16T00:24:35.330475Z","steps":["trace[944021823] 'agreement among raft nodes before linearized reading' (duration: 206.436551ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:24:35.330458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.18092457s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3170"} {"level":"warn","ts":"2026-06-16T00:24:35.330416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.104897879s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-16T00:24:35.33054Z","caller":"traceutil/trace.go:171","msg":"trace[1753724988] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"1.105059953s","start":"2026-06-16T00:24:34.225469Z","end":"2026-06-16T00:24:35.330529Z","steps":["trace[1753724988] 'agreement among raft nodes before linearized reading' (duration: 1.10492696s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:24:35.33057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:24:34.225432Z","time spent":"1.105132005s","remote":"127.0.0.1:46650","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-06-16T00:24:35.330558Z","caller":"traceutil/trace.go:171","msg":"trace[1675545560] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:10; }","duration":"1.181051303s","start":"2026-06-16T00:24:34.149478Z","end":"2026-06-16T00:24:35.330529Z","steps":["trace[1675545560] 'agreement among raft nodes before linearized reading' (duration: 1.180843508s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:24:35.330565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:24:34.225781Z","time spent":"1.104689875s","remote":"127.0.0.1:47094","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-06-16T00:24:35.330621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.052049506s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-16T00:24:35.33062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:24:34.149473Z","time spent":"1.181133356s","remote":"127.0.0.1:46720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3193,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-06-16T00:24:35.330669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.105128055s","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-06-16T00:24:35.330693Z","caller":"traceutil/trace.go:171","msg":"trace[1850243186] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"1.105164986s","start":"2026-06-16T00:24:34.22552Z","end":"2026-06-16T00:24:35.330685Z","steps":["trace[1850243186] 'agreement among raft nodes before linearized reading' (duration: 1.105134685s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:24:35.330714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:24:34.225509Z","time spent":"1.105200066s","remote":"127.0.0.1:46612","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":"info","ts":"2026-06-16T00:24:35.330733Z","caller":"traceutil/trace.go:171","msg":"trace[13518756] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"1.104783297s","start":"2026-06-16T00:24:34.225936Z","end":"2026-06-16T00:24:35.33072Z","steps":["trace[13518756] 'process raft request' (duration: 1.103066265s)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:24:35.330787Z","caller":"traceutil/trace.go:171","msg":"trace[1577838899] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"1.104583272s","start":"2026-06-16T00:24:34.226195Z","end":"2026-06-16T00:24:35.330778Z","steps":["trace[1577838899] 'process raft request' (duration: 1.102946152s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:24:35.330799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:24:34.225922Z","time spent":"1.104848948s","remote":"127.0.0.1:47094","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-16T00:24:35.330817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.132911135s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-16T00:24:35.330861Z","caller":"traceutil/trace.go:171","msg":"trace[1588613860] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"1.104842758s","start":"2026-06-16T00:24:34.226013Z","end":"2026-06-16T00:24:35.330855Z","steps":["trace[1588613860] 'process raft request' (duration: 1.103045984s)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:24:35.330877Z","caller":"traceutil/trace.go:171","msg":"trace[817475933] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"1.133003907s","start":"2026-06-16T00:24:34.197857Z","end":"2026-06-16T00:24:35.330861Z","steps":["trace[817475933] 'agreement among raft nodes before linearized reading' (duration: 1.132900555s)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:24:35.330641Z","caller":"traceutil/trace.go:171","msg":"trace[787236584] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"1.052096398s","start":"2026-06-16T00:24:34.278536Z","end":"2026-06-16T00:24:35.330633Z","steps":["trace[787236584] 'agreement among raft nodes before linearized reading' (duration: 1.052070597s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:24:35.330893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:24:34.226003Z","time spent":"1.10487632s","remote":"127.0.0.1:47094","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-06-16T00:24:35.330923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:24:34.278501Z","time spent":"1.052415325s","remote":"127.0.0.1:46650","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-06-16T00:24:35.330595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.714748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-16T00:24:35.330916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.133068589s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-16T00:24:35.330957Z","caller":"traceutil/trace.go:171","msg":"trace[2026550353] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:10; }","duration":"207.108007ms","start":"2026-06-16T00:24:35.123838Z","end":"2026-06-16T00:24:35.330946Z","steps":["trace[2026550353] 'agreement among raft nodes before linearized reading' (duration: 206.742618ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:24:35.330924Z","caller":"traceutil/trace.go:171","msg":"trace[1709850767] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"1.104697515s","start":"2026-06-16T00:24:34.226195Z","end":"2026-06-16T00:24:35.330893Z","steps":["trace[1709850767] 'process raft request' (duration: 1.102894181s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:24:35.330977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.107721469s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.23\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-16T00:24:35.330832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:24:34.226183Z","time spent":"1.104627263s","remote":"127.0.0.1:47094","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-06-16T00:24:35.331011Z","caller":"traceutil/trace.go:171","msg":"trace[1596766936] range","detail":"{range_begin:/registry/masterleases/199.204.45.23; range_end:; response_count:0; response_revision:10; }","duration":"1.10776728s","start":"2026-06-16T00:24:34.223235Z","end":"2026-06-16T00:24:35.331002Z","steps":["trace[1596766936] 'agreement among raft nodes before linearized reading' (duration: 1.107717388s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:24:35.331051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:24:34.223226Z","time spent":"1.10781671s","remote":"127.0.0.1:46552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.204.45.23\" "} {"level":"warn","ts":"2026-06-16T00:24:35.331057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:24:34.226186Z","time spent":"1.104811137s","remote":"127.0.0.1:47094","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-06-16T00:24:35.330929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:24:34.197852Z","time spent":"1.133063408s","remote":"127.0.0.1:46650","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-06-16T00:24:35.33097Z","caller":"traceutil/trace.go:171","msg":"trace[1365919947] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"1.13312148s","start":"2026-06-16T00:24:34.197832Z","end":"2026-06-16T00:24:35.330954Z","steps":["trace[1365919947] 'agreement among raft nodes before linearized reading' (duration: 1.133038278s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:24:35.331154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:24:34.197815Z","time spent":"1.133330675s","remote":"127.0.0.1:46650","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-06-16T00:24:35.331018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.181501234s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3170"} {"level":"info","ts":"2026-06-16T00:24:35.331214Z","caller":"traceutil/trace.go:171","msg":"trace[1634902637] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:10; }","duration":"1.181699258s","start":"2026-06-16T00:24:34.149505Z","end":"2026-06-16T00:24:35.331204Z","steps":["trace[1634902637] 'agreement among raft nodes before linearized reading' (duration: 1.181484983s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:24:35.331233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.131275025s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-16T00:24:35.331248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:24:34.1495Z","time spent":"1.18173833s","remote":"127.0.0.1:46720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3193,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-06-16T00:24:35.331254Z","caller":"traceutil/trace.go:171","msg":"trace[1898009394] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"1.131318725s","start":"2026-06-16T00:24:34.199929Z","end":"2026-06-16T00:24:35.331247Z","steps":["trace[1898009394] 'agreement among raft nodes before linearized reading' (duration: 1.131279715s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:24:35.331389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:24:34.199921Z","time spent":"1.13143178s","remote":"127.0.0.1:46650","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-06-16T00:24:35.860148Z","caller":"traceutil/trace.go:171","msg":"trace[1852356230] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"525.134997ms","start":"2026-06-16T00:24:35.334965Z","end":"2026-06-16T00:24:35.8601Z","steps":["trace[1852356230] 'process raft request' (duration: 497.331846ms)","trace[1852356230] 'compare' (duration: 27.589896ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:24:35.860399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:24:35.334952Z","time spent":"525.30079ms","remote":"127.0.0.1:46574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T00:24:35.860692Z","caller":"traceutil/trace.go:171","msg":"trace[1870152743] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"524.87523ms","start":"2026-06-16T00:24:35.335782Z","end":"2026-06-16T00:24:35.860657Z","steps":["trace[1870152743] 'process raft request' (duration: 524.555402ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:24:35.86081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:24:35.335761Z","time spent":"525.001674ms","remote":"127.0.0.1:47094","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-06-16T00:24:35.860933Z","caller":"traceutil/trace.go:171","msg":"trace[1210990295] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"524.613534ms","start":"2026-06-16T00:24:35.336305Z","end":"2026-06-16T00:24:35.860918Z","steps":["trace[1210990295] 'process raft request' (duration: 524.289297ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:24:35.860918Z","caller":"traceutil/trace.go:171","msg":"trace[31533615] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"524.886031ms","start":"2026-06-16T00:24:35.335977Z","end":"2026-06-16T00:24:35.860863Z","steps":["trace[31533615] 'process raft request' (duration: 524.574604ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:24:35.861025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:24:35.336293Z","time spent":"524.692386ms","remote":"127.0.0.1:47094","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-06-16T00:24:35.861091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:24:35.335951Z","time spent":"525.072545ms","remote":"127.0.0.1:46650","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-06-16T00:24:35.861599Z","caller":"traceutil/trace.go:171","msg":"trace[925642650] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"526.618253ms","start":"2026-06-16T00:24:35.334952Z","end":"2026-06-16T00:24:35.861571Z","steps":["trace[925642650] 'process raft request' (duration: 525.677989ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:24:35.861768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:24:35.33494Z","time spent":"526.747337ms","remote":"127.0.0.1:46920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-16T00:24:35.86177Z","caller":"traceutil/trace.go:171","msg":"trace[1247489526] transaction","detail":"{read_only:false; number_of_response:0; response_revision:15; }","duration":"524.242715ms","start":"2026-06-16T00:24:35.337509Z","end":"2026-06-16T00:24:35.861751Z","steps":["trace[1247489526] 'process raft request' (duration: 523.957259ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:24:35.861956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:24:35.337497Z","time spent":"524.379068ms","remote":"127.0.0.1:46650","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-06-16T00:24:35.862436Z","caller":"traceutil/trace.go:171","msg":"trace[1817666474] linearizableReadLoop","detail":"{readStateIndex:16; appliedIndex:13; }","duration":"526.353607ms","start":"2026-06-16T00:24:35.336069Z","end":"2026-06-16T00:24:35.862422Z","steps":["trace[1817666474] 'read index received' (duration: 496.130327ms)","trace[1817666474] 'applied index is now lower than readState.Index' (duration: 30.22216ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:24:35.86261Z","caller":"traceutil/trace.go:171","msg":"trace[486157624] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"524.83849ms","start":"2026-06-16T00:24:35.337764Z","end":"2026-06-16T00:24:35.862602Z","steps":["trace[486157624] 'process raft request' (duration: 524.41338ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:24:35.862866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:24:35.337753Z","time spent":"525.087606ms","remote":"127.0.0.1:47020","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-06-16T00:24:35.862999Z","caller":"traceutil/trace.go:171","msg":"trace[802120553] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"525.127206ms","start":"2026-06-16T00:24:35.337864Z","end":"2026-06-16T00:24:35.862991Z","steps":["trace[802120553] 'process raft request' (duration: 524.374258ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:24:35.863039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:24:35.337846Z","time spent":"525.176348ms","remote":"127.0.0.1:47094","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-06-16T00:24:35.863177Z","caller":"traceutil/trace.go:171","msg":"trace[1377384944] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"525.168777ms","start":"2026-06-16T00:24:35.337979Z","end":"2026-06-16T00:24:35.863148Z","steps":["trace[1377384944] 'process raft request' (duration: 524.283646ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:24:35.863464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:24:35.337971Z","time spent":"525.24923ms","remote":"127.0.0.1:47094","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-06-16T00:24:35.863668Z","caller":"traceutil/trace.go:171","msg":"trace[1407977359] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"525.666649ms","start":"2026-06-16T00:24:35.33799Z","end":"2026-06-16T00:24:35.863656Z","steps":["trace[1407977359] 'process raft request' (duration: 524.302996ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:24:35.863727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:24:35.337961Z","time spent":"525.731201ms","remote":"127.0.0.1:47094","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-16T00:24:35.863879Z","caller":"traceutil/trace.go:171","msg":"trace[1782141662] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"524.680925ms","start":"2026-06-16T00:24:35.339188Z","end":"2026-06-16T00:24:35.863869Z","steps":["trace[1782141662] 'process raft request' (duration: 523.124708ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:24:35.863915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:24:35.339179Z","time spent":"524.721317ms","remote":"127.0.0.1:46964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":651,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-16T00:24:35.864193Z","caller":"traceutil/trace.go:171","msg":"trace[1234803531] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"520.156016ms","start":"2026-06-16T00:24:35.344021Z","end":"2026-06-16T00:24:35.864177Z","steps":["trace[1234803531] 'process raft request' (duration: 518.325631ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:24:35.864323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:24:35.344011Z","time spent":"520.264468ms","remote":"127.0.0.1:46720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3478,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T00:24:35.864513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.437419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-16T00:24:35.864668Z","caller":"traceutil/trace.go:171","msg":"trace[12006639] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:21; }","duration":"528.613532ms","start":"2026-06-16T00:24:35.336041Z","end":"2026-06-16T00:24:35.864654Z","steps":["trace[12006639] 'agreement among raft nodes before linearized reading' (duration: 528.439618ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:24:35.864729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:24:35.336029Z","time spent":"528.687614ms","remote":"127.0.0.1:46904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":27,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" "} {"level":"warn","ts":"2026-06-16T00:24:35.865111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.637684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-bx5cn\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-06-16T00:24:35.865215Z","caller":"traceutil/trace.go:171","msg":"trace[1388175534] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-bx5cn; range_end:; response_count:1; response_revision:21; }","duration":"526.760757ms","start":"2026-06-16T00:24:35.338441Z","end":"2026-06-16T00:24:35.865202Z","steps":["trace[1388175534] 'agreement among raft nodes before linearized reading' (duration: 526.620373ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:24:35.865275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:24:35.338295Z","time spent":"526.969142ms","remote":"127.0.0.1:46816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":884,"request content":"key:\"/registry/certificatesigningrequests/csr-bx5cn\" "} {"level":"warn","ts":"2026-06-16T00:24:35.864972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.39935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-16T00:24:35.865471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.109984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-node-lease\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-16T00:24:35.865522Z","caller":"traceutil/trace.go:171","msg":"trace[581419397] range","detail":"{range_begin:/registry/namespaces/kube-node-lease; range_end:; response_count:0; response_revision:21; }","duration":"475.196976ms","start":"2026-06-16T00:24:35.390309Z","end":"2026-06-16T00:24:35.865506Z","steps":["trace[581419397] 'agreement among raft nodes before linearized reading' (duration: 475.119664ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:24:35.865546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:24:35.390285Z","time spent":"475.257157ms","remote":"127.0.0.1:46650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-node-lease\" "} {"level":"info","ts":"2026-06-16T00:24:35.865496Z","caller":"traceutil/trace.go:171","msg":"trace[972928381] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:21; }","duration":"325.959624ms","start":"2026-06-16T00:24:35.539515Z","end":"2026-06-16T00:24:35.865474Z","steps":["trace[972928381] 'agreement among raft nodes before linearized reading' (duration: 325.37215ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:24:35.865717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:24:35.539498Z","time spent":"326.131979ms","remote":"127.0.0.1:46824","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-06-16T00:24:35.865227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.692759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-16T00:24:35.865926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.247103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-06-16T00:24:35.865956Z","caller":"traceutil/trace.go:171","msg":"trace[1424318117] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:21; }","duration":"472.314695ms","start":"2026-06-16T00:24:35.393634Z","end":"2026-06-16T00:24:35.865949Z","steps":["trace[1424318117] 'agreement among raft nodes before linearized reading' (duration: 472.274594ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:24:35.865976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:24:35.393619Z","time spent":"472.353355ms","remote":"127.0.0.1:46650","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":"warn","ts":"2026-06-16T00:24:35.865892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.868866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-06-16T00:24:35.865935Z","caller":"traceutil/trace.go:171","msg":"trace[1095553155] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21; }","duration":"528.969061ms","start":"2026-06-16T00:24:35.336954Z","end":"2026-06-16T00:24:35.865923Z","steps":["trace[1095553155] 'agreement among raft nodes before linearized reading' (duration: 528.204712ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:24:35.866607Z","caller":"traceutil/trace.go:171","msg":"trace[310634951] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:21; }","duration":"430.544272ms","start":"2026-06-16T00:24:35.435968Z","end":"2026-06-16T00:24:35.866512Z","steps":["trace[310634951] 'agreement among raft nodes before linearized reading' (duration: 429.874506ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:24:35.866674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:24:35.336942Z","time spent":"529.686508ms","remote":"127.0.0.1:46498","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-06-16T00:24:35.866684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:24:35.435941Z","time spent":"430.728627ms","remote":"127.0.0.1:46650","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-06-16T00:25:08.730384Z","caller":"traceutil/trace.go:171","msg":"trace[1992771046] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"108.188347ms","start":"2026-06-16T00:25:08.62217Z","end":"2026-06-16T00:25:08.730358Z","steps":["trace[1992771046] 'process raft request' (duration: 107.862269ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:25:10.026585Z","caller":"traceutil/trace.go:171","msg":"trace[2034456285] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"148.924883ms","start":"2026-06-16T00:25:09.877636Z","end":"2026-06-16T00:25:10.026561Z","steps":["trace[2034456285] 'process raft request' (duration: 148.349059ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:25:10.826383Z","caller":"traceutil/trace.go:171","msg":"trace[426740407] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"477.644276ms","start":"2026-06-16T00:25:10.348711Z","end":"2026-06-16T00:25:10.826356Z","steps":["trace[426740407] 'process raft request' (duration: 477.40039ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:25:10.826722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:25:10.348689Z","time spent":"477.802719ms","remote":"127.0.0.1:46824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T00:25:10.878654Z","caller":"traceutil/trace.go:171","msg":"trace[1824237265] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"431.354853ms","start":"2026-06-16T00:25:10.447278Z","end":"2026-06-16T00:25:10.878633Z","steps":["trace[1824237265] 'process raft request' (duration: 431.045375ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:25:10.878723Z","caller":"traceutil/trace.go:171","msg":"trace[201937027] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"422.912926ms","start":"2026-06-16T00:25:10.455784Z","end":"2026-06-16T00:25:10.878697Z","steps":["trace[201937027] 'process raft request' (duration: 422.650019ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:25:10.878726Z","caller":"traceutil/trace.go:171","msg":"trace[324109740] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"441.093221ms","start":"2026-06-16T00:25:10.437585Z","end":"2026-06-16T00:25:10.878679Z","steps":["trace[324109740] 'process raft request' (duration: 440.632959ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:25:10.878839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:25:10.455764Z","time spent":"423.014309ms","remote":"127.0.0.1:46560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-16T00:25:10.878882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:25:10.447269Z","time spent":"431.452085ms","remote":"127.0.0.1:46560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-16T00:25:10.878901Z","caller":"traceutil/trace.go:171","msg":"trace[275604757] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"417.120764ms","start":"2026-06-16T00:25:10.461769Z","end":"2026-06-16T00:25:10.87889Z","steps":["trace[275604757] 'process raft request' (duration: 416.702924ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:25:10.878913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:25:10.437564Z","time spent":"441.268346ms","remote":"127.0.0.1:46560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-16T00:25:10.879006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:25:10.461751Z","time spent":"417.189477ms","remote":"127.0.0.1:46560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-16T00:25:10.879033Z","caller":"traceutil/trace.go:171","msg":"trace[633822057] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"431.537107ms","start":"2026-06-16T00:25:10.447485Z","end":"2026-06-16T00:25:10.879022Z","steps":["trace[633822057] 'process raft request' (duration: 430.87266ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:25:10.879078Z","caller":"traceutil/trace.go:171","msg":"trace[703697150] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"443.144801ms","start":"2026-06-16T00:25:10.435921Z","end":"2026-06-16T00:25:10.879066Z","steps":["trace[703697150] 'process raft request' (duration: 442.162166ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:25:10.879106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:25:10.447477Z","time spent":"431.604849ms","remote":"127.0.0.1:46560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-16T00:25:10.879134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:25:10.435902Z","time spent":"443.205983ms","remote":"127.0.0.1:46560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-16T00:25:10.879147Z","caller":"traceutil/trace.go:171","msg":"trace[1828255385] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"404.945657ms","start":"2026-06-16T00:25:10.474191Z","end":"2026-06-16T00:25:10.879137Z","steps":["trace[1828255385] 'process raft request' (duration: 404.352823ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:25:10.87919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:25:10.474178Z","time spent":"404.993178ms","remote":"127.0.0.1:46560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-16T00:25:10.879215Z","caller":"traceutil/trace.go:171","msg":"trace[455394148] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"429.959149ms","start":"2026-06-16T00:25:10.449246Z","end":"2026-06-16T00:25:10.879205Z","steps":["trace[455394148] 'process raft request' (duration: 429.152049ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:25:10.879229Z","caller":"traceutil/trace.go:171","msg":"trace[540417536] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"440.139587ms","start":"2026-06-16T00:25:10.439079Z","end":"2026-06-16T00:25:10.879219Z","steps":["trace[540417536] 'process raft request' (duration: 439.169953ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:25:10.87926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:25:10.449238Z","time spent":"430.00123ms","remote":"127.0.0.1:46560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-16T00:25:10.878835Z","caller":"traceutil/trace.go:171","msg":"trace[749292784] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"273.534812ms","start":"2026-06-16T00:25:10.605274Z","end":"2026-06-16T00:25:10.878809Z","steps":["trace[749292784] 'process raft request' (duration: 273.44211ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:25:10.879346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:25:10.43907Z","time spent":"440.202359ms","remote":"127.0.0.1:46560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-16T00:25:10.879344Z","caller":"traceutil/trace.go:171","msg":"trace[969381205] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"433.795023ms","start":"2026-06-16T00:25:10.44553Z","end":"2026-06-16T00:25:10.879325Z","steps":["trace[969381205] 'process raft request' (duration: 432.754447ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:25:10.879416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:25:10.445509Z","time spent":"433.873874ms","remote":"127.0.0.1:46560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-16T00:25:10.879264Z","caller":"traceutil/trace.go:171","msg":"trace[587457015] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"385.309547ms","start":"2026-06-16T00:25:10.493946Z","end":"2026-06-16T00:25:10.879256Z","steps":["trace[587457015] 'process raft request' (duration: 384.663722ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:25:10.879856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:25:10.493919Z","time spent":"385.910151ms","remote":"127.0.0.1:46560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-16T00:25:11.001483Z","caller":"traceutil/trace.go:171","msg":"trace[901394759] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"105.041099ms","start":"2026-06-16T00:25:10.896413Z","end":"2026-06-16T00:25:11.001454Z","steps":["trace[901394759] 'process raft request' (duration: 47.365909ms)","trace[901394759] 'compare' (duration: 57.518736ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:25:11.020695Z","caller":"traceutil/trace.go:171","msg":"trace[1627650417] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"123.935612ms","start":"2026-06-16T00:25:10.896719Z","end":"2026-06-16T00:25:11.020655Z","steps":["trace[1627650417] 'process raft request' (duration: 123.732497ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:25:11.020883Z","caller":"traceutil/trace.go:171","msg":"trace[887727208] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"124.084866ms","start":"2026-06-16T00:25:10.896773Z","end":"2026-06-16T00:25:11.020858Z","steps":["trace[887727208] 'process raft request' (duration: 123.816009ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:25:11.021338Z","caller":"traceutil/trace.go:171","msg":"trace[1496145509] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"124.390594ms","start":"2026-06-16T00:25:10.896938Z","end":"2026-06-16T00:25:11.021328Z","steps":["trace[1496145509] 'process raft request' (duration: 123.694036ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:25:11.021291Z","caller":"traceutil/trace.go:171","msg":"trace[1827194679] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"124.172877ms","start":"2026-06-16T00:25:10.896447Z","end":"2026-06-16T00:25:11.02062Z","steps":["trace[1827194679] 'process raft request' (duration: 123.87777ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:25:11.021699Z","caller":"traceutil/trace.go:171","msg":"trace[187823787] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"124.637629ms","start":"2026-06-16T00:25:10.896989Z","end":"2026-06-16T00:25:11.021627Z","steps":["trace[187823787] 'process raft request' (duration: 123.835529ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:25:11.021819Z","caller":"traceutil/trace.go:171","msg":"trace[1033033458] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"121.681008ms","start":"2026-06-16T00:25:10.900088Z","end":"2026-06-16T00:25:11.021769Z","steps":["trace[1033033458] 'process raft request' (duration: 121.428322ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:25:11.02253Z","caller":"traceutil/trace.go:171","msg":"trace[40388871] linearizableReadLoop","detail":"{readStateIndex:524; appliedIndex:516; }","duration":"120.199281ms","start":"2026-06-16T00:25:10.902315Z","end":"2026-06-16T00:25:11.022515Z","steps":["trace[40388871] 'read index received' (duration: 41.370672ms)","trace[40388871] 'applied index is now lower than readState.Index' (duration: 78.827579ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:25:11.022621Z","caller":"traceutil/trace.go:171","msg":"trace[1892651406] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"122.396355ms","start":"2026-06-16T00:25:10.900216Z","end":"2026-06-16T00:25:11.022612Z","steps":["trace[1892651406] 'process raft request' (duration: 121.431792ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:25:11.022823Z","caller":"traceutil/trace.go:171","msg":"trace[1372057496] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"116.912131ms","start":"2026-06-16T00:25:10.905899Z","end":"2026-06-16T00:25:11.022811Z","steps":["trace[1372057496] 'process raft request' (duration: 116.567942ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:25:11.023384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.047461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-16T00:25:11.023438Z","caller":"traceutil/trace.go:171","msg":"trace[1750372408] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:509; }","duration":"121.145513ms","start":"2026-06-16T00:25:10.90228Z","end":"2026-06-16T00:25:11.023426Z","steps":["trace[1750372408] 'agreement among raft nodes before linearized reading' (duration: 120.935468ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:25:11.022903Z","caller":"traceutil/trace.go:171","msg":"trace[386461720] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"117.137896ms","start":"2026-06-16T00:25:10.905677Z","end":"2026-06-16T00:25:11.022814Z","steps":["trace[386461720] 'process raft request' (duration: 116.726086ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:25:11.024593Z","caller":"traceutil/trace.go:171","msg":"trace[292368575] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"119.836222ms","start":"2026-06-16T00:25:10.904745Z","end":"2026-06-16T00:25:11.024581Z","steps":["trace[292368575] 'process raft request' (duration: 117.582627ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:25:11.523295Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125955418997303385,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-16T00:25:11.556089Z","caller":"traceutil/trace.go:171","msg":"trace[287582332] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"605.194325ms","start":"2026-06-16T00:25:10.950847Z","end":"2026-06-16T00:25:11.556041Z","steps":["trace[287582332] 'process raft request' (duration: 597.877687ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:25:11.556216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:25:10.950832Z","time spent":"605.321808ms","remote":"127.0.0.1:46560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-16T00:25:11.556225Z","caller":"traceutil/trace.go:171","msg":"trace[1486488819] transaction","detail":"{read_only:false; number_of_response:0; response_revision:510; }","duration":"528.947769ms","start":"2026-06-16T00:25:11.027253Z","end":"2026-06-16T00:25:11.556201Z","steps":["trace[1486488819] 'process raft request' (duration: 528.683743ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:25:11.556367Z","caller":"traceutil/trace.go:171","msg":"trace[777730168] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"525.017014ms","start":"2026-06-16T00:25:11.031316Z","end":"2026-06-16T00:25:11.556333Z","steps":["trace[777730168] 'process raft request' (duration: 524.709846ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:25:11.556435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:25:11.02723Z","time spent":"529.154585ms","remote":"127.0.0.1:47094","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-16T00:25:11.556572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:25:11.031294Z","time spent":"525.169268ms","remote":"127.0.0.1:46824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T00:25:11.556735Z","caller":"traceutil/trace.go:171","msg":"trace[141731781] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"514.747873ms","start":"2026-06-16T00:25:11.041951Z","end":"2026-06-16T00:25:11.556699Z","steps":["trace[141731781] 'process raft request' (duration: 514.225119ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:25:11.556935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:25:11.041932Z","time spent":"514.894106ms","remote":"127.0.0.1:46560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T00:25:11.557383Z","caller":"traceutil/trace.go:171","msg":"trace[1119882797] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"494.656552ms","start":"2026-06-16T00:25:11.062707Z","end":"2026-06-16T00:25:11.557364Z","steps":["trace[1119882797] 'process raft request' (duration: 493.863103ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:25:11.557493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:25:11.062682Z","time spent":"494.755754ms","remote":"127.0.0.1:46560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T00:25:11.558969Z","caller":"traceutil/trace.go:171","msg":"trace[1869600433] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"483.044356ms","start":"2026-06-16T00:25:11.075906Z","end":"2026-06-16T00:25:11.55895Z","steps":["trace[1869600433] 'process raft request' (duration: 482.514864ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:25:11.563377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:25:11.075882Z","time spent":"487.363733ms","remote":"127.0.0.1:46560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T00:25:11.558811Z","caller":"traceutil/trace.go:171","msg":"trace[901789194] linearizableReadLoop","detail":"{readStateIndex:535; appliedIndex:527; }","duration":"536.209378ms","start":"2026-06-16T00:25:11.022584Z","end":"2026-06-16T00:25:11.558794Z","steps":["trace[901789194] 'read index received' (duration: 526.150342ms)","trace[901789194] 'applied index is now lower than readState.Index' (duration: 10.057926ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:25:11.559963Z","caller":"traceutil/trace.go:171","msg":"trace[617223366] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"490.873249ms","start":"2026-06-16T00:25:11.069074Z","end":"2026-06-16T00:25:11.559947Z","steps":["trace[617223366] 'process raft request' (duration: 487.867615ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:25:11.563568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:25:11.069065Z","time spent":"494.469286ms","remote":"127.0.0.1:46560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T00:25:11.56017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.247276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:25:11.563725Z","caller":"traceutil/trace.go:171","msg":"trace[1033843592] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:516; }","duration":"617.795653ms","start":"2026-06-16T00:25:10.945904Z","end":"2026-06-16T00:25:11.5637Z","steps":["trace[1033843592] 'agreement among raft nodes before linearized reading' (duration: 613.469428ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:25:11.560325Z","caller":"traceutil/trace.go:171","msg":"trace[1212386961] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"455.150085ms","start":"2026-06-16T00:25:11.105158Z","end":"2026-06-16T00:25:11.560308Z","steps":["trace[1212386961] 'process raft request' (duration: 453.487944ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:25:11.5638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:25:10.945853Z","time spent":"617.921186ms","remote":"127.0.0.1:46482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-16T00:25:11.563961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:25:11.105112Z","time spent":"458.686661ms","remote":"127.0.0.1:46560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T00:25:11.564276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:6903"} {"level":"info","ts":"2026-06-16T00:25:11.564322Z","caller":"traceutil/trace.go:171","msg":"trace[534254835] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:516; }","duration":"530.274643ms","start":"2026-06-16T00:25:11.034033Z","end":"2026-06-16T00:25:11.564308Z","steps":["trace[534254835] 'agreement among raft nodes before linearized reading' (duration: 530.130139ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:25:11.564345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.366552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-06-16T00:25:11.564351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:25:11.034025Z","time spent":"530.318873ms","remote":"127.0.0.1:46560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":1,"response size":6926,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-06-16T00:25:11.564379Z","caller":"traceutil/trace.go:171","msg":"trace[1113571888] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:516; }","duration":"496.425064ms","start":"2026-06-16T00:25:11.067944Z","end":"2026-06-16T00:25:11.564369Z","steps":["trace[1113571888] 'agreement among raft nodes before linearized reading' (duration: 496.315781ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:25:11.564403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:25:11.067929Z","time spent":"496.468905ms","remote":"127.0.0.1:46824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-16T00:25:17.849878Z","caller":"traceutil/trace.go:171","msg":"trace[801285875] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"201.653523ms","start":"2026-06-16T00:25:17.648182Z","end":"2026-06-16T00:25:17.849835Z","steps":["trace[801285875] 'process raft request' (duration: 201.377107ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:25:17.924054Z","caller":"traceutil/trace.go:171","msg":"trace[710162073] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"116.459529ms","start":"2026-06-16T00:25:17.807565Z","end":"2026-06-16T00:25:17.924025Z","steps":["trace[710162073] 'process raft request' (duration: 115.913445ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:25:18.229402Z","caller":"traceutil/trace.go:171","msg":"trace[46754244] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:618; }","duration":"161.369918ms","start":"2026-06-16T00:25:18.068007Z","end":"2026-06-16T00:25:18.229376Z","steps":["trace[46754244] 'read index received' (duration: 138.828126ms)","trace[46754244] 'applied index is now lower than readState.Index' (duration: 22.540822ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:25:18.229606Z","caller":"traceutil/trace.go:171","msg":"trace[1889007436] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"250.882679ms","start":"2026-06-16T00:25:17.97864Z","end":"2026-06-16T00:25:18.229522Z","steps":["trace[1889007436] 'process raft request' (duration: 228.246934ms)","trace[1889007436] 'compare' (duration: 22.352538ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:25:18.229675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.647835ms","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-06-16T00:25:18.229719Z","caller":"traceutil/trace.go:171","msg":"trace[266877376] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:598; }","duration":"161.747848ms","start":"2026-06-16T00:25:18.067958Z","end":"2026-06-16T00:25:18.229706Z","steps":["trace[266877376] 'agreement among raft nodes before linearized reading' (duration: 161.511422ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:25:18.961691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.980534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8mmsg\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-06-16T00:25:18.96177Z","caller":"traceutil/trace.go:171","msg":"trace[68985507] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8mmsg; range_end:; response_count:1; response_revision:604; }","duration":"476.117678ms","start":"2026-06-16T00:25:18.485635Z","end":"2026-06-16T00:25:18.961753Z","steps":["trace[68985507] 'range keys from in-memory index tree' (duration: 475.903362ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:25:18.961774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.698932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-06-16T00:25:18.961807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:25:18.485592Z","time spent":"476.20717ms","remote":"127.0.0.1:46722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-8mmsg\" "} {"level":"info","ts":"2026-06-16T00:25:18.961834Z","caller":"traceutil/trace.go:171","msg":"trace[1675114964] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:604; }","duration":"113.800114ms","start":"2026-06-16T00:25:18.848018Z","end":"2026-06-16T00:25:18.961818Z","steps":["trace[1675114964] 'range keys from in-memory index tree' (duration: 113.494296ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:25:19.280204Z","caller":"traceutil/trace.go:171","msg":"trace[1692206473] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"313.985041ms","start":"2026-06-16T00:25:18.966196Z","end":"2026-06-16T00:25:19.280181Z","steps":["trace[1692206473] 'process raft request' (duration: 313.830788ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:25:19.280457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:25:18.966177Z","time spent":"314.094013ms","remote":"127.0.0.1:46824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T00:25:19.313245Z","caller":"traceutil/trace.go:171","msg":"trace[2104743228] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"209.861513ms","start":"2026-06-16T00:25:19.103359Z","end":"2026-06-16T00:25:19.313221Z","steps":["trace[2104743228] 'process raft request' (duration: 209.789671ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:25:19.3133Z","caller":"traceutil/trace.go:171","msg":"trace[1338557389] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"338.022649ms","start":"2026-06-16T00:25:18.975237Z","end":"2026-06-16T00:25:19.313259Z","steps":["trace[1338557389] 'process raft request' (duration: 337.728302ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:25:19.313415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:25:18.97522Z","time spent":"338.136292ms","remote":"127.0.0.1:46722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T00:25:23.579578Z","caller":"traceutil/trace.go:171","msg":"trace[1910701012] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"139.293087ms","start":"2026-06-16T00:25:23.440244Z","end":"2026-06-16T00:25:23.579537Z","steps":["trace[1910701012] 'process raft request' (duration: 139.070141ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:25:23.710533Z","caller":"traceutil/trace.go:171","msg":"trace[658695993] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"111.16856ms","start":"2026-06-16T00:25:23.599337Z","end":"2026-06-16T00:25:23.710505Z","steps":["trace[658695993] 'process raft request' (duration: 110.999146ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:25:23.906964Z","caller":"traceutil/trace.go:171","msg":"trace[1753825411] linearizableReadLoop","detail":"{readStateIndex:695; appliedIndex:693; }","duration":"242.01192ms","start":"2026-06-16T00:25:23.664932Z","end":"2026-06-16T00:25:23.906944Z","steps":["trace[1753825411] 'read index received' (duration: 45.539924ms)","trace[1753825411] 'applied index is now lower than readState.Index' (duration: 196.471236ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:25:23.907041Z","caller":"traceutil/trace.go:171","msg":"trace[1671455882] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"261.384504ms","start":"2026-06-16T00:25:23.645632Z","end":"2026-06-16T00:25:23.907016Z","steps":["trace[1671455882] 'process raft request' (duration: 237.617623ms)","trace[1671455882] 'compare' (duration: 23.568516ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:25:23.907135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.200764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-16T00:25:23.907162Z","caller":"traceutil/trace.go:171","msg":"trace[1628200919] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:673; }","duration":"242.283936ms","start":"2026-06-16T00:25:23.664869Z","end":"2026-06-16T00:25:23.907153Z","steps":["trace[1628200919] 'agreement among raft nodes before linearized reading' (duration: 242.165944ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:25:23.950138Z","caller":"traceutil/trace.go:171","msg":"trace[1324073445] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"124.512337ms","start":"2026-06-16T00:25:23.825588Z","end":"2026-06-16T00:25:23.9501Z","steps":["trace[1324073445] 'process raft request' (duration: 124.264081ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:25:24.416934Z","caller":"traceutil/trace.go:171","msg":"trace[1424333264] linearizableReadLoop","detail":"{readStateIndex:703; appliedIndex:702; }","duration":"221.270523ms","start":"2026-06-16T00:25:24.195641Z","end":"2026-06-16T00:25:24.416912Z","steps":["trace[1424333264] 'read index received' (duration: 220.963596ms)","trace[1424333264] 'applied index is now lower than readState.Index' (duration: 306.047µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:25:24.416987Z","caller":"traceutil/trace.go:171","msg":"trace[111448606] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"237.790597ms","start":"2026-06-16T00:25:24.179161Z","end":"2026-06-16T00:25:24.416951Z","steps":["trace[111448606] 'process raft request' (duration: 237.52104ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:25:24.417286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.14322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-16T00:25:24.417366Z","caller":"traceutil/trace.go:171","msg":"trace[1427036868] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:681; }","duration":"107.269573ms","start":"2026-06-16T00:25:24.310076Z","end":"2026-06-16T00:25:24.417345Z","steps":["trace[1427036868] 'agreement among raft nodes before linearized reading' (duration: 107.053068ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:25:24.417647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.033222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-06-16T00:25:24.41776Z","caller":"traceutil/trace.go:171","msg":"trace[1421940713] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:681; }","duration":"222.210136ms","start":"2026-06-16T00:25:24.195532Z","end":"2026-06-16T00:25:24.417742Z","steps":["trace[1421940713] 'agreement among raft nodes before linearized reading' (duration: 221.499278ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:25:24.759171Z","caller":"traceutil/trace.go:171","msg":"trace[1644882140] linearizableReadLoop","detail":"{readStateIndex:707; appliedIndex:706; }","duration":"163.144182ms","start":"2026-06-16T00:25:24.596003Z","end":"2026-06-16T00:25:24.759147Z","steps":["trace[1644882140] 'read index received' (duration: 155.105526ms)","trace[1644882140] 'applied index is now lower than readState.Index' (duration: 8.037726ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:25:24.759367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.346507ms","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-06-16T00:25:24.759439Z","caller":"traceutil/trace.go:171","msg":"trace[766240449] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:685; }","duration":"163.44077ms","start":"2026-06-16T00:25:24.595968Z","end":"2026-06-16T00:25:24.759409Z","steps":["trace[766240449] 'agreement among raft nodes before linearized reading' (duration: 163.266156ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:25:24.759501Z","caller":"traceutil/trace.go:171","msg":"trace[1903081846] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"172.100591ms","start":"2026-06-16T00:25:24.587352Z","end":"2026-06-16T00:25:24.759452Z","steps":["trace[1903081846] 'process raft request' (duration: 163.740186ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:25:32.230804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.4594ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T00:25:32.230917Z","caller":"traceutil/trace.go:171","msg":"trace[22069707] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"195.797466ms","start":"2026-06-16T00:25:32.035101Z","end":"2026-06-16T00:25:32.230898Z","steps":["trace[22069707] 'process raft request' (duration: 63.939146ms)","trace[22069707] 'compare' (duration: 131.332946ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:25:35.245525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.912025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-c2lgr\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-06-16T00:25:35.245629Z","caller":"traceutil/trace.go:171","msg":"trace[1675496793] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-c2lgr; range_end:; response_count:1; response_revision:763; }","duration":"663.027688ms","start":"2026-06-16T00:25:34.582583Z","end":"2026-06-16T00:25:35.245611Z","steps":["trace[1675496793] 'range keys from in-memory index tree' (duration: 662.751721ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:25:35.245668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:25:34.582523Z","time spent":"663.13673ms","remote":"127.0.0.1:46722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-c2lgr\" "} {"level":"info","ts":"2026-06-16T00:25:35.436412Z","caller":"traceutil/trace.go:171","msg":"trace[1811359549] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"184.163985ms","start":"2026-06-16T00:25:35.25222Z","end":"2026-06-16T00:25:35.436384Z","steps":["trace[1811359549] 'process raft request' (duration: 183.698594ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:26:10.698835Z","caller":"traceutil/trace.go:171","msg":"trace[687700491] linearizableReadLoop","detail":"{readStateIndex:1016; appliedIndex:1015; }","duration":"279.383026ms","start":"2026-06-16T00:26:10.419432Z","end":"2026-06-16T00:26:10.698815Z","steps":["trace[687700491] 'read index received' (duration: 279.303704ms)","trace[687700491] 'applied index is now lower than readState.Index' (duration: 78.212µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:26:10.69891Z","caller":"traceutil/trace.go:171","msg":"trace[773231098] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"421.377972ms","start":"2026-06-16T00:26:10.2775Z","end":"2026-06-16T00:26:10.698878Z","steps":["trace[773231098] 'process raft request' (duration: 421.200507ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:26:10.69909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.641324ms","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-06-16T00:26:10.699137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:26:10.277477Z","time spent":"421.585197ms","remote":"127.0.0.1:46824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T00:26:10.699153Z","caller":"traceutil/trace.go:171","msg":"trace[1665316766] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:982; }","duration":"279.763977ms","start":"2026-06-16T00:26:10.419374Z","end":"2026-06-16T00:26:10.699138Z","steps":["trace[1665316766] 'agreement among raft nodes before linearized reading' (duration: 279.53156ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:26:11.199949Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125955418997304396,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-16T00:26:11.454552Z","caller":"traceutil/trace.go:171","msg":"trace[1798613569] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"924.925323ms","start":"2026-06-16T00:26:10.529606Z","end":"2026-06-16T00:26:11.454532Z","steps":["trace[1798613569] 'process raft request' (duration: 924.844551ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:26:11.454648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:26:10.529578Z","time spent":"925.022875ms","remote":"127.0.0.1:46824","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-06-16T00:26:11.502303Z","caller":"traceutil/trace.go:171","msg":"trace[1649426897] linearizableReadLoop","detail":"{readStateIndex:1018; appliedIndex:1016; }","duration":"803.369133ms","start":"2026-06-16T00:26:10.698901Z","end":"2026-06-16T00:26:11.50227Z","steps":["trace[1649426897] 'read index received' (duration: 755.541336ms)","trace[1649426897] 'applied index is now lower than readState.Index' (duration: 47.826627ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:26:11.502449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.94068ms","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-06-16T00:26:11.502508Z","caller":"traceutil/trace.go:171","msg":"trace[337422113] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:984; }","duration":"824.039242ms","start":"2026-06-16T00:26:10.678455Z","end":"2026-06-16T00:26:11.502495Z","steps":["trace[337422113] 'agreement among raft nodes before linearized reading' (duration: 823.834007ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:26:11.502526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.682908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-16T00:26:11.502553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:26:10.678438Z","time spent":"824.106164ms","remote":"127.0.0.1:46824","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":"info","ts":"2026-06-16T00:26:11.502561Z","caller":"traceutil/trace.go:171","msg":"trace[1384859920] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:984; }","duration":"559.745918ms","start":"2026-06-16T00:26:10.942802Z","end":"2026-06-16T00:26:11.502547Z","steps":["trace[1384859920] 'agreement among raft nodes before linearized reading' (duration: 559.701647ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:26:11.502596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:26:10.942782Z","time spent":"559.80606ms","remote":"127.0.0.1:46498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-16T00:26:11.502468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.776504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:26:11.502777Z","caller":"traceutil/trace.go:171","msg":"trace[946746910] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"799.601201ms","start":"2026-06-16T00:26:10.703165Z","end":"2026-06-16T00:26:11.502766Z","steps":["trace[946746910] 'process raft request' (duration: 798.960225ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:26:11.502777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.266627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-16T00:26:11.502857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:26:10.703144Z","time spent":"799.659853ms","remote":"127.0.0.1:46824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T00:26:11.50291Z","caller":"traceutil/trace.go:171","msg":"trace[345624399] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:984; }","duration":"867.470062ms","start":"2026-06-16T00:26:10.63541Z","end":"2026-06-16T00:26:11.50288Z","steps":["trace[345624399] 'agreement among raft nodes before linearized reading' (duration: 866.979771ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:26:11.502956Z","caller":"traceutil/trace.go:171","msg":"trace[1244910829] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:984; }","duration":"912.166433ms","start":"2026-06-16T00:26:10.590629Z","end":"2026-06-16T00:26:11.502795Z","steps":["trace[1244910829] 'agreement among raft nodes before linearized reading' (duration: 911.792164ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:26:11.503051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:26:10.63535Z","time spent":"867.688779ms","remote":"127.0.0.1:46824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-16T00:26:11.503166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:26:10.590604Z","time spent":"912.468412ms","remote":"127.0.0.1:46760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"info","ts":"2026-06-16T00:26:11.93036Z","caller":"traceutil/trace.go:171","msg":"trace[1981737974] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"421.206228ms","start":"2026-06-16T00:26:11.509126Z","end":"2026-06-16T00:26:11.930332Z","steps":["trace[1981737974] 'process raft request' (duration: 421.119286ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:26:11.93053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:26:11.509114Z","time spent":"421.33553ms","remote":"127.0.0.1:46824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T00:26:11.930753Z","caller":"traceutil/trace.go:171","msg":"trace[98055526] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"422.96898ms","start":"2026-06-16T00:26:11.507771Z","end":"2026-06-16T00:26:11.93074Z","steps":["trace[98055526] 'process raft request' (duration: 352.801133ms)","trace[98055526] 'compare' (duration: 69.480531ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:26:11.930809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:26:11.50775Z","time spent":"423.025762ms","remote":"127.0.0.1:46824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T00:26:11.933477Z","caller":"traceutil/trace.go:171","msg":"trace[1826336837] linearizableReadLoop","detail":"{readStateIndex:1020; appliedIndex:1018; }","duration":"247.032635ms","start":"2026-06-16T00:26:11.68641Z","end":"2026-06-16T00:26:11.933443Z","steps":["trace[1826336837] 'read index received' (duration: 174.284615ms)","trace[1826336837] 'applied index is now lower than readState.Index' (duration: 72.74671ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:26:11.933699Z","caller":"traceutil/trace.go:171","msg":"trace[431009945] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"118.812183ms","start":"2026-06-16T00:26:11.81473Z","end":"2026-06-16T00:26:11.933542Z","steps":["trace[431009945] 'process raft request' (duration: 118.503776ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:26:11.934234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.2662ms","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-06-16T00:26:11.934289Z","caller":"traceutil/trace.go:171","msg":"trace[1279174304] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:987; }","duration":"247.913776ms","start":"2026-06-16T00:26:11.686362Z","end":"2026-06-16T00:26:11.934276Z","steps":["trace[1279174304] 'agreement among raft nodes before linearized reading' (duration: 247.196519ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:26:16.171504Z","caller":"traceutil/trace.go:171","msg":"trace[2081332015] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"134.553674ms","start":"2026-06-16T00:26:16.036917Z","end":"2026-06-16T00:26:16.171471Z","steps":["trace[2081332015] 'process raft request' (duration: 105.645595ms)","trace[2081332015] 'compare' (duration: 28.794717ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:26:38.054123Z","caller":"traceutil/trace.go:171","msg":"trace[1995193309] linearizableReadLoop","detail":"{readStateIndex:1109; appliedIndex:1108; }","duration":"110.532907ms","start":"2026-06-16T00:26:37.943565Z","end":"2026-06-16T00:26:38.054098Z","steps":["trace[1995193309] 'read index received' (duration: 110.436885ms)","trace[1995193309] 'applied index is now lower than readState.Index' (duration: 94.802µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:26:38.05423Z","caller":"traceutil/trace.go:171","msg":"trace[918559837] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"414.220524ms","start":"2026-06-16T00:26:37.63998Z","end":"2026-06-16T00:26:38.0542Z","steps":["trace[918559837] 'process raft request' (duration: 413.972378ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:26:38.054255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.66634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:26:38.054305Z","caller":"traceutil/trace.go:171","msg":"trace[603830658] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1069; }","duration":"110.791583ms","start":"2026-06-16T00:26:37.943503Z","end":"2026-06-16T00:26:38.054294Z","steps":["trace[603830658] 'agreement among raft nodes before linearized reading' (duration: 110.696381ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:26:38.054535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:26:37.639968Z","time spent":"414.329787ms","remote":"127.0.0.1:46824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T00:26:38.376521Z","caller":"traceutil/trace.go:171","msg":"trace[2027015619] linearizableReadLoop","detail":"{readStateIndex:1110; appliedIndex:1109; }","duration":"321.046877ms","start":"2026-06-16T00:26:38.055454Z","end":"2026-06-16T00:26:38.376501Z","steps":["trace[2027015619] 'read index received' (duration: 239.763645ms)","trace[2027015619] 'applied index is now lower than readState.Index' (duration: 81.282182ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:26:38.376601Z","caller":"traceutil/trace.go:171","msg":"trace[591446861] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"405.244118ms","start":"2026-06-16T00:26:37.971325Z","end":"2026-06-16T00:26:38.376569Z","steps":["trace[591446861] 'process raft request' (duration: 323.952467ms)","trace[591446861] 'compare' (duration: 81.046205ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:26:38.376634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.16673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:26:38.376663Z","caller":"traceutil/trace.go:171","msg":"trace[1828609548] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1070; }","duration":"321.234481ms","start":"2026-06-16T00:26:38.055422Z","end":"2026-06-16T00:26:38.376656Z","steps":["trace[1828609548] 'agreement among raft nodes before linearized reading' (duration: 321.17676ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:26:38.376684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:26:38.055408Z","time spent":"321.271193ms","remote":"127.0.0.1:46498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-16T00:26:38.376739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:26:37.971219Z","time spent":"405.442553ms","remote":"127.0.0.1:46824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T00:26:38.377672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.969788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T00:26:38.377715Z","caller":"traceutil/trace.go:171","msg":"trace[625547283] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1071; }","duration":"254.05143ms","start":"2026-06-16T00:26:38.123651Z","end":"2026-06-16T00:26:38.377702Z","steps":["trace[625547283] 'agreement among raft nodes before linearized reading' (duration: 253.975328ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:26:38.377802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.817079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-06-16T00:26:38.377845Z","caller":"traceutil/trace.go:171","msg":"trace[496658477] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1071; }","duration":"116.88524ms","start":"2026-06-16T00:26:38.260948Z","end":"2026-06-16T00:26:38.377833Z","steps":["trace[496658477] 'agreement among raft nodes before linearized reading' (duration: 116.699856ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:26:38.37791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.775752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:26:38.377949Z","caller":"traceutil/trace.go:171","msg":"trace[590907493] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1071; }","duration":"151.849804ms","start":"2026-06-16T00:26:38.226087Z","end":"2026-06-16T00:26:38.377936Z","steps":["trace[590907493] 'agreement among raft nodes before linearized reading' (duration: 151.787522ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:26:38.377971Z","caller":"traceutil/trace.go:171","msg":"trace[641406774] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"317.746356ms","start":"2026-06-16T00:26:38.060213Z","end":"2026-06-16T00:26:38.377959Z","steps":["trace[641406774] 'process raft request' (duration: 317.338797ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:26:38.37805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:26:38.06019Z","time spent":"317.813928ms","remote":"127.0.0.1:46824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T00:26:38.378197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.191876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-16T00:26:38.378229Z","caller":"traceutil/trace.go:171","msg":"trace[58982822] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1071; }","duration":"228.254538ms","start":"2026-06-16T00:26:38.149965Z","end":"2026-06-16T00:26:38.37822Z","steps":["trace[58982822] 'agreement among raft nodes before linearized reading' (duration: 228.124795ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:26:38.378244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.337528ms","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-06-16T00:26:38.378279Z","caller":"traceutil/trace.go:171","msg":"trace[194875892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1071; }","duration":"230.40993ms","start":"2026-06-16T00:26:38.147859Z","end":"2026-06-16T00:26:38.378269Z","steps":["trace[194875892] 'agreement among raft nodes before linearized reading' (duration: 230.314888ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:26:39.037735Z","caller":"traceutil/trace.go:171","msg":"trace[1591190414] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"148.166125ms","start":"2026-06-16T00:26:38.889544Z","end":"2026-06-16T00:26:39.03771Z","steps":["trace[1591190414] 'process raft request' (duration: 148.013961ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:26:40.173922Z","caller":"traceutil/trace.go:171","msg":"trace[1023770284] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"111.889919ms","start":"2026-06-16T00:26:40.062005Z","end":"2026-06-16T00:26:40.173895Z","steps":["trace[1023770284] 'process raft request' (duration: 111.757756ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:26:42.31755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.479314ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-16T00:26:42.317802Z","caller":"traceutil/trace.go:171","msg":"trace[398689579] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"135.421368ms","start":"2026-06-16T00:26:42.182361Z","end":"2026-06-16T00:26:42.317782Z","steps":["trace[398689579] 'process raft request' (duration: 135.300815ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:26:42.317862Z","caller":"traceutil/trace.go:171","msg":"trace[1661085919] linearizableReadLoop","detail":"{readStateIndex:1122; appliedIndex:1121; }","duration":"441.017161ms","start":"2026-06-16T00:26:41.876778Z","end":"2026-06-16T00:26:42.317796Z","steps":["trace[1661085919] 'read index received' (duration: 192.229559ms)","trace[1661085919] 'applied index is now lower than readState.Index' (duration: 248.783262ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:26:42.318051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.265787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T00:26:42.318109Z","caller":"traceutil/trace.go:171","msg":"trace[950066235] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1082; }","duration":"441.366179ms","start":"2026-06-16T00:26:41.876728Z","end":"2026-06-16T00:26:42.318094Z","steps":["trace[950066235] 'agreement among raft nodes before linearized reading' (duration: 441.271257ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:26:42.318145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:26:41.876707Z","time spent":"441.428892ms","remote":"127.0.0.1:46650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-06-16T00:26:42.318168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.049795ms","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-06-16T00:26:42.318208Z","caller":"traceutil/trace.go:171","msg":"trace[1634055683] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1082; }","duration":"139.132827ms","start":"2026-06-16T00:26:42.179063Z","end":"2026-06-16T00:26:42.318196Z","steps":["trace[1634055683] 'agreement among raft nodes before linearized reading' (duration: 138.954442ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:26:56.908616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.49562ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T00:26:56.908776Z","caller":"traceutil/trace.go:171","msg":"trace[81781002] linearizableReadLoop","detail":"{readStateIndex:1227; appliedIndex:1225; }","duration":"233.812652ms","start":"2026-06-16T00:26:56.674951Z","end":"2026-06-16T00:26:56.908763Z","steps":["trace[81781002] 'read index received' (duration: 127.117808ms)","trace[81781002] 'applied index is now lower than readState.Index' (duration: 106.693794ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:26:56.90886Z","caller":"traceutil/trace.go:171","msg":"trace[1826741530] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"314.711874ms","start":"2026-06-16T00:26:56.594126Z","end":"2026-06-16T00:26:56.908838Z","steps":["trace[1826741530] 'process raft request' (duration: 207.930947ms)","trace[1826741530] 'compare' (duration: 106.365057ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:26:56.908912Z","caller":"traceutil/trace.go:171","msg":"trace[173662482] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"298.518613ms","start":"2026-06-16T00:26:56.610384Z","end":"2026-06-16T00:26:56.908902Z","steps":["trace[173662482] 'process raft request' (duration: 298.313668ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:26:56.908966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:26:56.594107Z","time spent":"314.803465ms","remote":"127.0.0.1:46824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T00:26:56.909146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.193042ms","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-06-16T00:26:56.909169Z","caller":"traceutil/trace.go:171","msg":"trace[1008391872] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1181; }","duration":"234.250642ms","start":"2026-06-16T00:26:56.674912Z","end":"2026-06-16T00:26:56.909163Z","steps":["trace[1008391872] 'agreement among raft nodes before linearized reading' (duration: 234.14161ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:26:56.909238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.483838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-16T00:26:56.909263Z","caller":"traceutil/trace.go:171","msg":"trace[2081736484] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1181; }","duration":"217.53905ms","start":"2026-06-16T00:26:56.691717Z","end":"2026-06-16T00:26:56.909256Z","steps":["trace[2081736484] 'agreement among raft nodes before linearized reading' (duration: 217.438027ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:26:56.90935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.198863ms","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-06-16T00:26:56.909368Z","caller":"traceutil/trace.go:171","msg":"trace[2072136646] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1181; }","duration":"215.234534ms","start":"2026-06-16T00:26:56.694126Z","end":"2026-06-16T00:26:56.90936Z","steps":["trace[2072136646] 'agreement among raft nodes before linearized reading' (duration: 215.189633ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:26:58.607786Z","caller":"traceutil/trace.go:171","msg":"trace[1636295392] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"181.673793ms","start":"2026-06-16T00:26:58.42609Z","end":"2026-06-16T00:26:58.607764Z","steps":["trace[1636295392] 'process raft request' (duration: 181.5613ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:27:11.187583Z","caller":"traceutil/trace.go:171","msg":"trace[569615994] linearizableReadLoop","detail":"{readStateIndex:1275; appliedIndex:1274; }","duration":"182.727118ms","start":"2026-06-16T00:27:11.00482Z","end":"2026-06-16T00:27:11.187547Z","steps":["trace[569615994] 'read index received' (duration: 182.35248ms)","trace[569615994] 'applied index is now lower than readState.Index' (duration: 372.988µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:27:11.187844Z","caller":"traceutil/trace.go:171","msg":"trace[1273688593] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"216.912273ms","start":"2026-06-16T00:27:10.970873Z","end":"2026-06-16T00:27:11.187786Z","steps":["trace[1273688593] 'process raft request' (duration: 216.445682ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:27:11.188194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.339603ms","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-06-16T00:27:11.188251Z","caller":"traceutil/trace.go:171","msg":"trace[2083904192] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1227; }","duration":"183.456046ms","start":"2026-06-16T00:27:11.004778Z","end":"2026-06-16T00:27:11.188234Z","steps":["trace[2083904192] 'agreement among raft nodes before linearized reading' (duration: 183.001135ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:27:17.128887Z","caller":"traceutil/trace.go:171","msg":"trace[1749676454] linearizableReadLoop","detail":"{readStateIndex:1317; appliedIndex:1316; }","duration":"403.379934ms","start":"2026-06-16T00:27:16.725485Z","end":"2026-06-16T00:27:17.128865Z","steps":["trace[1749676454] 'read index received' (duration: 403.21177ms)","trace[1749676454] 'applied index is now lower than readState.Index' (duration: 166.964µs)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:27:17.129062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.559878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-16T00:27:17.129097Z","caller":"traceutil/trace.go:171","msg":"trace[948324504] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:1267; }","duration":"403.632019ms","start":"2026-06-16T00:27:16.725456Z","end":"2026-06-16T00:27:17.129088Z","steps":["trace[948324504] 'agreement among raft nodes before linearized reading' (duration: 403.494696ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:27:17.129131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:27:16.725446Z","time spent":"403.67597ms","remote":"127.0.0.1:46552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-06-16T00:27:17.129279Z","caller":"traceutil/trace.go:171","msg":"trace[68533819] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"403.913067ms","start":"2026-06-16T00:27:16.725303Z","end":"2026-06-16T00:27:17.129216Z","steps":["trace[68533819] 'process raft request' (duration: 403.425315ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:27:17.12944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:27:16.725287Z","time spent":"404.06345ms","remote":"127.0.0.1:46824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T00:27:17.747042Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125955418997305075,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-16T00:27:17.888872Z","caller":"traceutil/trace.go:171","msg":"trace[1392211358] linearizableReadLoop","detail":"{readStateIndex:1318; appliedIndex:1317; }","duration":"642.592545ms","start":"2026-06-16T00:27:17.246255Z","end":"2026-06-16T00:27:17.888847Z","steps":["trace[1392211358] 'read index received' (duration: 545.529983ms)","trace[1392211358] 'applied index is now lower than readState.Index' (duration: 97.061252ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:27:17.888918Z","caller":"traceutil/trace.go:171","msg":"trace[41420949] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"662.077775ms","start":"2026-06-16T00:27:17.226807Z","end":"2026-06-16T00:27:17.888885Z","steps":["trace[41420949] 'process raft request' (duration: 565.086354ms)","trace[41420949] 'compare' (duration: 96.574181ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:27:17.889092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.81958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-16T00:27:17.889128Z","caller":"traceutil/trace.go:171","msg":"trace[472309706] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1268; }","duration":"642.899812ms","start":"2026-06-16T00:27:17.246218Z","end":"2026-06-16T00:27:17.889118Z","steps":["trace[472309706] 'agreement among raft nodes before linearized reading' (duration: 642.755659ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:27:17.889117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:27:17.226777Z","time spent":"662.217829ms","remote":"127.0.0.1:46824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T00:27:17.889163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:27:17.246199Z","time spent":"642.955894ms","remote":"127.0.0.1:46824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-16T00:27:17.889262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.781556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-06-16T00:27:17.889357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.402877ms","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-06-16T00:27:17.889354Z","caller":"traceutil/trace.go:171","msg":"trace[2137952717] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1268; }","duration":"641.897528ms","start":"2026-06-16T00:27:17.247431Z","end":"2026-06-16T00:27:17.889329Z","steps":["trace[2137952717] 'agreement among raft nodes before linearized reading' (duration: 641.655883ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:27:17.889388Z","caller":"traceutil/trace.go:171","msg":"trace[350586057] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1268; }","duration":"641.452008ms","start":"2026-06-16T00:27:17.247927Z","end":"2026-06-16T00:27:17.889379Z","steps":["trace[350586057] 'agreement among raft nodes before linearized reading' (duration: 641.363516ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:27:17.889412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:27:17.247918Z","time spent":"641.488209ms","remote":"127.0.0.1:46824","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-06-16T00:27:17.889409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:27:17.247422Z","time spent":"641.9741ms","remote":"127.0.0.1:46824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-16T00:27:17.889405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.347974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-16T00:27:17.889426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.617935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:27:17.889459Z","caller":"traceutil/trace.go:171","msg":"trace[885164308] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1268; }","duration":"556.447166ms","start":"2026-06-16T00:27:17.332999Z","end":"2026-06-16T00:27:17.889446Z","steps":["trace[885164308] 'agreement among raft nodes before linearized reading' (duration: 556.273752ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:27:17.889478Z","caller":"traceutil/trace.go:171","msg":"trace[428237558] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1268; }","duration":"407.698488ms","start":"2026-06-16T00:27:17.481763Z","end":"2026-06-16T00:27:17.889461Z","steps":["trace[428237558] 'agreement among raft nodes before linearized reading' (duration: 407.616946ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:27:17.889513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:27:17.481748Z","time spent":"407.756439ms","remote":"127.0.0.1:46876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-06-16T00:27:17.889515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:27:17.332978Z","time spent":"556.521068ms","remote":"127.0.0.1:46824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-16T00:27:18.22545Z","caller":"traceutil/trace.go:171","msg":"trace[2129597930] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"105.543686ms","start":"2026-06-16T00:27:18.119876Z","end":"2026-06-16T00:27:18.225419Z","steps":["trace[2129597930] 'process raft request' (duration: 105.372722ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:27:18.465645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.074122ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-16T00:27:18.465934Z","caller":"traceutil/trace.go:171","msg":"trace[1834741435] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"233.926964ms","start":"2026-06-16T00:27:18.231984Z","end":"2026-06-16T00:27:18.465911Z","steps":["trace[1834741435] 'process raft request' (duration: 56.529934ms)","trace[1834741435] 'compare' (duration: 176.95819ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:27:18.531704Z","caller":"traceutil/trace.go:171","msg":"trace[1472576100] linearizableReadLoop","detail":"{readStateIndex:1325; appliedIndex:1323; }","duration":"271.051481ms","start":"2026-06-16T00:27:18.260626Z","end":"2026-06-16T00:27:18.531677Z","steps":["trace[1472576100] 'read index received' (duration: 27.938454ms)","trace[1472576100] 'applied index is now lower than readState.Index' (duration: 243.111817ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:27:18.531818Z","caller":"traceutil/trace.go:171","msg":"trace[1146483386] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"299.60063ms","start":"2026-06-16T00:27:18.232205Z","end":"2026-06-16T00:27:18.531805Z","steps":["trace[1146483386] 'process raft request' (duration: 299.295092ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:27:18.532137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.494881ms","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-06-16T00:27:18.532176Z","caller":"traceutil/trace.go:171","msg":"trace[1963121273] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1275; }","duration":"271.583193ms","start":"2026-06-16T00:27:18.26058Z","end":"2026-06-16T00:27:18.532164Z","steps":["trace[1963121273] 'agreement among raft nodes before linearized reading' (duration: 271.478091ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:27:18.829681Z","caller":"traceutil/trace.go:171","msg":"trace[1985281340] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"293.061431ms","start":"2026-06-16T00:27:18.536596Z","end":"2026-06-16T00:27:18.829657Z","steps":["trace[1985281340] 'process raft request' (duration: 292.659212ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:27:18.835972Z","caller":"traceutil/trace.go:171","msg":"trace[202834359] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"273.104949ms","start":"2026-06-16T00:27:18.562844Z","end":"2026-06-16T00:27:18.835949Z","steps":["trace[202834359] 'process raft request' (duration: 272.977156ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:27:28.924428Z","caller":"traceutil/trace.go:171","msg":"trace[607476038] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"161.21867ms","start":"2026-06-16T00:27:28.763184Z","end":"2026-06-16T00:27:28.924403Z","steps":["trace[607476038] 'process raft request' (duration: 161.093737ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:27:29.783194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.681575ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T00:27:29.78342Z","caller":"traceutil/trace.go:171","msg":"trace[1306896275] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"854.454988ms","start":"2026-06-16T00:27:28.928928Z","end":"2026-06-16T00:27:29.783383Z","steps":["trace[1306896275] 'process raft request' (duration: 701.479496ms)","trace[1306896275] 'compare' (duration: 152.545941ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:27:29.783791Z","caller":"traceutil/trace.go:171","msg":"trace[1118081898] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"474.12625ms","start":"2026-06-16T00:27:29.309625Z","end":"2026-06-16T00:27:29.783751Z","steps":["trace[1118081898] 'process raft request' (duration: 473.69077ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:27:29.78382Z","caller":"traceutil/trace.go:171","msg":"trace[1052756368] linearizableReadLoop","detail":"{readStateIndex:1403; appliedIndex:1402; }","duration":"605.415698ms","start":"2026-06-16T00:27:29.178372Z","end":"2026-06-16T00:27:29.783788Z","steps":["trace[1052756368] 'read index received' (duration: 452.029287ms)","trace[1052756368] 'applied index is now lower than readState.Index' (duration: 153.380641ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:27:29.783598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:27:28.928909Z","time spent":"854.590681ms","remote":"127.0.0.1:46824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T00:27:29.784039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.479533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T00:27:29.784116Z","caller":"traceutil/trace.go:171","msg":"trace[1579227261] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1352; }","duration":"124.619677ms","start":"2026-06-16T00:27:29.659477Z","end":"2026-06-16T00:27:29.784097Z","steps":["trace[1579227261] 'agreement among raft nodes before linearized reading' (duration: 124.471473ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:27:29.784068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.690504ms","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-06-16T00:27:29.784279Z","caller":"traceutil/trace.go:171","msg":"trace[237714811] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1352; }","duration":"605.959561ms","start":"2026-06-16T00:27:29.17831Z","end":"2026-06-16T00:27:29.784269Z","steps":["trace[237714811] 'agreement among raft nodes before linearized reading' (duration: 605.613463ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:27:29.784335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:27:29.178286Z","time spent":"606.039943ms","remote":"127.0.0.1:46824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-16T00:27:29.784297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:27:29.309602Z","time spent":"474.249343ms","remote":"127.0.0.1:46824","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-06-16T00:28:42.871051Z","caller":"traceutil/trace.go:171","msg":"trace[704301841] linearizableReadLoop","detail":"{readStateIndex:1755; appliedIndex:1754; }","duration":"374.091501ms","start":"2026-06-16T00:28:42.496928Z","end":"2026-06-16T00:28:42.87102Z","steps":["trace[704301841] 'read index received' (duration: 322.574885ms)","trace[704301841] 'applied index is now lower than readState.Index' (duration: 51.515166ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:28:42.871257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.318266ms","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-06-16T00:28:42.871296Z","caller":"traceutil/trace.go:171","msg":"trace[469021868] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1688; }","duration":"374.397998ms","start":"2026-06-16T00:28:42.496887Z","end":"2026-06-16T00:28:42.871285Z","steps":["trace[469021868] 'agreement among raft nodes before linearized reading' (duration: 374.247374ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:28:42.871337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:28:42.496868Z","time spent":"374.460859ms","remote":"127.0.0.1:46824","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-06-16T00:28:42.871372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.051936ms","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-06-16T00:28:42.87143Z","caller":"traceutil/trace.go:171","msg":"trace[473931853] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1688; }","duration":"371.138488ms","start":"2026-06-16T00:28:42.500276Z","end":"2026-06-16T00:28:42.871414Z","steps":["trace[473931853] 'agreement among raft nodes before linearized reading' (duration: 370.976964ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:28:42.871494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:28:42.500262Z","time spent":"371.219201ms","remote":"127.0.0.1:46824","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-06-16T00:28:42.87167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.337093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-16T00:28:42.871702Z","caller":"traceutil/trace.go:171","msg":"trace[441292662] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1688; }","duration":"371.397665ms","start":"2026-06-16T00:28:42.500296Z","end":"2026-06-16T00:28:42.871693Z","steps":["trace[441292662] 'agreement among raft nodes before linearized reading' (duration: 371.281672ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:28:42.871729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:28:42.500275Z","time spent":"371.447465ms","remote":"127.0.0.1:46824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-16T00:28:42.871086Z","caller":"traceutil/trace.go:171","msg":"trace[723241680] transaction","detail":"{read_only:false; response_revision:1688; number_of_response:1; }","duration":"406.905487ms","start":"2026-06-16T00:28:42.464133Z","end":"2026-06-16T00:28:42.871038Z","steps":["trace[723241680] 'process raft request' (duration: 355.463253ms)","trace[723241680] 'compare' (duration: 51.222769ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:28:42.87201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:28:42.464096Z","time spent":"407.8577ms","remote":"127.0.0.1:46824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}