{"level":"warn","ts":"2026-07-02T15:34:59.23278Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-02T15:34:59.232989Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.115: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.115:2380","--initial-cluster=instance=https://199.204.45.115:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.115:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.115:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-02T15:34:59.233077Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-02T15:34:59.233091Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-07-02T15:34:59.233123Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-02T15:34:59.233931Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"]} {"level":"info","ts":"2026-07-02T15:34:59.234022Z","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.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.115:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-02T15:34:59.238231Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.962372ms"} {"level":"info","ts":"2026-07-02T15:34:59.24883Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e3aaa5c123e150e0","cluster-id":"61ec519698823541"} {"level":"info","ts":"2026-07-02T15:34:59.248903Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=()"} {"level":"info","ts":"2026-07-02T15:34:59.24911Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 0"} {"level":"info","ts":"2026-07-02T15:34:59.24913Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e3aaa5c123e150e0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-02T15:34:59.249145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 1"} {"level":"info","ts":"2026-07-02T15:34:59.249284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"warn","ts":"2026-07-02T15:34:59.259618Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-02T15:34:59.261301Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-02T15:34:59.262599Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-02T15:34:59.264834Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e3aaa5c123e150e0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-02T15:34:59.265087Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e3aaa5c123e150e0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-02T15:34:59.26514Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-02T15:34:59.265183Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-02T15:34:59.265238Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-02T15:34:59.266019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"info","ts":"2026-07-02T15:34:59.266984Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","added-peer-id":"e3aaa5c123e150e0","added-peer-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-07-02T15:34:59.268371Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-02T15:34:59.268555Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e3aaa5c123e150e0","initial-advertise-peer-urls":["https://199.204.45.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-02T15:34:59.268577Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-02T15:34:59.268689Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-07-02T15:34:59.268703Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-07-02T15:35:00.050443Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 is starting a new election at term 1"} {"level":"info","ts":"2026-07-02T15:35:00.050535Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-02T15:35:00.050562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgPreVoteResp from e3aaa5c123e150e0 at term 1"} {"level":"info","ts":"2026-07-02T15:35:00.050581Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became candidate at term 2"} {"level":"info","ts":"2026-07-02T15:35:00.050593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgVoteResp from e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-07-02T15:35:00.050609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became leader at term 2"} {"level":"info","ts":"2026-07-02T15:35:00.050626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e3aaa5c123e150e0 elected leader e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-07-02T15:35:00.051649Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T15:35:00.052129Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e3aaa5c123e150e0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.115:2379]}","request-path":"/0/members/e3aaa5c123e150e0/attributes","cluster-id":"61ec519698823541","publish-timeout":"7s"} {"level":"info","ts":"2026-07-02T15:35:00.052147Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T15:35:00.052136Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T15:35:00.052422Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-02T15:35:00.052464Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-02T15:35:00.052748Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T15:35:00.052845Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T15:35:00.052882Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T15:35:00.053814Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-02T15:35:00.055084Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.115:2379"} {"level":"info","ts":"2026-07-02T15:35:24.472038Z","caller":"traceutil/trace.go:171","msg":"trace[1802733928] linearizableReadLoop","detail":"{readStateIndex:442; appliedIndex:441; }","duration":"207.957645ms","start":"2026-07-02T15:35:24.264056Z","end":"2026-07-02T15:35:24.472014Z","steps":["trace[1802733928] 'read index received' (duration: 207.79352ms)","trace[1802733928] 'applied index is now lower than readState.Index' (duration: 163.215µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:35:24.472209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.1322ms","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-07-02T15:35:24.472276Z","caller":"traceutil/trace.go:171","msg":"trace[252047675] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:422; }","duration":"208.248563ms","start":"2026-07-02T15:35:24.264016Z","end":"2026-07-02T15:35:24.472265Z","steps":["trace[252047675] 'agreement among raft nodes before linearized reading' (duration: 208.116509ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:35:24.472595Z","caller":"traceutil/trace.go:171","msg":"trace[1433410907] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"436.46889ms","start":"2026-07-02T15:35:24.036114Z","end":"2026-07-02T15:35:24.472583Z","steps":["trace[1433410907] 'process raft request' (duration: 435.790191ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:35:24.473067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:35:24.036097Z","time spent":"436.531481ms","remote":"127.0.0.1:52090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:35:24.875568Z","caller":"traceutil/trace.go:171","msg":"trace[31721786] linearizableReadLoop","detail":"{readStateIndex:443; appliedIndex:442; }","duration":"378.915884ms","start":"2026-07-02T15:35:24.496626Z","end":"2026-07-02T15:35:24.875542Z","steps":["trace[31721786] 'read index received' (duration: 376.035093ms)","trace[31721786] 'applied index is now lower than readState.Index' (duration: 2.879841ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:35:24.875644Z","caller":"traceutil/trace.go:171","msg":"trace[1908998668] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"398.932529ms","start":"2026-07-02T15:35:24.476682Z","end":"2026-07-02T15:35:24.875615Z","steps":["trace[1908998668] 'process raft request' (duration: 395.995256ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:35:24.875721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.067498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T15:35:24.875775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:35:24.476664Z","time spent":"399.043453ms","remote":"127.0.0.1:52090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:35:24.8758Z","caller":"traceutil/trace.go:171","msg":"trace[937216824] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:423; }","duration":"379.187042ms","start":"2026-07-02T15:35:24.496593Z","end":"2026-07-02T15:35:24.87578Z","steps":["trace[937216824] 'agreement among raft nodes before linearized reading' (duration: 379.043208ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:35:24.875845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:35:24.496583Z","time spent":"379.254433ms","remote":"127.0.0.1:51830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-02T15:35:33.072241Z","caller":"traceutil/trace.go:171","msg":"trace[1081194949] linearizableReadLoop","detail":"{readStateIndex:462; appliedIndex:461; }","duration":"146.750415ms","start":"2026-07-02T15:35:32.925464Z","end":"2026-07-02T15:35:33.072214Z","steps":["trace[1081194949] 'read index received' (duration: 146.55733ms)","trace[1081194949] 'applied index is now lower than readState.Index' (duration: 191.915µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:35:33.072286Z","caller":"traceutil/trace.go:171","msg":"trace[1255358083] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"163.269399ms","start":"2026-07-02T15:35:32.90899Z","end":"2026-07-02T15:35:33.072259Z","steps":["trace[1255358083] 'process raft request' (duration: 163.084503ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:35:33.072443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.959271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12673"} {"level":"warn","ts":"2026-07-02T15:35:33.072504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.53085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.115\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-02T15:35:33.072571Z","caller":"traceutil/trace.go:171","msg":"trace[1392472449] range","detail":"{range_begin:/registry/masterleases/199.204.45.115; range_end:; response_count:1; response_revision:441; }","duration":"138.634573ms","start":"2026-07-02T15:35:32.93392Z","end":"2026-07-02T15:35:33.072555Z","steps":["trace[1392472449] 'agreement among raft nodes before linearized reading' (duration: 138.53405ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:35:33.072513Z","caller":"traceutil/trace.go:171","msg":"trace[1646441319] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:441; }","duration":"147.077114ms","start":"2026-07-02T15:35:32.925415Z","end":"2026-07-02T15:35:33.072493Z","steps":["trace[1646441319] 'agreement among raft nodes before linearized reading' (duration: 146.93696ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:35:35.222546Z","caller":"traceutil/trace.go:171","msg":"trace[182490520] linearizableReadLoop","detail":"{readStateIndex:502; appliedIndex:501; }","duration":"138.490879ms","start":"2026-07-02T15:35:35.084033Z","end":"2026-07-02T15:35:35.222524Z","steps":["trace[182490520] 'read index received' (duration: 138.290393ms)","trace[182490520] 'applied index is now lower than readState.Index' (duration: 199.456µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:35:35.222673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.614403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:35:35.222715Z","caller":"traceutil/trace.go:171","msg":"trace[1143255737] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:480; }","duration":"138.718185ms","start":"2026-07-02T15:35:35.083985Z","end":"2026-07-02T15:35:35.222703Z","steps":["trace[1143255737] 'agreement among raft nodes before linearized reading' (duration: 138.630152ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:35:35.222818Z","caller":"traceutil/trace.go:171","msg":"trace[858468891] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"141.700797ms","start":"2026-07-02T15:35:35.081077Z","end":"2026-07-02T15:35:35.222778Z","steps":["trace[858468891] 'process raft request' (duration: 141.291526ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:35:35.270604Z","caller":"traceutil/trace.go:171","msg":"trace[1971436545] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"164.679937ms","start":"2026-07-02T15:35:35.10589Z","end":"2026-07-02T15:35:35.27057Z","steps":["trace[1971436545] 'process raft request' (duration: 147.237558ms)","trace[1971436545] 'compare' (duration: 17.230003ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:35:35.857171Z","caller":"traceutil/trace.go:171","msg":"trace[235352367] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"245.022891ms","start":"2026-07-02T15:35:35.612127Z","end":"2026-07-02T15:35:35.85715Z","steps":["trace[235352367] 'process raft request' (duration: 244.98974ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:35:35.857393Z","caller":"traceutil/trace.go:171","msg":"trace[373311088] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"244.858127ms","start":"2026-07-02T15:35:35.612525Z","end":"2026-07-02T15:35:35.857383Z","steps":["trace[373311088] 'process raft request' (duration: 244.557468ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:35:35.858048Z","caller":"traceutil/trace.go:171","msg":"trace[1119904678] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"255.93488ms","start":"2026-07-02T15:35:35.601761Z","end":"2026-07-02T15:35:35.857695Z","steps":["trace[1119904678] 'process raft request' (duration: 255.21437ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:35:35.865615Z","caller":"traceutil/trace.go:171","msg":"trace[1726739503] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"240.346183ms","start":"2026-07-02T15:35:35.625249Z","end":"2026-07-02T15:35:35.865595Z","steps":["trace[1726739503] 'process raft request' (duration: 240.266171ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:35:35.865788Z","caller":"traceutil/trace.go:171","msg":"trace[1416480249] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"252.742432ms","start":"2026-07-02T15:35:35.613024Z","end":"2026-07-02T15:35:35.865766Z","steps":["trace[1416480249] 'process raft request' (duration: 252.381753ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:35:35.865892Z","caller":"traceutil/trace.go:171","msg":"trace[1962298342] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"238.630646ms","start":"2026-07-02T15:35:35.627242Z","end":"2026-07-02T15:35:35.865873Z","steps":["trace[1962298342] 'process raft request' (duration: 238.491713ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:35:35.865938Z","caller":"traceutil/trace.go:171","msg":"trace[2104662872] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"238.790391ms","start":"2026-07-02T15:35:35.627125Z","end":"2026-07-02T15:35:35.865916Z","steps":["trace[2104662872] 'process raft request' (duration: 238.447541ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:35:35.865992Z","caller":"traceutil/trace.go:171","msg":"trace[1732671478] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"236.60059ms","start":"2026-07-02T15:35:35.629384Z","end":"2026-07-02T15:35:35.865985Z","steps":["trace[1732671478] 'process raft request' (duration: 236.409565ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:35:35.866085Z","caller":"traceutil/trace.go:171","msg":"trace[2017064556] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"232.410225ms","start":"2026-07-02T15:35:35.633664Z","end":"2026-07-02T15:35:35.866074Z","steps":["trace[2017064556] 'process raft request' (duration: 232.206689ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:35:35.866148Z","caller":"traceutil/trace.go:171","msg":"trace[1788842779] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"221.208548ms","start":"2026-07-02T15:35:35.644917Z","end":"2026-07-02T15:35:35.866126Z","steps":["trace[1788842779] 'process raft request' (duration: 221.084375ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:35:35.866177Z","caller":"traceutil/trace.go:171","msg":"trace[681909919] linearizableReadLoop","detail":"{readStateIndex:528; appliedIndex:516; }","duration":"118.547041ms","start":"2026-07-02T15:35:35.747619Z","end":"2026-07-02T15:35:35.866166Z","steps":["trace[681909919] 'read index received' (duration: 109.290468ms)","trace[681909919] 'applied index is now lower than readState.Index' (duration: 9.256023ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:35:35.866196Z","caller":"traceutil/trace.go:171","msg":"trace[878904842] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"211.599714ms","start":"2026-07-02T15:35:35.654591Z","end":"2026-07-02T15:35:35.866191Z","steps":["trace[878904842] 'process raft request' (duration: 211.44561ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:35:35.866168Z","caller":"traceutil/trace.go:171","msg":"trace[1809168933] transaction","detail":"{read_only:false; number_of_response:1; response_revision:501; }","duration":"236.634991ms","start":"2026-07-02T15:35:35.629526Z","end":"2026-07-02T15:35:35.866161Z","steps":["trace[1809168933] 'process raft request' (duration: 236.300222ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:35:35.86628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.646744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-02T15:35:35.866302Z","caller":"traceutil/trace.go:171","msg":"trace[1035989095] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:505; }","duration":"118.716526ms","start":"2026-07-02T15:35:35.74758Z","end":"2026-07-02T15:35:35.866297Z","steps":["trace[1035989095] 'agreement among raft nodes before linearized reading' (duration: 118.622223ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:35:36.853958Z","caller":"traceutil/trace.go:171","msg":"trace[947442853] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"721.061879ms","start":"2026-07-02T15:35:36.132243Z","end":"2026-07-02T15:35:36.853304Z","steps":["trace[947442853] 'process raft request' (duration: 717.690007ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:35:36.854098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:35:36.132221Z","time spent":"721.80307ms","remote":"127.0.0.1:51870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80679,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:35:36.855689Z","caller":"traceutil/trace.go:171","msg":"trace[2078821998] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"712.041521ms","start":"2026-07-02T15:35:36.142554Z","end":"2026-07-02T15:35:36.854596Z","steps":["trace[2078821998] 'process raft request' (duration: 711.726673ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:35:36.855845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:35:36.142521Z","time spent":"713.231004ms","remote":"127.0.0.1:51870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59616,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:35:36.856685Z","caller":"traceutil/trace.go:171","msg":"trace[842265334] linearizableReadLoop","detail":"{readStateIndex:559; appliedIndex:556; }","duration":"358.498714ms","start":"2026-07-02T15:35:36.498139Z","end":"2026-07-02T15:35:36.856638Z","steps":["trace[842265334] 'read index received' (duration: 351.515743ms)","trace[842265334] 'applied index is now lower than readState.Index' (duration: 6.903669ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:35:36.857192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.029919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T15:35:36.857233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.263653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-02T15:35:36.857384Z","caller":"traceutil/trace.go:171","msg":"trace[1606104037] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:535; }","duration":"313.427418ms","start":"2026-07-02T15:35:36.543911Z","end":"2026-07-02T15:35:36.857339Z","steps":["trace[1606104037] 'agreement among raft nodes before linearized reading' (duration: 313.252103ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:35:36.857513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:35:36.543892Z","time spent":"313.606562ms","remote":"127.0.0.1:52090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-02T15:35:36.857563Z","caller":"traceutil/trace.go:171","msg":"trace[1475248894] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"683.729695ms","start":"2026-07-02T15:35:36.173618Z","end":"2026-07-02T15:35:36.857348Z","steps":["trace[1475248894] 'process raft request' (duration: 680.953759ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:35:36.857665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:35:36.173604Z","time spent":"684.001313ms","remote":"127.0.0.1:51870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":73770,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T15:35:36.857297Z","caller":"traceutil/trace.go:171","msg":"trace[1118231211] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:535; }","duration":"359.170873ms","start":"2026-07-02T15:35:36.498098Z","end":"2026-07-02T15:35:36.857269Z","steps":["trace[1118231211] 'agreement among raft nodes before linearized reading' (duration: 358.764752ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:35:36.858246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:35:36.498082Z","time spent":"360.144459ms","remote":"127.0.0.1:51830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-02T15:35:37.012463Z","caller":"traceutil/trace.go:171","msg":"trace[1994501419] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"124.580157ms","start":"2026-07-02T15:35:36.887854Z","end":"2026-07-02T15:35:37.012434Z","steps":["trace[1994501419] 'process raft request' (duration: 88.58941ms)","trace[1994501419] 'compare' (duration: 35.73599ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:35:37.012947Z","caller":"traceutil/trace.go:171","msg":"trace[230873580] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"119.596471ms","start":"2026-07-02T15:35:36.893336Z","end":"2026-07-02T15:35:37.012933Z","steps":["trace[230873580] 'process raft request' (duration: 118.978524ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:35:43.369643Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832892504520253,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-02T15:35:43.446698Z","caller":"traceutil/trace.go:171","msg":"trace[612123539] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:615; }","duration":"577.609613ms","start":"2026-07-02T15:35:42.869062Z","end":"2026-07-02T15:35:43.446671Z","steps":["trace[612123539] 'read index received' (duration: 576.886363ms)","trace[612123539] 'applied index is now lower than readState.Index' (duration: 721.76µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:35:43.446793Z","caller":"traceutil/trace.go:171","msg":"trace[1743743896] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"697.615824ms","start":"2026-07-02T15:35:42.749144Z","end":"2026-07-02T15:35:43.44676Z","steps":["trace[1743743896] 'process raft request' (duration: 697.013898ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:35:43.446939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.858409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-swlhw\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-02T15:35:43.446985Z","caller":"traceutil/trace.go:171","msg":"trace[1269401823] range","detail":"{range_begin:/registry/pods/kube-system/cilium-swlhw; range_end:; response_count:1; response_revision:591; }","duration":"577.961423ms","start":"2026-07-02T15:35:42.869009Z","end":"2026-07-02T15:35:43.44697Z","steps":["trace[1269401823] 'agreement among raft nodes before linearized reading' (duration: 577.804189ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:35:43.446977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:35:42.749127Z","time spent":"697.732188ms","remote":"127.0.0.1:51924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T15:35:43.446981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.99844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-02T15:35:43.447031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:35:42.868976Z","time spent":"578.045975ms","remote":"127.0.0.1:52022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-swlhw\" "} {"level":"warn","ts":"2026-07-02T15:35:43.447041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.138892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-07-02T15:35:43.447133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.949822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.115\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-02T15:35:43.447135Z","caller":"traceutil/trace.go:171","msg":"trace[732210181] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:591; }","duration":"172.283666ms","start":"2026-07-02T15:35:43.274829Z","end":"2026-07-02T15:35:43.447113Z","steps":["trace[732210181] 'agreement among raft nodes before linearized reading' (duration: 172.05807ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:35:43.447167Z","caller":"traceutil/trace.go:171","msg":"trace[46175811] range","detail":"{range_begin:/registry/masterleases/199.204.45.115; range_end:; response_count:1; response_revision:591; }","duration":"512.007703ms","start":"2026-07-02T15:35:42.935151Z","end":"2026-07-02T15:35:43.447158Z","steps":["trace[46175811] 'agreement among raft nodes before linearized reading' (duration: 511.951792ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:35:43.447146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.44458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-02T15:35:43.447208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:35:42.935135Z","time spent":"512.066596ms","remote":"127.0.0.1:51860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.115\" "} {"level":"info","ts":"2026-07-02T15:35:43.447232Z","caller":"traceutil/trace.go:171","msg":"trace[1188831175] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:591; }","duration":"137.553033ms","start":"2026-07-02T15:35:43.309655Z","end":"2026-07-02T15:35:43.447208Z","steps":["trace[1188831175] 'agreement among raft nodes before linearized reading' (duration: 137.320436ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:35:43.447072Z","caller":"traceutil/trace.go:171","msg":"trace[1693153992] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:591; }","duration":"521.120433ms","start":"2026-07-02T15:35:42.925929Z","end":"2026-07-02T15:35:43.447049Z","steps":["trace[1693153992] 'agreement among raft nodes before linearized reading' (duration: 520.946919ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:35:43.447374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:35:42.925915Z","time spent":"521.448922ms","remote":"127.0.0.1:52090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-02T15:35:43.598026Z","caller":"traceutil/trace.go:171","msg":"trace[25830300] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"144.045221ms","start":"2026-07-02T15:35:43.453965Z","end":"2026-07-02T15:35:43.59801Z","steps":["trace[25830300] 'process raft request' (duration: 143.879597ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:35:43.59828Z","caller":"traceutil/trace.go:171","msg":"trace[701828409] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"144.200555ms","start":"2026-07-02T15:35:43.454064Z","end":"2026-07-02T15:35:43.598264Z","steps":["trace[701828409] 'process raft request' (duration: 143.914177ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:35:43.59889Z","caller":"traceutil/trace.go:171","msg":"trace[1024036769] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"144.397001ms","start":"2026-07-02T15:35:43.454121Z","end":"2026-07-02T15:35:43.598518Z","steps":["trace[1024036769] 'process raft request' (duration: 144.058162ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:35:43.599056Z","caller":"traceutil/trace.go:171","msg":"trace[1823824620] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"105.089802ms","start":"2026-07-02T15:35:43.493903Z","end":"2026-07-02T15:35:43.598992Z","steps":["trace[1823824620] 'process raft request' (duration: 104.979869ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:35:43.599699Z","caller":"traceutil/trace.go:171","msg":"trace[2021228454] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"134.609582ms","start":"2026-07-02T15:35:43.464999Z","end":"2026-07-02T15:35:43.599608Z","steps":["trace[2021228454] 'process raft request' (duration: 133.416759ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:35:44.033637Z","caller":"traceutil/trace.go:171","msg":"trace[825649462] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:623; }","duration":"118.882261ms","start":"2026-07-02T15:35:43.914667Z","end":"2026-07-02T15:35:44.033549Z","steps":["trace[825649462] 'read index received' (duration: 75.565572ms)","trace[825649462] 'applied index is now lower than readState.Index' (duration: 43.315849ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:35:44.033642Z","caller":"traceutil/trace.go:171","msg":"trace[33586041] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"414.169481ms","start":"2026-07-02T15:35:43.619437Z","end":"2026-07-02T15:35:44.033607Z","steps":["trace[33586041] 'process raft request' (duration: 370.75223ms)","trace[33586041] 'compare' (duration: 42.65092ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:35:44.033801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:35:43.619425Z","time spent":"414.301745ms","remote":"127.0.0.1:52022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T15:35:44.033871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.17967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-02T15:35:44.033938Z","caller":"traceutil/trace.go:171","msg":"trace[100631895] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:598; }","duration":"119.283382ms","start":"2026-07-02T15:35:43.914635Z","end":"2026-07-02T15:35:44.033918Z","steps":["trace[100631895] 'agreement among raft nodes before linearized reading' (duration: 119.060346ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:35:45.339319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.096402825s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T15:35:45.339667Z","caller":"traceutil/trace.go:171","msg":"trace[1085742649] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"1.302141858s","start":"2026-07-02T15:35:44.037493Z","end":"2026-07-02T15:35:45.339635Z","steps":["trace[1085742649] 'process raft request' (duration: 1.301976934s)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:35:45.339697Z","caller":"traceutil/trace.go:171","msg":"trace[1389201329] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"1.302058436s","start":"2026-07-02T15:35:44.037622Z","end":"2026-07-02T15:35:45.33968Z","steps":["trace[1389201329] 'process raft request' (duration: 1.301937162s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:35:45.339776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:35:44.037605Z","time spent":"1.302137738s","remote":"127.0.0.1:52066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:35:45.339819Z","caller":"traceutil/trace.go:171","msg":"trace[1791766035] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:625; }","duration":"843.687813ms","start":"2026-07-02T15:35:44.496117Z","end":"2026-07-02T15:35:45.339805Z","steps":["trace[1791766035] 'read index received' (duration: 58.472µs)","trace[1791766035] 'applied index is now lower than readState.Index' (duration: 843.628231ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:35:45.339666Z","caller":"traceutil/trace.go:171","msg":"trace[1438049001] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"1.302133718s","start":"2026-07-02T15:35:44.037504Z","end":"2026-07-02T15:35:45.339637Z","steps":["trace[1438049001] 'process raft request' (duration: 205.216279ms)","trace[1438049001] 'compare' (duration: 1.095977964s)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:35:45.339829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:35:44.037465Z","time spent":"1.302275432s","remote":"127.0.0.1:51904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T15:35:45.33989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:35:44.037484Z","time spent":"1.302373615s","remote":"127.0.0.1:52090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:35:45.339923Z","caller":"traceutil/trace.go:171","msg":"trace[721354329] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"1.300448782s","start":"2026-07-02T15:35:44.039459Z","end":"2026-07-02T15:35:45.339907Z","steps":["trace[721354329] 'process raft request' (duration: 1.300180895s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:35:45.340029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:35:44.03945Z","time spent":"1.300521254s","remote":"127.0.0.1:52022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T15:35:45.340033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.92795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:35:45.340095Z","caller":"traceutil/trace.go:171","msg":"trace[1721079214] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:603; }","duration":"844.027173ms","start":"2026-07-02T15:35:44.496052Z","end":"2026-07-02T15:35:45.340079Z","steps":["trace[1721079214] 'agreement among raft nodes before linearized reading' (duration: 843.93214ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:35:45.340138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:35:44.496033Z","time spent":"844.094924ms","remote":"127.0.0.1:51830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T15:36:01.913458Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832892504520583,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T15:36:02.119243Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.144768141s","expected-duration":"1s"} {"level":"info","ts":"2026-07-02T15:36:02.119749Z","caller":"traceutil/trace.go:171","msg":"trace[1862092371] linearizableReadLoop","detail":"{readStateIndex:789; appliedIndex:788; }","duration":"706.372596ms","start":"2026-07-02T15:36:01.413355Z","end":"2026-07-02T15:36:02.119728Z","steps":["trace[1862092371] 'read index received' (duration: 706.322034ms)","trace[1862092371] 'applied index is now lower than readState.Index' (duration: 49.492µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:36:02.11986Z","caller":"traceutil/trace.go:171","msg":"trace[389190519] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"1.14543857s","start":"2026-07-02T15:36:00.974366Z","end":"2026-07-02T15:36:02.119805Z","steps":["trace[389190519] 'process raft request' (duration: 1.145229704s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:36:02.120016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.640174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-02T15:36:02.120096Z","caller":"traceutil/trace.go:171","msg":"trace[2048965298] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:759; }","duration":"706.772917ms","start":"2026-07-02T15:36:01.413303Z","end":"2026-07-02T15:36:02.120076Z","steps":["trace[2048965298] 'agreement among raft nodes before linearized reading' (duration: 706.530701ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:36:02.120145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:36:01.413284Z","time spent":"706.852369ms","remote":"127.0.0.1:52090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-02T15:36:02.120265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:36:00.97435Z","time spent":"1.145708937s","remote":"127.0.0.1:52022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T15:36:02.127579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.533349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:36:02.1277Z","caller":"traceutil/trace.go:171","msg":"trace[651142396] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:760; }","duration":"629.702493ms","start":"2026-07-02T15:36:01.497971Z","end":"2026-07-02T15:36:02.127674Z","steps":["trace[651142396] 'agreement among raft nodes before linearized reading' (duration: 629.472037ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:36:02.127764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:36:01.49796Z","time spent":"629.794076ms","remote":"127.0.0.1:51832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T15:36:02.127794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.522157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-02T15:36:02.127849Z","caller":"traceutil/trace.go:171","msg":"trace[1170035483] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:760; }","duration":"443.612489ms","start":"2026-07-02T15:36:01.684222Z","end":"2026-07-02T15:36:02.127835Z","steps":["trace[1170035483] 'agreement among raft nodes before linearized reading' (duration: 443.412303ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:36:02.127841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.309203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-02T15:36:02.127952Z","caller":"traceutil/trace.go:171","msg":"trace[649257904] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:760; }","duration":"450.473987ms","start":"2026-07-02T15:36:01.677454Z","end":"2026-07-02T15:36:02.127928Z","steps":["trace[649257904] 'agreement among raft nodes before linearized reading' (duration: 450.20465ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:36:02.127881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:36:01.684211Z","time spent":"443.663531ms","remote":"127.0.0.1:52090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-02T15:36:02.128005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:36:01.677435Z","time spent":"450.562009ms","remote":"127.0.0.1:52090","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-07-02T15:36:02.127959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.754893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:36:02.128245Z","caller":"traceutil/trace.go:171","msg":"trace[1151691893] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:760; }","duration":"444.083832ms","start":"2026-07-02T15:36:01.684153Z","end":"2026-07-02T15:36:02.128237Z","steps":["trace[1151691893] 'agreement among raft nodes before linearized reading' (duration: 443.765083ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:36:02.128272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:36:01.684126Z","time spent":"444.136563ms","remote":"127.0.0.1:52134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-07-02T15:36:02.128253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.347859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-02T15:36:02.12832Z","caller":"traceutil/trace.go:171","msg":"trace[240910550] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:760; }","duration":"440.441731ms","start":"2026-07-02T15:36:01.687867Z","end":"2026-07-02T15:36:02.128308Z","steps":["trace[240910550] 'agreement among raft nodes before linearized reading' (duration: 439.65547ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:36:02.129105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:36:01.687857Z","time spent":"441.227374ms","remote":"127.0.0.1:52090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-02T15:36:37.348425Z","caller":"traceutil/trace.go:171","msg":"trace[1237566685] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"589.293303ms","start":"2026-07-02T15:36:36.759112Z","end":"2026-07-02T15:36:37.348406Z","steps":["trace[1237566685] 'process raft request' (duration: 589.17043ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:36:37.348559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:36:36.759073Z","time spent":"589.414706ms","remote":"127.0.0.1:52090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T15:36:37.864883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.236547ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T15:36:37.865015Z","caller":"traceutil/trace.go:171","msg":"trace[1234192037] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"946.51141ms","start":"2026-07-02T15:36:36.918481Z","end":"2026-07-02T15:36:37.864992Z","steps":["trace[1234192037] 'process raft request' (duration: 757.072178ms)","trace[1234192037] 'compare' (duration: 188.729803ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:36:37.86509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:36:36.918454Z","time spent":"946.602753ms","remote":"127.0.0.1:52090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T15:36:38.446937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.739444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T15:36:38.447013Z","caller":"traceutil/trace.go:171","msg":"trace[366208711] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:975; }","duration":"196.863246ms","start":"2026-07-02T15:36:38.250132Z","end":"2026-07-02T15:36:38.446995Z","steps":["trace[366208711] 'count revisions from in-memory index tree' (duration: 196.685531ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:36:59.998434Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832892504521247,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-02T15:37:00.047945Z","caller":"traceutil/trace.go:171","msg":"trace[1057006550] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"595.872955ms","start":"2026-07-02T15:36:59.452043Z","end":"2026-07-02T15:37:00.047916Z","steps":["trace[1057006550] 'process raft request' (duration: 595.710641ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:37:00.048225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:36:59.452026Z","time spent":"595.998678ms","remote":"127.0.0.1:52090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:37:00.368219Z","caller":"traceutil/trace.go:171","msg":"trace[392538969] linearizableReadLoop","detail":"{readStateIndex:1092; appliedIndex:1090; }","duration":"870.221418ms","start":"2026-07-02T15:36:59.497961Z","end":"2026-07-02T15:37:00.368183Z","steps":["trace[392538969] 'read index received' (duration: 549.759984ms)","trace[392538969] 'applied index is now lower than readState.Index' (duration: 320.459934ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:37:00.368247Z","caller":"traceutil/trace.go:171","msg":"trace[2043820329] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"431.463464ms","start":"2026-07-02T15:36:59.936739Z","end":"2026-07-02T15:37:00.368202Z","steps":["trace[2043820329] 'process raft request' (duration: 415.916605ms)","trace[2043820329] 'compare' (duration: 15.308632ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:37:00.368377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"870.396783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T15:37:00.368414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.826944ms","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-07-02T15:37:00.368417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:36:59.936714Z","time spent":"431.603678ms","remote":"127.0.0.1:52090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:37:00.368448Z","caller":"traceutil/trace.go:171","msg":"trace[1942172914] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1047; }","duration":"870.514917ms","start":"2026-07-02T15:36:59.497914Z","end":"2026-07-02T15:37:00.368429Z","steps":["trace[1942172914] 'agreement among raft nodes before linearized reading' (duration: 870.404784ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:37:00.368492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:36:59.497894Z","time spent":"870.590379ms","remote":"127.0.0.1:51830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-02T15:37:00.368455Z","caller":"traceutil/trace.go:171","msg":"trace[1179315468] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1047; }","duration":"308.918757ms","start":"2026-07-02T15:37:00.059524Z","end":"2026-07-02T15:37:00.368443Z","steps":["trace[1179315468] 'agreement among raft nodes before linearized reading' (duration: 308.822184ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:37:00.368587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:37:00.059504Z","time spent":"309.07018ms","remote":"127.0.0.1:53638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"info","ts":"2026-07-02T15:37:00.874025Z","caller":"traceutil/trace.go:171","msg":"trace[1711687980] linearizableReadLoop","detail":"{readStateIndex:1096; appliedIndex:1094; }","duration":"200.96025ms","start":"2026-07-02T15:37:00.673034Z","end":"2026-07-02T15:37:00.873995Z","steps":["trace[1711687980] 'read index received' (duration: 50.79192ms)","trace[1711687980] 'applied index is now lower than readState.Index' (duration: 150.16714ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:37:00.874097Z","caller":"traceutil/trace.go:171","msg":"trace[2076707138] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"202.186874ms","start":"2026-07-02T15:37:00.671869Z","end":"2026-07-02T15:37:00.874056Z","steps":["trace[2076707138] 'process raft request' (duration: 142.891469ms)","trace[2076707138] 'compare' (duration: 59.040347ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:37:00.87417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.105484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:37:00.874206Z","caller":"traceutil/trace.go:171","msg":"trace[1485654301] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1051; }","duration":"201.185386ms","start":"2026-07-02T15:37:00.67301Z","end":"2026-07-02T15:37:00.874195Z","steps":["trace[1485654301] 'agreement among raft nodes before linearized reading' (duration: 201.095614ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:37:02.323854Z","caller":"traceutil/trace.go:171","msg":"trace[1651724386] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"266.681372ms","start":"2026-07-02T15:37:02.057151Z","end":"2026-07-02T15:37:02.323832Z","steps":["trace[1651724386] 'process raft request' (duration: 266.544518ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:37:02.520957Z","caller":"traceutil/trace.go:171","msg":"trace[2076043751] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"144.830442ms","start":"2026-07-02T15:37:02.376076Z","end":"2026-07-02T15:37:02.520906Z","steps":["trace[2076043751] 'process raft request' (duration: 144.521723ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:37:02.739769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.886692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-02T15:37:02.73986Z","caller":"traceutil/trace.go:171","msg":"trace[1831719766] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1053; }","duration":"150.056536ms","start":"2026-07-02T15:37:02.589783Z","end":"2026-07-02T15:37:02.73984Z","steps":["trace[1831719766] 'range keys from in-memory index tree' (duration: 149.631575ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:37:03.408463Z","caller":"traceutil/trace.go:171","msg":"trace[444079564] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"111.752331ms","start":"2026-07-02T15:37:03.296686Z","end":"2026-07-02T15:37:03.408439Z","steps":["trace[444079564] 'process raft request' (duration: 111.625537ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:37:21.141171Z","caller":"traceutil/trace.go:171","msg":"trace[732318537] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"698.337481ms","start":"2026-07-02T15:37:20.442807Z","end":"2026-07-02T15:37:21.141145Z","steps":["trace[732318537] 'process raft request' (duration: 698.133545ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:37:21.141325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:37:20.442769Z","time spent":"698.476944ms","remote":"127.0.0.1:52090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:37:21.174739Z","caller":"traceutil/trace.go:171","msg":"trace[1916635848] linearizableReadLoop","detail":"{readStateIndex:1215; appliedIndex:1213; }","duration":"532.234302ms","start":"2026-07-02T15:37:20.642483Z","end":"2026-07-02T15:37:21.174717Z","steps":["trace[1916635848] 'read index received' (duration: 498.512332ms)","trace[1916635848] 'applied index is now lower than readState.Index' (duration: 33.72088ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:37:21.174858Z","caller":"traceutil/trace.go:171","msg":"trace[58143740] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"582.59405ms","start":"2026-07-02T15:37:20.592234Z","end":"2026-07-02T15:37:21.174828Z","steps":["trace[58143740] 'process raft request' (duration: 582.334913ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:37:21.174903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.396596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:37:21.174952Z","caller":"traceutil/trace.go:171","msg":"trace[1795122684] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1164; }","duration":"532.490329ms","start":"2026-07-02T15:37:20.642451Z","end":"2026-07-02T15:37:21.174941Z","steps":["trace[1795122684] 'agreement among raft nodes before linearized reading' (duration: 532.386256ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:37:21.174991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:37:20.642439Z","time spent":"532.54199ms","remote":"127.0.0.1:53712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-07-02T15:37:21.17499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.442875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-02T15:37:21.17504Z","caller":"traceutil/trace.go:171","msg":"trace[158010365] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1164; }","duration":"270.523848ms","start":"2026-07-02T15:37:20.904503Z","end":"2026-07-02T15:37:21.175027Z","steps":["trace[158010365] 'agreement among raft nodes before linearized reading' (duration: 270.371303ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:37:21.175038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:37:20.592217Z","time spent":"582.729203ms","remote":"127.0.0.1:52090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T15:37:21.175093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.939752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-02T15:37:21.175191Z","caller":"traceutil/trace.go:171","msg":"trace[406798336] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1164; }","duration":"241.067216ms","start":"2026-07-02T15:37:20.934105Z","end":"2026-07-02T15:37:21.175172Z","steps":["trace[406798336] 'agreement among raft nodes before linearized reading' (duration: 240.85111ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:37:21.175294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.472044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-02T15:37:21.175341Z","caller":"traceutil/trace.go:171","msg":"trace[2119448030] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1164; }","duration":"246.555647ms","start":"2026-07-02T15:37:20.928771Z","end":"2026-07-02T15:37:21.175327Z","steps":["trace[2119448030] 'agreement among raft nodes before linearized reading' (duration: 246.401573ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:37:21.175438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.952313ms","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-07-02T15:37:21.175493Z","caller":"traceutil/trace.go:171","msg":"trace[729938644] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1164; }","duration":"117.033636ms","start":"2026-07-02T15:37:21.058443Z","end":"2026-07-02T15:37:21.175477Z","steps":["trace[729938644] 'agreement among raft nodes before linearized reading' (duration: 116.850851ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:37:21.426727Z","caller":"traceutil/trace.go:171","msg":"trace[2100860326] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"241.9849ms","start":"2026-07-02T15:37:21.184721Z","end":"2026-07-02T15:37:21.426706Z","steps":["trace[2100860326] 'process raft request' (duration: 241.889528ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:37:21.426874Z","caller":"traceutil/trace.go:171","msg":"trace[1195900446] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"242.143505ms","start":"2026-07-02T15:37:21.184723Z","end":"2026-07-02T15:37:21.426867Z","steps":["trace[1195900446] 'process raft request' (duration: 207.827309ms)","trace[1195900446] 'compare' (duration: 33.75755ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:37:21.431206Z","caller":"traceutil/trace.go:171","msg":"trace[190933800] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"245.317473ms","start":"2026-07-02T15:37:21.185865Z","end":"2026-07-02T15:37:21.431182Z","steps":["trace[190933800] 'process raft request' (duration: 245.272962ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:37:21.431307Z","caller":"traceutil/trace.go:171","msg":"trace[1798697155] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"245.438716ms","start":"2026-07-02T15:37:21.185813Z","end":"2026-07-02T15:37:21.431252Z","steps":["trace[1798697155] 'process raft request' (duration: 245.203899ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:37:38.0262Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832892504521694,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-02T15:37:38.030009Z","caller":"traceutil/trace.go:171","msg":"trace[1271814620] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"521.427207ms","start":"2026-07-02T15:37:37.508559Z","end":"2026-07-02T15:37:38.029986Z","steps":["trace[1271814620] 'process raft request' (duration: 521.101248ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:37:38.030147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:37:37.508554Z","time spent":"521.514449ms","remote":"127.0.0.1:52346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:37:38.030204Z","caller":"traceutil/trace.go:171","msg":"trace[470412004] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"520.663486ms","start":"2026-07-02T15:37:37.509513Z","end":"2026-07-02T15:37:38.030177Z","steps":["trace[470412004] 'process raft request' (duration: 520.378508ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:37:38.030341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:37:37.509507Z","time spent":"520.759929ms","remote":"127.0.0.1:52090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T15:37:38.678033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.665189ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T15:37:38.678473Z","caller":"traceutil/trace.go:171","msg":"trace[250428895] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"1.146304101s","start":"2026-07-02T15:37:37.532115Z","end":"2026-07-02T15:37:38.678419Z","steps":["trace[250428895] 'process raft request' (duration: 738.16417ms)","trace[250428895] 'compare' (duration: 407.214766ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:37:38.679079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:37:37.532091Z","time spent":"1.146472086s","remote":"127.0.0.1:52090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:37:38.705462Z","caller":"traceutil/trace.go:171","msg":"trace[800531188] linearizableReadLoop","detail":"{readStateIndex:1296; appliedIndex:1292; }","duration":"1.179879712s","start":"2026-07-02T15:37:37.525557Z","end":"2026-07-02T15:37:38.705436Z","steps":["trace[800531188] 'read index received' (duration: 504.011549ms)","trace[800531188] 'applied index is now lower than readState.Index' (duration: 675.866643ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:37:38.705709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.19692359s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-02T15:37:38.705744Z","caller":"traceutil/trace.go:171","msg":"trace[1741957654] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1241; }","duration":"1.196997581s","start":"2026-07-02T15:37:37.508735Z","end":"2026-07-02T15:37:38.705733Z","steps":["trace[1741957654] 'agreement among raft nodes before linearized reading' (duration: 1.196828626s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:37:38.705781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:37:37.508722Z","time spent":"1.197050773s","remote":"127.0.0.1:52090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-02T15:37:38.739533Z","caller":"traceutil/trace.go:171","msg":"trace[1347458936] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"387.929747ms","start":"2026-07-02T15:37:38.351591Z","end":"2026-07-02T15:37:38.739521Z","steps":["trace[1347458936] 'process raft request' (duration: 387.831055ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:37:38.739624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:37:38.351561Z","time spent":"388.013409ms","remote":"127.0.0.1:52006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5640,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T15:37:38.740133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.195723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:37:38.742605Z","caller":"traceutil/trace.go:171","msg":"trace[626497928] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1242; }","duration":"182.294099ms","start":"2026-07-02T15:37:38.560274Z","end":"2026-07-02T15:37:38.742568Z","steps":["trace[626497928] 'agreement among raft nodes before linearized reading' (duration: 179.600885ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:37:39.353701Z","caller":"traceutil/trace.go:171","msg":"trace[818487228] linearizableReadLoop","detail":"{readStateIndex:1300; appliedIndex:1299; }","duration":"107.59323ms","start":"2026-07-02T15:37:39.246078Z","end":"2026-07-02T15:37:39.353671Z","steps":["trace[818487228] 'read index received' (duration: 107.437945ms)","trace[818487228] 'applied index is now lower than readState.Index' (duration: 153.755µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:37:39.353984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.886808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-02T15:37:39.35404Z","caller":"traceutil/trace.go:171","msg":"trace[1426818380] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1245; }","duration":"108.008581ms","start":"2026-07-02T15:37:39.246019Z","end":"2026-07-02T15:37:39.354027Z","steps":["trace[1426818380] 'agreement among raft nodes before linearized reading' (duration: 107.764325ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:37:39.354495Z","caller":"traceutil/trace.go:171","msg":"trace[1929836553] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"111.850997ms","start":"2026-07-02T15:37:39.242623Z","end":"2026-07-02T15:37:39.354474Z","steps":["trace[1929836553] 'process raft request' (duration: 110.86556ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:37:39.487295Z","caller":"traceutil/trace.go:171","msg":"trace[2021722003] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"128.049132ms","start":"2026-07-02T15:37:39.359205Z","end":"2026-07-02T15:37:39.487255Z","steps":["trace[2021722003] 'process raft request' (duration: 92.017694ms)","trace[2021722003] 'compare' (duration: 35.883214ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:37:40.233048Z","caller":"traceutil/trace.go:171","msg":"trace[576909189] linearizableReadLoop","detail":"{readStateIndex:1303; appliedIndex:1302; }","duration":"198.318578ms","start":"2026-07-02T15:37:40.034698Z","end":"2026-07-02T15:37:40.233016Z","steps":["trace[576909189] 'read index received' (duration: 197.969578ms)","trace[576909189] 'applied index is now lower than readState.Index' (duration: 347.41µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:37:40.233076Z","caller":"traceutil/trace.go:171","msg":"trace[1401309431] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"547.729838ms","start":"2026-07-02T15:37:39.685306Z","end":"2026-07-02T15:37:40.233035Z","steps":["trace[1401309431] 'process raft request' (duration: 547.510912ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:37:40.233251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:37:39.685278Z","time spent":"547.886973ms","remote":"127.0.0.1:51904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T15:37:40.233409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.685487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-02T15:37:40.233447Z","caller":"traceutil/trace.go:171","msg":"trace[409892108] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1248; }","duration":"198.794861ms","start":"2026-07-02T15:37:40.034641Z","end":"2026-07-02T15:37:40.233436Z","steps":["trace[409892108] 'agreement among raft nodes before linearized reading' (duration: 198.554905ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:37:40.706714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.165631ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T15:37:40.706847Z","caller":"traceutil/trace.go:171","msg":"trace[1085928288] linearizableReadLoop","detail":"{readStateIndex:1304; appliedIndex:1303; }","duration":"376.489063ms","start":"2026-07-02T15:37:40.330337Z","end":"2026-07-02T15:37:40.706826Z","steps":["trace[1085928288] 'read index received' (duration: 138.128017ms)","trace[1085928288] 'applied index is now lower than readState.Index' (duration: 238.359406ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:37:40.706982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.656518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:37:40.707017Z","caller":"traceutil/trace.go:171","msg":"trace[1822901776] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1249; }","duration":"376.74127ms","start":"2026-07-02T15:37:40.330266Z","end":"2026-07-02T15:37:40.707007Z","steps":["trace[1822901776] 'agreement among raft nodes before linearized reading' (duration: 376.682768ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:37:40.707062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:37:40.330237Z","time spent":"376.816432ms","remote":"127.0.0.1:52090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-02T15:37:40.707049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.809328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:37:40.707137Z","caller":"traceutil/trace.go:171","msg":"trace[1782350848] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1249; }","duration":"207.967953ms","start":"2026-07-02T15:37:40.499149Z","end":"2026-07-02T15:37:40.707117Z","steps":["trace[1782350848] 'agreement among raft nodes before linearized reading' (duration: 207.817899ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:37:40.70743Z","caller":"traceutil/trace.go:171","msg":"trace[990508813] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"463.969682ms","start":"2026-07-02T15:37:40.243349Z","end":"2026-07-02T15:37:40.707319Z","steps":["trace[990508813] 'process raft request' (duration: 225.097013ms)","trace[990508813] 'compare' (duration: 237.998525ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:37:40.707805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:37:40.243327Z","time spent":"464.204418ms","remote":"127.0.0.1:52090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:37:40.906635Z","caller":"traceutil/trace.go:171","msg":"trace[1765174363] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"192.947781ms","start":"2026-07-02T15:37:40.713649Z","end":"2026-07-02T15:37:40.906597Z","steps":["trace[1765174363] 'process raft request' (duration: 93.401911ms)","trace[1765174363] 'compare' (duration: 99.313294ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:37:40.906905Z","caller":"traceutil/trace.go:171","msg":"trace[62940096] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"164.278495ms","start":"2026-07-02T15:37:40.742581Z","end":"2026-07-02T15:37:40.906859Z","steps":["trace[62940096] 'process raft request' (duration: 163.946466ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:37:40.907217Z","caller":"traceutil/trace.go:171","msg":"trace[526090355] linearizableReadLoop","detail":"{readStateIndex:1307; appliedIndex:1304; }","duration":"148.037519ms","start":"2026-07-02T15:37:40.759118Z","end":"2026-07-02T15:37:40.907155Z","steps":["trace[526090355] 'read index received' (duration: 6.92496ms)","trace[526090355] 'applied index is now lower than readState.Index' (duration: 141.107479ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:37:40.907172Z","caller":"traceutil/trace.go:171","msg":"trace[1567494025] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"133.565933ms","start":"2026-07-02T15:37:40.773537Z","end":"2026-07-02T15:37:40.907103Z","steps":["trace[1567494025] 'process raft request' (duration: 133.309026ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:37:40.907657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.626469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-07-02T15:37:40.907704Z","caller":"traceutil/trace.go:171","msg":"trace[1506696786] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1253; }","duration":"135.733572ms","start":"2026-07-02T15:37:40.771958Z","end":"2026-07-02T15:37:40.907692Z","steps":["trace[1506696786] 'agreement among raft nodes before linearized reading' (duration: 135.405473ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:37:40.908897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.752836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-02T15:37:40.908955Z","caller":"traceutil/trace.go:171","msg":"trace[1700297725] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1253; }","duration":"149.878669ms","start":"2026-07-02T15:37:40.759063Z","end":"2026-07-02T15:37:40.908941Z","steps":["trace[1700297725] 'agreement among raft nodes before linearized reading' (duration: 148.231414ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:37:41.597541Z","caller":"traceutil/trace.go:171","msg":"trace[673616015] linearizableReadLoop","detail":"{readStateIndex:1312; appliedIndex:1311; }","duration":"105.850733ms","start":"2026-07-02T15:37:41.491653Z","end":"2026-07-02T15:37:41.597503Z","steps":["trace[673616015] 'read index received' (duration: 105.547574ms)","trace[673616015] 'applied index is now lower than readState.Index' (duration: 300.959µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:37:41.597679Z","caller":"traceutil/trace.go:171","msg":"trace[90927029] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"236.815994ms","start":"2026-07-02T15:37:41.360835Z","end":"2026-07-02T15:37:41.597651Z","steps":["trace[90927029] 'process raft request' (duration: 236.454984ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:37:41.59786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.178462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-02T15:37:41.5979Z","caller":"traceutil/trace.go:171","msg":"trace[1050179933] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1257; }","duration":"106.306545ms","start":"2026-07-02T15:37:41.491581Z","end":"2026-07-02T15:37:41.597888Z","steps":["trace[1050179933] 'agreement among raft nodes before linearized reading' (duration: 106.10688ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:37:42.414283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.605656ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T15:37:42.414483Z","caller":"traceutil/trace.go:171","msg":"trace[385575004] linearizableReadLoop","detail":"{readStateIndex:1314; appliedIndex:1312; }","duration":"195.097649ms","start":"2026-07-02T15:37:42.219366Z","end":"2026-07-02T15:37:42.414464Z","steps":["trace[385575004] 'read index received' (duration: 146.222159ms)","trace[385575004] 'applied index is now lower than readState.Index' (duration: 48.87456ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:37:42.414585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.218063ms","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-07-02T15:37:42.414613Z","caller":"traceutil/trace.go:171","msg":"trace[684775674] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1259; }","duration":"195.301605ms","start":"2026-07-02T15:37:42.219302Z","end":"2026-07-02T15:37:42.414604Z","steps":["trace[684775674] 'agreement among raft nodes before linearized reading' (duration: 195.214583ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:37:42.414579Z","caller":"traceutil/trace.go:171","msg":"trace[1364894076] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"812.192951ms","start":"2026-07-02T15:37:41.602343Z","end":"2026-07-02T15:37:42.414536Z","steps":["trace[1364894076] 'process raft request' (duration: 586.231135ms)","trace[1364894076] 'compare' (duration: 224.849406ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:37:42.414604Z","caller":"traceutil/trace.go:171","msg":"trace[1930643911] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"811.652776ms","start":"2026-07-02T15:37:41.602911Z","end":"2026-07-02T15:37:42.414564Z","steps":["trace[1930643911] 'process raft request' (duration: 811.492641ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:37:42.414799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:37:41.602316Z","time spent":"812.358885ms","remote":"127.0.0.1:52090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T15:37:42.414873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:37:41.602886Z","time spent":"811.80505ms","remote":"127.0.0.1:52090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:37:42.847452Z","caller":"traceutil/trace.go:171","msg":"trace[2010961753] linearizableReadLoop","detail":"{readStateIndex:1315; appliedIndex:1314; }","duration":"103.127047ms","start":"2026-07-02T15:37:42.744294Z","end":"2026-07-02T15:37:42.847421Z","steps":["trace[2010961753] 'read index received' (duration: 102.803388ms)","trace[2010961753] 'applied index is now lower than readState.Index' (duration: 322.039µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:37:42.847615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.289162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:37:42.847568Z","caller":"traceutil/trace.go:171","msg":"trace[34235301] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"129.046669ms","start":"2026-07-02T15:37:42.718467Z","end":"2026-07-02T15:37:42.847514Z","steps":["trace[34235301] 'process raft request' (duration: 128.72944ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:37:42.84767Z","caller":"traceutil/trace.go:171","msg":"trace[787504191] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1260; }","duration":"103.400795ms","start":"2026-07-02T15:37:42.744256Z","end":"2026-07-02T15:37:42.847657Z","steps":["trace[787504191] 'agreement among raft nodes before linearized reading' (duration: 103.284281ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:37:51.494456Z","caller":"traceutil/trace.go:171","msg":"trace[91194513] linearizableReadLoop","detail":"{readStateIndex:1381; appliedIndex:1380; }","duration":"496.500574ms","start":"2026-07-02T15:37:50.997925Z","end":"2026-07-02T15:37:51.494426Z","steps":["trace[91194513] 'read index received' (duration: 496.206146ms)","trace[91194513] 'applied index is now lower than readState.Index' (duration: 293.018µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:37:51.494632Z","caller":"traceutil/trace.go:171","msg":"trace[977101993] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"603.759236ms","start":"2026-07-02T15:37:50.890853Z","end":"2026-07-02T15:37:51.494612Z","steps":["trace[977101993] 'process raft request' (duration: 603.383865ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:37:51.494767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:37:50.89082Z","time spent":"603.851188ms","remote":"127.0.0.1:52090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T15:37:51.494971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.172904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-07-02T15:37:51.495068Z","caller":"traceutil/trace.go:171","msg":"trace[126771696] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1324; }","duration":"485.344558ms","start":"2026-07-02T15:37:51.009698Z","end":"2026-07-02T15:37:51.495043Z","steps":["trace[126771696] 'agreement among raft nodes before linearized reading' (duration: 485.094601ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:37:51.495116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:37:51.009669Z","time spent":"485.437361ms","remote":"127.0.0.1:52090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-02T15:37:51.49519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.319337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T15:37:51.49523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.101844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-02T15:37:51.495241Z","caller":"traceutil/trace.go:171","msg":"trace[1364363038] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1324; }","duration":"346.398818ms","start":"2026-07-02T15:37:51.148828Z","end":"2026-07-02T15:37:51.495227Z","steps":["trace[1364363038] 'agreement among raft nodes before linearized reading' (duration: 346.317166ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:37:51.495262Z","caller":"traceutil/trace.go:171","msg":"trace[1760717590] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1324; }","duration":"453.157416ms","start":"2026-07-02T15:37:51.042095Z","end":"2026-07-02T15:37:51.495252Z","steps":["trace[1760717590] 'agreement among raft nodes before linearized reading' (duration: 453.065574ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:37:51.495278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:37:51.148814Z","time spent":"346.456981ms","remote":"127.0.0.1:52070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-07-02T15:37:51.495295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:37:51.042081Z","time spent":"453.208637ms","remote":"127.0.0.1:52090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-02T15:37:51.495987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.051338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-02T15:37:51.496056Z","caller":"traceutil/trace.go:171","msg":"trace[1515778599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1324; }","duration":"498.178691ms","start":"2026-07-02T15:37:50.997864Z","end":"2026-07-02T15:37:51.496042Z","steps":["trace[1515778599] 'agreement among raft nodes before linearized reading' (duration: 497.615945ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:37:51.496096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:37:50.997834Z","time spent":"498.248373ms","remote":"127.0.0.1:52090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-02T15:37:52.413491Z","caller":"traceutil/trace.go:171","msg":"trace[926364928] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"911.796401ms","start":"2026-07-02T15:37:51.501649Z","end":"2026-07-02T15:37:52.413446Z","steps":["trace[926364928] 'process raft request' (duration: 908.442329ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:37:52.413951Z","caller":"traceutil/trace.go:171","msg":"trace[2075278839] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"912.225152ms","start":"2026-07-02T15:37:51.501689Z","end":"2026-07-02T15:37:52.413914Z","steps":["trace[2075278839] 'process raft request' (duration: 911.660807ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:37:52.414073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"912.222633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:37:52.41412Z","caller":"traceutil/trace.go:171","msg":"trace[1450656175] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1328; }","duration":"912.315566ms","start":"2026-07-02T15:37:51.501795Z","end":"2026-07-02T15:37:52.41411Z","steps":["trace[1450656175] 'agreement among raft nodes before linearized reading' (duration: 912.202073ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:37:52.414159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:37:51.501785Z","time spent":"912.364687ms","remote":"127.0.0.1:51832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-02T15:37:52.413936Z","caller":"traceutil/trace.go:171","msg":"trace[86434135] linearizableReadLoop","detail":"{readStateIndex:1384; appliedIndex:1381; }","duration":"912.083869ms","start":"2026-07-02T15:37:51.501826Z","end":"2026-07-02T15:37:52.41391Z","steps":["trace[86434135] 'read index received' (duration: 62.193755ms)","trace[86434135] 'applied index is now lower than readState.Index' (duration: 849.889064ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:37:52.414175Z","caller":"traceutil/trace.go:171","msg":"trace[1267848588] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"785.365764ms","start":"2026-07-02T15:37:51.628785Z","end":"2026-07-02T15:37:52.41415Z","steps":["trace[1267848588] 'process raft request' (duration: 785.089716ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:37:52.414477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.178862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T15:37:52.414508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:37:51.501678Z","time spent":"912.642644ms","remote":"127.0.0.1:52090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T15:37:52.414607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.824499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:37:52.41464Z","caller":"traceutil/trace.go:171","msg":"trace[829582023] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1328; }","duration":"571.918041ms","start":"2026-07-02T15:37:51.842714Z","end":"2026-07-02T15:37:52.414632Z","steps":["trace[829582023] 'agreement among raft nodes before linearized reading' (duration: 571.87032ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:37:52.414669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:37:51.842683Z","time spent":"571.979083ms","remote":"127.0.0.1:52252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"info","ts":"2026-07-02T15:37:52.414523Z","caller":"traceutil/trace.go:171","msg":"trace[1144847058] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1328; }","duration":"199.284765ms","start":"2026-07-02T15:37:52.215226Z","end":"2026-07-02T15:37:52.41451Z","steps":["trace[1144847058] 'agreement among raft nodes before linearized reading' (duration: 199.217613ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:37:52.415243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"895.183926ms","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-07-02T15:37:52.415378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:37:51.501638Z","time spent":"911.966986ms","remote":"127.0.0.1:52090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:37:52.415379Z","caller":"traceutil/trace.go:171","msg":"trace[30140953] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1328; }","duration":"896.07845ms","start":"2026-07-02T15:37:51.519276Z","end":"2026-07-02T15:37:52.415354Z","steps":["trace[30140953] 'agreement among raft nodes before linearized reading' (duration: 895.064722ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:37:52.415538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:37:51.519259Z","time spent":"896.262955ms","remote":"127.0.0.1:52090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-02T15:37:52.416272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:37:51.628761Z","time spent":"785.489277ms","remote":"127.0.0.1:52090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T15:38:11.902117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.586409ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T15:38:11.90229Z","caller":"traceutil/trace.go:171","msg":"trace[770893137] transaction","detail":"{read_only:false; response_revision:1418; number_of_response:1; }","duration":"228.872346ms","start":"2026-07-02T15:38:11.673385Z","end":"2026-07-02T15:38:11.902258Z","steps":["trace[770893137] 'process raft request' (duration: 77.971308ms)","trace[770893137] 'compare' (duration: 150.308442ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:39:10.964887Z","caller":"traceutil/trace.go:171","msg":"trace[1555937635] linearizableReadLoop","detail":"{readStateIndex:1761; appliedIndex:1760; }","duration":"118.651577ms","start":"2026-07-02T15:39:10.846208Z","end":"2026-07-02T15:39:10.96486Z","steps":["trace[1555937635] 'read index received' (duration: 28.176329ms)","trace[1555937635] 'applied index is now lower than readState.Index' (duration: 90.473718ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:39:10.965044Z","caller":"traceutil/trace.go:171","msg":"trace[1330305372] transaction","detail":"{read_only:false; response_revision:1688; number_of_response:1; }","duration":"141.914802ms","start":"2026-07-02T15:39:10.82311Z","end":"2026-07-02T15:39:10.965024Z","steps":["trace[1330305372] 'process raft request' (duration: 51.257578ms)","trace[1330305372] 'compare' (duration: 90.363866ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:39:10.965369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.14775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-02T15:39:10.965427Z","caller":"traceutil/trace.go:171","msg":"trace[1261327147] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1688; }","duration":"119.256103ms","start":"2026-07-02T15:39:10.846159Z","end":"2026-07-02T15:39:10.965415Z","steps":["trace[1261327147] 'agreement among raft nodes before linearized reading' (duration: 119.071328ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:39:11.094971Z","caller":"traceutil/trace.go:171","msg":"trace[1415173660] transaction","detail":"{read_only:false; response_revision:1690; number_of_response:1; }","duration":"124.516217ms","start":"2026-07-02T15:39:10.970422Z","end":"2026-07-02T15:39:11.094938Z","steps":["trace[1415173660] 'process raft request' (duration: 95.423043ms)","trace[1415173660] 'compare' (duration: 28.900058ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:39:11.095369Z","caller":"traceutil/trace.go:171","msg":"trace[1478316921] transaction","detail":"{read_only:false; response_revision:1691; number_of_response:1; }","duration":"124.560998ms","start":"2026-07-02T15:39:10.970789Z","end":"2026-07-02T15:39:11.09535Z","steps":["trace[1478316921] 'process raft request' (duration: 124.092655ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:39:11.095364Z","caller":"traceutil/trace.go:171","msg":"trace[110427610] transaction","detail":"{read_only:false; response_revision:1692; number_of_response:1; }","duration":"124.422194ms","start":"2026-07-02T15:39:10.970882Z","end":"2026-07-02T15:39:11.095304Z","steps":["trace[110427610] 'process raft request' (duration: 124.227078ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:39:15.163537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.014986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:39:15.163738Z","caller":"traceutil/trace.go:171","msg":"trace[1092438283] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1707; }","duration":"119.256913ms","start":"2026-07-02T15:39:15.044449Z","end":"2026-07-02T15:39:15.163706Z","steps":["trace[1092438283] 'count revisions from in-memory index tree' (duration: 118.905843ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:39:15.323623Z","caller":"traceutil/trace.go:171","msg":"trace[100652538] transaction","detail":"{read_only:false; response_revision:1710; number_of_response:1; }","duration":"154.575757ms","start":"2026-07-02T15:39:15.168966Z","end":"2026-07-02T15:39:15.323541Z","steps":["trace[100652538] 'process raft request' (duration: 154.388071ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:39:15.323661Z","caller":"traceutil/trace.go:171","msg":"trace[1854029778] transaction","detail":"{read_only:false; response_revision:1709; number_of_response:1; }","duration":"156.360285ms","start":"2026-07-02T15:39:15.167263Z","end":"2026-07-02T15:39:15.323624Z","steps":["trace[1854029778] 'process raft request' (duration: 151.738259ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:39:15.323868Z","caller":"traceutil/trace.go:171","msg":"trace[797670944] linearizableReadLoop","detail":"{readStateIndex:1784; appliedIndex:1782; }","duration":"140.214495ms","start":"2026-07-02T15:39:15.183625Z","end":"2026-07-02T15:39:15.32384Z","steps":["trace[797670944] 'read index received' (duration: 53.958162ms)","trace[797670944] 'applied index is now lower than readState.Index' (duration: 86.255033ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:39:15.324024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.361749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T15:39:15.324131Z","caller":"traceutil/trace.go:171","msg":"trace[789701969] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1710; }","duration":"140.522463ms","start":"2026-07-02T15:39:15.18358Z","end":"2026-07-02T15:39:15.324102Z","steps":["trace[789701969] 'agreement among raft nodes before linearized reading' (duration: 140.350158ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:39:16.4907Z","caller":"traceutil/trace.go:171","msg":"trace[530382047] transaction","detail":"{read_only:false; response_revision:1714; number_of_response:1; }","duration":"104.232984ms","start":"2026-07-02T15:39:16.38644Z","end":"2026-07-02T15:39:16.490673Z","steps":["trace[530382047] 'process raft request' (duration: 104.062579ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:39:20.024167Z","caller":"traceutil/trace.go:171","msg":"trace[35551907] transaction","detail":"{read_only:false; response_revision:1730; number_of_response:1; }","duration":"195.401491ms","start":"2026-07-02T15:39:19.82872Z","end":"2026-07-02T15:39:20.024121Z","steps":["trace[35551907] 'process raft request' (duration: 195.136944ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:39:35.944035Z","caller":"traceutil/trace.go:171","msg":"trace[866129744] transaction","detail":"{read_only:false; response_revision:1802; number_of_response:1; }","duration":"247.300835ms","start":"2026-07-02T15:39:35.69671Z","end":"2026-07-02T15:39:35.944011Z","steps":["trace[866129744] 'process raft request' (duration: 152.881899ms)","trace[866129744] 'compare' (duration: 94.019445ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:39:36.259139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.49532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-07-02T15:39:36.259247Z","caller":"traceutil/trace.go:171","msg":"trace[2001941597] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1802; }","duration":"189.662204ms","start":"2026-07-02T15:39:36.069561Z","end":"2026-07-02T15:39:36.259223Z","steps":["trace[2001941597] 'range keys from in-memory index tree' (duration: 189.310974ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:39:49.980315Z","caller":"traceutil/trace.go:171","msg":"trace[23271657] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"100.962765ms","start":"2026-07-02T15:39:49.879326Z","end":"2026-07-02T15:39:49.980289Z","steps":["trace[23271657] 'process raft request' (duration: 100.78153ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:40:00.800349Z","caller":"traceutil/trace.go:171","msg":"trace[1718889856] transaction","detail":"{read_only:false; response_revision:1916; number_of_response:1; }","duration":"106.407619ms","start":"2026-07-02T15:40:00.693918Z","end":"2026-07-02T15:40:00.800326Z","steps":["trace[1718889856] 'process raft request' (duration: 105.807403ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:40:01.063044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.20273ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T15:40:01.063208Z","caller":"traceutil/trace.go:171","msg":"trace[244761142] transaction","detail":"{read_only:false; response_revision:1917; number_of_response:1; }","duration":"257.807662ms","start":"2026-07-02T15:40:00.805373Z","end":"2026-07-02T15:40:01.06318Z","steps":["trace[244761142] 'process raft request' (duration: 104.356925ms)","trace[244761142] 'compare' (duration: 152.799169ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:40:06.683599Z","caller":"traceutil/trace.go:171","msg":"trace[495344280] linearizableReadLoop","detail":"{readStateIndex:2025; appliedIndex:2024; }","duration":"270.217433ms","start":"2026-07-02T15:40:06.413352Z","end":"2026-07-02T15:40:06.683569Z","steps":["trace[495344280] 'read index received' (duration: 270.008907ms)","trace[495344280] 'applied index is now lower than readState.Index' (duration: 207.386µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:40:06.683661Z","caller":"traceutil/trace.go:171","msg":"trace[726465430] transaction","detail":"{read_only:false; response_revision:1941; number_of_response:1; }","duration":"308.580077ms","start":"2026-07-02T15:40:06.37504Z","end":"2026-07-02T15:40:06.68362Z","steps":["trace[726465430] 'process raft request' (duration: 308.367142ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:40:06.683874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.50097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-02T15:40:06.683973Z","caller":"traceutil/trace.go:171","msg":"trace[2039005409] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1941; }","duration":"270.680595ms","start":"2026-07-02T15:40:06.413271Z","end":"2026-07-02T15:40:06.683951Z","steps":["trace[2039005409] 'agreement among raft nodes before linearized reading' (duration: 270.419778ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:40:06.684066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.04358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-02T15:40:06.684111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:40:06.375006Z","time spent":"308.899546ms","remote":"127.0.0.1:52090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:40:06.684125Z","caller":"traceutil/trace.go:171","msg":"trace[42722488] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1941; }","duration":"212.240466ms","start":"2026-07-02T15:40:06.471871Z","end":"2026-07-02T15:40:06.684112Z","steps":["trace[42722488] 'agreement among raft nodes before linearized reading' (duration: 211.865556ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:40:06.684416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.475086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-07-02T15:40:06.684422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.265602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:40:06.68453Z","caller":"traceutil/trace.go:171","msg":"trace[281732206] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1941; }","duration":"186.427077ms","start":"2026-07-02T15:40:06.498073Z","end":"2026-07-02T15:40:06.6845Z","steps":["trace[281732206] 'agreement among raft nodes before linearized reading' (duration: 186.287833ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:40:06.68446Z","caller":"traceutil/trace.go:171","msg":"trace[762070648] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1941; }","duration":"147.539809ms","start":"2026-07-02T15:40:06.536909Z","end":"2026-07-02T15:40:06.684449Z","steps":["trace[762070648] 'agreement among raft nodes before linearized reading' (duration: 147.439606ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:40:07.072404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.412557ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T15:40:07.072585Z","caller":"traceutil/trace.go:171","msg":"trace[2079271198] transaction","detail":"{read_only:false; response_revision:1943; number_of_response:1; }","duration":"383.339013ms","start":"2026-07-02T15:40:06.689217Z","end":"2026-07-02T15:40:07.072556Z","steps":["trace[2079271198] 'process raft request' (duration: 60.634678ms)","trace[2079271198] 'compare' (duration: 322.005596ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:40:07.072677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:40:06.689188Z","time spent":"383.448176ms","remote":"127.0.0.1:52090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:40:07.278708Z","caller":"traceutil/trace.go:171","msg":"trace[521930556] transaction","detail":"{read_only:false; response_revision:1944; number_of_response:1; }","duration":"148.822502ms","start":"2026-07-02T15:40:07.129833Z","end":"2026-07-02T15:40:07.278655Z","steps":["trace[521930556] 'process raft request' (duration: 51.259388ms)","trace[521930556] 'compare' (duration: 97.231926ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:40:08.912869Z","caller":"traceutil/trace.go:171","msg":"trace[1336344725] transaction","detail":"{read_only:false; response_revision:1952; number_of_response:1; }","duration":"169.673499ms","start":"2026-07-02T15:40:08.743153Z","end":"2026-07-02T15:40:08.912826Z","steps":["trace[1336344725] 'process raft request' (duration: 169.466674ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:40:13.070724Z","caller":"traceutil/trace.go:171","msg":"trace[378455897] linearizableReadLoop","detail":"{readStateIndex:2055; appliedIndex:2054; }","duration":"122.803118ms","start":"2026-07-02T15:40:12.947888Z","end":"2026-07-02T15:40:13.070691Z","steps":["trace[378455897] 'read index received' (duration: 121.139154ms)","trace[378455897] 'applied index is now lower than readState.Index' (duration: 1.662184ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:40:13.070753Z","caller":"traceutil/trace.go:171","msg":"trace[1878778536] transaction","detail":"{read_only:false; response_revision:1970; number_of_response:1; }","duration":"221.519143ms","start":"2026-07-02T15:40:12.849198Z","end":"2026-07-02T15:40:13.070717Z","steps":["trace[1878778536] 'process raft request' (duration: 219.862738ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:40:13.071052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.051485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.115\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-02T15:40:13.0711Z","caller":"traceutil/trace.go:171","msg":"trace[593356414] range","detail":"{range_begin:/registry/masterleases/199.204.45.115; range_end:; response_count:1; response_revision:1970; }","duration":"123.190959ms","start":"2026-07-02T15:40:12.947896Z","end":"2026-07-02T15:40:13.071087Z","steps":["trace[593356414] 'agreement among raft nodes before linearized reading' (duration: 122.981713ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:40:13.071147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.220449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-02T15:40:13.071231Z","caller":"traceutil/trace.go:171","msg":"trace[2072585523] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1970; }","duration":"123.371413ms","start":"2026-07-02T15:40:12.94784Z","end":"2026-07-02T15:40:13.071211Z","steps":["trace[2072585523] 'agreement among raft nodes before linearized reading' (duration: 123.033464ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:40:13.442299Z","caller":"traceutil/trace.go:171","msg":"trace[1885460181] transaction","detail":"{read_only:false; response_revision:1973; number_of_response:1; }","duration":"288.867731ms","start":"2026-07-02T15:40:13.153399Z","end":"2026-07-02T15:40:13.442267Z","steps":["trace[1885460181] 'process raft request' (duration: 286.315553ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:40:17.858022Z","caller":"traceutil/trace.go:171","msg":"trace[891456370] transaction","detail":"{read_only:false; response_revision:1992; number_of_response:1; }","duration":"228.433387ms","start":"2026-07-02T15:40:17.629474Z","end":"2026-07-02T15:40:17.857907Z","steps":["trace[891456370] 'process raft request' (duration: 143.09089ms)","trace[891456370] 'compare' (duration: 85.125432ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:40:17.859058Z","caller":"traceutil/trace.go:171","msg":"trace[1122306197] transaction","detail":"{read_only:false; response_revision:1993; number_of_response:1; }","duration":"105.64517ms","start":"2026-07-02T15:40:17.752592Z","end":"2026-07-02T15:40:17.858237Z","steps":["trace[1122306197] 'process raft request' (duration: 105.26823ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:40:18.202842Z","caller":"traceutil/trace.go:171","msg":"trace[695197777] transaction","detail":"{read_only:false; response_revision:1996; number_of_response:1; }","duration":"123.531018ms","start":"2026-07-02T15:40:18.079279Z","end":"2026-07-02T15:40:18.20281Z","steps":["trace[695197777] 'process raft request' (duration: 111.168808ms)","trace[695197777] 'compare' (duration: 12.232566ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:40:38.426622Z","caller":"traceutil/trace.go:171","msg":"trace[897139807] transaction","detail":"{read_only:false; response_revision:2088; number_of_response:1; }","duration":"175.644299ms","start":"2026-07-02T15:40:38.250924Z","end":"2026-07-02T15:40:38.426568Z","steps":["trace[897139807] 'process raft request' (duration: 104.356866ms)","trace[897139807] 'compare' (duration: 71.071627ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:40:39.820429Z","caller":"traceutil/trace.go:171","msg":"trace[2124618726] transaction","detail":"{read_only:false; response_revision:2093; number_of_response:1; }","duration":"100.707758ms","start":"2026-07-02T15:40:39.71967Z","end":"2026-07-02T15:40:39.820378Z","steps":["trace[2124618726] 'process raft request' (duration: 52.041259ms)","trace[2124618726] 'compare' (duration: 48.398542ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:40:43.62623Z","caller":"traceutil/trace.go:171","msg":"trace[2145975864] linearizableReadLoop","detail":"{readStateIndex:2202; appliedIndex:2201; }","duration":"127.916934ms","start":"2026-07-02T15:40:43.498272Z","end":"2026-07-02T15:40:43.626189Z","steps":["trace[2145975864] 'read index received' (duration: 127.666898ms)","trace[2145975864] 'applied index is now lower than readState.Index' (duration: 249.016µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:40:43.626303Z","caller":"traceutil/trace.go:171","msg":"trace[1515093239] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"187.937937ms","start":"2026-07-02T15:40:43.438315Z","end":"2026-07-02T15:40:43.626253Z","steps":["trace[1515093239] 'process raft request' (duration: 187.704231ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:40:43.626391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.095179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:40:43.626509Z","caller":"traceutil/trace.go:171","msg":"trace[758481831] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2110; }","duration":"128.296164ms","start":"2026-07-02T15:40:43.4982Z","end":"2026-07-02T15:40:43.626496Z","steps":["trace[758481831] 'agreement among raft nodes before linearized reading' (duration: 128.11568ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:41:46.806808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.580009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-02T15:41:46.806924Z","caller":"traceutil/trace.go:171","msg":"trace[435263107] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2419; }","duration":"187.767414ms","start":"2026-07-02T15:41:46.619134Z","end":"2026-07-02T15:41:46.806901Z","steps":["trace[435263107] 'range keys from in-memory index tree' (duration: 187.379544ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:41:50.489248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.458434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-02T15:41:50.489355Z","caller":"traceutil/trace.go:171","msg":"trace[260032874] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2440; }","duration":"102.632328ms","start":"2026-07-02T15:41:50.3867Z","end":"2026-07-02T15:41:50.489332Z","steps":["trace[260032874] 'range keys from in-memory index tree' (duration: 102.187296ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:41:54.291004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.509958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:41:54.291151Z","caller":"traceutil/trace.go:171","msg":"trace[239124783] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:2458; }","duration":"292.738104ms","start":"2026-07-02T15:41:53.998372Z","end":"2026-07-02T15:41:54.29111Z","steps":["trace[239124783] 'count revisions from in-memory index tree' (duration: 292.374835ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:41:54.291543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.620214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-02T15:41:54.29243Z","caller":"traceutil/trace.go:171","msg":"trace[463709749] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2458; }","duration":"406.762537ms","start":"2026-07-02T15:41:53.88483Z","end":"2026-07-02T15:41:54.291592Z","steps":["trace[463709749] 'range keys from in-memory index tree' (duration: 406.039268ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:41:54.292517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:41:53.884795Z","time spent":"407.704212ms","remote":"127.0.0.1:52090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-02T15:41:54.516057Z","caller":"traceutil/trace.go:171","msg":"trace[1940163556] transaction","detail":"{read_only:false; response_revision:2459; number_of_response:1; }","duration":"516.20253ms","start":"2026-07-02T15:41:53.999833Z","end":"2026-07-02T15:41:54.516035Z","steps":["trace[1940163556] 'process raft request' (duration: 515.956924ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:41:54.516235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:41:53.999802Z","time spent":"516.327724ms","remote":"127.0.0.1:52090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:41:54.554874Z","caller":"traceutil/trace.go:171","msg":"trace[1550941986] linearizableReadLoop","detail":"{readStateIndex:2567; appliedIndex:2565; }","duration":"209.090679ms","start":"2026-07-02T15:41:54.345752Z","end":"2026-07-02T15:41:54.554843Z","steps":["trace[1550941986] 'read index received' (duration: 170.198998ms)","trace[1550941986] 'applied index is now lower than readState.Index' (duration: 38.890001ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:41:54.555032Z","caller":"traceutil/trace.go:171","msg":"trace[71373387] transaction","detail":"{read_only:false; response_revision:2460; number_of_response:1; }","duration":"256.235006ms","start":"2026-07-02T15:41:54.298752Z","end":"2026-07-02T15:41:54.554987Z","steps":["trace[71373387] 'process raft request' (duration: 255.919387ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:41:54.555472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.692324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-02T15:41:54.55555Z","caller":"traceutil/trace.go:171","msg":"trace[840743864] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2460; }","duration":"209.838558ms","start":"2026-07-02T15:41:54.345698Z","end":"2026-07-02T15:41:54.555536Z","steps":["trace[840743864] 'agreement among raft nodes before linearized reading' (duration: 209.373826ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:41:54.858343Z","caller":"traceutil/trace.go:171","msg":"trace[1505197679] linearizableReadLoop","detail":"{readStateIndex:2568; appliedIndex:2567; }","duration":"291.4432ms","start":"2026-07-02T15:41:54.566859Z","end":"2026-07-02T15:41:54.858302Z","steps":["trace[1505197679] 'read index received' (duration: 207.956639ms)","trace[1505197679] 'applied index is now lower than readState.Index' (duration: 83.485501ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:41:54.858862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.974824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-07-02T15:41:54.858864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-02T15:41:54.858885Z","caller":"traceutil/trace.go:171","msg":"trace[2053074254] transaction","detail":"{read_only:false; response_revision:2461; number_of_response:1; }","duration":"297.260363ms","start":"2026-07-02T15:41:54.561561Z","end":"2026-07-02T15:41:54.858821Z","steps":["trace[2053074254] 'process raft request' (duration: 213.247448ms)","trace[2053074254] 'compare' (duration: 83.327267ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:41:54.85895Z","caller":"traceutil/trace.go:171","msg":"trace[1636976] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2461; }","duration":"292.090608ms","start":"2026-07-02T15:41:54.566838Z","end":"2026-07-02T15:41:54.858929Z","steps":["trace[1636976] 'agreement among raft nodes before linearized reading' (duration: 291.758089ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:41:54.858963Z","caller":"traceutil/trace.go:171","msg":"trace[1380188274] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2461; }","duration":"269.871134ms","start":"2026-07-02T15:41:54.589069Z","end":"2026-07-02T15:41:54.85894Z","steps":["trace[1380188274] 'agreement among raft nodes before linearized reading' (duration: 269.573886ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:41:54.859153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.46884ms","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:509"} {"level":"info","ts":"2026-07-02T15:41:54.859232Z","caller":"traceutil/trace.go:171","msg":"trace[1687622733] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2461; }","duration":"246.612784ms","start":"2026-07-02T15:41:54.612594Z","end":"2026-07-02T15:41:54.859207Z","steps":["trace[1687622733] 'agreement among raft nodes before linearized reading' (duration: 246.296246ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:41:58.453428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.824387ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-07-02T15:41:58.453971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.082678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:500 ","response":"range_response_count:5 size:1788"} {"level":"info","ts":"2026-07-02T15:41:58.454008Z","caller":"traceutil/trace.go:171","msg":"trace[783760395] linearizableReadLoop","detail":"{readStateIndex:2587; appliedIndex:2586; }","duration":"144.678868ms","start":"2026-07-02T15:41:58.308858Z","end":"2026-07-02T15:41:58.453537Z","steps":["trace[783760395] 'read index received' (duration: 11.705887ms)","trace[783760395] 'applied index is now lower than readState.Index' (duration: 132.970951ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:41:58.454068Z","caller":"traceutil/trace.go:171","msg":"trace[758947490] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:5; response_revision:2479; }","duration":"145.245373ms","start":"2026-07-02T15:41:58.308796Z","end":"2026-07-02T15:41:58.454042Z","steps":["trace[758947490] 'agreement among raft nodes before linearized reading' (duration: 144.832252ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:42:01.07976Z","caller":"traceutil/trace.go:171","msg":"trace[1256279500] linearizableReadLoop","detail":"{readStateIndex:2601; appliedIndex:2600; }","duration":"129.13611ms","start":"2026-07-02T15:42:00.950596Z","end":"2026-07-02T15:42:01.079733Z","steps":["trace[1256279500] 'read index received' (duration: 44.235021ms)","trace[1256279500] 'applied index is now lower than readState.Index' (duration: 84.899699ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:42:01.079878Z","caller":"traceutil/trace.go:171","msg":"trace[1856394779] transaction","detail":"{read_only:false; response_revision:2493; number_of_response:1; }","duration":"139.667326ms","start":"2026-07-02T15:42:00.940149Z","end":"2026-07-02T15:42:01.079816Z","steps":["trace[1856394779] 'process raft request' (duration: 54.757317ms)","trace[1856394779] 'compare' (duration: 84.703714ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:42:01.080026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.152332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-02T15:42:01.080057Z","caller":"traceutil/trace.go:171","msg":"trace[860363587] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2493; }","duration":"102.249744ms","start":"2026-07-02T15:42:00.977799Z","end":"2026-07-02T15:42:01.080049Z","steps":["trace[860363587] 'agreement among raft nodes before linearized reading' (duration: 102.0863ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:42:01.080033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.419027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-02T15:42:01.080102Z","caller":"traceutil/trace.go:171","msg":"trace[1086195416] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2493; }","duration":"129.515579ms","start":"2026-07-02T15:42:00.95057Z","end":"2026-07-02T15:42:01.080085Z","steps":["trace[1086195416] 'agreement among raft nodes before linearized reading' (duration: 129.276423ms)"],"step_count":1}