{"level":"warn","ts":"2026-05-10T04:00:45.678766Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-10T04:00:45.678848Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.244:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.244:2380","--initial-cluster=instance=https://199.204.45.244:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.244:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.244:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-10T04:00:45.678935Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-10T04:00:45.678948Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.244:2380"]} {"level":"info","ts":"2026-05-10T04:00:45.678964Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-10T04:00:45.679663Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"]} {"level":"info","ts":"2026-05-10T04:00:45.67973Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.244:2380"],"listen-peer-urls":["https://199.204.45.244:2380"],"advertise-client-urls":["https://199.204.45.244:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.244:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-10T04:00:45.684688Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.759461ms"} {"level":"info","ts":"2026-05-10T04:00:45.704198Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1686e9b1ea717edf","cluster-id":"71c60fe001ca5b3c"} {"level":"info","ts":"2026-05-10T04:00:45.704275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=()"} {"level":"info","ts":"2026-05-10T04:00:45.704294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became follower at term 0"} {"level":"info","ts":"2026-05-10T04:00:45.704304Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1686e9b1ea717edf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-10T04:00:45.70431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became follower at term 1"} {"level":"info","ts":"2026-05-10T04:00:45.704339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=(1623241666065432287)"} {"level":"warn","ts":"2026-05-10T04:00:45.710305Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-10T04:00:45.729201Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-10T04:00:45.730468Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-10T04:00:45.73398Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1686e9b1ea717edf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-10T04:00:45.734388Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-10T04:00:45.73449Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1686e9b1ea717edf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-10T04:00:45.734462Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-10T04:00:45.735106Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-10T04:00:45.735445Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=(1623241666065432287)"} {"level":"info","ts":"2026-05-10T04:00:45.735591Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"71c60fe001ca5b3c","local-member-id":"1686e9b1ea717edf","added-peer-id":"1686e9b1ea717edf","added-peer-peer-urls":["https://199.204.45.244:2380"]} {"level":"info","ts":"2026-05-10T04:00:45.738535Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-10T04:00:45.738818Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1686e9b1ea717edf","initial-advertise-peer-urls":["https://199.204.45.244:2380"],"listen-peer-urls":["https://199.204.45.244:2380"],"advertise-client-urls":["https://199.204.45.244:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-10T04:00:45.738874Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-10T04:00:45.73951Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.244:2380"} {"level":"info","ts":"2026-05-10T04:00:45.739552Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.244:2380"} {"level":"info","ts":"2026-05-10T04:00:45.805307Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf is starting a new election at term 1"} {"level":"info","ts":"2026-05-10T04:00:45.805359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became pre-candidate at term 1"} {"level":"info","ts":"2026-05-10T04:00:45.805404Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf received MsgPreVoteResp from 1686e9b1ea717edf at term 1"} {"level":"info","ts":"2026-05-10T04:00:45.805419Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became candidate at term 2"} {"level":"info","ts":"2026-05-10T04:00:45.805427Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf received MsgVoteResp from 1686e9b1ea717edf at term 2"} {"level":"info","ts":"2026-05-10T04:00:45.805438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became leader at term 2"} {"level":"info","ts":"2026-05-10T04:00:45.805459Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1686e9b1ea717edf elected leader 1686e9b1ea717edf at term 2"} {"level":"info","ts":"2026-05-10T04:00:45.806263Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1686e9b1ea717edf","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.244:2379]}","request-path":"/0/members/1686e9b1ea717edf/attributes","cluster-id":"71c60fe001ca5b3c","publish-timeout":"7s"} {"level":"info","ts":"2026-05-10T04:00:45.80635Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-10T04:00:45.806432Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-10T04:00:45.806466Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-10T04:00:45.806504Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-10T04:00:45.806292Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-10T04:00:45.811884Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"71c60fe001ca5b3c","local-member-id":"1686e9b1ea717edf","cluster-version":"3.5"} {"level":"info","ts":"2026-05-10T04:00:45.812Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-10T04:00:45.812053Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-10T04:00:45.81178Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-10T04:00:45.813011Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.244:2379"} {"level":"info","ts":"2026-05-10T04:00:47.906921Z","caller":"traceutil/trace.go:171","msg":"trace[344884438] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"114.710949ms","start":"2026-05-10T04:00:47.792177Z","end":"2026-05-10T04:00:47.906888Z","steps":["trace[344884438] 'read index received' (duration: 114.480123ms)","trace[344884438] 'applied index is now lower than readState.Index' (duration: 229.356µs)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:00:47.906947Z","caller":"traceutil/trace.go:171","msg":"trace[322032614] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"115.904202ms","start":"2026-05-10T04:00:47.791011Z","end":"2026-05-10T04:00:47.906915Z","steps":["trace[322032614] 'process raft request' (duration: 115.632324ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:00:47.907278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.994037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T04:00:47.907416Z","caller":"traceutil/trace.go:171","msg":"trace[579096518] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:2; }","duration":"115.236474ms","start":"2026-05-10T04:00:47.792152Z","end":"2026-05-10T04:00:47.907389Z","steps":["trace[579096518] 'agreement among raft nodes before linearized reading' (duration: 114.863673ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:00:48.15151Z","caller":"traceutil/trace.go:171","msg":"trace[1408542053] linearizableReadLoop","detail":"{readStateIndex:13; appliedIndex:5; }","duration":"244.466744ms","start":"2026-05-10T04:00:47.90702Z","end":"2026-05-10T04:00:48.151486Z","steps":["trace[1408542053] 'read index received' (duration: 243.93976ms)","trace[1408542053] 'applied index is now lower than readState.Index' (duration: 525.914µs)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:00:48.151551Z","caller":"traceutil/trace.go:171","msg":"trace[360625732] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"289.459217ms","start":"2026-05-10T04:00:47.862058Z","end":"2026-05-10T04:00:48.151517Z","steps":["trace[360625732] 'process raft request' (duration: 288.913382ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:00:48.151649Z","caller":"traceutil/trace.go:171","msg":"trace[1308135298] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"289.486557ms","start":"2026-05-10T04:00:47.86215Z","end":"2026-05-10T04:00:48.151637Z","steps":["trace[1308135298] 'process raft request' (duration: 289.148018ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:00:48.152071Z","caller":"traceutil/trace.go:171","msg":"trace[2137356910] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"289.840177ms","start":"2026-05-10T04:00:47.862216Z","end":"2026-05-10T04:00:48.152056Z","steps":["trace[2137356910] 'process raft request' (duration: 289.108657ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:00:48.152077Z","caller":"traceutil/trace.go:171","msg":"trace[328950596] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"289.853628ms","start":"2026-05-10T04:00:47.862132Z","end":"2026-05-10T04:00:48.151986Z","steps":["trace[328950596] 'process raft request' (duration: 289.125108ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:00:48.152221Z","caller":"traceutil/trace.go:171","msg":"trace[1619913764] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"289.996542ms","start":"2026-05-10T04:00:47.862217Z","end":"2026-05-10T04:00:48.152213Z","steps":["trace[1619913764] 'process raft request' (duration: 289.146858ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:00:48.152405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.256136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T04:00:48.152957Z","caller":"traceutil/trace.go:171","msg":"trace[476478165] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"291.849253ms","start":"2026-05-10T04:00:47.86109Z","end":"2026-05-10T04:00:48.152939Z","steps":["trace[476478165] 'agreement among raft nodes before linearized reading' (duration: 291.207725ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:00:48.152463Z","caller":"traceutil/trace.go:171","msg":"trace[68849467] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"290.047333ms","start":"2026-05-10T04:00:47.862398Z","end":"2026-05-10T04:00:48.152445Z","steps":["trace[68849467] 'process raft request' (duration: 288.991424ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:00:48.152497Z","caller":"traceutil/trace.go:171","msg":"trace[740054675] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"278.217936ms","start":"2026-05-10T04:00:47.874271Z","end":"2026-05-10T04:00:48.152489Z","steps":["trace[740054675] 'process raft request' (duration: 277.177768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:00:48.152539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.060914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T04:00:48.153738Z","caller":"traceutil/trace.go:171","msg":"trace[1817004418] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:10; }","duration":"358.291298ms","start":"2026-05-10T04:00:47.795428Z","end":"2026-05-10T04:00:48.153719Z","steps":["trace[1817004418] 'agreement among raft nodes before linearized reading' (duration: 357.020593ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:00:48.15379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:00:47.795424Z","time spent":"358.351989ms","remote":"127.0.0.1:46712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-10T04:00:48.152541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.795686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T04:00:48.153949Z","caller":"traceutil/trace.go:171","msg":"trace[24899514] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"291.261587ms","start":"2026-05-10T04:00:47.862675Z","end":"2026-05-10T04:00:48.153936Z","steps":["trace[24899514] 'agreement among raft nodes before linearized reading' (duration: 289.838907ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:00:48.152592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.122545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T04:00:48.154081Z","caller":"traceutil/trace.go:171","msg":"trace[1168697448] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:0; response_revision:10; }","duration":"358.611627ms","start":"2026-05-10T04:00:47.795451Z","end":"2026-05-10T04:00:48.154062Z","steps":["trace[1168697448] 'agreement among raft nodes before linearized reading' (duration: 357.125356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:00:48.152598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.294932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.244\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-10T04:00:48.154251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:00:47.795444Z","time spent":"358.775112ms","remote":"127.0.0.1:46534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":0,"response size":26,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"info","ts":"2026-05-10T04:00:48.154292Z","caller":"traceutil/trace.go:171","msg":"trace[542834054] range","detail":"{range_begin:/registry/masterleases/199.204.45.244; range_end:; response_count:0; response_revision:10; }","duration":"295.00071ms","start":"2026-05-10T04:00:47.859261Z","end":"2026-05-10T04:00:48.154261Z","steps":["trace[542834054] 'agreement among raft nodes before linearized reading' (duration: 293.311933ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:00:48.15261Z","caller":"traceutil/trace.go:171","msg":"trace[1182915084] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"283.614124ms","start":"2026-05-10T04:00:47.868975Z","end":"2026-05-10T04:00:48.152589Z","steps":["trace[1182915084] 'process raft request' (duration: 282.440503ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:00:48.152648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.990489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T04:00:48.155844Z","caller":"traceutil/trace.go:171","msg":"trace[2096791468] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"243.156909ms","start":"2026-05-10T04:00:47.912612Z","end":"2026-05-10T04:00:48.155769Z","steps":["trace[2096791468] 'agreement among raft nodes before linearized reading' (duration: 240.019031ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:00:48.152678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.003239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-10T04:00:48.152698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.848008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-10T04:00:48.15277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.606689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T04:00:48.156039Z","caller":"traceutil/trace.go:171","msg":"trace[2123619436] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:10; }","duration":"235.348151ms","start":"2026-05-10T04:00:47.92067Z","end":"2026-05-10T04:00:48.156018Z","steps":["trace[2123619436] 'agreement among raft nodes before linearized reading' (duration: 231.993868ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:00:48.156266Z","caller":"traceutil/trace.go:171","msg":"trace[19677489] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:10; }","duration":"361.109375ms","start":"2026-05-10T04:00:47.795132Z","end":"2026-05-10T04:00:48.15616Z","steps":["trace[19677489] 'agreement among raft nodes before linearized reading' (duration: 357.577778ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:00:48.156242Z","caller":"traceutil/trace.go:171","msg":"trace[1591684575] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"221.281073ms","start":"2026-05-10T04:00:47.934795Z","end":"2026-05-10T04:00:48.156076Z","steps":["trace[1591684575] 'agreement among raft nodes before linearized reading' (duration: 217.850208ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:00:48.1564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:00:47.795127Z","time spent":"361.227919ms","remote":"127.0.0.1:46712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-10T04:01:18.73527Z","caller":"traceutil/trace.go:171","msg":"trace[22186796] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"387.859141ms","start":"2026-05-10T04:01:18.347382Z","end":"2026-05-10T04:01:18.735241Z","steps":["trace[22186796] 'process raft request' (duration: 387.688646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:18.735774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:01:18.347354Z","time spent":"387.993884ms","remote":"127.0.0.1:46512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-10T04:01:18.75483Z","caller":"traceutil/trace.go:171","msg":"trace[37905817] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"401.43281ms","start":"2026-05-10T04:01:18.353373Z","end":"2026-05-10T04:01:18.754806Z","steps":["trace[37905817] 'process raft request' (duration: 400.997898ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:18.754912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:01:18.353315Z","time spent":"401.556364ms","remote":"127.0.0.1:46512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-10T04:01:18.754797Z","caller":"traceutil/trace.go:171","msg":"trace[470245979] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"388.54609ms","start":"2026-05-10T04:01:18.366223Z","end":"2026-05-10T04:01:18.754769Z","steps":["trace[470245979] 'process raft request' (duration: 388.318453ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:01:18.755104Z","caller":"traceutil/trace.go:171","msg":"trace[1844553818] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"359.894672ms","start":"2026-05-10T04:01:18.395113Z","end":"2026-05-10T04:01:18.755008Z","steps":["trace[1844553818] 'process raft request' (duration: 359.569744ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:01:18.755167Z","caller":"traceutil/trace.go:171","msg":"trace[1038109172] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"324.619853ms","start":"2026-05-10T04:01:18.430539Z","end":"2026-05-10T04:01:18.755159Z","steps":["trace[1038109172] 'process raft request' (duration: 324.301715ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:01:18.755002Z","caller":"traceutil/trace.go:171","msg":"trace[1499050146] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"395.343805ms","start":"2026-05-10T04:01:18.359647Z","end":"2026-05-10T04:01:18.754991Z","steps":["trace[1499050146] 'process raft request' (duration: 394.854681ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:18.755228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:01:18.366199Z","time spent":"388.926349ms","remote":"127.0.0.1:46512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-10T04:01:18.755296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:01:18.395097Z","time spent":"360.109517ms","remote":"127.0.0.1:46512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-10T04:01:18.754969Z","caller":"traceutil/trace.go:171","msg":"trace[2079115413] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"374.073978ms","start":"2026-05-10T04:01:18.380759Z","end":"2026-05-10T04:01:18.754833Z","steps":["trace[2079115413] 'process raft request' (duration: 373.860061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:18.755405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:01:18.380737Z","time spent":"374.634944ms","remote":"127.0.0.1:46512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-10T04:01:18.755037Z","caller":"traceutil/trace.go:171","msg":"trace[652111068] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"374.20299ms","start":"2026-05-10T04:01:18.380828Z","end":"2026-05-10T04:01:18.755031Z","steps":["trace[652111068] 'process raft request' (duration: 373.82268ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:18.755481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:01:18.380824Z","time spent":"374.632682ms","remote":"127.0.0.1:46512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-10T04:01:18.755067Z","caller":"traceutil/trace.go:171","msg":"trace[784841153] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"345.038879ms","start":"2026-05-10T04:01:18.410022Z","end":"2026-05-10T04:01:18.755061Z","steps":["trace[784841153] 'process raft request' (duration: 344.767402ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:18.755495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:01:18.359619Z","time spent":"395.678104ms","remote":"127.0.0.1:46512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-10T04:01:18.755058Z","caller":"traceutil/trace.go:171","msg":"trace[1165715301] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"345.049019ms","start":"2026-05-10T04:01:18.409978Z","end":"2026-05-10T04:01:18.755027Z","steps":["trace[1165715301] 'process raft request' (duration: 344.744201ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:18.755647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:01:18.409959Z","time spent":"345.670906ms","remote":"127.0.0.1:46512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-10T04:01:18.75559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:01:18.410005Z","time spent":"345.556452ms","remote":"127.0.0.1:46512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-10T04:01:18.755078Z","caller":"traceutil/trace.go:171","msg":"trace[2065844430] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"382.035513ms","start":"2026-05-10T04:01:18.373021Z","end":"2026-05-10T04:01:18.755057Z","steps":["trace[2065844430] 'process raft request' (duration: 381.55842ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:18.755228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:01:18.430514Z","time spent":"324.683265ms","remote":"127.0.0.1:46512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-10T04:01:18.755779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:01:18.372979Z","time spent":"382.755143ms","remote":"127.0.0.1:46512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-10T04:01:18.755821Z","caller":"traceutil/trace.go:171","msg":"trace[281311352] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"225.676338ms","start":"2026-05-10T04:01:18.530123Z","end":"2026-05-10T04:01:18.7558Z","steps":["trace[281311352] 'process raft request' (duration: 224.839325ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:01:18.755813Z","caller":"traceutil/trace.go:171","msg":"trace[1685836152] linearizableReadLoop","detail":"{readStateIndex:503; appliedIndex:493; }","duration":"284.869295ms","start":"2026-05-10T04:01:18.470916Z","end":"2026-05-10T04:01:18.755785Z","steps":["trace[1685836152] 'read index received' (duration: 264.581394ms)","trace[1685836152] 'applied index is now lower than readState.Index' (duration: 20.286861ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:01:18.756016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.08705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-10T04:01:18.756068Z","caller":"traceutil/trace.go:171","msg":"trace[280032867] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:486; }","duration":"285.192254ms","start":"2026-05-10T04:01:18.470866Z","end":"2026-05-10T04:01:18.756058Z","steps":["trace[280032867] 'agreement among raft nodes before linearized reading' (duration: 284.997488ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:19.243155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.535299ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-10T04:01:19.243408Z","caller":"traceutil/trace.go:171","msg":"trace[1617130429] linearizableReadLoop","detail":"{readStateIndex:507; appliedIndex:504; }","duration":"487.462407ms","start":"2026-05-10T04:01:18.75593Z","end":"2026-05-10T04:01:19.243392Z","steps":["trace[1617130429] 'read index received' (duration: 140.564717ms)","trace[1617130429] 'applied index is now lower than readState.Index' (duration: 346.89641ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:01:19.243413Z","caller":"traceutil/trace.go:171","msg":"trace[479606279] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"501.624421ms","start":"2026-05-10T04:01:18.741758Z","end":"2026-05-10T04:01:19.243382Z","steps":["trace[479606279] 'process raft request' (duration: 154.614719ms)","trace[479606279] 'compare' (duration: 346.361365ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:01:19.243477Z","caller":"traceutil/trace.go:171","msg":"trace[45091412] transaction","detail":"{read_only:false; number_of_response:1; response_revision:488; }","duration":"499.72768ms","start":"2026-05-10T04:01:18.743742Z","end":"2026-05-10T04:01:19.24347Z","steps":["trace[45091412] 'process raft request' (duration: 499.596286ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:01:19.243496Z","caller":"traceutil/trace.go:171","msg":"trace[1648513685] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"500.191552ms","start":"2026-05-10T04:01:18.743274Z","end":"2026-05-10T04:01:19.243466Z","steps":["trace[1648513685] 'process raft request' (duration: 500.007687ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:19.243628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:01:18.741738Z","time spent":"501.749464ms","remote":"127.0.0.1:46952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-10T04:01:19.24369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:01:18.743734Z","time spent":"499.76516ms","remote":"127.0.0.1:46512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":6936,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T04:01:19.243705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:01:18.743268Z","time spent":"500.302675ms","remote":"127.0.0.1:46512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T04:01:19.244044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.504797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-10T04:01:19.244173Z","caller":"traceutil/trace.go:171","msg":"trace[269687727] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:488; }","duration":"761.685241ms","start":"2026-05-10T04:01:18.48247Z","end":"2026-05-10T04:01:19.244156Z","steps":["trace[269687727] 'agreement among raft nodes before linearized reading' (duration: 761.463525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:19.244218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:01:18.48245Z","time spent":"761.757124ms","remote":"127.0.0.1:46712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-10T04:01:19.244345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.869984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-10T04:01:19.244386Z","caller":"traceutil/trace.go:171","msg":"trace[1374372076] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:488; }","duration":"720.956566ms","start":"2026-05-10T04:01:18.523406Z","end":"2026-05-10T04:01:19.244363Z","steps":["trace[1374372076] 'agreement among raft nodes before linearized reading' (duration: 720.72194ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:19.244406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:01:18.523358Z","time spent":"721.044369ms","remote":"127.0.0.1:46712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-10T04:01:19.299228Z","caller":"traceutil/trace.go:171","msg":"trace[772172610] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"539.744126ms","start":"2026-05-10T04:01:18.759446Z","end":"2026-05-10T04:01:19.29919Z","steps":["trace[772172610] 'process raft request' (duration: 539.222922ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:19.299493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:01:18.759422Z","time spent":"539.974292ms","remote":"127.0.0.1:46712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T04:01:19.299909Z","caller":"traceutil/trace.go:171","msg":"trace[1182005421] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"536.034594ms","start":"2026-05-10T04:01:18.763855Z","end":"2026-05-10T04:01:19.29989Z","steps":["trace[1182005421] 'process raft request' (duration: 535.14702ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:19.300033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:01:18.763845Z","time spent":"536.151408ms","remote":"127.0.0.1:46952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-10T04:01:19.300141Z","caller":"traceutil/trace.go:171","msg":"trace[2047413927] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"532.826908ms","start":"2026-05-10T04:01:18.767295Z","end":"2026-05-10T04:01:19.300122Z","steps":["trace[2047413927] 'process raft request' (duration: 532.583341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:19.30022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:01:18.767283Z","time spent":"532.90343ms","remote":"127.0.0.1:46952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-10T04:01:19.300876Z","caller":"traceutil/trace.go:171","msg":"trace[1654817466] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"530.171267ms","start":"2026-05-10T04:01:18.770688Z","end":"2026-05-10T04:01:19.300859Z","steps":["trace[1654817466] 'process raft request' (duration: 529.280042ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:19.300986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:01:18.77067Z","time spent":"530.285089ms","remote":"127.0.0.1:46952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-10T04:01:19.301041Z","caller":"traceutil/trace.go:171","msg":"trace[1615958083] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"523.738431ms","start":"2026-05-10T04:01:18.777282Z","end":"2026-05-10T04:01:19.30102Z","steps":["trace[1615958083] 'process raft request' (duration: 523.499125ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:01:19.301319Z","caller":"traceutil/trace.go:171","msg":"trace[2076619805] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"523.936905ms","start":"2026-05-10T04:01:18.77737Z","end":"2026-05-10T04:01:19.301307Z","steps":["trace[2076619805] 'process raft request' (duration: 523.572956ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:19.301438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:01:18.777365Z","time spent":"524.026669ms","remote":"127.0.0.1:46512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T04:01:19.301576Z","caller":"traceutil/trace.go:171","msg":"trace[815026733] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"495.550556ms","start":"2026-05-10T04:01:18.806009Z","end":"2026-05-10T04:01:19.301559Z","steps":["trace[815026733] 'process raft request' (duration: 495.510705ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:19.301655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:01:18.805969Z","time spent":"495.652128ms","remote":"127.0.0.1:46512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-10T04:01:19.301588Z","caller":"traceutil/trace.go:171","msg":"trace[564865768] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"524.011499ms","start":"2026-05-10T04:01:18.777538Z","end":"2026-05-10T04:01:19.30155Z","steps":["trace[564865768] 'process raft request' (duration: 523.653428ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:19.302012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:01:18.77753Z","time spent":"524.381818ms","remote":"127.0.0.1:46952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-10T04:01:19.301783Z","caller":"traceutil/trace.go:171","msg":"trace[1183844292] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"520.40763ms","start":"2026-05-10T04:01:18.781363Z","end":"2026-05-10T04:01:19.301771Z","steps":["trace[1183844292] 'process raft request' (duration: 520.022729ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:19.301221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:01:18.777264Z","time spent":"523.864104ms","remote":"127.0.0.1:46512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T04:01:19.302157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:01:18.781356Z","time spent":"520.75749ms","remote":"127.0.0.1:46512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T04:01:19.301783Z","caller":"traceutil/trace.go:171","msg":"trace[1880898613] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"523.554446ms","start":"2026-05-10T04:01:18.778054Z","end":"2026-05-10T04:01:19.301608Z","steps":["trace[1880898613] 'process raft request' (duration: 523.209576ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:19.302321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:01:18.778046Z","time spent":"524.230764ms","remote":"127.0.0.1:46512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T04:01:19.318496Z","caller":"traceutil/trace.go:171","msg":"trace[439750299] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"342.208542ms","start":"2026-05-10T04:01:18.976264Z","end":"2026-05-10T04:01:19.318472Z","steps":["trace[439750299] 'process raft request' (duration: 341.818931ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:19.320197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:01:18.976234Z","time spent":"342.418928ms","remote":"127.0.0.1:46712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T04:01:19.322455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.942698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-10T04:01:19.324238Z","caller":"traceutil/trace.go:171","msg":"trace[1310408137] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:503; }","duration":"493.769457ms","start":"2026-05-10T04:01:18.830448Z","end":"2026-05-10T04:01:19.324217Z","steps":["trace[1310408137] 'agreement among raft nodes before linearized reading' (duration: 491.834324ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:19.324379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:01:18.830433Z","time spent":"493.922001ms","remote":"127.0.0.1:46712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-10T04:01:19.772309Z","caller":"traceutil/trace.go:171","msg":"trace[1419522547] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"137.664998ms","start":"2026-05-10T04:01:19.634617Z","end":"2026-05-10T04:01:19.772282Z","steps":["trace[1419522547] 'process raft request' (duration: 71.029819ms)","trace[1419522547] 'compare' (duration: 66.466465ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:01:19.774423Z","caller":"traceutil/trace.go:171","msg":"trace[1240064540] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"125.98226ms","start":"2026-05-10T04:01:19.64843Z","end":"2026-05-10T04:01:19.774412Z","steps":["trace[1240064540] 'process raft request' (duration: 125.916509ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:01:19.774512Z","caller":"traceutil/trace.go:171","msg":"trace[359623917] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"136.138796ms","start":"2026-05-10T04:01:19.638326Z","end":"2026-05-10T04:01:19.774465Z","steps":["trace[359623917] 'process raft request' (duration: 135.945831ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:01:20.091619Z","caller":"traceutil/trace.go:171","msg":"trace[468002081] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"157.189518ms","start":"2026-05-10T04:01:19.933495Z","end":"2026-05-10T04:01:20.090684Z","steps":["trace[468002081] 'process raft request' (duration: 156.871399ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:01:20.098101Z","caller":"traceutil/trace.go:171","msg":"trace[975702748] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"164.095355ms","start":"2026-05-10T04:01:19.933994Z","end":"2026-05-10T04:01:20.09809Z","steps":["trace[975702748] 'process raft request' (duration: 163.89554ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:21.071968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.670035ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-10T04:01:21.072114Z","caller":"traceutil/trace.go:171","msg":"trace[1621385804] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"813.325325ms","start":"2026-05-10T04:01:20.258739Z","end":"2026-05-10T04:01:21.072064Z","steps":["trace[1621385804] 'process raft request' (duration: 393.487214ms)","trace[1621385804] 'compare' (duration: 419.519211ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:01:21.072209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:01:20.258719Z","time spent":"813.436527ms","remote":"127.0.0.1:46512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":98689,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T04:01:21.07952Z","caller":"traceutil/trace.go:171","msg":"trace[1292290952] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"671.602635ms","start":"2026-05-10T04:01:20.407902Z","end":"2026-05-10T04:01:21.079505Z","steps":["trace[1292290952] 'process raft request' (duration: 671.478171ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:21.0796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:01:20.407874Z","time spent":"671.690227ms","remote":"127.0.0.1:46512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-10T04:01:21.079709Z","caller":"traceutil/trace.go:171","msg":"trace[1156694861] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"779.675321ms","start":"2026-05-10T04:01:20.300026Z","end":"2026-05-10T04:01:21.079702Z","steps":["trace[1156694861] 'process raft request' (duration: 779.134966ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:21.079742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:01:20.299996Z","time spent":"779.722871ms","remote":"127.0.0.1:46512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151221,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T04:01:25.773282Z","caller":"traceutil/trace.go:171","msg":"trace[1022132456] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:595; }","duration":"222.997756ms","start":"2026-05-10T04:01:25.550259Z","end":"2026-05-10T04:01:25.773257Z","steps":["trace[1022132456] 'read index received' (duration: 222.41419ms)","trace[1022132456] 'applied index is now lower than readState.Index' (duration: 582.326µs)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:01:25.773343Z","caller":"traceutil/trace.go:171","msg":"trace[1387879703] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"278.80326ms","start":"2026-05-10T04:01:25.494505Z","end":"2026-05-10T04:01:25.773308Z","steps":["trace[1387879703] 'process raft request' (duration: 278.367078ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:25.773469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.181471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:01:25.773544Z","caller":"traceutil/trace.go:171","msg":"trace[288619650] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:575; }","duration":"223.267672ms","start":"2026-05-10T04:01:25.550234Z","end":"2026-05-10T04:01:25.773501Z","steps":["trace[288619650] 'agreement among raft nodes before linearized reading' (duration: 223.163379ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:25.773798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.056164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:01:25.773847Z","caller":"traceutil/trace.go:171","msg":"trace[1949884225] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:575; }","duration":"129.144658ms","start":"2026-05-10T04:01:25.64469Z","end":"2026-05-10T04:01:25.773834Z","steps":["trace[1949884225] 'agreement among raft nodes before linearized reading' (duration: 129.044604ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:25.773831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.755876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:01:25.773922Z","caller":"traceutil/trace.go:171","msg":"trace[348414953] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:575; }","duration":"100.861898ms","start":"2026-05-10T04:01:25.673038Z","end":"2026-05-10T04:01:25.7739Z","steps":["trace[348414953] 'agreement among raft nodes before linearized reading' (duration: 100.699273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:25.773968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.438455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:01:25.774014Z","caller":"traceutil/trace.go:171","msg":"trace[1944734204] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:575; }","duration":"101.488966ms","start":"2026-05-10T04:01:25.672513Z","end":"2026-05-10T04:01:25.774002Z","steps":["trace[1944734204] 'agreement among raft nodes before linearized reading' (duration: 101.427454ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:25.774027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.18278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:01:25.774056Z","caller":"traceutil/trace.go:171","msg":"trace[1462648341] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:575; }","duration":"128.254572ms","start":"2026-05-10T04:01:25.645794Z","end":"2026-05-10T04:01:25.774048Z","steps":["trace[1462648341] 'agreement among raft nodes before linearized reading' (duration: 128.207721ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:25.774104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.576807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:01:25.774136Z","caller":"traceutil/trace.go:171","msg":"trace[1881645345] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:575; }","duration":"101.624919ms","start":"2026-05-10T04:01:25.672502Z","end":"2026-05-10T04:01:25.774127Z","steps":["trace[1881645345] 'agreement among raft nodes before linearized reading' (duration: 101.578738ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:25.774184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.428634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:01:25.774218Z","caller":"traceutil/trace.go:171","msg":"trace[611263613] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:575; }","duration":"129.497456ms","start":"2026-05-10T04:01:25.644713Z","end":"2026-05-10T04:01:25.77421Z","steps":["trace[611263613] 'agreement among raft nodes before linearized reading' (duration: 129.450295ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:26.050228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.013607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-10T04:01:26.050273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.588474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:01:26.050368Z","caller":"traceutil/trace.go:171","msg":"trace[803486047] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:575; }","duration":"183.73467ms","start":"2026-05-10T04:01:25.866609Z","end":"2026-05-10T04:01:26.050344Z","steps":["trace[803486047] 'range keys from in-memory index tree' (duration: 183.492792ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:01:26.050468Z","caller":"traceutil/trace.go:171","msg":"trace[1089440568] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:575; }","duration":"184.14572ms","start":"2026-05-10T04:01:25.866143Z","end":"2026-05-10T04:01:26.050289Z","steps":["trace[1089440568] 'count revisions from in-memory index tree' (duration: 183.935393ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:26.050861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.631553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:01:26.050933Z","caller":"traceutil/trace.go:171","msg":"trace[208815578] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:575; }","duration":"184.806787ms","start":"2026-05-10T04:01:25.866107Z","end":"2026-05-10T04:01:26.050914Z","steps":["trace[208815578] 'range keys from in-memory index tree' (duration: 184.5168ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:26.787038Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199560333987079,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-10T04:01:27.152704Z","caller":"traceutil/trace.go:171","msg":"trace[1256414139] linearizableReadLoop","detail":"{readStateIndex:597; appliedIndex:596; }","duration":"865.717566ms","start":"2026-05-10T04:01:26.28697Z","end":"2026-05-10T04:01:27.152688Z","steps":["trace[1256414139] 'read index received' (duration: 862.843098ms)","trace[1256414139] 'applied index is now lower than readState.Index' (duration: 2.873928ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:01:27.153053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"865.776598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-10T04:01:27.153057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"866.039475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:01:27.153072Z","caller":"traceutil/trace.go:171","msg":"trace[54203676] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"913.187646ms","start":"2026-05-10T04:01:26.239854Z","end":"2026-05-10T04:01:27.153042Z","steps":["trace[54203676] 'process raft request' (duration: 910.903053ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:01:27.153135Z","caller":"traceutil/trace.go:171","msg":"trace[1697931354] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:576; }","duration":"866.166109ms","start":"2026-05-10T04:01:26.286951Z","end":"2026-05-10T04:01:27.153117Z","steps":["trace[1697931354] 'agreement among raft nodes before linearized reading' (duration: 865.981183ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:27.153188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.909765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-10T04:01:27.153199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:01:26.286942Z","time spent":"866.245191ms","remote":"127.0.0.1:34526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 "} {"level":"info","ts":"2026-05-10T04:01:27.153221Z","caller":"traceutil/trace.go:171","msg":"trace[1805493907] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:576; }","duration":"797.958047ms","start":"2026-05-10T04:01:26.355255Z","end":"2026-05-10T04:01:27.153213Z","steps":["trace[1805493907] 'agreement among raft nodes before linearized reading' (duration: 797.915836ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:27.153225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:01:26.239834Z","time spent":"913.31712ms","remote":"127.0.0.1:46564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036105,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T04:01:27.153263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:01:26.355248Z","time spent":"798.005618ms","remote":"127.0.0.1:34556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 "} {"level":"info","ts":"2026-05-10T04:01:27.153109Z","caller":"traceutil/trace.go:171","msg":"trace[1237063618] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:576; }","duration":"865.882761ms","start":"2026-05-10T04:01:26.287213Z","end":"2026-05-10T04:01:27.153096Z","steps":["trace[1237063618] 'agreement among raft nodes before linearized reading' (duration: 865.772518ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:27.153953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:01:26.287195Z","time spent":"866.745135ms","remote":"127.0.0.1:34526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-10T04:01:27.153153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.362472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:01:27.154458Z","caller":"traceutil/trace.go:171","msg":"trace[1621751865] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:576; }","duration":"601.535003ms","start":"2026-05-10T04:01:26.552758Z","end":"2026-05-10T04:01:27.154293Z","steps":["trace[1621751865] 'agreement among raft nodes before linearized reading' (duration: 600.376652ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:27.154482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:01:26.552741Z","time spent":"601.73547ms","remote":"127.0.0.1:46474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-10T04:01:27.154495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.515099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:01:27.154554Z","caller":"traceutil/trace.go:171","msg":"trace[599720802] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:576; }","duration":"799.597481ms","start":"2026-05-10T04:01:26.354929Z","end":"2026-05-10T04:01:27.154526Z","steps":["trace[599720802] 'agreement among raft nodes before linearized reading' (duration: 799.025395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:27.154589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:01:26.354892Z","time spent":"799.688113ms","remote":"127.0.0.1:34556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-05-10T04:01:27.154871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"825.847083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:01:27.154896Z","caller":"traceutil/trace.go:171","msg":"trace[764251715] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:576; }","duration":"825.898974ms","start":"2026-05-10T04:01:26.328984Z","end":"2026-05-10T04:01:27.154883Z","steps":["trace[764251715] 'agreement among raft nodes before linearized reading' (duration: 825.858283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:27.154913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:01:26.328969Z","time spent":"825.938956ms","remote":"127.0.0.1:34544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"warn","ts":"2026-05-10T04:01:27.154981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.671352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:01:27.155029Z","caller":"traceutil/trace.go:171","msg":"trace[99042543] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:576; }","duration":"845.741064ms","start":"2026-05-10T04:01:26.309264Z","end":"2026-05-10T04:01:27.155006Z","steps":["trace[99042543] 'agreement among raft nodes before linearized reading' (duration: 845.685633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:27.155054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"800.170515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-10T04:01:27.155063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:01:26.30924Z","time spent":"845.812885ms","remote":"127.0.0.1:34534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"info","ts":"2026-05-10T04:01:27.15508Z","caller":"traceutil/trace.go:171","msg":"trace[1685971204] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:576; }","duration":"800.207568ms","start":"2026-05-10T04:01:26.354859Z","end":"2026-05-10T04:01:27.155067Z","steps":["trace[1685971204] 'agreement among raft nodes before linearized reading' (duration: 800.177436ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:27.155101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:01:26.354848Z","time spent":"800.247099ms","remote":"127.0.0.1:34556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 "} {"level":"warn","ts":"2026-05-10T04:01:27.155431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.368358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-10T04:01:27.155419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"846.117384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-10T04:01:27.155419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"825.822723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vnncc\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-10T04:01:27.15548Z","caller":"traceutil/trace.go:171","msg":"trace[1144615289] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:576; }","duration":"846.224647ms","start":"2026-05-10T04:01:26.309238Z","end":"2026-05-10T04:01:27.155463Z","steps":["trace[1144615289] 'agreement among raft nodes before linearized reading' (duration: 845.909269ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:27.155521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:01:26.309195Z","time spent":"846.31803ms","remote":"127.0.0.1:34534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-05-10T04:01:27.155515Z","caller":"traceutil/trace.go:171","msg":"trace[137524560] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vnncc; range_end:; response_count:1; response_revision:576; }","duration":"825.922125ms","start":"2026-05-10T04:01:26.329566Z","end":"2026-05-10T04:01:27.155488Z","steps":["trace[137524560] 'agreement among raft nodes before linearized reading' (duration: 825.683229ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:27.155572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:01:26.329538Z","time spent":"826.012628ms","remote":"127.0.0.1:46638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-vnncc\" "} {"level":"warn","ts":"2026-05-10T04:01:27.155652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.930155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:01:27.155683Z","caller":"traceutil/trace.go:171","msg":"trace[1493166948] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:576; }","duration":"867.974556ms","start":"2026-05-10T04:01:26.287701Z","end":"2026-05-10T04:01:27.155676Z","steps":["trace[1493166948] 'agreement among raft nodes before linearized reading' (duration: 867.931385ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:27.155711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:01:26.287695Z","time spent":"868.001138ms","remote":"127.0.0.1:34526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 "} {"level":"info","ts":"2026-05-10T04:01:27.155466Z","caller":"traceutil/trace.go:171","msg":"trace[1586836765] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:576; }","duration":"826.416929ms","start":"2026-05-10T04:01:26.329038Z","end":"2026-05-10T04:01:27.155455Z","steps":["trace[1586836765] 'agreement among raft nodes before linearized reading' (duration: 826.353007ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:27.155797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:01:26.329032Z","time spent":"826.753818ms","remote":"127.0.0.1:34544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-10T04:01:27.182963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.178916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:01:27.183025Z","caller":"traceutil/trace.go:171","msg":"trace[1711162229] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:576; }","duration":"853.266388ms","start":"2026-05-10T04:01:26.329745Z","end":"2026-05-10T04:01:27.183011Z","steps":["trace[1711162229] 'agreement among raft nodes before linearized reading' (duration: 826.012067ms)","trace[1711162229] 'range keys from in-memory index tree' (duration: 27.151026ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:01:27.183176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:01:26.329739Z","time spent":"853.425543ms","remote":"127.0.0.1:34534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 "} {"level":"info","ts":"2026-05-10T04:01:28.849712Z","caller":"traceutil/trace.go:171","msg":"trace[1323385268] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"374.21778ms","start":"2026-05-10T04:01:28.475472Z","end":"2026-05-10T04:01:28.84969Z","steps":["trace[1323385268] 'process raft request' (duration: 374.099157ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:01:28.849775Z","caller":"traceutil/trace.go:171","msg":"trace[493345655] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:619; }","duration":"297.125757ms","start":"2026-05-10T04:01:28.55262Z","end":"2026-05-10T04:01:28.849746Z","steps":["trace[493345655] 'read index received' (duration: 297.115826ms)","trace[493345655] 'applied index is now lower than readState.Index' (duration: 7.961µs)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:01:28.849951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:01:28.475459Z","time spent":"374.374805ms","remote":"127.0.0.1:46712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-10T04:01:28.850003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.371744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:01:28.850042Z","caller":"traceutil/trace.go:171","msg":"trace[776170063] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:598; }","duration":"297.592001ms","start":"2026-05-10T04:01:28.552439Z","end":"2026-05-10T04:01:28.850031Z","steps":["trace[776170063] 'agreement among raft nodes before linearized reading' (duration: 297.406275ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:01:28.867172Z","caller":"traceutil/trace.go:171","msg":"trace[1956271905] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"191.922461ms","start":"2026-05-10T04:01:28.675227Z","end":"2026-05-10T04:01:28.867149Z","steps":["trace[1956271905] 'process raft request' (duration: 189.864555ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:01:43.115286Z","caller":"traceutil/trace.go:171","msg":"trace[119821309] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"169.602856ms","start":"2026-05-10T04:01:42.945656Z","end":"2026-05-10T04:01:43.115259Z","steps":["trace[119821309] 'process raft request' (duration: 169.457631ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:02:17.382741Z","caller":"traceutil/trace.go:171","msg":"trace[1347525578] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"110.019513ms","start":"2026-05-10T04:02:17.272688Z","end":"2026-05-10T04:02:17.382707Z","steps":["trace[1347525578] 'process raft request' (duration: 109.827107ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:02:18.138063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.990181ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-10T04:02:18.138367Z","caller":"traceutil/trace.go:171","msg":"trace[1366913129] linearizableReadLoop","detail":"{readStateIndex:1004; appliedIndex:1001; }","duration":"492.25642ms","start":"2026-05-10T04:02:17.646085Z","end":"2026-05-10T04:02:18.138342Z","steps":["trace[1366913129] 'read index received' (duration: 3.942831ms)","trace[1366913129] 'applied index is now lower than readState.Index' (duration: 488.312339ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:02:18.138486Z","caller":"traceutil/trace.go:171","msg":"trace[1252996352] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"494.548139ms","start":"2026-05-10T04:02:17.643924Z","end":"2026-05-10T04:02:18.138472Z","steps":["trace[1252996352] 'process raft request' (duration: 313.007953ms)","trace[1252996352] 'compare' (duration: 180.750886ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:02:18.138554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:02:17.643899Z","time spent":"494.618451ms","remote":"127.0.0.1:46712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T04:02:18.138655Z","caller":"traceutil/trace.go:171","msg":"trace[579939313] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"492.723373ms","start":"2026-05-10T04:02:17.645894Z","end":"2026-05-10T04:02:18.138618Z","steps":["trace[579939313] 'process raft request' (duration: 492.321833ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:02:18.138702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.594859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-10T04:02:18.138779Z","caller":"traceutil/trace.go:171","msg":"trace[1393070844] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:968; }","duration":"492.716261ms","start":"2026-05-10T04:02:17.646042Z","end":"2026-05-10T04:02:18.138758Z","steps":["trace[1393070844] 'agreement among raft nodes before linearized reading' (duration: 492.501706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:02:18.138814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:02:17.646016Z","time spent":"492.789614ms","remote":"127.0.0.1:46712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-10T04:02:18.138815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:02:17.645879Z","time spent":"492.849215ms","remote":"127.0.0.1:46712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T04:02:18.138816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.657778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-10T04:02:18.138918Z","caller":"traceutil/trace.go:171","msg":"trace[1894319421] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:968; }","duration":"314.853683ms","start":"2026-05-10T04:02:17.824042Z","end":"2026-05-10T04:02:18.138896Z","steps":["trace[1894319421] 'agreement among raft nodes before linearized reading' (duration: 314.575925ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:02:18.138963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:02:17.824021Z","time spent":"314.932924ms","remote":"127.0.0.1:46712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-10T04:02:19.197824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.348112ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-10T04:02:19.197994Z","caller":"traceutil/trace.go:171","msg":"trace[408722597] linearizableReadLoop","detail":"{readStateIndex:1006; appliedIndex:1004; }","duration":"645.989235ms","start":"2026-05-10T04:02:18.551988Z","end":"2026-05-10T04:02:19.197977Z","steps":["trace[408722597] 'read index received' (duration: 40.432048ms)","trace[408722597] 'applied index is now lower than readState.Index' (duration: 605.556387ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:02:19.198133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.15562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:02:19.198139Z","caller":"traceutil/trace.go:171","msg":"trace[1655810770] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"1.039180548s","start":"2026-05-10T04:02:18.158927Z","end":"2026-05-10T04:02:19.198107Z","steps":["trace[1655810770] 'process raft request' (duration: 433.476946ms)","trace[1655810770] 'compare' (duration: 604.620514ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:02:19.19817Z","caller":"traceutil/trace.go:171","msg":"trace[339536492] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:970; }","duration":"646.229632ms","start":"2026-05-10T04:02:18.551926Z","end":"2026-05-10T04:02:19.198156Z","steps":["trace[339536492] 'agreement among raft nodes before linearized reading' (duration: 646.104388ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:02:19.19821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:02:18.551907Z","time spent":"646.295254ms","remote":"127.0.0.1:46474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-10T04:02:19.198223Z","caller":"traceutil/trace.go:171","msg":"trace[87844539] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"1.039080755s","start":"2026-05-10T04:02:18.159126Z","end":"2026-05-10T04:02:19.198207Z","steps":["trace[87844539] 'process raft request' (duration: 1.038796927s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:02:19.198265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:02:18.158889Z","time spent":"1.039304601s","remote":"127.0.0.1:46712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T04:02:19.198321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:02:18.159109Z","time spent":"1.039152127s","remote":"127.0.0.1:46712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T04:02:40.094287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.516647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-10T04:02:40.094393Z","caller":"traceutil/trace.go:171","msg":"trace[1622762987] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1038; }","duration":"357.693002ms","start":"2026-05-10T04:02:39.736682Z","end":"2026-05-10T04:02:40.094375Z","steps":["trace[1622762987] 'range keys from in-memory index tree' (duration: 357.23877ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:02:40.094437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:02:39.736668Z","time spent":"357.759295ms","remote":"127.0.0.1:46712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-10T04:02:40.440347Z","caller":"traceutil/trace.go:171","msg":"trace[1780135144] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"342.130489ms","start":"2026-05-10T04:02:40.098189Z","end":"2026-05-10T04:02:40.44032Z","steps":["trace[1780135144] 'process raft request' (duration: 341.958844ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:02:40.440589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:02:40.098162Z","time spent":"342.343555ms","remote":"127.0.0.1:46712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T04:02:40.460068Z","caller":"traceutil/trace.go:171","msg":"trace[1224858517] linearizableReadLoop","detail":"{readStateIndex:1081; appliedIndex:1079; }","duration":"220.013064ms","start":"2026-05-10T04:02:40.240032Z","end":"2026-05-10T04:02:40.460045Z","steps":["trace[1224858517] 'read index received' (duration: 200.095827ms)","trace[1224858517] 'applied index is now lower than readState.Index' (duration: 19.916157ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:02:40.460399Z","caller":"traceutil/trace.go:171","msg":"trace[487757769] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"240.649668ms","start":"2026-05-10T04:02:40.219696Z","end":"2026-05-10T04:02:40.460345Z","steps":["trace[487757769] 'process raft request' (duration: 240.218848ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:02:40.460482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.423224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-10T04:02:40.460715Z","caller":"traceutil/trace.go:171","msg":"trace[2083702500] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1040; }","duration":"220.63043ms","start":"2026-05-10T04:02:40.240027Z","end":"2026-05-10T04:02:40.460657Z","steps":["trace[2083702500] 'agreement among raft nodes before linearized reading' (duration: 220.138937ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:02:41.794858Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199560333988072,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-10T04:02:41.796531Z","caller":"traceutil/trace.go:171","msg":"trace[1383063733] linearizableReadLoop","detail":"{readStateIndex:1083; appliedIndex:1082; }","duration":"502.540748ms","start":"2026-05-10T04:02:41.293947Z","end":"2026-05-10T04:02:41.796488Z","steps":["trace[1383063733] 'read index received' (duration: 502.351982ms)","trace[1383063733] 'applied index is now lower than readState.Index' (duration: 187.906µs)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:02:41.796628Z","caller":"traceutil/trace.go:171","msg":"trace[1672576936] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"669.516925ms","start":"2026-05-10T04:02:41.127077Z","end":"2026-05-10T04:02:41.796594Z","steps":["trace[1672576936] 'process raft request' (duration: 669.216737ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:02:41.79678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.818484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-10T04:02:41.796825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.618557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:02:41.796863Z","caller":"traceutil/trace.go:171","msg":"trace[1349958653] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1042; }","duration":"502.953037ms","start":"2026-05-10T04:02:41.293894Z","end":"2026-05-10T04:02:41.796848Z","steps":["trace[1349958653] 'agreement among raft nodes before linearized reading' (duration: 502.711031ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:02:41.796899Z","caller":"traceutil/trace.go:171","msg":"trace[1304033859] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1042; }","duration":"500.7346ms","start":"2026-05-10T04:02:41.296147Z","end":"2026-05-10T04:02:41.796881Z","steps":["trace[1304033859] 'agreement among raft nodes before linearized reading' (duration: 500.619137ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:02:41.796913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:02:41.293865Z","time spent":"503.03646ms","remote":"127.0.0.1:46712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-10T04:02:41.796947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:02:41.296132Z","time spent":"500.803622ms","remote":"127.0.0.1:34450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-10T04:02:41.797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.377975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:02:41.797603Z","caller":"traceutil/trace.go:171","msg":"trace[2115597723] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1042; }","duration":"245.168875ms","start":"2026-05-10T04:02:41.5524Z","end":"2026-05-10T04:02:41.797568Z","steps":["trace[2115597723] 'agreement among raft nodes before linearized reading' (duration: 244.384435ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:02:41.79705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.459868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-10T04:02:41.797798Z","caller":"traceutil/trace.go:171","msg":"trace[622778662] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1042; }","duration":"318.25567ms","start":"2026-05-10T04:02:41.479519Z","end":"2026-05-10T04:02:41.797774Z","steps":["trace[622778662] 'agreement among raft nodes before linearized reading' (duration: 317.334636ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:02:41.797862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:02:41.479501Z","time spent":"318.345543ms","remote":"127.0.0.1:46712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-10T04:02:41.79709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.503297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-10T04:02:41.797965Z","caller":"traceutil/trace.go:171","msg":"trace[966582336] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1042; }","duration":"493.41328ms","start":"2026-05-10T04:02:41.304533Z","end":"2026-05-10T04:02:41.797946Z","steps":["trace[966582336] 'agreement among raft nodes before linearized reading' (duration: 492.447925ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:02:41.796855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:02:41.127049Z","time spent":"669.683991ms","remote":"127.0.0.1:46712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T04:02:41.798024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:02:41.304511Z","time spent":"493.496253ms","remote":"127.0.0.1:46712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-10T04:02:42.007763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.940074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:02:42.00786Z","caller":"traceutil/trace.go:171","msg":"trace[786772644] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1045; }","duration":"109.102649ms","start":"2026-05-10T04:02:41.898734Z","end":"2026-05-10T04:02:42.007836Z","steps":["trace[786772644] 'count revisions from in-memory index tree' (duration: 108.826811ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:10.824149Z","caller":"traceutil/trace.go:171","msg":"trace[762380751] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"158.355369ms","start":"2026-05-10T04:03:10.665761Z","end":"2026-05-10T04:03:10.824116Z","steps":["trace[762380751] 'process raft request' (duration: 158.106343ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:14.4609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.863968ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-10T04:03:14.460995Z","caller":"traceutil/trace.go:171","msg":"trace[2098535687] linearizableReadLoop","detail":"{readStateIndex:1288; appliedIndex:1287; }","duration":"314.401336ms","start":"2026-05-10T04:03:14.146578Z","end":"2026-05-10T04:03:14.46098Z","steps":["trace[2098535687] 'read index received' (duration: 132.356794ms)","trace[2098535687] 'applied index is now lower than readState.Index' (duration: 182.043362ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:03:14.461113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.527869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:03:14.461146Z","caller":"traceutil/trace.go:171","msg":"trace[1416522457] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1239; }","duration":"314.596161ms","start":"2026-05-10T04:03:14.146541Z","end":"2026-05-10T04:03:14.461137Z","steps":["trace[1416522457] 'agreement among raft nodes before linearized reading' (duration: 314.480898ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:14.461182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:14.146522Z","time spent":"314.652552ms","remote":"127.0.0.1:46712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-10T04:03:14.461287Z","caller":"traceutil/trace.go:171","msg":"trace[145119138] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"323.103052ms","start":"2026-05-10T04:03:14.138127Z","end":"2026-05-10T04:03:14.46123Z","steps":["trace[145119138] 'process raft request' (duration: 140.847584ms)","trace[145119138] 'compare' (duration: 181.769555ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:03:14.461419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:14.138109Z","time spent":"323.252775ms","remote":"127.0.0.1:46550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-10T04:03:14.790952Z","caller":"traceutil/trace.go:171","msg":"trace[1515499385] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"326.128695ms","start":"2026-05-10T04:03:14.46471Z","end":"2026-05-10T04:03:14.790838Z","steps":["trace[1515499385] 'process raft request' (duration: 325.281482ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:14.791149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:14.464688Z","time spent":"326.367071ms","remote":"127.0.0.1:46550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":837,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-10T04:03:14.791564Z","caller":"traceutil/trace.go:171","msg":"trace[872347232] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"326.476514ms","start":"2026-05-10T04:03:14.464976Z","end":"2026-05-10T04:03:14.791453Z","steps":["trace[872347232] 'process raft request' (duration: 325.796605ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:14.791642Z","caller":"traceutil/trace.go:171","msg":"trace[1736596358] linearizableReadLoop","detail":"{readStateIndex:1291; appliedIndex:1288; }","duration":"239.684987ms","start":"2026-05-10T04:03:14.551881Z","end":"2026-05-10T04:03:14.791566Z","steps":["trace[1736596358] 'read index received' (duration: 179.807692ms)","trace[1736596358] 'applied index is now lower than readState.Index' (duration: 59.794873ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:03:14.791702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:14.464954Z","time spent":"326.687749ms","remote":"127.0.0.1:46712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-10T04:03:14.791856Z","caller":"traceutil/trace.go:171","msg":"trace[904390356] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"489.232352ms","start":"2026-05-10T04:03:14.302564Z","end":"2026-05-10T04:03:14.791797Z","steps":["trace[904390356] 'process raft request' (duration: 428.989897ms)","trace[904390356] 'compare' (duration: 58.251322ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:03:14.792018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.123349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-10T04:03:14.792119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:14.302542Z","time spent":"489.398027ms","remote":"127.0.0.1:46712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T04:03:14.79206Z","caller":"traceutil/trace.go:171","msg":"trace[270733284] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1243; }","duration":"240.218772ms","start":"2026-05-10T04:03:14.551826Z","end":"2026-05-10T04:03:14.792045Z","steps":["trace[270733284] 'agreement among raft nodes before linearized reading' (duration: 239.884512ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:14.792369Z","caller":"traceutil/trace.go:171","msg":"trace[783077645] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"119.743661ms","start":"2026-05-10T04:03:14.672603Z","end":"2026-05-10T04:03:14.792346Z","steps":["trace[783077645] 'process raft request' (duration: 118.581561ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:14.937709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.660854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-10T04:03:14.937808Z","caller":"traceutil/trace.go:171","msg":"trace[1121713163] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1245; }","duration":"103.800678ms","start":"2026-05-10T04:03:14.833982Z","end":"2026-05-10T04:03:14.937783Z","steps":["trace[1121713163] 'agreement among raft nodes before linearized reading' (duration: 56.270048ms)","trace[1121713163] 'range keys from in-memory index tree' (duration: 47.296044ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:03:14.982815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.852379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-10T04:03:14.982893Z","caller":"traceutil/trace.go:171","msg":"trace[65121759] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1246; }","duration":"142.962401ms","start":"2026-05-10T04:03:14.839911Z","end":"2026-05-10T04:03:14.982873Z","steps":["trace[65121759] 'agreement among raft nodes before linearized reading' (duration: 142.581841ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:26.586263Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199560333988645,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-10T04:03:27.087206Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199560333988645,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-10T04:03:27.39553Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.309882364s","expected-duration":"1s"} {"level":"info","ts":"2026-05-10T04:03:27.396049Z","caller":"traceutil/trace.go:171","msg":"trace[1682277949] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"1.310523891s","start":"2026-05-10T04:03:26.085506Z","end":"2026-05-10T04:03:27.39603Z","steps":["trace[1682277949] 'process raft request' (duration: 1.310393477s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:27.396292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:26.085482Z","time spent":"1.310712236s","remote":"127.0.0.1:46712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T04:03:27.628136Z","caller":"traceutil/trace.go:171","msg":"trace[770508543] linearizableReadLoop","detail":"{readStateIndex:1365; appliedIndex:1363; }","duration":"1.542305793s","start":"2026-05-10T04:03:26.085805Z","end":"2026-05-10T04:03:27.628111Z","steps":["trace[770508543] 'read index received' (duration: 1.310121979s)","trace[770508543] 'applied index is now lower than readState.Index' (duration: 232.182564ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:03:27.628184Z","caller":"traceutil/trace.go:171","msg":"trace[188309989] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"797.023719ms","start":"2026-05-10T04:03:26.831126Z","end":"2026-05-10T04:03:27.628149Z","steps":["trace[188309989] 'process raft request' (duration: 738.86237ms)","trace[188309989] 'compare' (duration: 57.999294ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:03:27.628357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.534717687s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-10T04:03:27.628375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.54255501s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-05-10T04:03:27.628397Z","caller":"traceutil/trace.go:171","msg":"trace[1895304493] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1313; }","duration":"1.534796429s","start":"2026-05-10T04:03:26.09359Z","end":"2026-05-10T04:03:27.628386Z","steps":["trace[1895304493] 'agreement among raft nodes before linearized reading' (duration: 1.534673136s)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:27.628413Z","caller":"traceutil/trace.go:171","msg":"trace[1255323024] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1313; }","duration":"1.542649454s","start":"2026-05-10T04:03:26.085752Z","end":"2026-05-10T04:03:27.628402Z","steps":["trace[1255323024] 'agreement among raft nodes before linearized reading' (duration: 1.54248371s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:27.628421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:26.093573Z","time spent":"1.53484321s","remote":"127.0.0.1:46712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-10T04:03:27.62845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:26.08573Z","time spent":"1.542712375s","remote":"127.0.0.1:46712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":494,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-10T04:03:27.62858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.075449288s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:03:27.628615Z","caller":"traceutil/trace.go:171","msg":"trace[1186578853] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1313; }","duration":"1.07551345s","start":"2026-05-10T04:03:26.553089Z","end":"2026-05-10T04:03:27.628602Z","steps":["trace[1186578853] 'agreement among raft nodes before linearized reading' (duration: 1.075469289s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:27.628639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:26.55307Z","time spent":"1.075564271s","remote":"127.0.0.1:46474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-10T04:03:27.628667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.623663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-10T04:03:27.628693Z","caller":"traceutil/trace.go:171","msg":"trace[1855668038] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1313; }","duration":"573.680595ms","start":"2026-05-10T04:03:27.055005Z","end":"2026-05-10T04:03:27.628685Z","steps":["trace[1855668038] 'agreement among raft nodes before linearized reading' (duration: 573.598883ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:27.628716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:27.054988Z","time spent":"573.724067ms","remote":"127.0.0.1:46712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-10T04:03:27.628621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:26.831102Z","time spent":"797.160733ms","remote":"127.0.0.1:46712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T04:03:27.628743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.444175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-05-10T04:03:27.628799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.26442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-10T04:03:27.628829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.856869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-10T04:03:27.628841Z","caller":"traceutil/trace.go:171","msg":"trace[869191515] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1313; }","duration":"708.590539ms","start":"2026-05-10T04:03:26.920227Z","end":"2026-05-10T04:03:27.628818Z","steps":["trace[869191515] 'agreement among raft nodes before linearized reading' (duration: 708.290141ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:27.6289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-05-10T04:03:27.628927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.429521461s","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-05-10T04:03:27.628851Z","caller":"traceutil/trace.go:171","msg":"trace[672204644] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1313; }","duration":"599.345072ms","start":"2026-05-10T04:03:27.029491Z","end":"2026-05-10T04:03:27.628836Z","steps":["trace[672204644] 'agreement among raft nodes before linearized reading' (duration: 599.175247ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:27.628953Z","caller":"traceutil/trace.go:171","msg":"trace[944257542] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1313; }","duration":"1.429586343s","start":"2026-05-10T04:03:26.199361Z","end":"2026-05-10T04:03:27.628947Z","steps":["trace[944257542] 'agreement among raft nodes before linearized reading' (duration: 1.429523641s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:27.628966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:27.029475Z","time spent":"599.481176ms","remote":"127.0.0.1:46712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-10T04:03:27.628995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:26.199338Z","time spent":"1.429647255s","remote":"127.0.0.1:46712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-10T04:03:27.629014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.075201761s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-10T04:03:27.629053Z","caller":"traceutil/trace.go:171","msg":"trace[1882629627] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1313; }","duration":"1.075276263s","start":"2026-05-10T04:03:26.553767Z","end":"2026-05-10T04:03:27.629043Z","steps":["trace[1882629627] 'agreement among raft nodes before linearized reading' (duration: 1.075205621s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:27.628915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:26.9202Z","time spent":"708.693701ms","remote":"127.0.0.1:46712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-05-10T04:03:27.629139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:26.553746Z","time spent":"1.075343195s","remote":"127.0.0.1:46806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":29,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-05-10T04:03:27.629101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.807651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:03:27.628946Z","caller":"traceutil/trace.go:171","msg":"trace[1405225243] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1313; }","duration":"834.699302ms","start":"2026-05-10T04:03:26.794233Z","end":"2026-05-10T04:03:27.628933Z","steps":["trace[1405225243] 'agreement among raft nodes before linearized reading' (duration: 834.60005ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:27.628856Z","caller":"traceutil/trace.go:171","msg":"trace[995782693] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1313; }","duration":"335.91347ms","start":"2026-05-10T04:03:27.292932Z","end":"2026-05-10T04:03:27.628846Z","steps":["trace[995782693] 'agreement among raft nodes before linearized reading' (duration: 335.842238ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:27.629242Z","caller":"traceutil/trace.go:171","msg":"trace[1827461697] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1313; }","duration":"667.967475ms","start":"2026-05-10T04:03:26.961209Z","end":"2026-05-10T04:03:27.629176Z","steps":["trace[1827461697] 'agreement among raft nodes before linearized reading' (duration: 667.641686ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:27.629261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:27.292911Z","time spent":"336.340081ms","remote":"127.0.0.1:46712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-10T04:03:27.6293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:26.961187Z","time spent":"668.098568ms","remote":"127.0.0.1:46832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":27,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-05-10T04:03:27.629311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:26.794213Z","time spent":"834.97246ms","remote":"127.0.0.1:46712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":548,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-10T04:04:10.189063Z","caller":"traceutil/trace.go:171","msg":"trace[1291653684] transaction","detail":"{read_only:false; response_revision:1514; number_of_response:1; }","duration":"118.760663ms","start":"2026-05-10T04:04:10.070276Z","end":"2026-05-10T04:04:10.189036Z","steps":["trace[1291653684] 'process raft request' (duration: 118.710252ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:04:10.189118Z","caller":"traceutil/trace.go:171","msg":"trace[46402234] transaction","detail":"{read_only:false; response_revision:1513; number_of_response:1; }","duration":"119.384179ms","start":"2026-05-10T04:04:10.069708Z","end":"2026-05-10T04:04:10.189092Z","steps":["trace[46402234] 'process raft request' (duration: 64.147675ms)","trace[46402234] 'compare' (duration: 54.964546ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:04:32.95617Z","caller":"traceutil/trace.go:171","msg":"trace[1274942307] transaction","detail":"{read_only:false; response_revision:1615; number_of_response:1; }","duration":"108.863316ms","start":"2026-05-10T04:04:32.847276Z","end":"2026-05-10T04:04:32.956139Z","steps":["trace[1274942307] 'process raft request' (duration: 78.742214ms)","trace[1274942307] 'compare' (duration: 29.976457ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:04:32.95666Z","caller":"traceutil/trace.go:171","msg":"trace[1705704386] transaction","detail":"{read_only:false; response_revision:1616; number_of_response:1; }","duration":"109.131502ms","start":"2026-05-10T04:04:32.847505Z","end":"2026-05-10T04:04:32.956637Z","steps":["trace[1705704386] 'process raft request' (duration: 109.027229ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:04:53.955743Z","caller":"traceutil/trace.go:171","msg":"trace[328779929] transaction","detail":"{read_only:false; response_revision:1709; number_of_response:1; }","duration":"168.905415ms","start":"2026-05-10T04:04:53.78679Z","end":"2026-05-10T04:04:53.955695Z","steps":["trace[328779929] 'process raft request' (duration: 168.656619ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:05:00.325664Z","caller":"traceutil/trace.go:171","msg":"trace[2052578168] transaction","detail":"{read_only:false; response_revision:1738; number_of_response:1; }","duration":"145.98601ms","start":"2026-05-10T04:05:00.179646Z","end":"2026-05-10T04:05:00.325632Z","steps":["trace[2052578168] 'process raft request' (duration: 145.813895ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:05:17.572743Z","caller":"traceutil/trace.go:171","msg":"trace[852933121] transaction","detail":"{read_only:false; response_revision:1817; number_of_response:1; }","duration":"109.206183ms","start":"2026-05-10T04:05:17.463509Z","end":"2026-05-10T04:05:17.572715Z","steps":["trace[852933121] 'process raft request' (duration: 108.997868ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:05:18.157336Z","caller":"traceutil/trace.go:171","msg":"trace[2094693970] transaction","detail":"{read_only:false; response_revision:1819; number_of_response:1; }","duration":"119.434779ms","start":"2026-05-10T04:05:18.037883Z","end":"2026-05-10T04:05:18.157317Z","steps":["trace[2094693970] 'process raft request' (duration: 115.146968ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:05:26.911113Z","caller":"traceutil/trace.go:171","msg":"trace[536644509] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"151.235936ms","start":"2026-05-10T04:05:26.759851Z","end":"2026-05-10T04:05:26.911086Z","steps":["trace[536644509] 'process raft request' (duration: 150.984569ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:05:52.715967Z","caller":"traceutil/trace.go:171","msg":"trace[215048364] transaction","detail":"{read_only:false; response_revision:1977; number_of_response:1; }","duration":"129.631674ms","start":"2026-05-10T04:05:52.58631Z","end":"2026-05-10T04:05:52.715942Z","steps":["trace[215048364] 'process raft request' (duration: 129.381948ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:05:53.19049Z","caller":"traceutil/trace.go:171","msg":"trace[789059522] transaction","detail":"{read_only:false; response_revision:1982; number_of_response:1; }","duration":"123.639259ms","start":"2026-05-10T04:05:53.06682Z","end":"2026-05-10T04:05:53.190459Z","steps":["trace[789059522] 'process raft request' (duration: 123.479484ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:05:53.218671Z","caller":"traceutil/trace.go:171","msg":"trace[1197446493] transaction","detail":"{read_only:false; response_revision:1983; number_of_response:1; }","duration":"151.775321ms","start":"2026-05-10T04:05:53.066865Z","end":"2026-05-10T04:05:53.21864Z","steps":["trace[1197446493] 'process raft request' (duration: 151.523045ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:05:58.373002Z","caller":"traceutil/trace.go:171","msg":"trace[276606679] linearizableReadLoop","detail":"{readStateIndex:2086; appliedIndex:2085; }","duration":"124.199602ms","start":"2026-05-10T04:05:58.248786Z","end":"2026-05-10T04:05:58.372986Z","steps":["trace[276606679] 'read index received' (duration: 124.049949ms)","trace[276606679] 'applied index is now lower than readState.Index' (duration: 149.133µs)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:05:58.373132Z","caller":"traceutil/trace.go:171","msg":"trace[155971622] transaction","detail":"{read_only:false; response_revision:2004; number_of_response:1; }","duration":"126.172194ms","start":"2026-05-10T04:05:58.246865Z","end":"2026-05-10T04:05:58.373037Z","steps":["trace[155971622] 'process raft request' (duration: 125.995159ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:05:58.373145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.349926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-10T04:05:58.373308Z","caller":"traceutil/trace.go:171","msg":"trace[1230560335] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2004; }","duration":"124.547312ms","start":"2026-05-10T04:05:58.248749Z","end":"2026-05-10T04:05:58.373296Z","steps":["trace[1230560335] 'agreement among raft nodes before linearized reading' (duration: 124.299845ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:06:18.933374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.752074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-10T04:06:18.933438Z","caller":"traceutil/trace.go:171","msg":"trace[1346329152] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2096; }","duration":"319.852747ms","start":"2026-05-10T04:06:18.61357Z","end":"2026-05-10T04:06:18.933423Z","steps":["trace[1346329152] 'range keys from in-memory index tree' (duration: 319.56356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:06:18.933461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:06:18.613547Z","time spent":"319.90943ms","remote":"127.0.0.1:46712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-10T04:06:18.93339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.94162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:06:18.93355Z","caller":"traceutil/trace.go:171","msg":"trace[952345512] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2096; }","duration":"382.119695ms","start":"2026-05-10T04:06:18.551406Z","end":"2026-05-10T04:06:18.933526Z","steps":["trace[952345512] 'range keys from in-memory index tree' (duration: 381.872389ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:06:18.933625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:06:18.551386Z","time spent":"382.215228ms","remote":"127.0.0.1:46474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-10T04:06:18.933628Z","caller":"traceutil/trace.go:171","msg":"trace[1530313347] transaction","detail":"{read_only:false; response_revision:2097; number_of_response:1; }","duration":"311.722957ms","start":"2026-05-10T04:06:18.621885Z","end":"2026-05-10T04:06:18.933608Z","steps":["trace[1530313347] 'process raft request' (duration: 311.565033ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:06:18.933732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:06:18.621857Z","time spent":"311.81173ms","remote":"127.0.0.1:46712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T04:06:25.795739Z","caller":"traceutil/trace.go:171","msg":"trace[688078331] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"164.135603ms","start":"2026-05-10T04:06:25.631527Z","end":"2026-05-10T04:06:25.795662Z","steps":["trace[688078331] 'process raft request' (duration: 163.809195ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:06:36.000119Z","caller":"traceutil/trace.go:171","msg":"trace[1037927896] transaction","detail":"{read_only:false; response_revision:2207; number_of_response:1; }","duration":"117.276207ms","start":"2026-05-10T04:06:35.882738Z","end":"2026-05-10T04:06:36.000015Z","steps":["trace[1037927896] 'process raft request' (duration: 117.0041ms)"],"step_count":1}