{"level":"warn","ts":"2026-07-27T13:12:13.686751Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-27T13:12:13.686866Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.86:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.86:2380","--initial-cluster=instance=https://199.19.213.86:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.86:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.86: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-07-27T13:12:13.686955Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-27T13:12:13.686974Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.86:2380"]} {"level":"info","ts":"2026-07-27T13:12:13.687002Z","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-07-27T13:12:13.687859Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.86:2379"]} {"level":"info","ts":"2026-07-27T13:12:13.687961Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.86:2380"],"listen-peer-urls":["https://199.19.213.86:2380"],"advertise-client-urls":["https://199.19.213.86:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.86:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.86: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-07-27T13:12:13.692547Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.296013ms"} {"level":"info","ts":"2026-07-27T13:12:13.702718Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"849a6ffb5e81c109","cluster-id":"d4d86b1dd8829199"} {"level":"info","ts":"2026-07-27T13:12:13.703011Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 switched to configuration voters=()"} {"level":"info","ts":"2026-07-27T13:12:13.703041Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became follower at term 0"} {"level":"info","ts":"2026-07-27T13:12:13.70307Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 849a6ffb5e81c109 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-27T13:12:13.703084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became follower at term 1"} {"level":"info","ts":"2026-07-27T13:12:13.70313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 switched to configuration voters=(9555072684832964873)"} {"level":"warn","ts":"2026-07-27T13:12:13.71262Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-27T13:12:13.714452Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-27T13:12:13.71555Z","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-07-27T13:12:13.717851Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"849a6ffb5e81c109","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-27T13:12:13.718105Z","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-07-27T13:12:13.718212Z","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-07-27T13:12:13.718307Z","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-07-27T13:12:13.718422Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"849a6ffb5e81c109","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-27T13:12:13.719539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 switched to configuration voters=(9555072684832964873)"} {"level":"info","ts":"2026-07-27T13:12:13.719756Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d4d86b1dd8829199","local-member-id":"849a6ffb5e81c109","added-peer-id":"849a6ffb5e81c109","added-peer-peer-urls":["https://199.19.213.86:2380"]} {"level":"info","ts":"2026-07-27T13:12:13.72242Z","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-07-27T13:12:13.722951Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"849a6ffb5e81c109","initial-advertise-peer-urls":["https://199.19.213.86:2380"],"listen-peer-urls":["https://199.19.213.86:2380"],"advertise-client-urls":["https://199.19.213.86:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.86:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-27T13:12:13.722983Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-27T13:12:13.724243Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.86:2380"} {"level":"info","ts":"2026-07-27T13:12:13.724261Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.86:2380"} {"level":"info","ts":"2026-07-27T13:12:13.80409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 is starting a new election at term 1"} {"level":"info","ts":"2026-07-27T13:12:13.80416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-27T13:12:13.804184Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 received MsgPreVoteResp from 849a6ffb5e81c109 at term 1"} {"level":"info","ts":"2026-07-27T13:12:13.804203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became candidate at term 2"} {"level":"info","ts":"2026-07-27T13:12:13.804216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 received MsgVoteResp from 849a6ffb5e81c109 at term 2"} {"level":"info","ts":"2026-07-27T13:12:13.804234Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became leader at term 2"} {"level":"info","ts":"2026-07-27T13:12:13.80425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 849a6ffb5e81c109 elected leader 849a6ffb5e81c109 at term 2"} {"level":"info","ts":"2026-07-27T13:12:13.805071Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-27T13:12:13.806023Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"849a6ffb5e81c109","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.86:2379]}","request-path":"/0/members/849a6ffb5e81c109/attributes","cluster-id":"d4d86b1dd8829199","publish-timeout":"7s"} {"level":"info","ts":"2026-07-27T13:12:13.806178Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-27T13:12:13.806251Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-27T13:12:13.809044Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.86:2379"} {"level":"info","ts":"2026-07-27T13:12:13.809441Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-27T13:12:13.809461Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-27T13:12:13.809891Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d4d86b1dd8829199","local-member-id":"849a6ffb5e81c109","cluster-version":"3.5"} {"level":"info","ts":"2026-07-27T13:12:13.810096Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-27T13:12:13.810177Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-27T13:12:13.817205Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-27T13:12:15.575413Z","caller":"traceutil/trace.go:171","msg":"trace[785685508] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"144.480586ms","start":"2026-07-27T13:12:15.430913Z","end":"2026-07-27T13:12:15.575393Z","steps":["trace[785685508] 'process raft request' (duration: 144.305511ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:15.962968Z","caller":"traceutil/trace.go:171","msg":"trace[1248464793] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"530.522409ms","start":"2026-07-27T13:12:15.432418Z","end":"2026-07-27T13:12:15.96294Z","steps":["trace[1248464793] 'process raft request' (duration: 530.247566ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:15.963062Z","caller":"traceutil/trace.go:171","msg":"trace[1365942975] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"470.855455ms","start":"2026-07-27T13:12:15.492189Z","end":"2026-07-27T13:12:15.963044Z","steps":["trace[1365942975] 'process raft request' (duration: 470.742273ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:15.963136Z","caller":"traceutil/trace.go:171","msg":"trace[913597598] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"469.214661ms","start":"2026-07-27T13:12:15.493912Z","end":"2026-07-27T13:12:15.963127Z","steps":["trace[913597598] 'process raft request' (duration: 469.046839ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:15.963226Z","caller":"traceutil/trace.go:171","msg":"trace[949919761] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"471.163244ms","start":"2026-07-27T13:12:15.492053Z","end":"2026-07-27T13:12:15.963217Z","steps":["trace[949919761] 'process raft request' (duration: 470.781971ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:15.963084Z","caller":"traceutil/trace.go:171","msg":"trace[1108548334] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"470.893242ms","start":"2026-07-27T13:12:15.492138Z","end":"2026-07-27T13:12:15.963032Z","steps":["trace[1108548334] 'process raft request' (duration: 470.72576ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:15.963198Z","caller":"traceutil/trace.go:171","msg":"trace[992657747] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"471.631534ms","start":"2026-07-27T13:12:15.491499Z","end":"2026-07-27T13:12:15.963131Z","steps":["trace[992657747] 'process raft request' (duration: 471.304161ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:15.96343Z","caller":"traceutil/trace.go:171","msg":"trace[1087307132] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"468.961161ms","start":"2026-07-27T13:12:15.494418Z","end":"2026-07-27T13:12:15.963379Z","steps":["trace[1087307132] 'process raft request' (duration: 468.564785ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:15.963566Z","caller":"traceutil/trace.go:171","msg":"trace[1015336760] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"443.694617ms","start":"2026-07-27T13:12:15.519857Z","end":"2026-07-27T13:12:15.963551Z","steps":["trace[1015336760] 'process raft request' (duration: 443.438838ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:15.963611Z","caller":"traceutil/trace.go:171","msg":"trace[490122142] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"450.179684ms","start":"2026-07-27T13:12:15.513422Z","end":"2026-07-27T13:12:15.963602Z","steps":["trace[490122142] 'process raft request' (duration: 449.580189ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:15.963743Z","caller":"traceutil/trace.go:171","msg":"trace[1787714764] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"527.648005ms","start":"2026-07-27T13:12:15.435859Z","end":"2026-07-27T13:12:15.963507Z","steps":["trace[1787714764] 'read index received' (duration: 139.375014ms)","trace[1787714764] 'applied index is now lower than readState.Index' (duration: 388.272071ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T13:12:15.965543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.850938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-27T13:12:15.965547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.704132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-27T13:12:15.965604Z","caller":"traceutil/trace.go:171","msg":"trace[2101138451] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"472.955489ms","start":"2026-07-27T13:12:15.492638Z","end":"2026-07-27T13:12:15.965594Z","steps":["trace[2101138451] 'agreement among raft nodes before linearized reading' (duration: 471.530234ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:15.965645Z","caller":"traceutil/trace.go:171","msg":"trace[2039776453] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"489.389506ms","start":"2026-07-27T13:12:15.476235Z","end":"2026-07-27T13:12:15.965624Z","steps":["trace[2039776453] 'agreement among raft nodes before linearized reading' (duration: 487.594211ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:12:15.965635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.756971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-27T13:12:15.965662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:12:15.492622Z","time spent":"473.033043ms","remote":"127.0.0.1:42754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-07-27T13:12:15.965703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:12:15.476214Z","time spent":"489.474003ms","remote":"127.0.0.1:42754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-07-27T13:12:15.96572Z","caller":"traceutil/trace.go:171","msg":"trace[967865914] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"529.875273ms","start":"2026-07-27T13:12:15.435824Z","end":"2026-07-27T13:12:15.965699Z","steps":["trace[967865914] 'agreement among raft nodes before linearized reading' (duration: 527.966616ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:12:15.965769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:12:15.435765Z","time spent":"529.992406ms","remote":"127.0.0.1:42994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-07-27T13:12:15.96585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.102763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-27T13:12:15.965882Z","caller":"traceutil/trace.go:171","msg":"trace[1136912196] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:11; }","duration":"400.161344ms","start":"2026-07-27T13:12:15.565713Z","end":"2026-07-27T13:12:15.965874Z","steps":["trace[1136912196] 'agreement among raft nodes before linearized reading' (duration: 400.115515ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:12:15.965908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:12:15.565695Z","time spent":"400.208603ms","remote":"127.0.0.1:42754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/default\" "} {"level":"warn","ts":"2026-07-27T13:12:15.965924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:12:15.432398Z","time spent":"530.642252ms","remote":"127.0.0.1:42704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T13:12:15.965962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.822242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-27T13:12:15.965977Z","caller":"traceutil/trace.go:171","msg":"trace[333147930] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"420.86035ms","start":"2026-07-27T13:12:15.545111Z","end":"2026-07-27T13:12:15.965972Z","steps":["trace[333147930] 'agreement among raft nodes before linearized reading' (duration: 420.837386ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:12:15.965991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:12:15.545095Z","time spent":"420.892826ms","remote":"127.0.0.1:42754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-07-27T13:12:15.966049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.676917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.86\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-27T13:12:15.966094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.193612ms","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-07-27T13:12:15.966125Z","caller":"traceutil/trace.go:171","msg":"trace[1029245264] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"472.245062ms","start":"2026-07-27T13:12:15.493864Z","end":"2026-07-27T13:12:15.966109Z","steps":["trace[1029245264] 'agreement among raft nodes before linearized reading' (duration: 472.210076ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:15.966089Z","caller":"traceutil/trace.go:171","msg":"trace[2138894904] range","detail":"{range_begin:/registry/masterleases/199.19.213.86; range_end:; response_count:0; response_revision:11; }","duration":"473.715884ms","start":"2026-07-27T13:12:15.492362Z","end":"2026-07-27T13:12:15.966078Z","steps":["trace[2138894904] 'agreement among raft nodes before linearized reading' (duration: 473.645781ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:12:15.966145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:12:15.493849Z","time spent":"472.289561ms","remote":"127.0.0.1:42730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-07-27T13:12:15.966168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:12:15.492315Z","time spent":"473.845189ms","remote":"127.0.0.1:42676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/199.19.213.86\" "} {"level":"warn","ts":"2026-07-27T13:12:15.966278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.783712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-27T13:12:15.966331Z","caller":"traceutil/trace.go:171","msg":"trace[351879800] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"485.866859ms","start":"2026-07-27T13:12:15.480448Z","end":"2026-07-27T13:12:15.966315Z","steps":["trace[351879800] 'agreement among raft nodes before linearized reading' (duration: 485.786674ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:12:15.966371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:12:15.480433Z","time spent":"485.928741ms","remote":"127.0.0.1:42754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-07-27T13:12:15.966353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.031053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-27T13:12:15.966856Z","caller":"traceutil/trace.go:171","msg":"trace[1055387378] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"487.551523ms","start":"2026-07-27T13:12:15.479264Z","end":"2026-07-27T13:12:15.966815Z","steps":["trace[1055387378] 'agreement among raft nodes before linearized reading' (duration: 486.949787ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:12:15.966553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:12:15.491481Z","time spent":"471.813269ms","remote":"127.0.0.1:43028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-27T13:12:15.96691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:12:15.479242Z","time spent":"487.655142ms","remote":"127.0.0.1:42754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-07-27T13:12:15.967028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:12:15.494371Z","time spent":"469.116621ms","remote":"127.0.0.1:43118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-27T13:12:15.967132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:12:15.492185Z","time spent":"470.924538ms","remote":"127.0.0.1:43118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-27T13:12:15.96723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:12:15.519842Z","time spent":"443.75984ms","remote":"127.0.0.1:42882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":845,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-27T13:12:15.967374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:12:15.493888Z","time spent":"469.26608ms","remote":"127.0.0.1:43118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-27T13:12:15.967486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:12:15.492044Z","time spent":"471.197751ms","remote":"127.0.0.1:43118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-27T13:12:15.967872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:12:15.513396Z","time spent":"450.237945ms","remote":"127.0.0.1:42814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-27T13:12:15.967989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:12:15.492133Z","time spent":"471.145551ms","remote":"127.0.0.1:43118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-27T13:12:50.659164Z","caller":"traceutil/trace.go:171","msg":"trace[1744746520] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"100.977624ms","start":"2026-07-27T13:12:50.558145Z","end":"2026-07-27T13:12:50.659123Z","steps":["trace[1744746520] 'process raft request' (duration: 100.460623ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:50.835195Z","caller":"traceutil/trace.go:171","msg":"trace[394623695] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"110.073151ms","start":"2026-07-27T13:12:50.725093Z","end":"2026-07-27T13:12:50.835166Z","steps":["trace[394623695] 'process raft request' (duration: 55.619899ms)","trace[394623695] 'compare' (duration: 54.204145ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T13:12:51.045352Z","caller":"traceutil/trace.go:171","msg":"trace[1677015482] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"111.969339ms","start":"2026-07-27T13:12:50.933352Z","end":"2026-07-27T13:12:51.045321Z","steps":["trace[1677015482] 'process raft request' (duration: 69.607976ms)","trace[1677015482] 'compare' (duration: 42.190147ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T13:12:51.546764Z","caller":"traceutil/trace.go:171","msg":"trace[283056199] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"164.750944ms","start":"2026-07-27T13:12:51.381978Z","end":"2026-07-27T13:12:51.546729Z","steps":["trace[283056199] 'process raft request' (duration: 114.580422ms)","trace[283056199] 'compare' (duration: 49.936988ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T13:12:51.547454Z","caller":"traceutil/trace.go:171","msg":"trace[978892973] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"156.472879ms","start":"2026-07-27T13:12:51.390964Z","end":"2026-07-27T13:12:51.547437Z","steps":["trace[978892973] 'process raft request' (duration: 155.696646ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:51.547635Z","caller":"traceutil/trace.go:171","msg":"trace[1180261721] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"112.009153ms","start":"2026-07-27T13:12:51.435619Z","end":"2026-07-27T13:12:51.547628Z","steps":["trace[1180261721] 'process raft request' (duration: 111.760115ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:51.736419Z","caller":"traceutil/trace.go:171","msg":"trace[1097528104] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"165.216442ms","start":"2026-07-27T13:12:51.571148Z","end":"2026-07-27T13:12:51.736364Z","steps":["trace[1097528104] 'process raft request' (duration: 118.352121ms)","trace[1097528104] 'compare' (duration: 46.461671ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T13:12:52.573303Z","caller":"traceutil/trace.go:171","msg":"trace[1272870327] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"133.739621ms","start":"2026-07-27T13:12:52.439526Z","end":"2026-07-27T13:12:52.573265Z","steps":["trace[1272870327] 'process raft request' (duration: 68.871299ms)","trace[1272870327] 'compare' (duration: 64.6409ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T13:12:53.340756Z","caller":"traceutil/trace.go:171","msg":"trace[535175571] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"155.186438ms","start":"2026-07-27T13:12:53.185547Z","end":"2026-07-27T13:12:53.340733Z","steps":["trace[535175571] 'process raft request' (duration: 155.013861ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:53.497901Z","caller":"traceutil/trace.go:171","msg":"trace[1776669548] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"307.644476ms","start":"2026-07-27T13:12:53.190219Z","end":"2026-07-27T13:12:53.497863Z","steps":["trace[1776669548] 'process raft request' (duration: 307.416464ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:53.498095Z","caller":"traceutil/trace.go:171","msg":"trace[373939870] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"307.648437ms","start":"2026-07-27T13:12:53.190422Z","end":"2026-07-27T13:12:53.498071Z","steps":["trace[373939870] 'process raft request' (duration: 307.36701ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:53.498178Z","caller":"traceutil/trace.go:171","msg":"trace[19501308] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"280.052903ms","start":"2026-07-27T13:12:53.218112Z","end":"2026-07-27T13:12:53.498165Z","steps":["trace[19501308] 'process raft request' (duration: 279.89591ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:53.498226Z","caller":"traceutil/trace.go:171","msg":"trace[694412808] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"295.016836ms","start":"2026-07-27T13:12:53.203153Z","end":"2026-07-27T13:12:53.49817Z","steps":["trace[694412808] 'process raft request' (duration: 294.689927ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:53.498267Z","caller":"traceutil/trace.go:171","msg":"trace[1310145947] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"279.671819ms","start":"2026-07-27T13:12:53.218565Z","end":"2026-07-27T13:12:53.498237Z","steps":["trace[1310145947] 'process raft request' (duration: 279.52934ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:53.498412Z","caller":"traceutil/trace.go:171","msg":"trace[813875989] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"272.7992ms","start":"2026-07-27T13:12:53.225603Z","end":"2026-07-27T13:12:53.498403Z","steps":["trace[813875989] 'process raft request' (duration: 272.543ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:12:53.498371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:12:53.190416Z","time spent":"307.76665ms","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-27T13:12:53.4981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:12:53.190196Z","time spent":"307.809271ms","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-27T13:12:53.498647Z","caller":"traceutil/trace.go:171","msg":"trace[2007151708] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"259.522531ms","start":"2026-07-27T13:12:53.239014Z","end":"2026-07-27T13:12:53.498537Z","steps":["trace[2007151708] 'process raft request' (duration: 259.172645ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:53.49879Z","caller":"traceutil/trace.go:171","msg":"trace[39107264] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"241.121074ms","start":"2026-07-27T13:12:53.25765Z","end":"2026-07-27T13:12:53.498771Z","steps":["trace[39107264] 'process raft request' (duration: 240.807558ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:53.50134Z","caller":"traceutil/trace.go:171","msg":"trace[1575846116] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"236.08409ms","start":"2026-07-27T13:12:53.262785Z","end":"2026-07-27T13:12:53.498869Z","steps":["trace[1575846116] 'process raft request' (duration: 235.887706ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:53.501687Z","caller":"traceutil/trace.go:171","msg":"trace[897696541] linearizableReadLoop","detail":"{readStateIndex:565; appliedIndex:555; }","duration":"223.81857ms","start":"2026-07-27T13:12:53.277818Z","end":"2026-07-27T13:12:53.501637Z","steps":["trace[897696541] 'read index received' (duration: 62.737813ms)","trace[897696541] 'applied index is now lower than readState.Index' (duration: 161.078316ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T13:12:53.501704Z","caller":"traceutil/trace.go:171","msg":"trace[997643925] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"220.149231ms","start":"2026-07-27T13:12:53.281522Z","end":"2026-07-27T13:12:53.501672Z","steps":["trace[997643925] 'process raft request' (duration: 217.254586ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:12:53.505758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.919876ms","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-07-27T13:12:53.505846Z","caller":"traceutil/trace.go:171","msg":"trace[1307454980] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:544; }","duration":"228.057804ms","start":"2026-07-27T13:12:53.277752Z","end":"2026-07-27T13:12:53.50581Z","steps":["trace[1307454980] 'agreement among raft nodes before linearized reading' (duration: 227.731835ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:53.609826Z","caller":"traceutil/trace.go:171","msg":"trace[402628839] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"262.79345ms","start":"2026-07-27T13:12:53.34699Z","end":"2026-07-27T13:12:53.609783Z","steps":["trace[402628839] 'process raft request' (duration: 262.579172ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:53.610244Z","caller":"traceutil/trace.go:171","msg":"trace[89016582] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"219.296757ms","start":"2026-07-27T13:12:53.390925Z","end":"2026-07-27T13:12:53.610222Z","steps":["trace[89016582] 'process raft request' (duration: 219.216605ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:53.610298Z","caller":"traceutil/trace.go:171","msg":"trace[1133672302] transaction","detail":"{read_only:false; number_of_response:1; response_revision:546; }","duration":"261.424054ms","start":"2026-07-27T13:12:53.348821Z","end":"2026-07-27T13:12:53.610245Z","steps":["trace[1133672302] 'process raft request' (duration: 260.93458ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:53.61016Z","caller":"traceutil/trace.go:171","msg":"trace[1573036817] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"261.621178ms","start":"2026-07-27T13:12:53.348424Z","end":"2026-07-27T13:12:53.610045Z","steps":["trace[1573036817] 'process raft request' (duration: 261.269221ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:53.673781Z","caller":"traceutil/trace.go:171","msg":"trace[225925984] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"155.203012ms","start":"2026-07-27T13:12:53.518551Z","end":"2026-07-27T13:12:53.673754Z","steps":["trace[225925984] 'process raft request' (duration: 155.023073ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:53.674037Z","caller":"traceutil/trace.go:171","msg":"trace[110168171] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"168.084882ms","start":"2026-07-27T13:12:53.505925Z","end":"2026-07-27T13:12:53.67401Z","steps":["trace[110168171] 'process raft request' (duration: 167.479655ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:53.674149Z","caller":"traceutil/trace.go:171","msg":"trace[1507448246] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"152.262915ms","start":"2026-07-27T13:12:53.521846Z","end":"2026-07-27T13:12:53.674109Z","steps":["trace[1507448246] 'process raft request' (duration: 151.863805ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:53.673892Z","caller":"traceutil/trace.go:171","msg":"trace[1331033345] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"154.786108ms","start":"2026-07-27T13:12:53.519063Z","end":"2026-07-27T13:12:53.673849Z","steps":["trace[1331033345] 'process raft request' (duration: 154.571479ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:53.673975Z","caller":"traceutil/trace.go:171","msg":"trace[1635075038] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"154.848286ms","start":"2026-07-27T13:12:53.519118Z","end":"2026-07-27T13:12:53.673966Z","steps":["trace[1635075038] 'process raft request' (duration: 154.542241ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:53.675129Z","caller":"traceutil/trace.go:171","msg":"trace[1261213082] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"152.479784ms","start":"2026-07-27T13:12:53.522635Z","end":"2026-07-27T13:12:53.675115Z","steps":["trace[1261213082] 'process raft request' (duration: 151.329459ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:53.675248Z","caller":"traceutil/trace.go:171","msg":"trace[1698766405] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"149.291388ms","start":"2026-07-27T13:12:53.525942Z","end":"2026-07-27T13:12:53.675233Z","steps":["trace[1698766405] 'process raft request' (duration: 148.89938ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:53.675496Z","caller":"traceutil/trace.go:171","msg":"trace[1649376903] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"151.022874ms","start":"2026-07-27T13:12:53.524461Z","end":"2026-07-27T13:12:53.675484Z","steps":["trace[1649376903] 'process raft request' (duration: 150.237998ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:53.675273Z","caller":"traceutil/trace.go:171","msg":"trace[361066755] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"147.097295ms","start":"2026-07-27T13:12:53.528166Z","end":"2026-07-27T13:12:53.675263Z","steps":["trace[361066755] 'process raft request' (duration: 146.733285ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:53.839818Z","caller":"traceutil/trace.go:171","msg":"trace[273125237] linearizableReadLoop","detail":"{readStateIndex:581; appliedIndex:579; }","duration":"161.185126ms","start":"2026-07-27T13:12:53.678596Z","end":"2026-07-27T13:12:53.839781Z","steps":["trace[273125237] 'read index received' (duration: 121.615782ms)","trace[273125237] 'applied index is now lower than readState.Index' (duration: 39.568124ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T13:12:53.839928Z","caller":"traceutil/trace.go:171","msg":"trace[799142233] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"214.095037ms","start":"2026-07-27T13:12:53.625792Z","end":"2026-07-27T13:12:53.839887Z","steps":["trace[799142233] 'process raft request' (duration: 213.863864ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:53.839945Z","caller":"traceutil/trace.go:171","msg":"trace[524410524] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"218.624032ms","start":"2026-07-27T13:12:53.621206Z","end":"2026-07-27T13:12:53.83983Z","steps":["trace[524410524] 'process raft request' (duration: 178.963452ms)","trace[524410524] 'compare' (duration: 39.236884ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T13:12:53.840066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.372738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T13:12:53.840289Z","caller":"traceutil/trace.go:171","msg":"trace[1271961234] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:558; }","duration":"161.622886ms","start":"2026-07-27T13:12:53.678552Z","end":"2026-07-27T13:12:53.840175Z","steps":["trace[1271961234] 'agreement among raft nodes before linearized reading' (duration: 161.370627ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:53.840576Z","caller":"traceutil/trace.go:171","msg":"trace[862961532] transaction","detail":"{read_only:false; number_of_response:0; response_revision:558; }","duration":"161.660747ms","start":"2026-07-27T13:12:53.678895Z","end":"2026-07-27T13:12:53.840555Z","steps":["trace[862961532] 'process raft request' (duration: 161.450829ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:53.840691Z","caller":"traceutil/trace.go:171","msg":"trace[1887834551] transaction","detail":"{read_only:false; number_of_response:0; response_revision:558; }","duration":"158.729181ms","start":"2026-07-27T13:12:53.681945Z","end":"2026-07-27T13:12:53.840674Z","steps":["trace[1887834551] 'process raft request' (duration: 158.588223ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:53.840832Z","caller":"traceutil/trace.go:171","msg":"trace[114214473] transaction","detail":"{read_only:false; number_of_response:0; response_revision:558; }","duration":"158.83444ms","start":"2026-07-27T13:12:53.681989Z","end":"2026-07-27T13:12:53.840823Z","steps":["trace[114214473] 'process raft request' (duration: 158.561715ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:53.840885Z","caller":"traceutil/trace.go:171","msg":"trace[630079754] transaction","detail":"{read_only:false; number_of_response:0; response_revision:558; }","duration":"158.994125ms","start":"2026-07-27T13:12:53.681881Z","end":"2026-07-27T13:12:53.840875Z","steps":["trace[630079754] 'process raft request' (duration: 158.622683ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:53.840992Z","caller":"traceutil/trace.go:171","msg":"trace[1117194808] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"151.708842ms","start":"2026-07-27T13:12:53.689274Z","end":"2026-07-27T13:12:53.840983Z","steps":["trace[1117194808] 'process raft request' (duration: 151.307472ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:53.841195Z","caller":"traceutil/trace.go:171","msg":"trace[576828300] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"145.305712ms","start":"2026-07-27T13:12:53.695875Z","end":"2026-07-27T13:12:53.841181Z","steps":["trace[576828300] 'process raft request' (duration: 144.925688ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:53.841379Z","caller":"traceutil/trace.go:171","msg":"trace[802492275] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"144.309398ms","start":"2026-07-27T13:12:53.697054Z","end":"2026-07-27T13:12:53.841363Z","steps":["trace[802492275] 'process raft request' (duration: 144.051228ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:53.841608Z","caller":"traceutil/trace.go:171","msg":"trace[1782350062] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"115.755602ms","start":"2026-07-27T13:12:53.725837Z","end":"2026-07-27T13:12:53.841593Z","steps":["trace[1782350062] 'process raft request' (duration: 115.522728ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:54.12546Z","caller":"traceutil/trace.go:171","msg":"trace[924502950] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"266.501741ms","start":"2026-07-27T13:12:53.858925Z","end":"2026-07-27T13:12:54.125427Z","steps":["trace[924502950] 'process raft request' (duration: 266.447066ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:54.125513Z","caller":"traceutil/trace.go:171","msg":"trace[47289690] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"270.240179ms","start":"2026-07-27T13:12:53.855216Z","end":"2026-07-27T13:12:54.125456Z","steps":["trace[47289690] 'process raft request' (duration: 201.85541ms)","trace[47289690] 'compare' (duration: 68.075704ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T13:12:54.139111Z","caller":"traceutil/trace.go:171","msg":"trace[866632402] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:590; }","duration":"103.271954ms","start":"2026-07-27T13:12:54.035816Z","end":"2026-07-27T13:12:54.139088Z","steps":["trace[866632402] 'read index received' (duration: 21.345139ms)","trace[866632402] 'applied index is now lower than readState.Index' (duration: 81.925755ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T13:12:54.139337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.513532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-27T13:12:54.139394Z","caller":"traceutil/trace.go:171","msg":"trace[498199846] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:566; }","duration":"103.631674ms","start":"2026-07-27T13:12:54.035752Z","end":"2026-07-27T13:12:54.139384Z","steps":["trace[498199846] 'agreement among raft nodes before linearized reading' (duration: 103.427918ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:54.139159Z","caller":"traceutil/trace.go:171","msg":"trace[1974658885] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"187.916972ms","start":"2026-07-27T13:12:53.951212Z","end":"2026-07-27T13:12:54.139129Z","steps":["trace[1974658885] 'process raft request' (duration: 187.739662ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:54.419288Z","caller":"traceutil/trace.go:171","msg":"trace[692220815] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"276.143612ms","start":"2026-07-27T13:12:54.143081Z","end":"2026-07-27T13:12:54.419224Z","steps":["trace[692220815] 'process raft request' (duration: 187.771329ms)","trace[692220815] 'compare' (duration: 87.802116ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T13:12:54.419874Z","caller":"traceutil/trace.go:171","msg":"trace[1063500825] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"233.496207ms","start":"2026-07-27T13:12:54.186315Z","end":"2026-07-27T13:12:54.419812Z","steps":["trace[1063500825] 'process raft request' (duration: 233.355238ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:12:54.836533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.153096ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-27T13:12:54.836837Z","caller":"traceutil/trace.go:171","msg":"trace[1366064840] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"601.444479ms","start":"2026-07-27T13:12:54.235377Z","end":"2026-07-27T13:12:54.836821Z","steps":["trace[1366064840] 'process raft request' (duration: 601.367598ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:54.836834Z","caller":"traceutil/trace.go:171","msg":"trace[775868321] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"604.031843ms","start":"2026-07-27T13:12:54.232762Z","end":"2026-07-27T13:12:54.836794Z","steps":["trace[775868321] 'process raft request' (duration: 359.400205ms)","trace[775868321] 'compare' (duration: 243.975207ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T13:12:54.836924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:12:54.235344Z","time spent":"601.539216ms","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29546,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T13:12:54.837064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:12:54.232738Z","time spent":"604.188537ms","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23066,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T13:12:55.536326Z","caller":"traceutil/trace.go:171","msg":"trace[787958237] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:597; }","duration":"957.602728ms","start":"2026-07-27T13:12:54.578691Z","end":"2026-07-27T13:12:55.536294Z","steps":["trace[787958237] 'read index received' (duration: 13.952999ms)","trace[787958237] 'applied index is now lower than readState.Index' (duration: 943.647318ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T13:12:55.536409Z","caller":"traceutil/trace.go:171","msg":"trace[329030501] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"1.042240341s","start":"2026-07-27T13:12:54.49412Z","end":"2026-07-27T13:12:55.536361Z","steps":["trace[329030501] 'process raft request' (duration: 1.041894005s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:12:55.536563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"957.847206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-27T13:12:55.536609Z","caller":"traceutil/trace.go:171","msg":"trace[14552890] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:573; }","duration":"957.959597ms","start":"2026-07-27T13:12:54.578639Z","end":"2026-07-27T13:12:55.536599Z","steps":["trace[14552890] 'agreement among raft nodes before linearized reading' (duration: 957.811606ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:12:55.536656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:12:54.578617Z","time spent":"958.030396ms","remote":"127.0.0.1:42884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-27T13:12:55.536899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:12:54.494092Z","time spent":"1.042462753s","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":73770,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-27T13:12:56.036736Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909824449564806093,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-27T13:12:56.53757Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909824449564806093,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-27T13:12:56.810212Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.274305441s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-27T13:12:56.926366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.712211ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-27T13:12:56.926638Z","caller":"traceutil/trace.go:171","msg":"trace[1331617548] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"2.072585333s","start":"2026-07-27T13:12:54.854037Z","end":"2026-07-27T13:12:56.926622Z","steps":["trace[1331617548] 'process raft request' (duration: 2.072454207s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:12:56.926705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:12:54.854007Z","time spent":"2.072666255s","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T13:12:56.926878Z","caller":"traceutil/trace.go:171","msg":"trace[1997319860] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"2.075332341s","start":"2026-07-27T13:12:54.851535Z","end":"2026-07-27T13:12:56.926867Z","steps":["trace[1997319860] 'process raft request' (duration: 1.959025645s)","trace[1997319860] 'compare' (duration: 115.34939ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T13:12:56.926932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:12:54.851515Z","time spent":"2.075389917s","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29452,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T13:12:57.120079Z","caller":"traceutil/trace.go:171","msg":"trace[1065727730] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"1.578238902s","start":"2026-07-27T13:12:55.541824Z","end":"2026-07-27T13:12:57.120063Z","steps":["trace[1065727730] 'process raft request' (duration: 1.576535568s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:12:57.120444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:12:55.541802Z","time spent":"1.578576116s","remote":"127.0.0.1:42884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T13:12:57.120073Z","caller":"traceutil/trace.go:171","msg":"trace[966571796] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:600; }","duration":"1.583633257s","start":"2026-07-27T13:12:55.536416Z","end":"2026-07-27T13:12:57.120049Z","steps":["trace[966571796] 'read index received' (duration: 1.2740497s)","trace[966571796] 'applied index is now lower than readState.Index' (duration: 309.582246ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T13:12:57.12024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.337595818s","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-07-27T13:12:57.120756Z","caller":"traceutil/trace.go:171","msg":"trace[415245239] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:577; }","duration":"2.338162847s","start":"2026-07-27T13:12:54.782576Z","end":"2026-07-27T13:12:57.120739Z","steps":["trace[415245239] 'agreement among raft nodes before linearized reading' (duration: 2.337541393s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:12:57.120813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:12:54.78255Z","time spent":"2.33824739s","remote":"127.0.0.1:42884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-27T13:12:57.120277Z","caller":"traceutil/trace.go:171","msg":"trace[1877331281] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"1.560642629s","start":"2026-07-27T13:12:55.559625Z","end":"2026-07-27T13:12:57.120267Z","steps":["trace[1877331281] 'process raft request' (duration: 1.560376445s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:12:57.120961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:12:55.559595Z","time spent":"1.561269685s","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19834,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-27T13:12:57.265151Z","caller":"traceutil/trace.go:171","msg":"trace[1980591281] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"324.958238ms","start":"2026-07-27T13:12:56.940155Z","end":"2026-07-27T13:12:57.265114Z","steps":["trace[1980591281] 'process raft request' (duration: 324.708178ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:57.265452Z","caller":"traceutil/trace.go:171","msg":"trace[1436180678] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:604; }","duration":"144.767713ms","start":"2026-07-27T13:12:57.120667Z","end":"2026-07-27T13:12:57.265435Z","steps":["trace[1436180678] 'read index received' (duration: 144.2903ms)","trace[1436180678] 'applied index is now lower than readState.Index' (duration: 475.463µs)"],"step_count":2} {"level":"warn","ts":"2026-07-27T13:12:57.265442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:12:56.940132Z","time spent":"325.105398ms","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29461,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T13:12:57.265566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.66842535s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T13:12:57.265602Z","caller":"traceutil/trace.go:171","msg":"trace[1259692588] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:580; }","duration":"1.668505922s","start":"2026-07-27T13:12:55.597087Z","end":"2026-07-27T13:12:57.265593Z","steps":["trace[1259692588] 'agreement among raft nodes before linearized reading' (duration: 1.668409976s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:12:57.265631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:12:55.597064Z","time spent":"1.668560087s","remote":"127.0.0.1:42660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-27T13:12:57.265602Z","caller":"traceutil/trace.go:171","msg":"trace[1854379287] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"218.158232ms","start":"2026-07-27T13:12:57.047383Z","end":"2026-07-27T13:12:57.265541Z","steps":["trace[1854379287] 'process raft request' (duration: 217.916555ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:12:57.265793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.060066ms","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-07-27T13:12:57.265833Z","caller":"traceutil/trace.go:171","msg":"trace[321799236] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"310.002769ms","start":"2026-07-27T13:12:56.955792Z","end":"2026-07-27T13:12:57.265795Z","steps":["trace[321799236] 'process raft request' (duration: 309.247529ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:12:57.265821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.361419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-27T13:12:57.265993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:12:56.955771Z","time spent":"310.136236ms","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31102,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T13:12:57.265867Z","caller":"traceutil/trace.go:171","msg":"trace[1588109454] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:580; }","duration":"841.223852ms","start":"2026-07-27T13:12:56.424626Z","end":"2026-07-27T13:12:57.265849Z","steps":["trace[1588109454] 'agreement among raft nodes before linearized reading' (duration: 840.942773ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:12:57.266036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.587284426s","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-07-27T13:12:57.266056Z","caller":"traceutil/trace.go:171","msg":"trace[2089982746] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:580; }","duration":"381.605487ms","start":"2026-07-27T13:12:56.884381Z","end":"2026-07-27T13:12:57.265986Z","steps":["trace[2089982746] 'agreement among raft nodes before linearized reading' (duration: 381.245757ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:12:57.266132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:12:56.424609Z","time spent":"841.510982ms","remote":"127.0.0.1:42884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-27T13:12:57.266218Z","caller":"traceutil/trace.go:171","msg":"trace[935932566] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:580; }","duration":"1.587549569s","start":"2026-07-27T13:12:55.678642Z","end":"2026-07-27T13:12:57.266192Z","steps":["trace[935932566] 'agreement among raft nodes before linearized reading' (duration: 1.587036526s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:12:57.266322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:12:56.884362Z","time spent":"381.881385ms","remote":"127.0.0.1:42660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-27T13:12:57.266403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:12:55.678626Z","time spent":"1.58776297s","remote":"127.0.0.1:42884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-27T13:12:57.286463Z","caller":"traceutil/trace.go:171","msg":"trace[1417613559] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"159.650887ms","start":"2026-07-27T13:12:57.126792Z","end":"2026-07-27T13:12:57.286443Z","steps":["trace[1417613559] 'process raft request' (duration: 159.379672ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:57.675738Z","caller":"traceutil/trace.go:171","msg":"trace[1133638589] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:613; }","duration":"278.563397ms","start":"2026-07-27T13:12:57.397106Z","end":"2026-07-27T13:12:57.675669Z","steps":["trace[1133638589] 'read index received' (duration: 30.686872ms)","trace[1133638589] 'applied index is now lower than readState.Index' (duration: 247.875385ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T13:12:57.675838Z","caller":"traceutil/trace.go:171","msg":"trace[1561992423] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"302.8379ms","start":"2026-07-27T13:12:57.37295Z","end":"2026-07-27T13:12:57.675788Z","steps":["trace[1561992423] 'process raft request' (duration: 302.445821ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:12:57.675942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.807635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.86\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-27T13:12:57.676278Z","caller":"traceutil/trace.go:171","msg":"trace[210066807] range","detail":"{range_begin:/registry/masterleases/199.19.213.86; range_end:; response_count:1; response_revision:587; }","duration":"279.193823ms","start":"2026-07-27T13:12:57.397062Z","end":"2026-07-27T13:12:57.676256Z","steps":["trace[210066807] 'agreement among raft nodes before linearized reading' (duration: 278.769524ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:12:57.676368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:12:57.372916Z","time spent":"303.249374ms","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65190,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T13:12:57.712583Z","caller":"traceutil/trace.go:171","msg":"trace[250492248] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"275.228366ms","start":"2026-07-27T13:12:57.437326Z","end":"2026-07-27T13:12:57.712555Z","steps":["trace[250492248] 'process raft request' (duration: 274.325343ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:58.075451Z","caller":"traceutil/trace.go:171","msg":"trace[1886509307] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"115.055972ms","start":"2026-07-27T13:12:57.960352Z","end":"2026-07-27T13:12:58.075408Z","steps":["trace[1886509307] 'process raft request' (duration: 95.543298ms)","trace[1886509307] 'compare' (duration: 19.328362ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T13:12:58.857461Z","caller":"traceutil/trace.go:171","msg":"trace[597343306] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"100.277147ms","start":"2026-07-27T13:12:58.757157Z","end":"2026-07-27T13:12:58.857434Z","steps":["trace[597343306] 'process raft request' (duration: 99.589644ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:59.184265Z","caller":"traceutil/trace.go:171","msg":"trace[2074480247] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"218.54388ms","start":"2026-07-27T13:12:58.965677Z","end":"2026-07-27T13:12:59.184221Z","steps":["trace[2074480247] 'process raft request' (duration: 138.899315ms)","trace[2074480247] 'compare' (duration: 79.364837ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T13:12:59.357983Z","caller":"traceutil/trace.go:171","msg":"trace[1736240177] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"124.008959ms","start":"2026-07-27T13:12:59.23394Z","end":"2026-07-27T13:12:59.357949Z","steps":["trace[1736240177] 'process raft request' (duration: 97.338526ms)","trace[1736240177] 'compare' (duration: 26.121379ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T13:12:59.952652Z","caller":"traceutil/trace.go:171","msg":"trace[942665603] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"145.274263ms","start":"2026-07-27T13:12:59.807356Z","end":"2026-07-27T13:12:59.95263Z","steps":["trace[942665603] 'process raft request' (duration: 145.105486ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:13:00.72628Z","caller":"traceutil/trace.go:171","msg":"trace[537904028] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"117.849026ms","start":"2026-07-27T13:13:00.608412Z","end":"2026-07-27T13:13:00.726262Z","steps":["trace[537904028] 'process raft request' (duration: 117.813216ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:13:00.726374Z","caller":"traceutil/trace.go:171","msg":"trace[1663452481] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"118.300484ms","start":"2026-07-27T13:13:00.608011Z","end":"2026-07-27T13:13:00.726311Z","steps":["trace[1663452481] 'process raft request' (duration: 102.510144ms)","trace[1663452481] 'compare' (duration: 15.559495ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T13:13:00.780566Z","caller":"traceutil/trace.go:171","msg":"trace[648833382] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"164.484721ms","start":"2026-07-27T13:13:00.616039Z","end":"2026-07-27T13:13:00.780523Z","steps":["trace[648833382] 'process raft request' (duration: 164.200461ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:13:00.780762Z","caller":"traceutil/trace.go:171","msg":"trace[113923857] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"163.23776ms","start":"2026-07-27T13:13:00.617509Z","end":"2026-07-27T13:13:00.780747Z","steps":["trace[113923857] 'process raft request' (duration: 162.95502ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:00.780898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.747665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-07-27T13:13:00.780977Z","caller":"traceutil/trace.go:171","msg":"trace[367718171] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:631; }","duration":"110.931066ms","start":"2026-07-27T13:13:00.67003Z","end":"2026-07-27T13:13:00.780961Z","steps":["trace[367718171] 'agreement among raft nodes before linearized reading' (duration: 110.606215ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:13:00.780549Z","caller":"traceutil/trace.go:171","msg":"trace[924616671] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:654; }","duration":"110.385343ms","start":"2026-07-27T13:13:00.67013Z","end":"2026-07-27T13:13:00.780516Z","steps":["trace[924616671] 'read index received' (duration: 40.380382ms)","trace[924616671] 'applied index is now lower than readState.Index' (duration: 70.00306ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T13:13:00.780659Z","caller":"traceutil/trace.go:171","msg":"trace[500535143] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"163.186175ms","start":"2026-07-27T13:13:00.617438Z","end":"2026-07-27T13:13:00.780624Z","steps":["trace[500535143] 'process raft request' (duration: 162.969204ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:13:00.925943Z","caller":"traceutil/trace.go:171","msg":"trace[1059213614] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"195.036362ms","start":"2026-07-27T13:13:00.730862Z","end":"2026-07-27T13:13:00.925898Z","steps":["trace[1059213614] 'process raft request' (duration: 117.341813ms)","trace[1059213614] 'compare' (duration: 77.45519ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T13:13:00.92602Z","caller":"traceutil/trace.go:171","msg":"trace[1618153394] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"194.434272ms","start":"2026-07-27T13:13:00.731562Z","end":"2026-07-27T13:13:00.925996Z","steps":["trace[1618153394] 'process raft request' (duration: 194.225724ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:13:00.925944Z","caller":"traceutil/trace.go:171","msg":"trace[1156877414] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:659; }","duration":"133.775675ms","start":"2026-07-27T13:13:00.792143Z","end":"2026-07-27T13:13:00.925919Z","steps":["trace[1156877414] 'read index received' (duration: 55.993842ms)","trace[1156877414] 'applied index is now lower than readState.Index' (duration: 77.780483ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T13:13:00.926227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.052454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:10870"} {"level":"info","ts":"2026-07-27T13:13:00.926281Z","caller":"traceutil/trace.go:171","msg":"trace[276219209] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:634; }","duration":"134.154933ms","start":"2026-07-27T13:13:00.792113Z","end":"2026-07-27T13:13:00.926268Z","steps":["trace[276219209] 'agreement among raft nodes before linearized reading' (duration: 133.882336ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:00.926326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.162534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T13:13:00.926378Z","caller":"traceutil/trace.go:171","msg":"trace[1152141635] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:634; }","duration":"123.249698ms","start":"2026-07-27T13:13:00.803112Z","end":"2026-07-27T13:13:00.926362Z","steps":["trace[1152141635] 'agreement among raft nodes before linearized reading' (duration: 123.155652ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:13:00.92652Z","caller":"traceutil/trace.go:171","msg":"trace[133800895] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"194.399873ms","start":"2026-07-27T13:13:00.732111Z","end":"2026-07-27T13:13:00.926511Z","steps":["trace[133800895] 'process raft request' (duration: 193.767095ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:13:01.060984Z","caller":"traceutil/trace.go:171","msg":"trace[1765221980] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:663; }","duration":"122.881414ms","start":"2026-07-27T13:13:00.938077Z","end":"2026-07-27T13:13:01.060958Z","steps":["trace[1765221980] 'read index received' (duration: 56.261137ms)","trace[1765221980] 'applied index is now lower than readState.Index' (duration: 66.618756ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T13:13:01.061015Z","caller":"traceutil/trace.go:171","msg":"trace[732863340] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"129.016373ms","start":"2026-07-27T13:13:00.931972Z","end":"2026-07-27T13:13:01.060988Z","steps":["trace[732863340] 'process raft request' (duration: 94.595771ms)","trace[732863340] 'compare' (duration: 34.27202ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T13:13:01.061382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.270284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gateways.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:59631"} {"level":"info","ts":"2026-07-27T13:13:01.061474Z","caller":"traceutil/trace.go:171","msg":"trace[41695578] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gateways.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:636; }","duration":"123.402571ms","start":"2026-07-27T13:13:00.93805Z","end":"2026-07-27T13:13:01.061453Z","steps":["trace[41695578] 'agreement among raft nodes before linearized reading' (duration: 123.04282ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:13:01.226067Z","caller":"traceutil/trace.go:171","msg":"trace[239793938] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:664; }","duration":"141.249237ms","start":"2026-07-27T13:13:01.084793Z","end":"2026-07-27T13:13:01.226043Z","steps":["trace[239793938] 'read index received' (duration: 70.801309ms)","trace[239793938] 'applied index is now lower than readState.Index' (duration: 70.446828ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T13:13:01.226328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.50968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:80703"} {"level":"info","ts":"2026-07-27T13:13:01.226371Z","caller":"traceutil/trace.go:171","msg":"trace[1601136787] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:638; }","duration":"141.601897ms","start":"2026-07-27T13:13:01.084758Z","end":"2026-07-27T13:13:01.22636Z","steps":["trace[1601136787] 'agreement among raft nodes before linearized reading' (duration: 141.410273ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:13:01.226197Z","caller":"traceutil/trace.go:171","msg":"trace[1429887604] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"157.265704ms","start":"2026-07-27T13:13:01.068891Z","end":"2026-07-27T13:13:01.226156Z","steps":["trace[1429887604] 'process raft request' (duration: 156.983114ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:13:01.34988Z","caller":"traceutil/trace.go:171","msg":"trace[1189322681] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"115.554721ms","start":"2026-07-27T13:13:01.234289Z","end":"2026-07-27T13:13:01.349844Z","steps":["trace[1189322681] 'process raft request' (duration: 115.410341ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:13:01.350047Z","caller":"traceutil/trace.go:171","msg":"trace[2068929669] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"116.437602ms","start":"2026-07-27T13:13:01.233586Z","end":"2026-07-27T13:13:01.350023Z","steps":["trace[2068929669] 'process raft request' (duration: 112.125002ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:13:01.350203Z","caller":"traceutil/trace.go:171","msg":"trace[94184399] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"111.647967ms","start":"2026-07-27T13:13:01.238545Z","end":"2026-07-27T13:13:01.350193Z","steps":["trace[94184399] 'process raft request' (duration: 111.201781ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:13:01.504193Z","caller":"traceutil/trace.go:171","msg":"trace[234877525] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:671; }","duration":"108.705576ms","start":"2026-07-27T13:13:01.395464Z","end":"2026-07-27T13:13:01.504169Z","steps":["trace[234877525] 'read index received' (duration: 25.698776ms)","trace[234877525] 'applied index is now lower than readState.Index' (duration: 83.005419ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T13:13:01.504321Z","caller":"traceutil/trace.go:171","msg":"trace[411151631] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"145.227902ms","start":"2026-07-27T13:13:01.359063Z","end":"2026-07-27T13:13:01.504291Z","steps":["trace[411151631] 'process raft request' (duration: 144.881134ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:13:01.50431Z","caller":"traceutil/trace.go:171","msg":"trace[1723700742] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"143.392122ms","start":"2026-07-27T13:13:01.360848Z","end":"2026-07-27T13:13:01.50424Z","steps":["trace[1723700742] 'process raft request' (duration: 143.245671ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:01.504591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.11139ms","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:7708"} {"level":"info","ts":"2026-07-27T13:13:01.504644Z","caller":"traceutil/trace.go:171","msg":"trace[1320767392] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:645; }","duration":"109.211178ms","start":"2026-07-27T13:13:01.395416Z","end":"2026-07-27T13:13:01.504627Z","steps":["trace[1320767392] 'agreement among raft nodes before linearized reading' (duration: 109.003919ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:01.504802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.466324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-27T13:13:01.504883Z","caller":"traceutil/trace.go:171","msg":"trace[1359649096] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:645; }","duration":"107.639233ms","start":"2026-07-27T13:13:01.397225Z","end":"2026-07-27T13:13:01.504864Z","steps":["trace[1359649096] 'agreement among raft nodes before linearized reading' (duration: 107.232528ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:01.504901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.183971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T13:13:01.504944Z","caller":"traceutil/trace.go:171","msg":"trace[181017842] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:645; }","duration":"102.25431ms","start":"2026-07-27T13:13:01.402676Z","end":"2026-07-27T13:13:01.50493Z","steps":["trace[181017842] 'agreement among raft nodes before linearized reading' (duration: 102.178489ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:13:01.725584Z","caller":"traceutil/trace.go:171","msg":"trace[755063367] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:678; }","duration":"124.556857ms","start":"2026-07-27T13:13:01.601005Z","end":"2026-07-27T13:13:01.725561Z","steps":["trace[755063367] 'read index received' (duration: 34.407479ms)","trace[755063367] 'applied index is now lower than readState.Index' (duration: 90.147978ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T13:13:01.72569Z","caller":"traceutil/trace.go:171","msg":"trace[234733548] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"133.396356ms","start":"2026-07-27T13:13:01.592241Z","end":"2026-07-27T13:13:01.725637Z","steps":["trace[234733548] 'process raft request' (duration: 43.170536ms)","trace[234733548] 'compare' (duration: 90.042519ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T13:13:01.72585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.018459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T13:13:01.725912Z","caller":"traceutil/trace.go:171","msg":"trace[184318328] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:651; }","duration":"122.083547ms","start":"2026-07-27T13:13:01.603808Z","end":"2026-07-27T13:13:01.725892Z","steps":["trace[184318328] 'agreement among raft nodes before linearized reading' (duration: 122.014608ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:01.72574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.733167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T13:13:01.726066Z","caller":"traceutil/trace.go:171","msg":"trace[261762320] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:651; }","duration":"125.067602ms","start":"2026-07-27T13:13:01.600979Z","end":"2026-07-27T13:13:01.726046Z","steps":["trace[261762320] 'agreement among raft nodes before linearized reading' (duration: 124.689655ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:01.725941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.971081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:31126"} {"level":"info","ts":"2026-07-27T13:13:01.726375Z","caller":"traceutil/trace.go:171","msg":"trace[778435708] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:651; }","duration":"124.449886ms","start":"2026-07-27T13:13:01.601913Z","end":"2026-07-27T13:13:01.726363Z","steps":["trace[778435708] 'agreement among raft nodes before linearized reading' (duration: 123.842185ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:13:02.121439Z","caller":"traceutil/trace.go:171","msg":"trace[789415892] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:679; }","duration":"107.508048ms","start":"2026-07-27T13:13:02.01391Z","end":"2026-07-27T13:13:02.121418Z","steps":["trace[789415892] 'read index received' (duration: 107.292537ms)","trace[789415892] 'applied index is now lower than readState.Index' (duration: 214.651µs)"],"step_count":2} {"level":"info","ts":"2026-07-27T13:13:02.12152Z","caller":"traceutil/trace.go:171","msg":"trace[1597061599] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"111.127124ms","start":"2026-07-27T13:13:02.010331Z","end":"2026-07-27T13:13:02.121458Z","steps":["trace[1597061599] 'process raft request' (duration: 110.965778ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:02.121847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.887106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/securitypolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:208485"} {"level":"info","ts":"2026-07-27T13:13:02.121921Z","caller":"traceutil/trace.go:171","msg":"trace[1739240378] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/securitypolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:652; }","duration":"108.012511ms","start":"2026-07-27T13:13:02.013868Z","end":"2026-07-27T13:13:02.121881Z","steps":["trace[1739240378] 'agreement among raft nodes before linearized reading' (duration: 107.670685ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:13:02.366628Z","caller":"traceutil/trace.go:171","msg":"trace[493907025] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"231.797037ms","start":"2026-07-27T13:13:02.134802Z","end":"2026-07-27T13:13:02.366599Z","steps":["trace[493907025] 'process raft request' (duration: 211.688124ms)","trace[493907025] 'compare' (duration: 19.983677ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T13:13:02.418213Z","caller":"traceutil/trace.go:171","msg":"trace[1118756089] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"211.787811ms","start":"2026-07-27T13:13:02.206403Z","end":"2026-07-27T13:13:02.418191Z","steps":["trace[1118756089] 'process raft request' (duration: 211.637749ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:02.57332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.496256ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-27T13:13:02.573445Z","caller":"traceutil/trace.go:171","msg":"trace[348810203] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"147.727635ms","start":"2026-07-27T13:13:02.425686Z","end":"2026-07-27T13:13:02.573414Z","steps":["trace[348810203] 'process raft request' (duration: 45.020327ms)","trace[348810203] 'compare' (duration: 102.369091ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T13:13:02.761357Z","caller":"traceutil/trace.go:171","msg":"trace[1806879694] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"123.804591ms","start":"2026-07-27T13:13:02.637525Z","end":"2026-07-27T13:13:02.76133Z","steps":["trace[1806879694] 'process raft request' (duration: 123.593371ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:13:03.084497Z","caller":"traceutil/trace.go:171","msg":"trace[1859778724] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"120.055557ms","start":"2026-07-27T13:13:02.964415Z","end":"2026-07-27T13:13:03.084471Z","steps":["trace[1859778724] 'process raft request' (duration: 119.673168ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:13:03.734337Z","caller":"traceutil/trace.go:171","msg":"trace[96103589] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"125.936249ms","start":"2026-07-27T13:13:03.608366Z","end":"2026-07-27T13:13:03.734302Z","steps":["trace[96103589] 'process raft request' (duration: 104.842479ms)","trace[96103589] 'compare' (duration: 20.788063ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T13:13:03.734462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.985381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-27T13:13:03.734513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.077794ms","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-07-27T13:13:03.734579Z","caller":"traceutil/trace.go:171","msg":"trace[765632142] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:668; }","duration":"145.218664ms","start":"2026-07-27T13:13:03.589344Z","end":"2026-07-27T13:13:03.734562Z","steps":["trace[765632142] 'agreement among raft nodes before linearized reading' (duration: 144.997211ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:13:03.734647Z","caller":"traceutil/trace.go:171","msg":"trace[1356629147] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:668; }","duration":"139.133043ms","start":"2026-07-27T13:13:03.595422Z","end":"2026-07-27T13:13:03.734555Z","steps":["trace[1356629147] 'agreement among raft nodes before linearized reading' (duration: 138.939357ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:13:03.878082Z","caller":"traceutil/trace.go:171","msg":"trace[1613164670] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"130.969161ms","start":"2026-07-27T13:13:03.747082Z","end":"2026-07-27T13:13:03.878051Z","steps":["trace[1613164670] 'process raft request' (duration: 122.699769ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:13:04.019408Z","caller":"traceutil/trace.go:171","msg":"trace[1313849707] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"183.805944ms","start":"2026-07-27T13:13:03.835568Z","end":"2026-07-27T13:13:04.019374Z","steps":["trace[1313849707] 'process raft request' (duration: 182.851161ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:13:04.196847Z","caller":"traceutil/trace.go:171","msg":"trace[1104030534] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"302.052368ms","start":"2026-07-27T13:13:03.894764Z","end":"2026-07-27T13:13:04.196816Z","steps":["trace[1104030534] 'process raft request' (duration: 300.563753ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:04.197037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:03.894742Z","time spent":"302.182635ms","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13214,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T13:13:04.198274Z","caller":"traceutil/trace.go:171","msg":"trace[892501446] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"277.26763ms","start":"2026-07-27T13:13:03.920986Z","end":"2026-07-27T13:13:04.198254Z","steps":["trace[892501446] 'process raft request' (duration: 276.348148ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:04.704564Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909824449564806279,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-27T13:13:05.257788Z","caller":"traceutil/trace.go:171","msg":"trace[532422583] linearizableReadLoop","detail":"{readStateIndex:704; appliedIndex:702; }","duration":"1.054299992s","start":"2026-07-27T13:13:04.203461Z","end":"2026-07-27T13:13:05.257761Z","steps":["trace[532422583] 'read index received' (duration: 970.064031ms)","trace[532422583] 'applied index is now lower than readState.Index' (duration: 84.234891ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T13:13:05.257926Z","caller":"traceutil/trace.go:171","msg":"trace[1883288758] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"1.097007684s","start":"2026-07-27T13:13:04.160881Z","end":"2026-07-27T13:13:05.257889Z","steps":["trace[1883288758] 'process raft request' (duration: 1.096645019s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:05.258828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:04.160856Z","time spent":"1.097162128s","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188471,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T13:13:05.258832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.404296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-27T13:13:05.258893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.332213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-07-27T13:13:05.25901Z","caller":"traceutil/trace.go:171","msg":"trace[736013865] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:675; }","duration":"492.603233ms","start":"2026-07-27T13:13:04.766359Z","end":"2026-07-27T13:13:05.258962Z","steps":["trace[736013865] 'agreement among raft nodes before linearized reading' (duration: 491.723682ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:13:05.259031Z","caller":"traceutil/trace.go:171","msg":"trace[818556904] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:675; }","duration":"455.496419ms","start":"2026-07-27T13:13:04.803483Z","end":"2026-07-27T13:13:05.258979Z","steps":["trace[818556904] 'agreement among raft nodes before linearized reading' (duration: 454.618739ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:05.259073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:04.766338Z","time spent":"492.719057ms","remote":"127.0.0.1:42884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-27T13:13:05.259092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:04.803413Z","time spent":"455.664288ms","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":13253,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-07-27T13:13:05.259454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"855.235195ms","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":"warn","ts":"2026-07-27T13:13:05.259646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.056172718s","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-07-27T13:13:05.259741Z","caller":"traceutil/trace.go:171","msg":"trace[512769684] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:675; }","duration":"1.056270996s","start":"2026-07-27T13:13:04.203422Z","end":"2026-07-27T13:13:05.259693Z","steps":["trace[512769684] 'agreement among raft nodes before linearized reading' (duration: 1.054503901s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:05.259798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:04.203405Z","time spent":"1.056378017s","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":24268,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"info","ts":"2026-07-27T13:13:05.25988Z","caller":"traceutil/trace.go:171","msg":"trace[49003783] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:675; }","duration":"855.38981ms","start":"2026-07-27T13:13:04.404161Z","end":"2026-07-27T13:13:05.25955Z","steps":["trace[49003783] 'agreement among raft nodes before linearized reading' (duration: 853.946137ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:05.259961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:04.404134Z","time spent":"855.810649ms","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10401,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"info","ts":"2026-07-27T13:13:05.260387Z","caller":"traceutil/trace.go:171","msg":"trace[1891346637] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"1.183918524s","start":"2026-07-27T13:13:04.074157Z","end":"2026-07-27T13:13:05.258076Z","steps":["trace[1891346637] 'process raft request' (duration: 1.099612513s)","trace[1891346637] 'compare' (duration: 83.263673ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T13:13:05.260541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:04.074136Z","time spent":"1.186311968s","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-27T13:13:05.260771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"977.768116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T13:13:05.260878Z","caller":"traceutil/trace.go:171","msg":"trace[902991260] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:675; }","duration":"977.862843ms","start":"2026-07-27T13:13:04.282991Z","end":"2026-07-27T13:13:05.260854Z","steps":["trace[902991260] 'agreement among raft nodes before linearized reading' (duration: 975.059141ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:05.260937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:04.282949Z","time spent":"977.970544ms","remote":"127.0.0.1:42848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-07-27T13:13:05.261514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.837621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T13:13:05.261601Z","caller":"traceutil/trace.go:171","msg":"trace[1941037835] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:675; }","duration":"662.947552ms","start":"2026-07-27T13:13:04.598614Z","end":"2026-07-27T13:13:05.261562Z","steps":["trace[1941037835] 'agreement among raft nodes before linearized reading' (duration: 659.480041ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:05.261649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:04.598594Z","time spent":"663.04281ms","remote":"127.0.0.1:42660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-27T13:13:05.262108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.145077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-07-27T13:13:05.262162Z","caller":"traceutil/trace.go:171","msg":"trace[1490635557] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:675; }","duration":"659.22199ms","start":"2026-07-27T13:13:04.602915Z","end":"2026-07-27T13:13:05.262137Z","steps":["trace[1490635557] 'agreement among raft nodes before linearized reading' (duration: 655.171822ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:05.262194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:04.602894Z","time spent":"659.289479ms","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9344,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-07-27T13:13:05.265164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.785861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188486"} {"level":"info","ts":"2026-07-27T13:13:05.265216Z","caller":"traceutil/trace.go:171","msg":"trace[487035273] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:675; }","duration":"261.867534ms","start":"2026-07-27T13:13:05.003326Z","end":"2026-07-27T13:13:05.265193Z","steps":["trace[487035273] 'agreement among raft nodes before linearized reading' (duration: 254.759642ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:13:05.552403Z","caller":"traceutil/trace.go:171","msg":"trace[165199619] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"214.043688ms","start":"2026-07-27T13:13:05.338326Z","end":"2026-07-27T13:13:05.55237Z","steps":["trace[165199619] 'process raft request' (duration: 161.896273ms)","trace[165199619] 'compare' (duration: 51.854081ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T13:13:05.626982Z","caller":"traceutil/trace.go:171","msg":"trace[925908107] linearizableReadLoop","detail":"{readStateIndex:707; appliedIndex:705; }","duration":"240.226385ms","start":"2026-07-27T13:13:05.386737Z","end":"2026-07-27T13:13:05.626963Z","steps":["trace[925908107] 'read index received' (duration: 113.408875ms)","trace[925908107] 'applied index is now lower than readState.Index' (duration: 126.81637ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T13:13:05.627155Z","caller":"traceutil/trace.go:171","msg":"trace[510976978] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"263.2115ms","start":"2026-07-27T13:13:05.363922Z","end":"2026-07-27T13:13:05.627133Z","steps":["trace[510976978] 'process raft request' (duration: 262.825249ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:05.627523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.710464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-27T13:13:05.627647Z","caller":"traceutil/trace.go:171","msg":"trace[2070400969] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:678; }","duration":"240.911272ms","start":"2026-07-27T13:13:05.386705Z","end":"2026-07-27T13:13:05.627616Z","steps":["trace[2070400969] 'agreement among raft nodes before linearized reading' (duration: 240.479868ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:13:05.909798Z","caller":"traceutil/trace.go:171","msg":"trace[556133710] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"212.525583ms","start":"2026-07-27T13:13:05.697228Z","end":"2026-07-27T13:13:05.909753Z","steps":["trace[556133710] 'process raft request' (duration: 133.648074ms)","trace[556133710] 'compare' (duration: 73.190003ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T13:13:05.960715Z","caller":"traceutil/trace.go:171","msg":"trace[1903121535] linearizableReadLoop","detail":"{readStateIndex:711; appliedIndex:708; }","duration":"218.980369ms","start":"2026-07-27T13:13:05.741695Z","end":"2026-07-27T13:13:05.960676Z","steps":["trace[1903121535] 'read index received' (duration: 88.45945ms)","trace[1903121535] 'applied index is now lower than readState.Index' (duration: 130.519849ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T13:13:05.960837Z","caller":"traceutil/trace.go:171","msg":"trace[601800530] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"237.775224ms","start":"2026-07-27T13:13:05.723052Z","end":"2026-07-27T13:13:05.960828Z","steps":["trace[601800530] 'process raft request' (duration: 237.584719ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:05.961043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.33526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-27T13:13:05.961052Z","caller":"traceutil/trace.go:171","msg":"trace[1699527293] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"247.715128ms","start":"2026-07-27T13:13:05.713291Z","end":"2026-07-27T13:13:05.961006Z","steps":["trace[1699527293] 'process raft request' (duration: 247.117617ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:13:05.961077Z","caller":"traceutil/trace.go:171","msg":"trace[1890950337] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:682; }","duration":"219.409802ms","start":"2026-07-27T13:13:05.741656Z","end":"2026-07-27T13:13:05.961066Z","steps":["trace[1890950337] 'agreement among raft nodes before linearized reading' (duration: 219.301771ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:05.961292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.29716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-07-27T13:13:05.961361Z","caller":"traceutil/trace.go:171","msg":"trace[1363590519] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:682; }","duration":"169.427777ms","start":"2026-07-27T13:13:05.791914Z","end":"2026-07-27T13:13:05.961342Z","steps":["trace[1363590519] 'agreement among raft nodes before linearized reading' (duration: 169.131092ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:05.96162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.198454ms","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-07-27T13:13:05.961684Z","caller":"traceutil/trace.go:171","msg":"trace[938419099] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:682; }","duration":"186.307505ms","start":"2026-07-27T13:13:05.775362Z","end":"2026-07-27T13:13:05.96167Z","steps":["trace[938419099] 'agreement among raft nodes before linearized reading' (duration: 185.675394ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:06.127846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.38146ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-27T13:13:06.128162Z","caller":"traceutil/trace.go:171","msg":"trace[983996135] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"157.030857ms","start":"2026-07-27T13:13:05.971112Z","end":"2026-07-27T13:13:06.128142Z","steps":["trace[983996135] 'process raft request' (duration: 156.931979ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:13:06.12832Z","caller":"traceutil/trace.go:171","msg":"trace[1545693866] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"162.562849ms","start":"2026-07-27T13:13:05.965642Z","end":"2026-07-27T13:13:06.128205Z","steps":["trace[1545693866] 'process raft request' (duration: 47.734021ms)","trace[1545693866] 'compare' (duration: 114.258295ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T13:13:06.827504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.871848ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-27T13:13:06.82764Z","caller":"traceutil/trace.go:171","msg":"trace[1470426468] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"130.092815ms","start":"2026-07-27T13:13:06.697526Z","end":"2026-07-27T13:13:06.827619Z","steps":["trace[1470426468] 'process raft request' (duration: 14.033534ms)","trace[1470426468] 'compare' (duration: 115.675781ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T13:13:07.536408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.861792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.86\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-27T13:13:07.536518Z","caller":"traceutil/trace.go:171","msg":"trace[800090318] range","detail":"{range_begin:/registry/masterleases/199.19.213.86; range_end:; response_count:1; response_revision:690; }","duration":"131.019157ms","start":"2026-07-27T13:13:07.405481Z","end":"2026-07-27T13:13:07.536501Z","steps":["trace[800090318] 'range keys from in-memory index tree' (duration: 130.704626ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:13:13.501423Z","caller":"traceutil/trace.go:171","msg":"trace[2062267923] linearizableReadLoop","detail":"{readStateIndex:756; appliedIndex:755; }","duration":"174.753465ms","start":"2026-07-27T13:13:13.326647Z","end":"2026-07-27T13:13:13.501401Z","steps":["trace[2062267923] 'read index received' (duration: 174.525009ms)","trace[2062267923] 'applied index is now lower than readState.Index' (duration: 227.426µs)"],"step_count":2} {"level":"warn","ts":"2026-07-27T13:13:13.501783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.118583ms","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-07-27T13:13:13.501822Z","caller":"traceutil/trace.go:171","msg":"trace[1926096595] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:725; }","duration":"175.202167ms","start":"2026-07-27T13:13:13.32661Z","end":"2026-07-27T13:13:13.501812Z","steps":["trace[1926096595] 'agreement among raft nodes before linearized reading' (duration: 175.019203ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:13:13.501847Z","caller":"traceutil/trace.go:171","msg":"trace[1916552685] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"279.90953ms","start":"2026-07-27T13:13:13.22192Z","end":"2026-07-27T13:13:13.501829Z","steps":["trace[1916552685] 'process raft request' (duration: 279.320728ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:13:13.99483Z","caller":"traceutil/trace.go:171","msg":"trace[557209052] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"208.409193ms","start":"2026-07-27T13:13:13.78639Z","end":"2026-07-27T13:13:13.994799Z","steps":["trace[557209052] 'process raft request' (duration: 208.231361ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:13:14.5579Z","caller":"traceutil/trace.go:171","msg":"trace[843190035] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"385.193297ms","start":"2026-07-27T13:13:14.172672Z","end":"2026-07-27T13:13:14.557865Z","steps":["trace[843190035] 'process raft request' (duration: 385.026038ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:14.558229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:14.172658Z","time spent":"385.393934ms","remote":"127.0.0.1:42884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T13:13:32.401369Z","caller":"traceutil/trace.go:171","msg":"trace[846623202] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"268.522022ms","start":"2026-07-27T13:13:32.132822Z","end":"2026-07-27T13:13:32.401344Z","steps":["trace[846623202] 'process raft request' (duration: 268.368073ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:13:32.403368Z","caller":"traceutil/trace.go:171","msg":"trace[1052699987] linearizableReadLoop","detail":"{readStateIndex:885; appliedIndex:883; }","duration":"252.223187ms","start":"2026-07-27T13:13:32.151115Z","end":"2026-07-27T13:13:32.403339Z","steps":["trace[1052699987] 'read index received' (duration: 250.160166ms)","trace[1052699987] 'applied index is now lower than readState.Index' (duration: 2.062061ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T13:13:32.40374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.604327ms","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-07-27T13:13:32.403827Z","caller":"traceutil/trace.go:171","msg":"trace[1950503560] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:850; }","duration":"252.702262ms","start":"2026-07-27T13:13:32.151075Z","end":"2026-07-27T13:13:32.403777Z","steps":["trace[1950503560] 'agreement among raft nodes before linearized reading' (duration: 252.391853ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:13:32.403768Z","caller":"traceutil/trace.go:171","msg":"trace[1088439529] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"269.824161ms","start":"2026-07-27T13:13:32.133882Z","end":"2026-07-27T13:13:32.403706Z","steps":["trace[1088439529] 'process raft request' (duration: 269.235616ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:14:57.052763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.348641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-523e1935-ee50-467c-bedb-193b956e4b42\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-07-27T13:14:57.052875Z","caller":"traceutil/trace.go:171","msg":"trace[208578788] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-523e1935-ee50-467c-bedb-193b956e4b42; range_end:; response_count:1; response_revision:1942; }","duration":"340.516989ms","start":"2026-07-27T13:14:56.712334Z","end":"2026-07-27T13:14:57.052851Z","steps":["trace[208578788] 'range keys from in-memory index tree' (duration: 340.15692ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:14:57.053041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:14:56.712281Z","time spent":"340.742794ms","remote":"127.0.0.1:42828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-523e1935-ee50-467c-bedb-193b956e4b42\" "} {"level":"warn","ts":"2026-07-27T13:14:57.053077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.500552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-27T13:14:57.053144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.178631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-523e1935-ee50-467c-bedb-193b956e4b42\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-07-27T13:14:57.053233Z","caller":"traceutil/trace.go:171","msg":"trace[263477469] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-523e1935-ee50-467c-bedb-193b956e4b42; range_end:; response_count:1; response_revision:1942; }","duration":"233.302515ms","start":"2026-07-27T13:14:56.819909Z","end":"2026-07-27T13:14:57.053211Z","steps":["trace[263477469] 'range keys from in-memory index tree' (duration: 232.930714ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:14:57.053324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.051462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T13:14:57.05341Z","caller":"traceutil/trace.go:171","msg":"trace[1697008607] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1942; }","duration":"208.208709ms","start":"2026-07-27T13:14:56.845183Z","end":"2026-07-27T13:14:57.053392Z","steps":["trace[1697008607] 'count revisions from in-memory index tree' (duration: 207.779784ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:14:57.053165Z","caller":"traceutil/trace.go:171","msg":"trace[1711974936] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1942; }","duration":"167.629635ms","start":"2026-07-27T13:14:56.885513Z","end":"2026-07-27T13:14:57.053142Z","steps":["trace[1711974936] 'range keys from in-memory index tree' (duration: 167.355086ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:14:57.624884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.595451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-27T13:14:57.624977Z","caller":"traceutil/trace.go:171","msg":"trace[663745847] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1948; }","duration":"425.736606ms","start":"2026-07-27T13:14:57.199217Z","end":"2026-07-27T13:14:57.624954Z","steps":["trace[663745847] 'range keys from in-memory index tree' (duration: 425.335103ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:14:57.624987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.83181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.86\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-07-27T13:14:57.625028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:14:57.199198Z","time spent":"425.817315ms","remote":"127.0.0.1:50820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-07-27T13:14:57.625071Z","caller":"traceutil/trace.go:171","msg":"trace[1953076292] range","detail":"{range_begin:/registry/masterleases/199.19.213.86; range_end:; response_count:1; response_revision:1948; }","duration":"214.985037ms","start":"2026-07-27T13:14:57.410067Z","end":"2026-07-27T13:14:57.625052Z","steps":["trace[1953076292] 'range keys from in-memory index tree' (duration: 214.498134ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:14:57.625084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.859415ms","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-07-27T13:14:57.625486Z","caller":"traceutil/trace.go:171","msg":"trace[474620466] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1948; }","duration":"433.065907ms","start":"2026-07-27T13:14:57.192177Z","end":"2026-07-27T13:14:57.625243Z","steps":["trace[474620466] 'range keys from in-memory index tree' (duration: 432.443669ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:14:57.625548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:14:57.192157Z","time spent":"433.377451ms","remote":"127.0.0.1:42884","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-07-27T13:15:10.439262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.662883ms","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-07-27T13:15:10.439329Z","caller":"traceutil/trace.go:171","msg":"trace[1371159342] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2058; }","duration":"175.797111ms","start":"2026-07-27T13:15:10.263518Z","end":"2026-07-27T13:15:10.439315Z","steps":["trace[1371159342] 'range keys from in-memory index tree' (duration: 175.442234ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:15:32.823519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.847442ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-27T13:15:32.823899Z","caller":"traceutil/trace.go:171","msg":"trace[1276049262] linearizableReadLoop","detail":"{readStateIndex:2286; appliedIndex:2284; }","duration":"226.548584ms","start":"2026-07-27T13:15:32.597328Z","end":"2026-07-27T13:15:32.823877Z","steps":["trace[1276049262] 'read index received' (duration: 85.383331ms)","trace[1276049262] 'applied index is now lower than readState.Index' (duration: 141.164133ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T13:15:32.823959Z","caller":"traceutil/trace.go:171","msg":"trace[629736744] transaction","detail":"{read_only:false; response_revision:2196; number_of_response:1; }","duration":"254.287681ms","start":"2026-07-27T13:15:32.569627Z","end":"2026-07-27T13:15:32.823915Z","steps":["trace[629736744] 'process raft request' (duration: 254.035179ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:15:32.82401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.676887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T13:15:32.824132Z","caller":"traceutil/trace.go:171","msg":"trace[1485427241] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2196; }","duration":"226.833392ms","start":"2026-07-27T13:15:32.597283Z","end":"2026-07-27T13:15:32.824116Z","steps":["trace[1485427241] 'agreement among raft nodes before linearized reading' (duration: 226.686168ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:15:32.868911Z","caller":"traceutil/trace.go:171","msg":"trace[1105421395] transaction","detail":"{read_only:false; response_revision:2197; number_of_response:1; }","duration":"137.90351ms","start":"2026-07-27T13:15:32.730964Z","end":"2026-07-27T13:15:32.868867Z","steps":["trace[1105421395] 'process raft request' (duration: 137.659371ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:15:32.869182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.768476ms","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-07-27T13:15:32.869316Z","caller":"traceutil/trace.go:171","msg":"trace[1249675913] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2197; }","duration":"187.966168ms","start":"2026-07-27T13:15:32.681328Z","end":"2026-07-27T13:15:32.869294Z","steps":["trace[1249675913] 'agreement among raft nodes before linearized reading' (duration: 187.546609ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:15:33.708908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.567763ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-27T13:15:33.709333Z","caller":"traceutil/trace.go:171","msg":"trace[1060233270] linearizableReadLoop","detail":"{readStateIndex:2291; appliedIndex:2289; }","duration":"327.696394ms","start":"2026-07-27T13:15:33.38162Z","end":"2026-07-27T13:15:33.709316Z","steps":["trace[1060233270] 'read index received' (duration: 152.498718ms)","trace[1060233270] 'applied index is now lower than readState.Index' (duration: 175.196506ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T13:15:33.709597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.958387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-27T13:15:33.709347Z","caller":"traceutil/trace.go:171","msg":"trace[204618254] transaction","detail":"{read_only:false; response_revision:2200; number_of_response:1; }","duration":"411.248445ms","start":"2026-07-27T13:15:33.298062Z","end":"2026-07-27T13:15:33.70931Z","steps":["trace[204618254] 'process raft request' (duration: 236.102827ms)","trace[204618254] 'compare' (duration: 174.42806ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T13:15:33.709677Z","caller":"traceutil/trace.go:171","msg":"trace[551621636] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2201; }","duration":"328.083428ms","start":"2026-07-27T13:15:33.381576Z","end":"2026-07-27T13:15:33.709659Z","steps":["trace[551621636] 'agreement among raft nodes before linearized reading' (duration: 327.867212ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:15:33.70975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:15:33.381559Z","time spent":"328.180854ms","remote":"127.0.0.1:42884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-27T13:15:33.709833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:15:33.298036Z","time spent":"411.715872ms","remote":"127.0.0.1:42884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T13:15:33.709418Z","caller":"traceutil/trace.go:171","msg":"trace[37550038] transaction","detail":"{read_only:false; response_revision:2201; number_of_response:1; }","duration":"376.706126ms","start":"2026-07-27T13:15:33.332697Z","end":"2026-07-27T13:15:33.709403Z","steps":["trace[37550038] 'process raft request' (duration: 376.495591ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:15:33.710062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:15:33.332672Z","time spent":"377.299584ms","remote":"127.0.0.1:42884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T13:15:33.710317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.68845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T13:15:33.710385Z","caller":"traceutil/trace.go:171","msg":"trace[701702621] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2201; }","duration":"112.787076ms","start":"2026-07-27T13:15:33.597578Z","end":"2026-07-27T13:15:33.710365Z","steps":["trace[701702621] 'agreement among raft nodes before linearized reading' (duration: 112.649263ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:15:33.710323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.364625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-27T13:15:33.710495Z","caller":"traceutil/trace.go:171","msg":"trace[58008748] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2201; }","duration":"324.571359ms","start":"2026-07-27T13:15:33.385907Z","end":"2026-07-27T13:15:33.710478Z","steps":["trace[58008748] 'agreement among raft nodes before linearized reading' (duration: 324.242044ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:15:33.710571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:15:33.385892Z","time spent":"324.662534ms","remote":"127.0.0.1:42884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-27T13:15:34.003614Z","caller":"traceutil/trace.go:171","msg":"trace[1333571099] transaction","detail":"{read_only:false; response_revision:2202; number_of_response:1; }","duration":"288.904514ms","start":"2026-07-27T13:15:33.714692Z","end":"2026-07-27T13:15:34.003596Z","steps":["trace[1333571099] 'process raft request' (duration: 288.778203ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:15:34.024292Z","caller":"traceutil/trace.go:171","msg":"trace[1802800278] linearizableReadLoop","detail":"{readStateIndex:2293; appliedIndex:2291; }","duration":"212.523796ms","start":"2026-07-27T13:15:33.811739Z","end":"2026-07-27T13:15:34.024263Z","steps":["trace[1802800278] 'read index received' (duration: 191.732419ms)","trace[1802800278] 'applied index is now lower than readState.Index' (duration: 20.771804ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T13:15:34.024444Z","caller":"traceutil/trace.go:171","msg":"trace[789422308] transaction","detail":"{read_only:false; response_revision:2203; number_of_response:1; }","duration":"309.43679ms","start":"2026-07-27T13:15:33.714992Z","end":"2026-07-27T13:15:34.024429Z","steps":["trace[789422308] 'process raft request' (duration: 309.084101ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:15:34.0246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:15:33.714981Z","time spent":"309.513012ms","remote":"127.0.0.1:42884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T13:15:34.024814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.872517ms","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-07-27T13:15:34.02487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.992406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-27T13:15:34.024944Z","caller":"traceutil/trace.go:171","msg":"trace[1840510796] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2203; }","duration":"122.099104ms","start":"2026-07-27T13:15:33.902827Z","end":"2026-07-27T13:15:34.024926Z","steps":["trace[1840510796] 'agreement among raft nodes before linearized reading' (duration: 121.878027ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:15:34.024962Z","caller":"traceutil/trace.go:171","msg":"trace[2060915777] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2203; }","duration":"171.035294ms","start":"2026-07-27T13:15:33.853872Z","end":"2026-07-27T13:15:34.024907Z","steps":["trace[2060915777] 'agreement among raft nodes before linearized reading' (duration: 170.730423ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:15:34.024826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.074157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-27T13:15:34.025347Z","caller":"traceutil/trace.go:171","msg":"trace[792664766] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:2203; }","duration":"213.598594ms","start":"2026-07-27T13:15:33.811728Z","end":"2026-07-27T13:15:34.025326Z","steps":["trace[792664766] 'agreement among raft nodes before linearized reading' (duration: 212.959578ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:15:34.188973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.316711ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-27T13:15:34.189499Z","caller":"traceutil/trace.go:171","msg":"trace[1966422757] transaction","detail":"{read_only:false; response_revision:2204; number_of_response:1; }","duration":"160.108987ms","start":"2026-07-27T13:15:34.029361Z","end":"2026-07-27T13:15:34.18947Z","steps":["trace[1966422757] 'process raft request' (duration: 28.21409ms)","trace[1966422757] 'compare' (duration: 131.201102ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T13:15:34.616374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.608161ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-27T13:15:34.61686Z","caller":"traceutil/trace.go:171","msg":"trace[1205539094] linearizableReadLoop","detail":"{readStateIndex:2296; appliedIndex:2295; }","duration":"281.12937ms","start":"2026-07-27T13:15:34.335706Z","end":"2026-07-27T13:15:34.616835Z","steps":["trace[1205539094] 'read index received' (duration: 54.927111ms)","trace[1205539094] 'applied index is now lower than readState.Index' (duration: 226.200088ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T13:15:34.617017Z","caller":"traceutil/trace.go:171","msg":"trace[1558665129] transaction","detail":"{read_only:false; response_revision:2206; number_of_response:1; }","duration":"337.156284ms","start":"2026-07-27T13:15:34.27983Z","end":"2026-07-27T13:15:34.616986Z","steps":["trace[1558665129] 'process raft request' (duration: 110.815442ms)","trace[1558665129] 'compare' (duration: 225.333375ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T13:15:34.617056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.346247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-27T13:15:34.617125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.861642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-27T13:15:34.617168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:15:34.2798Z","time spent":"337.289316ms","remote":"127.0.0.1:42884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T13:15:34.617191Z","caller":"traceutil/trace.go:171","msg":"trace[385859135] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2206; }","duration":"276.960968ms","start":"2026-07-27T13:15:34.340203Z","end":"2026-07-27T13:15:34.617164Z","steps":["trace[385859135] 'agreement among raft nodes before linearized reading' (duration: 276.864142ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:15:34.617224Z","caller":"traceutil/trace.go:171","msg":"trace[1964310385] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:2206; }","duration":"281.49551ms","start":"2026-07-27T13:15:34.335651Z","end":"2026-07-27T13:15:34.617147Z","steps":["trace[1964310385] 'agreement among raft nodes before linearized reading' (duration: 281.347666ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:16:51.075962Z","caller":"traceutil/trace.go:171","msg":"trace[949434803] transaction","detail":"{read_only:false; response_revision:2719; number_of_response:1; }","duration":"132.49592ms","start":"2026-07-27T13:16:50.943428Z","end":"2026-07-27T13:16:51.075924Z","steps":["trace[949434803] 'process raft request' (duration: 132.263039ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:17:22.627566Z","caller":"traceutil/trace.go:171","msg":"trace[1369251100] transaction","detail":"{read_only:false; response_revision:2890; number_of_response:1; }","duration":"321.617437ms","start":"2026-07-27T13:17:22.305929Z","end":"2026-07-27T13:17:22.627546Z","steps":["trace[1369251100] 'process raft request' (duration: 321.429855ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:17:22.627772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:17:22.305904Z","time spent":"321.793377ms","remote":"127.0.0.1:42884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T13:18:43.571366Z","caller":"traceutil/trace.go:171","msg":"trace[1344389608] transaction","detail":"{read_only:false; response_revision:3317; number_of_response:1; }","duration":"191.747745ms","start":"2026-07-27T13:18:43.379585Z","end":"2026-07-27T13:18:43.571333Z","steps":["trace[1344389608] 'process raft request' (duration: 156.030473ms)","trace[1344389608] 'compare' (duration: 35.399338ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T13:18:43.571513Z","caller":"traceutil/trace.go:171","msg":"trace[1553775828] transaction","detail":"{read_only:false; response_revision:3318; number_of_response:1; }","duration":"169.085611ms","start":"2026-07-27T13:18:43.402391Z","end":"2026-07-27T13:18:43.571477Z","steps":["trace[1553775828] 'process raft request' (duration: 168.835833ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:21:51.534843Z","caller":"traceutil/trace.go:171","msg":"trace[839239805] linearizableReadLoop","detail":"{readStateIndex:5193; appliedIndex:5192; }","duration":"142.053843ms","start":"2026-07-27T13:21:51.39272Z","end":"2026-07-27T13:21:51.534774Z","steps":["trace[839239805] 'read index received' (duration: 141.780002ms)","trace[839239805] 'applied index is now lower than readState.Index' (duration: 272.831µs)"],"step_count":2} {"level":"warn","ts":"2026-07-27T13:21:51.535064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.316141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-27T13:21:51.535105Z","caller":"traceutil/trace.go:171","msg":"trace[232314638] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4995; }","duration":"142.427973ms","start":"2026-07-27T13:21:51.392665Z","end":"2026-07-27T13:21:51.535093Z","steps":["trace[232314638] 'agreement among raft nodes before linearized reading' (duration: 142.263745ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:21:51.535505Z","caller":"traceutil/trace.go:171","msg":"trace[1128197438] transaction","detail":"{read_only:false; response_revision:4995; number_of_response:1; }","duration":"152.314512ms","start":"2026-07-27T13:21:51.38313Z","end":"2026-07-27T13:21:51.535445Z","steps":["trace[1128197438] 'process raft request' (duration: 151.450498ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:22:12.297386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.440408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-07-27T13:22:12.297537Z","caller":"traceutil/trace.go:171","msg":"trace[1921152072] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5280; }","duration":"150.652339ms","start":"2026-07-27T13:22:12.146861Z","end":"2026-07-27T13:22:12.297513Z","steps":["trace[1921152072] 'range keys from in-memory index tree' (duration: 150.208217ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:22:12.675747Z","caller":"traceutil/trace.go:171","msg":"trace[41930010] linearizableReadLoop","detail":"{readStateIndex:5485; appliedIndex:5484; }","duration":"182.673483ms","start":"2026-07-27T13:22:12.493041Z","end":"2026-07-27T13:22:12.675714Z","steps":["trace[41930010] 'read index received' (duration: 139.538287ms)","trace[41930010] 'applied index is now lower than readState.Index' (duration: 43.133556ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T13:22:12.675983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.921132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-27T13:22:12.676016Z","caller":"traceutil/trace.go:171","msg":"trace[1337710958] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5280; }","duration":"183.018294ms","start":"2026-07-27T13:22:12.49299Z","end":"2026-07-27T13:22:12.676008Z","steps":["trace[1337710958] 'agreement among raft nodes before linearized reading' (duration: 182.862177ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:22:12.690236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.051796ms","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-07-27T13:22:12.690363Z","caller":"traceutil/trace.go:171","msg":"trace[1238922916] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5281; }","duration":"193.213623ms","start":"2026-07-27T13:22:12.497122Z","end":"2026-07-27T13:22:12.690336Z","steps":["trace[1238922916] 'agreement among raft nodes before linearized reading' (duration: 192.88458ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:22:14.016731Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2847} {"level":"info","ts":"2026-07-27T13:22:14.09357Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2847,"took":"74.944211ms","hash":3457988286,"current-db-size-bytes":51974144,"current-db-size":"52 MB","current-db-size-in-use-bytes":27492352,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-27T13:22:14.093647Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3457988286,"revision":2847,"compact-revision":-1} {"level":"warn","ts":"2026-07-27T13:23:11.418535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.784404ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-27T13:23:11.418674Z","caller":"traceutil/trace.go:171","msg":"trace[2002911745] transaction","detail":"{read_only:false; response_revision:5758; number_of_response:1; }","duration":"210.521507ms","start":"2026-07-27T13:23:11.208128Z","end":"2026-07-27T13:23:11.41865Z","steps":["trace[2002911745] 'process raft request' (duration: 40.467565ms)","trace[2002911745] 'compare' (duration: 169.49888ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T13:23:11.454836Z","caller":"traceutil/trace.go:171","msg":"trace[1172329326] transaction","detail":"{read_only:false; response_revision:5760; number_of_response:1; }","duration":"243.897ms","start":"2026-07-27T13:23:11.210892Z","end":"2026-07-27T13:23:11.454789Z","steps":["trace[1172329326] 'process raft request' (duration: 243.817891ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:23:11.455139Z","caller":"traceutil/trace.go:171","msg":"trace[1455982082] transaction","detail":"{read_only:false; response_revision:5759; number_of_response:1; }","duration":"246.596048ms","start":"2026-07-27T13:23:11.208526Z","end":"2026-07-27T13:23:11.455122Z","steps":["trace[1455982082] 'process raft request' (duration: 245.871789ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:23:11.490509Z","caller":"traceutil/trace.go:171","msg":"trace[1753000582] transaction","detail":"{read_only:false; response_revision:5761; number_of_response:1; }","duration":"227.645781ms","start":"2026-07-27T13:23:11.262823Z","end":"2026-07-27T13:23:11.490469Z","steps":["trace[1753000582] 'process raft request' (duration: 227.497464ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:25:57.064236Z","caller":"traceutil/trace.go:171","msg":"trace[213769011] transaction","detail":"{read_only:false; response_revision:6909; number_of_response:1; }","duration":"152.147166ms","start":"2026-07-27T13:25:56.912055Z","end":"2026-07-27T13:25:57.064202Z","steps":["trace[213769011] 'process raft request' (duration: 140.112348ms)","trace[213769011] 'compare' (duration: 11.91786ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T13:26:30.761771Z","caller":"traceutil/trace.go:171","msg":"trace[2688219] transaction","detail":"{read_only:false; response_revision:7138; number_of_response:1; }","duration":"157.81771ms","start":"2026-07-27T13:26:30.603915Z","end":"2026-07-27T13:26:30.761733Z","steps":["trace[2688219] 'process raft request' (duration: 148.035148ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:26:30.761886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.80791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T13:26:30.761938Z","caller":"traceutil/trace.go:171","msg":"trace[515135851] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7138; }","duration":"157.911118ms","start":"2026-07-27T13:26:30.604009Z","end":"2026-07-27T13:26:30.76192Z","steps":["trace[515135851] 'agreement among raft nodes before linearized reading' (duration: 157.784188ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:26:30.761747Z","caller":"traceutil/trace.go:171","msg":"trace[25241145] linearizableReadLoop","detail":"{readStateIndex:7405; appliedIndex:7403; }","duration":"157.651398ms","start":"2026-07-27T13:26:30.604043Z","end":"2026-07-27T13:26:30.761695Z","steps":["trace[25241145] 'read index received' (duration: 88.897774ms)","trace[25241145] 'applied index is now lower than readState.Index' (duration: 68.752113ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T13:27:14.048469Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5301} {"level":"info","ts":"2026-07-27T13:27:14.168568Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5301,"took":"118.388244ms","hash":1974990374,"current-db-size-bytes":51974144,"current-db-size":"52 MB","current-db-size-in-use-bytes":18931712,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-07-27T13:27:14.168633Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1974990374,"revision":5301,"compact-revision":2847}