{"level":"warn","ts":"2026-05-05T02:29:17.765219Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T02:29:17.765347Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.243:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.243:2380","--initial-cluster=instance=https://199.19.213.243:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.243:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.243:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-05T02:29:17.765411Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T02:29:17.765424Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.243:2380"]} {"level":"info","ts":"2026-05-05T02:29:17.765439Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-05T02:29:17.766055Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.243:2379"]} {"level":"info","ts":"2026-05-05T02:29:17.76613Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.243:2380"],"listen-peer-urls":["https://199.19.213.243:2380"],"advertise-client-urls":["https://199.19.213.243:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.243:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.243:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-05T02:29:17.774991Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.089795ms"} {"level":"info","ts":"2026-05-05T02:29:17.783909Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"864e14457b828f9c","cluster-id":"6c0ae60a355554b9"} {"level":"info","ts":"2026-05-05T02:29:17.784104Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T02:29:17.784178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c became follower at term 0"} {"level":"info","ts":"2026-05-05T02:29:17.78424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 864e14457b828f9c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T02:29:17.784277Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c became follower at term 1"} {"level":"info","ts":"2026-05-05T02:29:17.784372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c switched to configuration voters=(9677694937923227548)"} {"level":"warn","ts":"2026-05-05T02:29:17.793219Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T02:29:17.794761Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T02:29:17.796208Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-05T02:29:17.798425Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"864e14457b828f9c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T02:29:17.799142Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"864e14457b828f9c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T02:29:17.799274Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T02:29:17.799362Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T02:29:17.79938Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T02:29:17.800483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c switched to configuration voters=(9677694937923227548)"} {"level":"info","ts":"2026-05-05T02:29:17.801204Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6c0ae60a355554b9","local-member-id":"864e14457b828f9c","added-peer-id":"864e14457b828f9c","added-peer-peer-urls":["https://199.19.213.243:2380"]} {"level":"info","ts":"2026-05-05T02:29:17.805548Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-05T02:29:17.805699Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.243:2380"} {"level":"info","ts":"2026-05-05T02:29:17.805778Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.243:2380"} {"level":"info","ts":"2026-05-05T02:29:17.807887Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"864e14457b828f9c","initial-advertise-peer-urls":["https://199.19.213.243:2380"],"listen-peer-urls":["https://199.19.213.243:2380"],"advertise-client-urls":["https://199.19.213.243:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.243:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T02:29:17.808034Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T02:29:17.985323Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T02:29:17.985393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T02:29:17.98543Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c received MsgPreVoteResp from 864e14457b828f9c at term 1"} {"level":"info","ts":"2026-05-05T02:29:17.985453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c became candidate at term 2"} {"level":"info","ts":"2026-05-05T02:29:17.985472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c received MsgVoteResp from 864e14457b828f9c at term 2"} {"level":"info","ts":"2026-05-05T02:29:17.985491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c became leader at term 2"} {"level":"info","ts":"2026-05-05T02:29:17.985508Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 864e14457b828f9c elected leader 864e14457b828f9c at term 2"} {"level":"info","ts":"2026-05-05T02:29:17.98634Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"864e14457b828f9c","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.243:2379]}","request-path":"/0/members/864e14457b828f9c/attributes","cluster-id":"6c0ae60a355554b9","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T02:29:17.986438Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T02:29:17.986669Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T02:29:17.986765Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T02:29:17.98695Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T02:29:17.987013Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T02:29:17.988796Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T02:29:17.989878Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.243:2379"} {"level":"info","ts":"2026-05-05T02:29:17.990056Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6c0ae60a355554b9","local-member-id":"864e14457b828f9c","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T02:29:17.99019Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T02:29:17.990225Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T02:29:19.910707Z","caller":"traceutil/trace.go:171","msg":"trace[631183309] linearizableReadLoop","detail":"{readStateIndex:12; appliedIndex:7; }","duration":"467.108635ms","start":"2026-05-05T02:29:19.443583Z","end":"2026-05-05T02:29:19.910692Z","steps":["trace[631183309] 'read index received' (duration: 466.986202ms)","trace[631183309] 'applied index is now lower than readState.Index' (duration: 121.723µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:29:19.910699Z","caller":"traceutil/trace.go:171","msg":"trace[813940278] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"473.073192ms","start":"2026-05-05T02:29:19.437582Z","end":"2026-05-05T02:29:19.910655Z","steps":["trace[813940278] 'process raft request' (duration: 472.98379ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:29:19.910727Z","caller":"traceutil/trace.go:171","msg":"trace[1020522279] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"472.342974ms","start":"2026-05-05T02:29:19.438348Z","end":"2026-05-05T02:29:19.910691Z","steps":["trace[1020522279] 'process raft request' (duration: 472.250492ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:29:19.910799Z","caller":"traceutil/trace.go:171","msg":"trace[930645360] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"472.404276ms","start":"2026-05-05T02:29:19.438388Z","end":"2026-05-05T02:29:19.910793Z","steps":["trace[930645360] 'process raft request' (duration: 472.228812ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:29:19.91083Z","caller":"traceutil/trace.go:171","msg":"trace[1369607797] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"472.317995ms","start":"2026-05-05T02:29:19.438507Z","end":"2026-05-05T02:29:19.910825Z","steps":["trace[1369607797] 'process raft request' (duration: 472.12895ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:19.910909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.140178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-05T02:29:19.910914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.144364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T02:29:19.910956Z","caller":"traceutil/trace.go:171","msg":"trace[660511377] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:9; }","duration":"474.220701ms","start":"2026-05-05T02:29:19.436726Z","end":"2026-05-05T02:29:19.910946Z","steps":["trace[660511377] 'agreement among raft nodes before linearized reading' (duration: 474.118168ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:29:19.910958Z","caller":"traceutil/trace.go:171","msg":"trace[1755915236] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:9; }","duration":"473.216666ms","start":"2026-05-05T02:29:19.437733Z","end":"2026-05-05T02:29:19.910949Z","steps":["trace[1755915236] 'agreement among raft nodes before linearized reading' (duration: 473.095003ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:19.910979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:19.437723Z","time spent":"473.251687ms","remote":"127.0.0.1:50408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-05T02:29:19.911027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.425092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T02:29:19.911046Z","caller":"traceutil/trace.go:171","msg":"trace[1296415442] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:9; }","duration":"503.442143ms","start":"2026-05-05T02:29:19.407599Z","end":"2026-05-05T02:29:19.911041Z","steps":["trace[1296415442] 'agreement among raft nodes before linearized reading' (duration: 503.415542ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:19.911061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:19.407583Z","time spent":"503.474773ms","remote":"127.0.0.1:50670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-05-05T02:29:19.91106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.159349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.243\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T02:29:19.911142Z","caller":"traceutil/trace.go:171","msg":"trace[1261846675] range","detail":"{range_begin:/registry/masterleases/199.19.213.243; range_end:; response_count:0; response_revision:9; }","duration":"476.280472ms","start":"2026-05-05T02:29:19.43484Z","end":"2026-05-05T02:29:19.911121Z","steps":["trace[1261846675] 'agreement among raft nodes before linearized reading' (duration: 476.124388ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:19.911186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:19.434819Z","time spent":"476.357673ms","remote":"127.0.0.1:50334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.19.213.243\" "} {"level":"info","ts":"2026-05-05T02:29:19.91131Z","caller":"traceutil/trace.go:171","msg":"trace[1116327518] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"472.517539ms","start":"2026-05-05T02:29:19.43878Z","end":"2026-05-05T02:29:19.911297Z","steps":["trace[1116327518] 'process raft request' (duration: 471.874363ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:19.910984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:19.436706Z","time spent":"474.264771ms","remote":"127.0.0.1:50392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"info","ts":"2026-05-05T02:29:19.911338Z","caller":"traceutil/trace.go:171","msg":"trace[1272938893] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"474.533918ms","start":"2026-05-05T02:29:19.436742Z","end":"2026-05-05T02:29:19.911276Z","steps":["trace[1272938893] 'process raft request' (duration: 473.741438ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:19.913792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:19.437555Z","time spent":"473.238757ms","remote":"127.0.0.1:50828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T02:29:19.913835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.063117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T02:29:19.913872Z","caller":"traceutil/trace.go:171","msg":"trace[1733283446] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"425.102448ms","start":"2026-05-05T02:29:19.488761Z","end":"2026-05-05T02:29:19.913863Z","steps":["trace[1733283446] 'agreement among raft nodes before linearized reading' (duration: 424.965705ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:19.913902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:19.488746Z","time spent":"425.150979ms","remote":"127.0.0.1:50408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-05T02:29:19.913985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:19.438757Z","time spent":"472.57417ms","remote":"127.0.0.1:50748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T02:29:19.914014Z","caller":"traceutil/trace.go:171","msg":"trace[1919448193] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"469.090625ms","start":"2026-05-05T02:29:19.4449Z","end":"2026-05-05T02:29:19.91399Z","steps":["trace[1919448193] 'process raft request' (duration: 468.712935ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:29:19.913996Z","caller":"traceutil/trace.go:171","msg":"trace[1561453151] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"466.89138ms","start":"2026-05-05T02:29:19.447098Z","end":"2026-05-05T02:29:19.913989Z","steps":["trace[1561453151] 'process raft request' (duration: 466.589662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:19.914102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:19.438296Z","time spent":"472.518829ms","remote":"127.0.0.1:50828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T02:29:19.914108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:19.444882Z","time spent":"469.180406ms","remote":"127.0.0.1:50530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T02:29:19.914131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:19.447089Z","time spent":"467.014162ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T02:29:19.914201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:19.43833Z","time spent":"472.473488ms","remote":"127.0.0.1:50828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T02:29:19.914077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:19.436672Z","time spent":"474.767654ms","remote":"127.0.0.1:50828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T02:29:19.914366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:19.438394Z","time spent":"472.446807ms","remote":"127.0.0.1:50828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T02:29:19.914026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.276865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T02:29:19.914458Z","caller":"traceutil/trace.go:171","msg":"trace[420616616] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:11; }","duration":"266.717556ms","start":"2026-05-05T02:29:19.64771Z","end":"2026-05-05T02:29:19.914424Z","steps":["trace[420616616] 'agreement among raft nodes before linearized reading' (duration: 266.299496ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:29:20.061937Z","caller":"traceutil/trace.go:171","msg":"trace[918557616] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"140.631493ms","start":"2026-05-05T02:29:19.921256Z","end":"2026-05-05T02:29:20.061887Z","steps":["trace[918557616] 'process raft request' (duration: 135.742952ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:29:20.062059Z","caller":"traceutil/trace.go:171","msg":"trace[463045199] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"137.470005ms","start":"2026-05-05T02:29:19.924565Z","end":"2026-05-05T02:29:20.062035Z","steps":["trace[463045199] 'process raft request' (duration: 137.343862ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:29:20.062008Z","caller":"traceutil/trace.go:171","msg":"trace[1731516920] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"139.368712ms","start":"2026-05-05T02:29:19.922583Z","end":"2026-05-05T02:29:20.061951Z","steps":["trace[1731516920] 'process raft request' (duration: 139.231308ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:29:20.062206Z","caller":"traceutil/trace.go:171","msg":"trace[1536289422] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"139.608087ms","start":"2026-05-05T02:29:19.922585Z","end":"2026-05-05T02:29:20.062193Z","steps":["trace[1536289422] 'process raft request' (duration: 139.266909ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:29:20.062296Z","caller":"traceutil/trace.go:171","msg":"trace[1015482481] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"139.660938ms","start":"2026-05-05T02:29:19.922616Z","end":"2026-05-05T02:29:20.062277Z","steps":["trace[1015482481] 'process raft request' (duration: 139.260139ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:29:20.06237Z","caller":"traceutil/trace.go:171","msg":"trace[427357681] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"137.439433ms","start":"2026-05-05T02:29:19.92492Z","end":"2026-05-05T02:29:20.062359Z","steps":["trace[427357681] 'process raft request' (duration: 137.035794ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:20.0624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.954446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-05T02:29:20.062466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.206806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3491"} {"level":"info","ts":"2026-05-05T02:29:20.062508Z","caller":"traceutil/trace.go:171","msg":"trace[234413522] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:20; }","duration":"143.321109ms","start":"2026-05-05T02:29:19.919171Z","end":"2026-05-05T02:29:20.062493Z","steps":["trace[234413522] 'agreement among raft nodes before linearized reading' (duration: 143.190106ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:29:20.062524Z","caller":"traceutil/trace.go:171","msg":"trace[1996198184] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:20; }","duration":"138.091019ms","start":"2026-05-05T02:29:19.924388Z","end":"2026-05-05T02:29:20.062479Z","steps":["trace[1996198184] 'agreement among raft nodes before linearized reading' (duration: 137.919345ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:20.062715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.389221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-05T02:29:20.062689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.367529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T02:29:20.062761Z","caller":"traceutil/trace.go:171","msg":"trace[2048776660] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21; }","duration":"141.461783ms","start":"2026-05-05T02:29:19.921287Z","end":"2026-05-05T02:29:20.062749Z","steps":["trace[2048776660] 'agreement among raft nodes before linearized reading' (duration: 141.35063ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:29:20.062808Z","caller":"traceutil/trace.go:171","msg":"trace[594448476] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:20; }","duration":"102.506621ms","start":"2026-05-05T02:29:19.96026Z","end":"2026-05-05T02:29:20.062767Z","steps":["trace[594448476] 'agreement among raft nodes before linearized reading' (duration: 102.327797ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:29:22.183866Z","caller":"traceutil/trace.go:171","msg":"trace[86320224] transaction","detail":"{read_only:false; response_revision:213; number_of_response:1; }","duration":"145.926913ms","start":"2026-05-05T02:29:22.037896Z","end":"2026-05-05T02:29:22.183823Z","steps":["trace[86320224] 'process raft request' (duration: 145.692058ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:29:22.332271Z","caller":"traceutil/trace.go:171","msg":"trace[123621195] linearizableReadLoop","detail":"{readStateIndex:221; appliedIndex:218; }","duration":"136.032229ms","start":"2026-05-05T02:29:22.196213Z","end":"2026-05-05T02:29:22.332245Z","steps":["trace[123621195] 'read index received' (duration: 872.371µs)","trace[123621195] 'applied index is now lower than readState.Index' (duration: 135.158968ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:29:22.332325Z","caller":"traceutil/trace.go:171","msg":"trace[562376661] transaction","detail":"{read_only:false; response_revision:215; number_of_response:1; }","duration":"140.732585ms","start":"2026-05-05T02:29:22.191583Z","end":"2026-05-05T02:29:22.332315Z","steps":["trace[562376661] 'process raft request' (duration: 140.618542ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:22.332401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.165512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T02:29:22.332691Z","caller":"traceutil/trace.go:171","msg":"trace[520025505] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:0; response_revision:215; }","duration":"136.49011ms","start":"2026-05-05T02:29:22.19619Z","end":"2026-05-05T02:29:22.33268Z","steps":["trace[520025505] 'agreement among raft nodes before linearized reading' (duration: 136.165052ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:29:22.332283Z","caller":"traceutil/trace.go:171","msg":"trace[1470899879] transaction","detail":"{read_only:false; response_revision:214; number_of_response:1; }","duration":"142.210612ms","start":"2026-05-05T02:29:22.190058Z","end":"2026-05-05T02:29:22.332269Z","steps":["trace[1470899879] 'process raft request' (duration: 136.86964ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:22.333218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.413216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:430"} {"level":"info","ts":"2026-05-05T02:29:22.33329Z","caller":"traceutil/trace.go:171","msg":"trace[467360484] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:216; }","duration":"127.477268ms","start":"2026-05-05T02:29:22.205781Z","end":"2026-05-05T02:29:22.333259Z","steps":["trace[467360484] 'agreement among raft nodes before linearized reading' (duration: 127.377115ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:29:22.333326Z","caller":"traceutil/trace.go:171","msg":"trace[645765303] transaction","detail":"{read_only:false; response_revision:216; number_of_response:1; }","duration":"135.118367ms","start":"2026-05-05T02:29:22.198189Z","end":"2026-05-05T02:29:22.333308Z","steps":["trace[645765303] 'process raft request' (duration: 134.905011ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:22.333426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.365531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-05T02:29:22.333455Z","caller":"traceutil/trace.go:171","msg":"trace[1977523917] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:216; }","duration":"126.441453ms","start":"2026-05-05T02:29:22.207006Z","end":"2026-05-05T02:29:22.333448Z","steps":["trace[1977523917] 'agreement among raft nodes before linearized reading' (duration: 126.378421ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:29:27.291178Z","caller":"traceutil/trace.go:171","msg":"trace[364151476] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"141.879413ms","start":"2026-05-05T02:29:27.149272Z","end":"2026-05-05T02:29:27.291152Z","steps":["trace[364151476] 'process raft request' (duration: 60.863522ms)","trace[364151476] 'compare' (duration: 80.902818ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T02:29:28.408175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.303039ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T02:29:28.408352Z","caller":"traceutil/trace.go:171","msg":"trace[966823590] linearizableReadLoop","detail":"{readStateIndex:322; appliedIndex:319; }","duration":"1.221022292s","start":"2026-05-05T02:29:27.187314Z","end":"2026-05-05T02:29:28.408336Z","steps":["trace[966823590] 'read index received' (duration: 23.010608ms)","trace[966823590] 'applied index is now lower than readState.Index' (duration: 1.198010764s)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:29:28.408405Z","caller":"traceutil/trace.go:171","msg":"trace[973835706] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"1.258473687s","start":"2026-05-05T02:29:27.149896Z","end":"2026-05-05T02:29:28.40837Z","steps":["trace[973835706] 'process raft request' (duration: 1.258382375s)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:29:28.40843Z","caller":"traceutil/trace.go:171","msg":"trace[997031707] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"1.258805045s","start":"2026-05-05T02:29:27.149598Z","end":"2026-05-05T02:29:28.408403Z","steps":["trace[997031707] 'process raft request' (duration: 496.962722ms)","trace[997031707] 'compare' (duration: 761.193807ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T02:29:28.408496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.171908759s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-05-05T02:29:28.408451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.221132004s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-05T02:29:28.408525Z","caller":"traceutil/trace.go:171","msg":"trace[2128962684] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:312; }","duration":"1.17196653s","start":"2026-05-05T02:29:27.236548Z","end":"2026-05-05T02:29:28.408515Z","steps":["trace[2128962684] 'agreement among raft nodes before linearized reading' (duration: 1.171927899s)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:29:28.408553Z","caller":"traceutil/trace.go:171","msg":"trace[1772414498] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:312; }","duration":"1.221256508s","start":"2026-05-05T02:29:27.187285Z","end":"2026-05-05T02:29:28.408541Z","steps":["trace[1772414498] 'agreement among raft nodes before linearized reading' (duration: 1.221130355s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:28.408557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:27.236529Z","time spent":"1.172020432s","remote":"127.0.0.1:50498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-05-05T02:29:28.408571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.016084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-05T02:29:28.408591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:27.18727Z","time spent":"1.221309459s","remote":"127.0.0.1:50498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-05-05T02:29:28.408582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.020879889s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-05-05T02:29:28.408616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:27.149588Z","time spent":"1.258903917s","remote":"127.0.0.1:50638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T02:29:28.408623Z","caller":"traceutil/trace.go:171","msg":"trace[2039418756] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:312; }","duration":"606.099217ms","start":"2026-05-05T02:29:27.80251Z","end":"2026-05-05T02:29:28.408609Z","steps":["trace[2039418756] 'agreement among raft nodes before linearized reading' (duration: 606.006624ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:29:28.40864Z","caller":"traceutil/trace.go:171","msg":"trace[1397191994] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:312; }","duration":"1.020979142s","start":"2026-05-05T02:29:27.387645Z","end":"2026-05-05T02:29:28.408624Z","steps":["trace[1397191994] 'agreement among raft nodes before linearized reading' (duration: 1.02088629s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:28.408667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:27.387586Z","time spent":"1.021076264s","remote":"127.0.0.1:50498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-05-05T02:29:28.408663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:27.802493Z","time spent":"606.159168ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T02:29:28.408744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.121587587s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-05-05T02:29:28.408778Z","caller":"traceutil/trace.go:171","msg":"trace[1122229403] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:312; }","duration":"1.121664718s","start":"2026-05-05T02:29:27.287105Z","end":"2026-05-05T02:29:28.40877Z","steps":["trace[1122229403] 'agreement among raft nodes before linearized reading' (duration: 1.121598617s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:28.408792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"921.919916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-05T02:29:28.408809Z","caller":"traceutil/trace.go:171","msg":"trace[1641580471] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:312; }","duration":"921.978947ms","start":"2026-05-05T02:29:27.486826Z","end":"2026-05-05T02:29:28.408805Z","steps":["trace[1641580471] 'agreement among raft nodes before linearized reading' (duration: 921.948577ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:28.408825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:27.486804Z","time spent":"922.017888ms","remote":"127.0.0.1:50498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"warn","ts":"2026-05-05T02:29:28.408845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.071478739s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-05-05T02:29:28.408804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:27.287078Z","time spent":"1.121719219s","remote":"127.0.0.1:50498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":226,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-05-05T02:29:28.408574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:27.149862Z","time spent":"1.258627461s","remote":"127.0.0.1:50638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T02:29:28.408921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.981807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-05-05T02:29:28.408873Z","caller":"traceutil/trace.go:171","msg":"trace[548663940] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:312; }","duration":"1.07150268s","start":"2026-05-05T02:29:27.33736Z","end":"2026-05-05T02:29:28.408863Z","steps":["trace[548663940] 'agreement among raft nodes before linearized reading' (duration: 1.071460669s)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:29:28.408959Z","caller":"traceutil/trace.go:171","msg":"trace[1258629118] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:312; }","duration":"971.058989ms","start":"2026-05-05T02:29:27.437888Z","end":"2026-05-05T02:29:28.408947Z","steps":["trace[1258629118] 'agreement among raft nodes before linearized reading' (duration: 970.986667ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:28.408988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:27.337319Z","time spent":"1.071658144s","remote":"127.0.0.1:50498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-05-05T02:29:28.40899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:27.437821Z","time spent":"971.161882ms","remote":"127.0.0.1:50498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":218,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-05-05T02:29:28.924576Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10348319723542239976,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T02:29:29.483587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.946184ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T02:29:29.483696Z","caller":"traceutil/trace.go:171","msg":"trace[553859527] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"1.065276945s","start":"2026-05-05T02:29:28.418397Z","end":"2026-05-05T02:29:29.483674Z","steps":["trace[553859527] 'process raft request' (duration: 885.172098ms)","trace[553859527] 'compare' (duration: 179.102812ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T02:29:29.483755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:28.418385Z","time spent":"1.065344927s","remote":"127.0.0.1:50376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":649,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T02:29:30.87024Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.566471313s","expected-duration":"1s"} {"level":"info","ts":"2026-05-05T02:29:30.871022Z","caller":"traceutil/trace.go:171","msg":"trace[28063690] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"2.446690808s","start":"2026-05-05T02:29:28.424309Z","end":"2026-05-05T02:29:30.871Z","steps":["trace[28063690] 'process raft request' (duration: 2.446635897s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:30.871126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:28.424298Z","time spent":"2.44677494s","remote":"127.0.0.1:50486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T02:29:30.871393Z","caller":"traceutil/trace.go:171","msg":"trace[1321389853] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"2.44720551s","start":"2026-05-05T02:29:28.424176Z","end":"2026-05-05T02:29:30.871382Z","steps":["trace[1321389853] 'process raft request' (duration: 2.446651117s)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:29:30.871374Z","caller":"traceutil/trace.go:171","msg":"trace[531456015] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"2.447678332s","start":"2026-05-05T02:29:28.423655Z","end":"2026-05-05T02:29:30.871333Z","steps":["trace[531456015] 'process raft request' (duration: 2.447127939s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:30.871477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:28.424167Z","time spent":"2.447268452s","remote":"127.0.0.1:50540","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-05-05T02:29:30.871494Z","caller":"traceutil/trace.go:171","msg":"trace[1896100338] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"2.452901511s","start":"2026-05-05T02:29:28.418535Z","end":"2026-05-05T02:29:30.871437Z","steps":["trace[1896100338] 'process raft request' (duration: 2.452130582s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:30.871551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:28.423629Z","time spent":"2.447842856s","remote":"127.0.0.1:50804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2150,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T02:29:30.871597Z","caller":"traceutil/trace.go:171","msg":"trace[777505914] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"2.447352363s","start":"2026-05-05T02:29:28.424235Z","end":"2026-05-05T02:29:30.871587Z","steps":["trace[777505914] 'process raft request' (duration: 2.446640406s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:30.871657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:28.42423Z","time spent":"2.447390665s","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T02:29:30.871711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:28.41853Z","time spent":"2.453068025s","remote":"127.0.0.1:50406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T02:29:30.871769Z","caller":"traceutil/trace.go:171","msg":"trace[1326980496] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"2.447490558s","start":"2026-05-05T02:29:28.424268Z","end":"2026-05-05T02:29:30.871759Z","steps":["trace[1326980496] 'process raft request' (duration: 2.446649527s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:30.871823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:28.424264Z","time spent":"2.447528888s","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T02:29:30.87194Z","caller":"traceutil/trace.go:171","msg":"trace[1557999806] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"2.447492489s","start":"2026-05-05T02:29:28.424428Z","end":"2026-05-05T02:29:30.871921Z","steps":["trace[1557999806] 'process raft request' (duration: 2.446908923s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:30.872015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:28.424423Z","time spent":"2.44755395s","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4355,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T02:29:30.872267Z","caller":"traceutil/trace.go:171","msg":"trace[1345926248] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"2.447079187s","start":"2026-05-05T02:29:28.424322Z","end":"2026-05-05T02:29:30.871402Z","steps":["trace[1345926248] 'process raft request' (duration: 2.446651687s)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:29:30.872326Z","caller":"traceutil/trace.go:171","msg":"trace[1215570899] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"2.447871798s","start":"2026-05-05T02:29:28.424433Z","end":"2026-05-05T02:29:30.872305Z","steps":["trace[1215570899] 'process raft request' (duration: 2.447090658s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:30.872989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:28.424427Z","time spent":"2.448515213s","remote":"127.0.0.1:50474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T02:29:30.872353Z","caller":"traceutil/trace.go:171","msg":"trace[1756398351] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"2.447577891s","start":"2026-05-05T02:29:28.424762Z","end":"2026-05-05T02:29:30.87234Z","steps":["trace[1756398351] 'process raft request' (duration: 2.447351645s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:30.873207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:28.424741Z","time spent":"2.448425521s","remote":"127.0.0.1:50638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2264,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T02:29:30.872395Z","caller":"traceutil/trace.go:171","msg":"trace[1995706164] linearizableReadLoop","detail":"{readStateIndex:334; appliedIndex:322; }","duration":"2.448529494s","start":"2026-05-05T02:29:28.423855Z","end":"2026-05-05T02:29:30.872385Z","steps":["trace[1995706164] 'read index received' (duration: 879.728454ms)","trace[1995706164] 'applied index is now lower than readState.Index' (duration: 1.56880014s)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:29:30.872414Z","caller":"traceutil/trace.go:171","msg":"trace[889679579] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"2.446710769s","start":"2026-05-05T02:29:28.425694Z","end":"2026-05-05T02:29:30.872405Z","steps":["trace[889679579] 'process raft request' (duration: 2.446580746s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:30.873539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:28.425686Z","time spent":"2.447811516s","remote":"127.0.0.1:50530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1251,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T02:29:30.87244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.448578515s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T02:29:30.873685Z","caller":"traceutil/trace.go:171","msg":"trace[2068930395] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:324; }","duration":"2.449821075s","start":"2026-05-05T02:29:28.42385Z","end":"2026-05-05T02:29:30.873671Z","steps":["trace[2068930395] 'agreement among raft nodes before linearized reading' (duration: 2.448565665s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:30.873848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:28.424317Z","time spent":"2.448284947s","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T02:29:30.895205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.470339333s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:1 size:2205"} {"level":"info","ts":"2026-05-05T02:29:30.895324Z","caller":"traceutil/trace.go:171","msg":"trace[1246386600] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:1; response_revision:325; }","duration":"2.470487946s","start":"2026-05-05T02:29:28.424825Z","end":"2026-05-05T02:29:30.895313Z","steps":["trace[1246386600] 'agreement among raft nodes before linearized reading' (duration: 2.470320032s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:30.895362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:28.424818Z","time spent":"2.470537737s","remote":"127.0.0.1:50638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":2228,"request content":"key:\"/registry/clusterroles/edit\" "} {"level":"info","ts":"2026-05-05T02:29:30.895204Z","caller":"traceutil/trace.go:171","msg":"trace[1828924824] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"784.736858ms","start":"2026-05-05T02:29:30.110435Z","end":"2026-05-05T02:29:30.895172Z","steps":["trace[1828924824] 'process raft request' (duration: 784.587394ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:30.895644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:30.110408Z","time spent":"785.038665ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T02:29:31.209965Z","caller":"traceutil/trace.go:171","msg":"trace[1380128276] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"329.433645ms","start":"2026-05-05T02:29:30.880506Z","end":"2026-05-05T02:29:31.20994Z","steps":["trace[1380128276] 'process raft request' (duration: 329.334712ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:29:31.210031Z","caller":"traceutil/trace.go:171","msg":"trace[1962793588] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"329.715191ms","start":"2026-05-05T02:29:30.88028Z","end":"2026-05-05T02:29:31.209995Z","steps":["trace[1962793588] 'process raft request' (duration: 329.519016ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:29:31.210066Z","caller":"traceutil/trace.go:171","msg":"trace[750361904] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"330.065321ms","start":"2026-05-05T02:29:30.879969Z","end":"2026-05-05T02:29:31.210034Z","steps":["trace[750361904] 'process raft request' (duration: 323.468289ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:31.210093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:30.880498Z","time spent":"329.533927ms","remote":"127.0.0.1:50376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T02:29:31.210111Z","caller":"traceutil/trace.go:171","msg":"trace[857457535] linearizableReadLoop","detail":"{readStateIndex:342; appliedIndex:335; }","duration":"314.960237ms","start":"2026-05-05T02:29:30.895131Z","end":"2026-05-05T02:29:31.210091Z","steps":["trace[857457535] 'read index received' (duration: 308.382175ms)","trace[857457535] 'applied index is now lower than readState.Index' (duration: 6.576712ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:29:31.210162Z","caller":"traceutil/trace.go:171","msg":"trace[1808322050] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"326.714918ms","start":"2026-05-05T02:29:30.883425Z","end":"2026-05-05T02:29:31.21014Z","steps":["trace[1808322050] 'process raft request' (duration: 326.522093ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:31.210192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:30.879901Z","time spent":"330.231134ms","remote":"127.0.0.1:50376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T02:29:31.21022Z","caller":"traceutil/trace.go:171","msg":"trace[1896487050] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"322.059893ms","start":"2026-05-05T02:29:30.888148Z","end":"2026-05-05T02:29:31.210207Z","steps":["trace[1896487050] 'process raft request' (duration: 321.887529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:31.210224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:30.880264Z","time spent":"329.878606ms","remote":"127.0.0.1:50406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1739,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T02:29:31.210276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:30.883389Z","time spent":"326.82246ms","remote":"127.0.0.1:50530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2263,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T02:29:31.210317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:30.888129Z","time spent":"322.133825ms","remote":"127.0.0.1:50486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T02:29:31.210495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.026884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5336"} {"level":"info","ts":"2026-05-05T02:29:31.210543Z","caller":"traceutil/trace.go:171","msg":"trace[1166696578] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"328.241605ms","start":"2026-05-05T02:29:30.882285Z","end":"2026-05-05T02:29:31.210527Z","steps":["trace[1166696578] 'process raft request' (duration: 327.588319ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:29:31.210558Z","caller":"traceutil/trace.go:171","msg":"trace[6746377] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:332; }","duration":"331.193218ms","start":"2026-05-05T02:29:30.879349Z","end":"2026-05-05T02:29:31.210542Z","steps":["trace[6746377] 'agreement among raft nodes before linearized reading' (duration: 331.077066ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:31.210604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:30.879327Z","time spent":"331.26494ms","remote":"127.0.0.1:50486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5359,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-05-05T02:29:31.210619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:30.882274Z","time spent":"328.309357ms","remote":"127.0.0.1:50486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T02:29:31.210764Z","caller":"traceutil/trace.go:171","msg":"trace[1127805391] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"328.382939ms","start":"2026-05-05T02:29:30.882302Z","end":"2026-05-05T02:29:31.210685Z","steps":["trace[1127805391] 'process raft request' (duration: 327.61233ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:31.210816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:30.882283Z","time spent":"328.512682ms","remote":"127.0.0.1:50486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3378,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T02:29:31.211536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.777616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.243\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-05T02:29:31.211548Z","caller":"traceutil/trace.go:171","msg":"trace[236037901] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"309.270327ms","start":"2026-05-05T02:29:30.902256Z","end":"2026-05-05T02:29:31.211527Z","steps":["trace[236037901] 'process raft request' (duration: 309.151014ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:29:31.211577Z","caller":"traceutil/trace.go:171","msg":"trace[1174320465] range","detail":"{range_begin:/registry/masterleases/199.19.213.243; range_end:; response_count:1; response_revision:333; }","duration":"140.872839ms","start":"2026-05-05T02:29:31.070694Z","end":"2026-05-05T02:29:31.211567Z","steps":["trace[1174320465] 'agreement among raft nodes before linearized reading' (duration: 140.781657ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:31.21165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:30.902236Z","time spent":"309.36398ms","remote":"127.0.0.1:50638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T02:29:49.288386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.703661ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T02:29:49.288655Z","caller":"traceutil/trace.go:171","msg":"trace[1175835058] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"197.086338ms","start":"2026-05-05T02:29:49.091503Z","end":"2026-05-05T02:29:49.288589Z","steps":["trace[1175835058] 'process raft request' (duration: 29.022368ms)","trace[1175835058] 'compare' (duration: 167.568388ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:29:49.353838Z","caller":"traceutil/trace.go:171","msg":"trace[1342965795] linearizableReadLoop","detail":"{readStateIndex:445; appliedIndex:444; }","duration":"130.632427ms","start":"2026-05-05T02:29:49.223185Z","end":"2026-05-05T02:29:49.353817Z","steps":["trace[1342965795] 'read index received' (duration: 130.383151ms)","trace[1342965795] 'applied index is now lower than readState.Index' (duration: 248.326µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:29:49.35391Z","caller":"traceutil/trace.go:171","msg":"trace[89296473] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"247.218225ms","start":"2026-05-05T02:29:49.106655Z","end":"2026-05-05T02:29:49.353873Z","steps":["trace[89296473] 'process raft request' (duration: 247.01898ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:49.354009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.830342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T02:29:49.354056Z","caller":"traceutil/trace.go:171","msg":"trace[374260213] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:0; response_revision:430; }","duration":"130.940125ms","start":"2026-05-05T02:29:49.223105Z","end":"2026-05-05T02:29:49.354046Z","steps":["trace[374260213] 'agreement among raft nodes before linearized reading' (duration: 130.851812ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:52.825685Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10348319723542240365,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T02:29:53.078835Z","caller":"traceutil/trace.go:171","msg":"trace[292868876] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"780.302769ms","start":"2026-05-05T02:29:52.298499Z","end":"2026-05-05T02:29:53.078802Z","steps":["trace[292868876] 'process raft request' (duration: 769.062511ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:53.079042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:52.298477Z","time spent":"780.446623ms","remote":"127.0.0.1:50346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T02:29:53.080945Z","caller":"traceutil/trace.go:171","msg":"trace[1983859508] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:581; }","duration":"755.281731ms","start":"2026-05-05T02:29:52.325651Z","end":"2026-05-05T02:29:53.080933Z","steps":["trace[1983859508] 'read index received' (duration: 741.984673ms)","trace[1983859508] 'applied index is now lower than readState.Index' (duration: 13.296528ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:29:53.080994Z","caller":"traceutil/trace.go:171","msg":"trace[1691269458] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"774.454314ms","start":"2026-05-05T02:29:52.306535Z","end":"2026-05-05T02:29:53.080989Z","steps":["trace[1691269458] 'process raft request' (duration: 774.094035ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:53.081045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:52.306521Z","time spent":"774.484584ms","remote":"127.0.0.1:50346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T02:29:53.081145Z","caller":"traceutil/trace.go:171","msg":"trace[418007486] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"660.812677ms","start":"2026-05-05T02:29:52.420328Z","end":"2026-05-05T02:29:53.081141Z","steps":["trace[418007486] 'process raft request' (duration: 660.5389ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:53.081172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:52.420308Z","time spent":"660.852719ms","remote":"127.0.0.1:50346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T02:29:53.081227Z","caller":"traceutil/trace.go:171","msg":"trace[1055600024] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"581.874719ms","start":"2026-05-05T02:29:52.499348Z","end":"2026-05-05T02:29:53.081223Z","steps":["trace[1055600024] 'process raft request' (duration: 581.56645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:53.08125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:52.499231Z","time spent":"582.009022ms","remote":"127.0.0.1:50346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T02:29:53.081368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"755.703812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T02:29:53.081451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.410693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T02:29:53.081444Z","caller":"traceutil/trace.go:171","msg":"trace[1035145128] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:566; }","duration":"755.814495ms","start":"2026-05-05T02:29:52.325611Z","end":"2026-05-05T02:29:53.081425Z","steps":["trace[1035145128] 'agreement among raft nodes before linearized reading' (duration: 755.670631ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:29:53.081483Z","caller":"traceutil/trace.go:171","msg":"trace[1992339241] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:566; }","duration":"384.484425ms","start":"2026-05-05T02:29:52.696991Z","end":"2026-05-05T02:29:53.081476Z","steps":["trace[1992339241] 'agreement among raft nodes before linearized reading' (duration: 384.442394ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:53.081499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:52.696967Z","time spent":"384.529425ms","remote":"127.0.0.1:50346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-05-05T02:29:53.081497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:52.325594Z","time spent":"755.895186ms","remote":"127.0.0.1:50320","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-05-05T02:29:53.081554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.275381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T02:29:53.081584Z","caller":"traceutil/trace.go:171","msg":"trace[733828237] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:566; }","duration":"184.329232ms","start":"2026-05-05T02:29:52.897244Z","end":"2026-05-05T02:29:53.081574Z","steps":["trace[733828237] 'agreement among raft nodes before linearized reading' (duration: 184.285551ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:29:55.834362Z","caller":"traceutil/trace.go:171","msg":"trace[898765495] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:624; }","duration":"137.873752ms","start":"2026-05-05T02:29:55.696459Z","end":"2026-05-05T02:29:55.834332Z","steps":["trace[898765495] 'read index received' (duration: 43.667584ms)","trace[898765495] 'applied index is now lower than readState.Index' (duration: 94.204998ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:29:55.834396Z","caller":"traceutil/trace.go:171","msg":"trace[1663564893] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"241.06932ms","start":"2026-05-05T02:29:55.593278Z","end":"2026-05-05T02:29:55.834347Z","steps":["trace[1663564893] 'process raft request' (duration: 146.847441ms)","trace[1663564893] 'compare' (duration: 94.055004ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T02:29:55.834833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.359104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-05-05T02:29:55.834895Z","caller":"traceutil/trace.go:171","msg":"trace[573449322] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:606; }","duration":"138.473037ms","start":"2026-05-05T02:29:55.696403Z","end":"2026-05-05T02:29:55.834876Z","steps":["trace[573449322] 'agreement among raft nodes before linearized reading' (duration: 138.086407ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:55.83504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.147457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-05T02:29:55.835111Z","caller":"traceutil/trace.go:171","msg":"trace[741978515] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:606; }","duration":"107.25375ms","start":"2026-05-05T02:29:55.727836Z","end":"2026-05-05T02:29:55.835089Z","steps":["trace[741978515] 'agreement among raft nodes before linearized reading' (duration: 107.033414ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:29:56.387474Z","caller":"traceutil/trace.go:171","msg":"trace[1105804245] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"142.92624ms","start":"2026-05-05T02:29:56.244469Z","end":"2026-05-05T02:29:56.387396Z","steps":["trace[1105804245] 'process raft request' (duration: 141.279378ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:56.532903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.66789ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-05-05T02:29:56.533012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.484234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"info","ts":"2026-05-05T02:29:56.533108Z","caller":"traceutil/trace.go:171","msg":"trace[246922145] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:627; }","duration":"145.68086ms","start":"2026-05-05T02:29:56.3874Z","end":"2026-05-05T02:29:56.533081Z","steps":["trace[246922145] 'read index received' (duration: 56.061µs)","trace[246922145] 'applied index is now lower than readState.Index' (duration: 145.624079ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:29:56.533142Z","caller":"traceutil/trace.go:171","msg":"trace[374552988] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:608; }","duration":"236.648898ms","start":"2026-05-05T02:29:56.29646Z","end":"2026-05-05T02:29:56.533109Z","steps":["trace[374552988] 'agreement among raft nodes before linearized reading' (duration: 90.973058ms)","trace[374552988] 'range keys from in-memory index tree' (duration: 145.317001ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T02:29:56.533178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.453668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T02:29:56.533306Z","caller":"traceutil/trace.go:171","msg":"trace[465253980] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:608; }","duration":"199.599651ms","start":"2026-05-05T02:29:56.333698Z","end":"2026-05-05T02:29:56.533297Z","steps":["trace[465253980] 'agreement among raft nodes before linearized reading' (duration: 199.439087ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:29:59.918969Z","caller":"traceutil/trace.go:171","msg":"trace[1068341290] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"487.108905ms","start":"2026-05-05T02:29:59.431836Z","end":"2026-05-05T02:29:59.918944Z","steps":["trace[1068341290] 'read index received' (duration: 486.650814ms)","trace[1068341290] 'applied index is now lower than readState.Index' (duration: 454.311µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:29:59.91901Z","caller":"traceutil/trace.go:171","msg":"trace[1735067283] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"554.860637ms","start":"2026-05-05T02:29:59.364126Z","end":"2026-05-05T02:29:59.918987Z","steps":["trace[1735067283] 'process raft request' (duration: 554.654221ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:59.919141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.29708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-05T02:29:59.919163Z","caller":"traceutil/trace.go:171","msg":"trace[1234977291] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:636; }","duration":"487.365402ms","start":"2026-05-05T02:29:59.431791Z","end":"2026-05-05T02:29:59.919157Z","steps":["trace[1234977291] 'agreement among raft nodes before linearized reading' (duration: 487.261519ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:59.919152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:59.364106Z","time spent":"554.959149ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T02:29:59.919181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:29:59.431769Z","time spent":"487.409063ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-05T02:30:00.088871Z","caller":"traceutil/trace.go:171","msg":"trace[464268688] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"147.65335ms","start":"2026-05-05T02:29:59.941186Z","end":"2026-05-05T02:30:00.08884Z","steps":["trace[464268688] 'process raft request' (duration: 143.895735ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:30:00.107205Z","caller":"traceutil/trace.go:171","msg":"trace[104245512] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:656; }","duration":"117.771265ms","start":"2026-05-05T02:29:59.989405Z","end":"2026-05-05T02:30:00.107176Z","steps":["trace[104245512] 'read index received' (duration: 95.756848ms)","trace[104245512] 'applied index is now lower than readState.Index' (duration: 22.013137ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:30:00.107296Z","caller":"traceutil/trace.go:171","msg":"trace[1899470154] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"162.254948ms","start":"2026-05-05T02:29:59.945021Z","end":"2026-05-05T02:30:00.107276Z","steps":["trace[1899470154] 'process raft request' (duration: 161.939249ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:30:00.107562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.135554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qjk68\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-05T02:30:00.107615Z","caller":"traceutil/trace.go:171","msg":"trace[17361501] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qjk68; range_end:; response_count:1; response_revision:638; }","duration":"118.232036ms","start":"2026-05-05T02:29:59.989372Z","end":"2026-05-05T02:30:00.107604Z","steps":["trace[17361501] 'agreement among raft nodes before linearized reading' (duration: 118.055802ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:30:00.501574Z","caller":"traceutil/trace.go:171","msg":"trace[376487228] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:658; }","duration":"174.855237ms","start":"2026-05-05T02:30:00.326676Z","end":"2026-05-05T02:30:00.501531Z","steps":["trace[376487228] 'read index received' (duration: 174.676872ms)","trace[376487228] 'applied index is now lower than readState.Index' (duration: 175.345µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T02:30:00.501681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.99115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T02:30:00.501666Z","caller":"traceutil/trace.go:171","msg":"trace[2063547877] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"378.648734ms","start":"2026-05-05T02:30:00.122987Z","end":"2026-05-05T02:30:00.501636Z","steps":["trace[2063547877] 'process raft request' (duration: 378.398817ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:30:00.501716Z","caller":"traceutil/trace.go:171","msg":"trace[1547313781] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"175.061621ms","start":"2026-05-05T02:30:00.326645Z","end":"2026-05-05T02:30:00.501707Z","steps":["trace[1547313781] 'agreement among raft nodes before linearized reading' (duration: 174.99057ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:30:00.501829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:30:00.122958Z","time spent":"378.794777ms","remote":"127.0.0.1:50486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T02:30:01.621698Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10348319723542240639,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T02:30:02.463086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.139394ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T02:30:02.463422Z","caller":"traceutil/trace.go:171","msg":"trace[2057809814] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"757.536645ms","start":"2026-05-05T02:30:01.705847Z","end":"2026-05-05T02:30:02.463383Z","steps":["trace[2057809814] 'process raft request' (duration: 757.34055ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:30:02.463515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:30:01.705797Z","time spent":"757.676838ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T02:30:02.463883Z","caller":"traceutil/trace.go:171","msg":"trace[1972880887] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:660; }","duration":"1.342705334s","start":"2026-05-05T02:30:01.121142Z","end":"2026-05-05T02:30:02.463848Z","steps":["trace[1972880887] 'read index received' (duration: 622.880152ms)","trace[1972880887] 'applied index is now lower than readState.Index' (duration: 719.822921ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:30:02.463914Z","caller":"traceutil/trace.go:171","msg":"trace[324208033] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"1.382356436s","start":"2026-05-05T02:30:01.080862Z","end":"2026-05-05T02:30:02.463218Z","steps":["trace[324208033] 'process raft request' (duration: 662.981435ms)","trace[324208033] 'compare' (duration: 718.932159ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T02:30:02.464054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.342895208s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-05T02:30:02.464089Z","caller":"traceutil/trace.go:171","msg":"trace[825710846] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:641; }","duration":"1.342982521s","start":"2026-05-05T02:30:01.121096Z","end":"2026-05-05T02:30:02.464078Z","steps":["trace[825710846] 'agreement among raft nodes before linearized reading' (duration: 1.342899769s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:30:02.464117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:30:01.121069Z","time spent":"1.343041123s","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T02:30:02.464122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:30:01.080835Z","time spent":"1.383162516s","remote":"127.0.0.1:50334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T02:30:02.464166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.296936688s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-05T02:30:02.464236Z","caller":"traceutil/trace.go:171","msg":"trace[621725927] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:641; }","duration":"1.297081872s","start":"2026-05-05T02:30:01.167135Z","end":"2026-05-05T02:30:02.464217Z","steps":["trace[621725927] 'agreement among raft nodes before linearized reading' (duration: 1.296947598s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:30:02.46425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.858387ms","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-05-05T02:30:02.464263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.135756566s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T02:30:02.464275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:30:01.167104Z","time spent":"1.297162814s","remote":"127.0.0.1:50540","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-05-05T02:30:02.464276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.326583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-05T02:30:02.464308Z","caller":"traceutil/trace.go:171","msg":"trace[878583586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:641; }","duration":"370.95567ms","start":"2026-05-05T02:30:02.093336Z","end":"2026-05-05T02:30:02.464291Z","steps":["trace[878583586] 'agreement among raft nodes before linearized reading' (duration: 370.785065ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:30:02.464327Z","caller":"traceutil/trace.go:171","msg":"trace[1689204427] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"1.1358705s","start":"2026-05-05T02:30:01.328438Z","end":"2026-05-05T02:30:02.464309Z","steps":["trace[1689204427] 'agreement among raft nodes before linearized reading' (duration: 1.135774548s)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:30:02.464339Z","caller":"traceutil/trace.go:171","msg":"trace[1100223039] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:641; }","duration":"541.442336ms","start":"2026-05-05T02:30:01.922879Z","end":"2026-05-05T02:30:02.464322Z","steps":["trace[1100223039] 'agreement among raft nodes before linearized reading' (duration: 541.251892ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:30:02.464355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:30:02.093304Z","time spent":"371.039152ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T02:30:02.46437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:30:01.328402Z","time spent":"1.135957073s","remote":"127.0.0.1:50320","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-05-05T02:30:02.464379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:30:01.922841Z","time spent":"541.527118ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T02:30:02.464511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.572485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-05T02:30:02.46462Z","caller":"traceutil/trace.go:171","msg":"trace[1297007219] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:641; }","duration":"352.807791ms","start":"2026-05-05T02:30:02.111799Z","end":"2026-05-05T02:30:02.464606Z","steps":["trace[1297007219] 'agreement among raft nodes before linearized reading' (duration: 352.402081ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:30:02.464693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:30:02.111774Z","time spent":"352.880854ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-05T02:30:02.970191Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10348319723542240647,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T02:30:03.381074Z","caller":"traceutil/trace.go:171","msg":"trace[1313841980] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"912.949879ms","start":"2026-05-05T02:30:02.468098Z","end":"2026-05-05T02:30:03.381048Z","steps":["trace[1313841980] 'process raft request' (duration: 909.462652ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:30:03.381222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:30:02.468081Z","time spent":"913.056672ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T02:30:03.385159Z","caller":"traceutil/trace.go:171","msg":"trace[1704409847] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"915.985965ms","start":"2026-05-05T02:30:02.469147Z","end":"2026-05-05T02:30:03.385133Z","steps":["trace[1704409847] 'process raft request' (duration: 915.75944ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:30:03.385297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:30:02.469135Z","time spent":"916.085018ms","remote":"127.0.0.1:50540","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-05-05T02:30:03.385296Z","caller":"traceutil/trace.go:171","msg":"trace[1239664313] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:662; }","duration":"915.74345ms","start":"2026-05-05T02:30:02.469523Z","end":"2026-05-05T02:30:03.385267Z","steps":["trace[1239664313] 'read index received' (duration: 908.053755ms)","trace[1239664313] 'applied index is now lower than readState.Index' (duration: 7.688655ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:30:03.385335Z","caller":"traceutil/trace.go:171","msg":"trace[983465555] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"915.906893ms","start":"2026-05-05T02:30:02.469389Z","end":"2026-05-05T02:30:03.385296Z","steps":["trace[983465555] 'process raft request' (duration: 915.653697ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:30:03.385352Z","caller":"traceutil/trace.go:171","msg":"trace[13701639] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"915.925234ms","start":"2026-05-05T02:30:02.469393Z","end":"2026-05-05T02:30:03.385319Z","steps":["trace[13701639] 'process raft request' (duration: 915.723539ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:30:03.385391Z","caller":"traceutil/trace.go:171","msg":"trace[1199759333] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"915.699779ms","start":"2026-05-05T02:30:02.469661Z","end":"2026-05-05T02:30:03.385361Z","steps":["trace[1199759333] 'process raft request' (duration: 915.511154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:30:03.385518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:30:02.469381Z","time spent":"916.045908ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T02:30:03.38553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:30:02.469371Z","time spent":"916.070758ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T02:30:03.385546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:30:02.469652Z","time spent":"915.800581ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T02:30:03.385586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"916.047407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-05T02:30:03.385642Z","caller":"traceutil/trace.go:171","msg":"trace[1726155541] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:646; }","duration":"916.128539ms","start":"2026-05-05T02:30:02.469495Z","end":"2026-05-05T02:30:03.385624Z","steps":["trace[1726155541] 'agreement among raft nodes before linearized reading' (duration: 915.913084ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:30:03.385661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"913.147444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T02:30:03.385681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:30:02.469485Z","time spent":"916.185381ms","remote":"127.0.0.1:50334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-05-05T02:30:03.38571Z","caller":"traceutil/trace.go:171","msg":"trace[335000989] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:646; }","duration":"913.203775ms","start":"2026-05-05T02:30:02.472492Z","end":"2026-05-05T02:30:03.385696Z","steps":["trace[335000989] 'agreement among raft nodes before linearized reading' (duration: 913.122293ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:30:03.385837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:30:02.472488Z","time spent":"913.253416ms","remote":"127.0.0.1:50320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T02:30:05.122295Z","caller":"traceutil/trace.go:171","msg":"trace[775587859] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"126.127096ms","start":"2026-05-05T02:30:04.996149Z","end":"2026-05-05T02:30:05.122277Z","steps":["trace[775587859] 'process raft request' (duration: 126.023363ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:30:05.173262Z","caller":"traceutil/trace.go:171","msg":"trace[1468389996] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"123.778216ms","start":"2026-05-05T02:30:05.04946Z","end":"2026-05-05T02:30:05.173238Z","steps":["trace[1468389996] 'process raft request' (duration: 123.613072ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:30:11.443993Z","caller":"traceutil/trace.go:171","msg":"trace[604859790] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"170.456064ms","start":"2026-05-05T02:30:11.273498Z","end":"2026-05-05T02:30:11.443954Z","steps":["trace[604859790] 'process raft request' (duration: 105.413852ms)","trace[604859790] 'compare' (duration: 64.825347ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T02:30:25.750396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.907654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T02:30:25.750504Z","caller":"traceutil/trace.go:171","msg":"trace[1731430397] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:774; }","duration":"777.084118ms","start":"2026-05-05T02:30:24.973396Z","end":"2026-05-05T02:30:25.75048Z","steps":["trace[1731430397] 'count revisions from in-memory index tree' (duration: 776.810181ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:30:25.750558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:30:24.973375Z","time spent":"777.16845ms","remote":"127.0.0.1:50798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-05-05T02:30:25.750624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.750564ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T02:30:25.750746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.113792843s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T02:30:25.750799Z","caller":"traceutil/trace.go:171","msg":"trace[414826193] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:774; }","duration":"1.113886466s","start":"2026-05-05T02:30:24.6369Z","end":"2026-05-05T02:30:25.750786Z","steps":["trace[414826193] 'count revisions from in-memory index tree' (duration: 1.113711891s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:30:25.750832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:30:24.636878Z","time spent":"1.113945607s","remote":"127.0.0.1:50432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-05-05T02:30:25.750754Z","caller":"traceutil/trace.go:171","msg":"trace[1802380053] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:774; }","duration":"368.894267ms","start":"2026-05-05T02:30:25.38184Z","end":"2026-05-05T02:30:25.750735Z","steps":["trace[1802380053] 'range keys from in-memory index tree' (duration: 368.732444ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:30:25.827391Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10348319723542240909,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T02:30:25.957463Z","caller":"traceutil/trace.go:171","msg":"trace[1957856726] linearizableReadLoop","detail":"{readStateIndex:802; appliedIndex:801; }","duration":"630.133277ms","start":"2026-05-05T02:30:25.327309Z","end":"2026-05-05T02:30:25.957442Z","steps":["trace[1957856726] 'read index received' (duration: 629.903641ms)","trace[1957856726] 'applied index is now lower than readState.Index' (duration: 228.656µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T02:30:25.957668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.007376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-05T02:30:25.957733Z","caller":"traceutil/trace.go:171","msg":"trace[1430292657] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"847.616749ms","start":"2026-05-05T02:30:25.110076Z","end":"2026-05-05T02:30:25.957692Z","steps":["trace[1430292657] 'process raft request' (duration: 847.23387ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:30:25.957777Z","caller":"traceutil/trace.go:171","msg":"trace[104798755] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:775; }","duration":"391.18852ms","start":"2026-05-05T02:30:25.56658Z","end":"2026-05-05T02:30:25.957768Z","steps":["trace[104798755] 'agreement among raft nodes before linearized reading' (duration: 390.985295ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:30:25.957831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:30:25.566542Z","time spent":"391.282182ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T02:30:25.957859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.880434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-05T02:30:25.957896Z","caller":"traceutil/trace.go:171","msg":"trace[2013427861] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:775; }","duration":"377.951227ms","start":"2026-05-05T02:30:25.579932Z","end":"2026-05-05T02:30:25.957883Z","steps":["trace[2013427861] 'agreement among raft nodes before linearized reading' (duration: 377.888345ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:30:25.95793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:30:25.579915Z","time spent":"378.010448ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-05T02:30:25.957772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.017069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-05T02:30:25.957985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:30:25.110048Z","time spent":"847.785394ms","remote":"127.0.0.1:50520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T02:30:25.958002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.184348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T02:30:25.958024Z","caller":"traceutil/trace.go:171","msg":"trace[1637005255] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:775; }","duration":"382.324567ms","start":"2026-05-05T02:30:25.575679Z","end":"2026-05-05T02:30:25.958003Z","steps":["trace[1637005255] 'agreement among raft nodes before linearized reading' (duration: 381.949098ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:30:25.958056Z","caller":"traceutil/trace.go:171","msg":"trace[1267677987] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:775; }","duration":"385.269951ms","start":"2026-05-05T02:30:25.572773Z","end":"2026-05-05T02:30:25.958043Z","steps":["trace[1267677987] 'agreement among raft nodes before linearized reading' (duration: 385.105927ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:30:25.958069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:30:25.575635Z","time spent":"382.422649ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T02:30:25.958089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:30:25.572761Z","time spent":"385.321132ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T02:30:25.95776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.427364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T02:30:25.958407Z","caller":"traceutil/trace.go:171","msg":"trace[403571844] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:775; }","duration":"631.098422ms","start":"2026-05-05T02:30:25.327296Z","end":"2026-05-05T02:30:25.958395Z","steps":["trace[403571844] 'agreement among raft nodes before linearized reading' (duration: 630.321741ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:30:25.958449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:30:25.327204Z","time spent":"631.233195ms","remote":"127.0.0.1:50320","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-05-05T02:30:25.958542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.108568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-05T02:30:25.958666Z","caller":"traceutil/trace.go:171","msg":"trace[2137899963] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:775; }","duration":"385.342094ms","start":"2026-05-05T02:30:25.573266Z","end":"2026-05-05T02:30:25.958608Z","steps":["trace[2137899963] 'agreement among raft nodes before linearized reading' (duration: 384.570334ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:30:25.958769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:30:25.57325Z","time spent":"385.470367ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-05T02:30:39.788225Z","caller":"traceutil/trace.go:171","msg":"trace[1147752091] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"156.653777ms","start":"2026-05-05T02:30:39.631548Z","end":"2026-05-05T02:30:39.788201Z","steps":["trace[1147752091] 'process raft request' (duration: 156.560615ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:30:39.78833Z","caller":"traceutil/trace.go:171","msg":"trace[248418235] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"180.433897ms","start":"2026-05-05T02:30:39.607854Z","end":"2026-05-05T02:30:39.788288Z","steps":["trace[248418235] 'process raft request' (duration: 179.828292ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:30:39.801644Z","caller":"traceutil/trace.go:171","msg":"trace[1352578367] linearizableReadLoop","detail":"{readStateIndex:940; appliedIndex:937; }","duration":"140.876058ms","start":"2026-05-05T02:30:39.660753Z","end":"2026-05-05T02:30:39.801629Z","steps":["trace[1352578367] 'read index received' (duration: 126.944317ms)","trace[1352578367] 'applied index is now lower than readState.Index' (duration: 13.930841ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:30:39.801714Z","caller":"traceutil/trace.go:171","msg":"trace[733119876] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"149.511006ms","start":"2026-05-05T02:30:39.65218Z","end":"2026-05-05T02:30:39.801691Z","steps":["trace[733119876] 'process raft request' (duration: 149.353532ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:30:39.801852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.079573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T02:30:39.801895Z","caller":"traceutil/trace.go:171","msg":"trace[1541398892] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:910; }","duration":"141.166205ms","start":"2026-05-05T02:30:39.66072Z","end":"2026-05-05T02:30:39.801887Z","steps":["trace[1541398892] 'agreement among raft nodes before linearized reading' (duration: 141.033001ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:30:40.304669Z","caller":"traceutil/trace.go:171","msg":"trace[374977724] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"132.424016ms","start":"2026-05-05T02:30:40.172222Z","end":"2026-05-05T02:30:40.304646Z","steps":["trace[374977724] 'process raft request' (duration: 132.20169ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:30:40.344257Z","caller":"traceutil/trace.go:171","msg":"trace[1213903266] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"136.53ms","start":"2026-05-05T02:30:40.207694Z","end":"2026-05-05T02:30:40.344224Z","steps":["trace[1213903266] 'process raft request' (duration: 135.509703ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:01.364271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.888143ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T02:31:01.364395Z","caller":"traceutil/trace.go:171","msg":"trace[523767023] linearizableReadLoop","detail":"{readStateIndex:1026; appliedIndex:1025; }","duration":"544.357008ms","start":"2026-05-05T02:31:00.820014Z","end":"2026-05-05T02:31:01.364371Z","steps":["trace[523767023] 'read index received' (duration: 425.26821ms)","trace[523767023] 'applied index is now lower than readState.Index' (duration: 119.086988ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T02:31:01.364664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.638617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T02:31:01.364701Z","caller":"traceutil/trace.go:171","msg":"trace[371095470] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:990; }","duration":"544.73904ms","start":"2026-05-05T02:31:00.819952Z","end":"2026-05-05T02:31:01.364692Z","steps":["trace[371095470] 'agreement among raft nodes before linearized reading' (duration: 544.653827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:01.364732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:00.819925Z","time spent":"544.798341ms","remote":"127.0.0.1:50492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"info","ts":"2026-05-05T02:31:01.364876Z","caller":"traceutil/trace.go:171","msg":"trace[1791249094] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"941.634604ms","start":"2026-05-05T02:31:00.423233Z","end":"2026-05-05T02:31:01.364867Z","steps":["trace[1791249094] 'process raft request' (duration: 822.07142ms)","trace[1791249094] 'compare' (duration: 118.619955ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T02:31:01.364915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:00.423211Z","time spent":"941.681375ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T02:31:01.36509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.913578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.243\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-05T02:31:01.365109Z","caller":"traceutil/trace.go:171","msg":"trace[168096579] range","detail":"{range_begin:/registry/masterleases/199.19.213.243; range_end:; response_count:1; response_revision:990; }","duration":"288.963229ms","start":"2026-05-05T02:31:01.07614Z","end":"2026-05-05T02:31:01.365103Z","steps":["trace[168096579] 'agreement among raft nodes before linearized reading' (duration: 288.892207ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:02.526905Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10348319723542241358,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T02:31:02.593663Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.063402949s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-05T02:31:02.593967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:01.365715Z","time spent":"1.228245262s","remote":"127.0.0.1:50334","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-05T02:31:02.593959Z","caller":"traceutil/trace.go:171","msg":"trace[754209126] linearizableReadLoop","detail":"{readStateIndex:1027; appliedIndex:1026; }","duration":"568.065309ms","start":"2026-05-05T02:31:02.025871Z","end":"2026-05-05T02:31:02.593936Z","steps":["trace[754209126] 'read index received' (duration: 567.918315ms)","trace[754209126] 'applied index is now lower than readState.Index' (duration: 145.594µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T02:31:02.594083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.197504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T02:31:02.594127Z","caller":"traceutil/trace.go:171","msg":"trace[979991245] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:990; }","duration":"568.291576ms","start":"2026-05-05T02:31:02.025822Z","end":"2026-05-05T02:31:02.594113Z","steps":["trace[979991245] 'agreement among raft nodes before linearized reading' (duration: 568.196824ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:02.594117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.649686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T02:31:02.594162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:02.025798Z","time spent":"568.353788ms","remote":"127.0.0.1:44342","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-05-05T02:31:02.594172Z","caller":"traceutil/trace.go:171","msg":"trace[1774314900] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:990; }","duration":"534.744769ms","start":"2026-05-05T02:31:02.059413Z","end":"2026-05-05T02:31:02.594157Z","steps":["trace[1774314900] 'agreement among raft nodes before linearized reading' (duration: 534.652476ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:02.594229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:02.059398Z","time spent":"534.802201ms","remote":"127.0.0.1:44390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-05T02:31:02.594257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.958289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-05T02:31:02.594295Z","caller":"traceutil/trace.go:171","msg":"trace[719957038] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:990; }","duration":"253.061512ms","start":"2026-05-05T02:31:02.341223Z","end":"2026-05-05T02:31:02.594284Z","steps":["trace[719957038] 'agreement among raft nodes before linearized reading' (duration: 252.952519ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:02.594414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.060198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-05-05T02:31:02.594395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.464929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-05T02:31:02.594455Z","caller":"traceutil/trace.go:171","msg":"trace[1781088577] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:990; }","duration":"350.134181ms","start":"2026-05-05T02:31:02.244306Z","end":"2026-05-05T02:31:02.59444Z","steps":["trace[1781088577] 'agreement among raft nodes before linearized reading' (duration: 349.989877ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:02.594482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:02.244288Z","time spent":"350.189902ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-05T02:31:02.59448Z","caller":"traceutil/trace.go:171","msg":"trace[1296662222] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:990; }","duration":"281.590653ms","start":"2026-05-05T02:31:02.312871Z","end":"2026-05-05T02:31:02.594461Z","steps":["trace[1296662222] 'agreement among raft nodes before linearized reading' (duration: 281.333526ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:02.594512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.864503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-05-05T02:31:02.594423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.941939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T02:31:02.594548Z","caller":"traceutil/trace.go:171","msg":"trace[49608786] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:990; }","duration":"338.928944ms","start":"2026-05-05T02:31:02.255609Z","end":"2026-05-05T02:31:02.594538Z","steps":["trace[49608786] 'agreement among raft nodes before linearized reading' (duration: 338.836991ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:31:02.594553Z","caller":"traceutil/trace.go:171","msg":"trace[310659350] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:990; }","duration":"425.098684ms","start":"2026-05-05T02:31:02.169442Z","end":"2026-05-05T02:31:02.594541Z","steps":["trace[310659350] 'agreement among raft nodes before linearized reading' (duration: 424.949899ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:02.594574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:02.255592Z","time spent":"338.974605ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T02:31:02.594587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:02.169424Z","time spent":"425.153215ms","remote":"127.0.0.1:44498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-05-05T02:31:02.59548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.99073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-05T02:31:02.595534Z","caller":"traceutil/trace.go:171","msg":"trace[1085401479] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:990; }","duration":"334.065852ms","start":"2026-05-05T02:31:02.261455Z","end":"2026-05-05T02:31:02.595521Z","steps":["trace[1085401479] 'agreement among raft nodes before linearized reading' (duration: 332.65059ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:02.595566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:02.261447Z","time spent":"334.111593ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-05T02:31:02.707639Z","caller":"traceutil/trace.go:171","msg":"trace[2034609138] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"106.176853ms","start":"2026-05-05T02:31:02.601428Z","end":"2026-05-05T02:31:02.707605Z","steps":["trace[2034609138] 'process raft request' (duration: 106.093571ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:31:02.707665Z","caller":"traceutil/trace.go:171","msg":"trace[1592333800] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"112.23205ms","start":"2026-05-05T02:31:02.595396Z","end":"2026-05-05T02:31:02.707628Z","steps":["trace[1592333800] 'process raft request' (duration: 108.850401ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:31:02.707884Z","caller":"traceutil/trace.go:171","msg":"trace[1478544678] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"106.321118ms","start":"2026-05-05T02:31:02.601542Z","end":"2026-05-05T02:31:02.707863Z","steps":["trace[1478544678] 'process raft request' (duration: 106.247695ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:31:02.708068Z","caller":"traceutil/trace.go:171","msg":"trace[1104720689] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"106.493413ms","start":"2026-05-05T02:31:02.601564Z","end":"2026-05-05T02:31:02.708057Z","steps":["trace[1104720689] 'process raft request' (duration: 106.261806ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:31:02.708099Z","caller":"traceutil/trace.go:171","msg":"trace[695542137] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"106.579315ms","start":"2026-05-05T02:31:02.601508Z","end":"2026-05-05T02:31:02.708087Z","steps":["trace[695542137] 'process raft request' (duration: 106.158023ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:31:02.708443Z","caller":"traceutil/trace.go:171","msg":"trace[1865453564] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"106.856363ms","start":"2026-05-05T02:31:02.601576Z","end":"2026-05-05T02:31:02.708433Z","steps":["trace[1865453564] 'process raft request' (duration: 106.439341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:24.087737Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10348319723542241552,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T02:31:24.103864Z","caller":"traceutil/trace.go:171","msg":"trace[1343413324] linearizableReadLoop","detail":"{readStateIndex:1104; appliedIndex:1103; }","duration":"516.516468ms","start":"2026-05-05T02:31:23.587324Z","end":"2026-05-05T02:31:24.103841Z","steps":["trace[1343413324] 'read index received' (duration: 516.29402ms)","trace[1343413324] 'applied index is now lower than readState.Index' (duration: 221.258µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:31:24.103924Z","caller":"traceutil/trace.go:171","msg":"trace[408026875] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"656.907698ms","start":"2026-05-05T02:31:23.446995Z","end":"2026-05-05T02:31:24.103903Z","steps":["trace[408026875] 'process raft request' (duration: 656.703052ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:24.104009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.668466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T02:31:24.104048Z","caller":"traceutil/trace.go:171","msg":"trace[1596835618] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1063; }","duration":"257.958745ms","start":"2026-05-05T02:31:23.846079Z","end":"2026-05-05T02:31:24.104038Z","steps":["trace[1596835618] 'agreement among raft nodes before linearized reading' (duration: 257.881152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:24.104039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.673273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-05T02:31:24.104085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.857382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T02:31:24.104121Z","caller":"traceutil/trace.go:171","msg":"trace[1042056747] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1063; }","duration":"516.830817ms","start":"2026-05-05T02:31:23.587272Z","end":"2026-05-05T02:31:24.104103Z","steps":["trace[1042056747] 'agreement among raft nodes before linearized reading' (duration: 516.655542ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:31:24.104144Z","caller":"traceutil/trace.go:171","msg":"trace[1487098565] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1063; }","duration":"154.970315ms","start":"2026-05-05T02:31:23.949157Z","end":"2026-05-05T02:31:24.104127Z","steps":["trace[1487098565] 'agreement among raft nodes before linearized reading' (duration: 154.851262ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:24.104058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:23.446976Z","time spent":"657.000361ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T02:31:24.104176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:23.587207Z","time spent":"516.957771ms","remote":"127.0.0.1:50782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":2,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-05-05T02:31:24.906283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.38977ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T02:31:24.906361Z","caller":"traceutil/trace.go:171","msg":"trace[1136777872] linearizableReadLoop","detail":"{readStateIndex:1105; appliedIndex:1104; }","duration":"578.813543ms","start":"2026-05-05T02:31:24.327534Z","end":"2026-05-05T02:31:24.906347Z","steps":["trace[1136777872] 'read index received' (duration: 461.220487ms)","trace[1136777872] 'applied index is now lower than readState.Index' (duration: 117.592206ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:31:24.906429Z","caller":"traceutil/trace.go:171","msg":"trace[354621670] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"661.174372ms","start":"2026-05-05T02:31:24.245216Z","end":"2026-05-05T02:31:24.906391Z","steps":["trace[354621670] 'process raft request' (duration: 543.610077ms)","trace[354621670] 'compare' (duration: 116.681059ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T02:31:24.906463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.916296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T02:31:24.906556Z","caller":"traceutil/trace.go:171","msg":"trace[1579744574] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1064; }","duration":"579.02834ms","start":"2026-05-05T02:31:24.327486Z","end":"2026-05-05T02:31:24.906515Z","steps":["trace[1579744574] 'agreement among raft nodes before linearized reading' (duration: 578.908306ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:24.906594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:24.327464Z","time spent":"579.122292ms","remote":"127.0.0.1:50320","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-05-05T02:31:24.9066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:24.245179Z","time spent":"661.335087ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T02:31:25.336757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.497247ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T02:31:25.337017Z","caller":"traceutil/trace.go:171","msg":"trace[1718523154] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"425.938589ms","start":"2026-05-05T02:31:24.911049Z","end":"2026-05-05T02:31:25.336987Z","steps":["trace[1718523154] 'process raft request' (duration: 243.129472ms)","trace[1718523154] 'compare' (duration: 182.404005ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T02:31:25.337183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:24.911031Z","time spent":"426.041532ms","remote":"127.0.0.1:50540","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-05-05T02:31:25.337212Z","caller":"traceutil/trace.go:171","msg":"trace[419058783] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"425.804585ms","start":"2026-05-05T02:31:24.911387Z","end":"2026-05-05T02:31:25.337191Z","steps":["trace[419058783] 'process raft request' (duration: 425.664771ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:31:25.337219Z","caller":"traceutil/trace.go:171","msg":"trace[1889742964] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"426.058643ms","start":"2026-05-05T02:31:24.911131Z","end":"2026-05-05T02:31:25.337189Z","steps":["trace[1889742964] 'process raft request' (duration: 425.785175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:25.337311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:24.911377Z","time spent":"425.889228ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T02:31:25.337344Z","caller":"traceutil/trace.go:171","msg":"trace[1652797347] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"425.836176ms","start":"2026-05-05T02:31:24.911486Z","end":"2026-05-05T02:31:25.337322Z","steps":["trace[1652797347] 'process raft request' (duration: 425.653181ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:25.337369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:24.91111Z","time spent":"426.185136ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T02:31:25.337469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:24.911467Z","time spent":"425.926758ms","remote":"127.0.0.1:50540","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-05-05T02:31:25.337823Z","caller":"traceutil/trace.go:171","msg":"trace[305146503] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"424.942ms","start":"2026-05-05T02:31:24.912868Z","end":"2026-05-05T02:31:25.33781Z","steps":["trace[305146503] 'process raft request' (duration: 424.301052ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:25.337915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:24.91286Z","time spent":"425.003592ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T02:31:26.516108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.77442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T02:31:26.516201Z","caller":"traceutil/trace.go:171","msg":"trace[1007979129] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1071; }","duration":"187.953145ms","start":"2026-05-05T02:31:26.328229Z","end":"2026-05-05T02:31:26.516182Z","steps":["trace[1007979129] 'range keys from in-memory index tree' (duration: 187.653917ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:28.080384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.506931972s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-05T02:31:28.080534Z","caller":"traceutil/trace.go:171","msg":"trace[429051521] linearizableReadLoop","detail":"{readStateIndex:1113; appliedIndex:1112; }","duration":"1.012437285s","start":"2026-05-05T02:31:27.068077Z","end":"2026-05-05T02:31:28.080514Z","steps":["trace[429051521] 'read index received' (duration: 42.391µs)","trace[429051521] 'applied index is now lower than readState.Index' (duration: 1.012392984s)"],"step_count":2} {"level":"warn","ts":"2026-05-05T02:31:28.080902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.573787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T02:31:28.080944Z","caller":"traceutil/trace.go:171","msg":"trace[1479788356] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1071; }","duration":"831.68386ms","start":"2026-05-05T02:31:27.24925Z","end":"2026-05-05T02:31:28.080933Z","steps":["trace[1479788356] 'agreement among raft nodes before linearized reading' (duration: 831.549656ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:28.081028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:27.249233Z","time spent":"831.745272ms","remote":"127.0.0.1:50396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":9,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-05-05T02:31:28.081063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.151135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-05T02:31:28.081125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.544251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T02:31:28.081127Z","caller":"traceutil/trace.go:171","msg":"trace[948017495] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1071; }","duration":"739.271087ms","start":"2026-05-05T02:31:27.34184Z","end":"2026-05-05T02:31:28.081111Z","steps":["trace[948017495] 'agreement among raft nodes before linearized reading' (duration: 739.0208ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:31:28.081163Z","caller":"traceutil/trace.go:171","msg":"trace[1777120508] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1071; }","duration":"455.609683ms","start":"2026-05-05T02:31:27.625538Z","end":"2026-05-05T02:31:28.081148Z","steps":["trace[1777120508] 'agreement among raft nodes before linearized reading' (duration: 455.566031ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:28.081172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:27.341832Z","time spent":"739.33169ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T02:31:28.081195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:27.625518Z","time spent":"455.668905ms","remote":"127.0.0.1:50804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-05-05T02:31:28.081332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.214626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T02:31:28.081362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.083556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T02:31:28.081392Z","caller":"traceutil/trace.go:171","msg":"trace[334207908] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1071; }","duration":"607.153598ms","start":"2026-05-05T02:31:27.47423Z","end":"2026-05-05T02:31:28.081383Z","steps":["trace[334207908] 'agreement among raft nodes before linearized reading' (duration: 607.109887ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:28.081427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:27.474207Z","time spent":"607.212339ms","remote":"127.0.0.1:50458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-05-05T02:31:28.081438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.913245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-05T02:31:28.081365Z","caller":"traceutil/trace.go:171","msg":"trace[1145032819] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1071; }","duration":"751.279988ms","start":"2026-05-05T02:31:27.330075Z","end":"2026-05-05T02:31:28.081355Z","steps":["trace[1145032819] 'agreement among raft nodes before linearized reading' (duration: 751.233426ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:28.081071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.815118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T02:31:28.081515Z","caller":"traceutil/trace.go:171","msg":"trace[1161339469] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1071; }","duration":"740.993507ms","start":"2026-05-05T02:31:27.340481Z","end":"2026-05-05T02:31:28.081474Z","steps":["trace[1161339469] 'agreement among raft nodes before linearized reading' (duration: 740.803011ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:28.081512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:27.330055Z","time spent":"751.434431ms","remote":"127.0.0.1:50320","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-05-05T02:31:28.081551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:27.340461Z","time spent":"741.0816ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-05T02:31:28.081585Z","caller":"traceutil/trace.go:171","msg":"trace[162891996] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1071; }","duration":"446.304092ms","start":"2026-05-05T02:31:27.635236Z","end":"2026-05-05T02:31:28.08154Z","steps":["trace[162891996] 'agreement among raft nodes before linearized reading' (duration: 445.670014ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:28.081685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:27.635189Z","time spent":"446.445737ms","remote":"127.0.0.1:50820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-05T02:31:28.081718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.215657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-05T02:31:28.081683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.317678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-05T02:31:28.081746Z","caller":"traceutil/trace.go:171","msg":"trace[48737737] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1071; }","duration":"739.290459ms","start":"2026-05-05T02:31:27.342448Z","end":"2026-05-05T02:31:28.081739Z","steps":["trace[48737737] 'agreement among raft nodes before linearized reading' (duration: 738.886486ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:28.081764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:27.34242Z","time spent":"739.34031ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-05T02:31:28.081764Z","caller":"traceutil/trace.go:171","msg":"trace[2056556005] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1071; }","duration":"739.423302ms","start":"2026-05-05T02:31:27.342325Z","end":"2026-05-05T02:31:28.081748Z","steps":["trace[2056556005] 'agreement among raft nodes before linearized reading' (duration: 739.219225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:28.081803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:27.342318Z","time spent":"739.477154ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T02:31:28.081847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.149584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-05T02:31:28.081878Z","caller":"traceutil/trace.go:171","msg":"trace[680327452] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1071; }","duration":"739.196275ms","start":"2026-05-05T02:31:27.342671Z","end":"2026-05-05T02:31:28.081867Z","steps":["trace[680327452] 'agreement among raft nodes before linearized reading' (duration: 738.176165ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:28.081897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:27.342659Z","time spent":"739.233907ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T02:31:28.082136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.014050533s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T02:31:28.082211Z","caller":"traceutil/trace.go:171","msg":"trace[1833271135] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1071; }","duration":"1.014160506s","start":"2026-05-05T02:31:27.068034Z","end":"2026-05-05T02:31:28.082195Z","steps":["trace[1833271135] 'agreement among raft nodes before linearized reading' (duration: 1.012679362s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:28.082339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:27.068012Z","time spent":"1.014313971s","remote":"127.0.0.1:44554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-05-05T02:31:28.734265Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10348319723542241576,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T02:31:29.234696Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10348319723542241576,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T02:31:29.478669Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.394400433s","expected-duration":"1s"} {"level":"info","ts":"2026-05-05T02:31:29.479008Z","caller":"traceutil/trace.go:171","msg":"trace[1418559631] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"1.394789095s","start":"2026-05-05T02:31:28.084197Z","end":"2026-05-05T02:31:29.478986Z","steps":["trace[1418559631] 'process raft request' (duration: 1.394645151s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:29.479117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:28.084182Z","time spent":"1.394864957s","remote":"127.0.0.1:50540","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-05-05T02:31:29.854186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.389322ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T02:31:29.854424Z","caller":"traceutil/trace.go:171","msg":"trace[200854782] linearizableReadLoop","detail":"{readStateIndex:1119; appliedIndex:1113; }","duration":"1.620341865s","start":"2026-05-05T02:31:28.234072Z","end":"2026-05-05T02:31:29.854414Z","steps":["trace[200854782] 'read index received' (duration: 1.244831205s)","trace[200854782] 'applied index is now lower than readState.Index' (duration: 375.51ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:31:29.85449Z","caller":"traceutil/trace.go:171","msg":"trace[593919153] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"1.768930164s","start":"2026-05-05T02:31:28.085554Z","end":"2026-05-05T02:31:29.854484Z","steps":["trace[593919153] 'process raft request' (duration: 1.768727148s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:29.854547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:28.085536Z","time spent":"1.768985175s","remote":"127.0.0.1:50540","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-05-05T02:31:29.854567Z","caller":"traceutil/trace.go:171","msg":"trace[97251759] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"1.644568601s","start":"2026-05-05T02:31:28.209962Z","end":"2026-05-05T02:31:29.854531Z","steps":["trace[97251759] 'process raft request' (duration: 1.644425757s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:29.854755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.620674904s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-05T02:31:29.854752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:28.209938Z","time spent":"1.644719406s","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T02:31:29.854795Z","caller":"traceutil/trace.go:171","msg":"trace[2000416402] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1077; }","duration":"1.620745946s","start":"2026-05-05T02:31:28.23404Z","end":"2026-05-05T02:31:29.854786Z","steps":["trace[2000416402] 'agreement among raft nodes before linearized reading' (duration: 1.620653264s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:29.854823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:28.234027Z","time spent":"1.620788477s","remote":"127.0.0.1:50408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-05-05T02:31:29.854426Z","caller":"traceutil/trace.go:171","msg":"trace[364462375] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"1.768989985s","start":"2026-05-05T02:31:28.085378Z","end":"2026-05-05T02:31:29.854368Z","steps":["trace[364462375] 'process raft request' (duration: 1.446347606s)","trace[364462375] 'compare' (duration: 322.218137ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:31:29.854908Z","caller":"traceutil/trace.go:171","msg":"trace[974649860] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"1.767508501s","start":"2026-05-05T02:31:28.08738Z","end":"2026-05-05T02:31:29.854888Z","steps":["trace[974649860] 'process raft request' (duration: 1.766960165s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:29.854951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:28.085361Z","time spent":"1.769547851s","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T02:31:29.854988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.338193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T02:31:29.855007Z","caller":"traceutil/trace.go:171","msg":"trace[1911149052] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1077; }","duration":"526.391524ms","start":"2026-05-05T02:31:29.32861Z","end":"2026-05-05T02:31:29.855001Z","steps":["trace[1911149052] 'agreement among raft nodes before linearized reading' (duration: 526.355543ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:29.854987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.899599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-05T02:31:29.855027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:29.328591Z","time spent":"526.431596ms","remote":"127.0.0.1:50320","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-05-05T02:31:29.855011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:28.08736Z","time spent":"1.767604175s","remote":"127.0.0.1:50540","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-05-05T02:31:29.855051Z","caller":"traceutil/trace.go:171","msg":"trace[1670080849] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1077; }","duration":"112.003123ms","start":"2026-05-05T02:31:29.743029Z","end":"2026-05-05T02:31:29.855033Z","steps":["trace[1670080849] 'agreement among raft nodes before linearized reading' (duration: 111.901719ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:31:29.855071Z","caller":"traceutil/trace.go:171","msg":"trace[491500422] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"1.768467971s","start":"2026-05-05T02:31:28.086588Z","end":"2026-05-05T02:31:29.855056Z","steps":["trace[491500422] 'process raft request' (duration: 1.767732699s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:29.855039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"938.204842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T02:31:29.855143Z","caller":"traceutil/trace.go:171","msg":"trace[1819994700] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1077; }","duration":"938.395498ms","start":"2026-05-05T02:31:28.916728Z","end":"2026-05-05T02:31:29.855124Z","steps":["trace[1819994700] 'agreement among raft nodes before linearized reading' (duration: 938.1583ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:29.855161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:28.086573Z","time spent":"1.768540453s","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T02:31:29.855235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:28.916709Z","time spent":"938.508091ms","remote":"127.0.0.1:44292","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/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-05-05T02:31:31.438209Z","caller":"traceutil/trace.go:171","msg":"trace[1825170378] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"328.101508ms","start":"2026-05-05T02:31:31.110079Z","end":"2026-05-05T02:31:31.43818Z","steps":["trace[1825170378] 'process raft request' (duration: 323.868564ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:31.438345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:31.110057Z","time spent":"328.218542ms","remote":"127.0.0.1:50334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T02:31:31.438324Z","caller":"traceutil/trace.go:171","msg":"trace[514193313] linearizableReadLoop","detail":"{readStateIndex:1121; appliedIndex:1120; }","duration":"109.46234ms","start":"2026-05-05T02:31:31.328836Z","end":"2026-05-05T02:31:31.438298Z","steps":["trace[514193313] 'read index received' (duration: 105.0434ms)","trace[514193313] 'applied index is now lower than readState.Index' (duration: 4.417259ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T02:31:31.438666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.804579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T02:31:31.438957Z","caller":"traceutil/trace.go:171","msg":"trace[777897211] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1078; }","duration":"110.152349ms","start":"2026-05-05T02:31:31.328785Z","end":"2026-05-05T02:31:31.438937Z","steps":["trace[777897211] 'agreement among raft nodes before linearized reading' (duration: 109.619814ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:31:43.731638Z","caller":"traceutil/trace.go:171","msg":"trace[1141174627] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"173.756482ms","start":"2026-05-05T02:31:43.557856Z","end":"2026-05-05T02:31:43.731612Z","steps":["trace[1141174627] 'process raft request' (duration: 173.599418ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:31:44.248158Z","caller":"traceutil/trace.go:171","msg":"trace[1268413009] linearizableReadLoop","detail":"{readStateIndex:1220; appliedIndex:1219; }","duration":"278.433631ms","start":"2026-05-05T02:31:43.969706Z","end":"2026-05-05T02:31:44.24814Z","steps":["trace[1268413009] 'read index received' (duration: 278.258606ms)","trace[1268413009] 'applied index is now lower than readState.Index' (duration: 174.505µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:31:44.248208Z","caller":"traceutil/trace.go:171","msg":"trace[1165768205] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"330.985712ms","start":"2026-05-05T02:31:43.917202Z","end":"2026-05-05T02:31:44.248188Z","steps":["trace[1165768205] 'process raft request' (duration: 330.803527ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:44.248293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.579996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-05T02:31:44.248316Z","caller":"traceutil/trace.go:171","msg":"trace[1155033451] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1173; }","duration":"278.644527ms","start":"2026-05-05T02:31:43.969664Z","end":"2026-05-05T02:31:44.248309Z","steps":["trace[1155033451] 'agreement among raft nodes before linearized reading' (duration: 278.558465ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:44.248318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:43.91718Z","time spent":"331.072285ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T02:31:44.248441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.513963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-05T02:31:44.248459Z","caller":"traceutil/trace.go:171","msg":"trace[325702404] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1173; }","duration":"278.545555ms","start":"2026-05-05T02:31:43.96991Z","end":"2026-05-05T02:31:44.248455Z","steps":["trace[325702404] 'agreement among raft nodes before linearized reading' (duration: 278.500793ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:44.248487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.695268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T02:31:44.248541Z","caller":"traceutil/trace.go:171","msg":"trace[908944366] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1173; }","duration":"278.765622ms","start":"2026-05-05T02:31:43.969762Z","end":"2026-05-05T02:31:44.248528Z","steps":["trace[908944366] 'agreement among raft nodes before linearized reading' (duration: 278.640827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:44.248583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.775672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-05T02:31:44.2486Z","caller":"traceutil/trace.go:171","msg":"trace[1376628189] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1173; }","duration":"278.804623ms","start":"2026-05-05T02:31:43.969791Z","end":"2026-05-05T02:31:44.248596Z","steps":["trace[1376628189] 'agreement among raft nodes before linearized reading' (duration: 278.72138ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:31:44.362485Z","caller":"traceutil/trace.go:171","msg":"trace[403342414] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"110.031726ms","start":"2026-05-05T02:31:44.252431Z","end":"2026-05-05T02:31:44.362462Z","steps":["trace[403342414] 'process raft request' (duration: 109.994535ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:31:44.362734Z","caller":"traceutil/trace.go:171","msg":"trace[918012711] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"110.93003ms","start":"2026-05-05T02:31:44.25178Z","end":"2026-05-05T02:31:44.36271Z","steps":["trace[918012711] 'process raft request' (duration: 84.401143ms)","trace[918012711] 'compare' (duration: 25.946793ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:31:44.362865Z","caller":"traceutil/trace.go:171","msg":"trace[749421722] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"110.806347ms","start":"2026-05-05T02:31:44.25204Z","end":"2026-05-05T02:31:44.362847Z","steps":["trace[749421722] 'process raft request' (duration: 110.249873ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:31:44.36291Z","caller":"traceutil/trace.go:171","msg":"trace[1415262053] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"110.805596ms","start":"2026-05-05T02:31:44.252072Z","end":"2026-05-05T02:31:44.362877Z","steps":["trace[1415262053] 'process raft request' (duration: 110.318194ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:31:46.466559Z","caller":"traceutil/trace.go:171","msg":"trace[257639290] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"728.281063ms","start":"2026-05-05T02:31:45.738243Z","end":"2026-05-05T02:31:46.466524Z","steps":["trace[257639290] 'process raft request' (duration: 728.085238ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:46.466708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:45.738219Z","time spent":"728.409296ms","remote":"127.0.0.1:50540","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-05-05T02:31:46.917649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.705645ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T02:31:46.917858Z","caller":"traceutil/trace.go:171","msg":"trace[1529419823] linearizableReadLoop","detail":"{readStateIndex:1228; appliedIndex:1225; }","duration":"589.344189ms","start":"2026-05-05T02:31:46.328498Z","end":"2026-05-05T02:31:46.917842Z","steps":["trace[1529419823] 'read index received' (duration: 137.861867ms)","trace[1529419823] 'applied index is now lower than readState.Index' (duration: 451.481402ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:31:46.917924Z","caller":"traceutil/trace.go:171","msg":"trace[1342016568] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"666.319594ms","start":"2026-05-05T02:31:46.251595Z","end":"2026-05-05T02:31:46.917914Z","steps":["trace[1342016568] 'process raft request' (duration: 666.16533ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:46.91798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:46.251568Z","time spent":"666.378006ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T02:31:46.918037Z","caller":"traceutil/trace.go:171","msg":"trace[382468370] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"876.219692ms","start":"2026-05-05T02:31:46.041782Z","end":"2026-05-05T02:31:46.918002Z","steps":["trace[382468370] 'process raft request' (duration: 650.081106ms)","trace[382468370] 'compare' (duration: 225.49146ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T02:31:46.918199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:46.041764Z","time spent":"876.344265ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T02:31:46.918306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.77111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T02:31:46.918394Z","caller":"traceutil/trace.go:171","msg":"trace[1440442379] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1181; }","duration":"589.923014ms","start":"2026-05-05T02:31:46.328449Z","end":"2026-05-05T02:31:46.918372Z","steps":["trace[1440442379] 'agreement among raft nodes before linearized reading' (duration: 589.727688ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:46.918441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:46.32843Z","time spent":"590.002376ms","remote":"127.0.0.1:50320","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-05-05T02:31:47.158057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.155009ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-05T02:31:47.158139Z","caller":"traceutil/trace.go:171","msg":"trace[1227542898] linearizableReadLoop","detail":"{readStateIndex:1229; appliedIndex:1228; }","duration":"240.240801ms","start":"2026-05-05T02:31:46.917885Z","end":"2026-05-05T02:31:47.158126Z","steps":["trace[1227542898] 'read index received' (duration: 97.00188ms)","trace[1227542898] 'applied index is now lower than readState.Index' (duration: 143.237601ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T02:31:47.158292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.560139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-05T02:31:47.158319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.794245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T02:31:47.158348Z","caller":"traceutil/trace.go:171","msg":"trace[283873714] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1181; }","duration":"790.664422ms","start":"2026-05-05T02:31:46.367671Z","end":"2026-05-05T02:31:47.158336Z","steps":["trace[283873714] 'agreement among raft nodes before linearized reading' (duration: 790.465717ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:47.158352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.734963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-05T02:31:47.158374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:46.367652Z","time spent":"790.717462ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-05T02:31:47.158378Z","caller":"traceutil/trace.go:171","msg":"trace[114564659] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1181; }","duration":"790.769763ms","start":"2026-05-05T02:31:46.367601Z","end":"2026-05-05T02:31:47.158371Z","steps":["trace[114564659] 'agreement among raft nodes before linearized reading' (duration: 790.588739ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:31:47.158372Z","caller":"traceutil/trace.go:171","msg":"trace[135553086] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1181; }","duration":"238.868616ms","start":"2026-05-05T02:31:46.919487Z","end":"2026-05-05T02:31:47.158356Z","steps":["trace[135553086] 'agreement among raft nodes before linearized reading' (duration: 238.791194ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:47.158405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:46.367574Z","time spent":"790.825105ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T02:31:47.158383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.703582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-05T02:31:47.158448Z","caller":"traceutil/trace.go:171","msg":"trace[1432658944] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1181; }","duration":"790.821175ms","start":"2026-05-05T02:31:46.367611Z","end":"2026-05-05T02:31:47.158433Z","steps":["trace[1432658944] 'agreement among raft nodes before linearized reading' (duration: 790.558268ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:47.158446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.244866ms","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-05-05T02:31:47.158481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:46.367593Z","time spent":"790.881657ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-05T02:31:47.158505Z","caller":"traceutil/trace.go:171","msg":"trace[1994586180] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1181; }","duration":"791.354808ms","start":"2026-05-05T02:31:46.367137Z","end":"2026-05-05T02:31:47.158492Z","steps":["trace[1994586180] 'agreement among raft nodes before linearized reading' (duration: 791.164744ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:47.158556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:46.367116Z","time spent":"791.42943ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T02:31:47.158701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.344547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T02:31:47.158763Z","caller":"traceutil/trace.go:171","msg":"trace[885121365] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1181; }","duration":"307.410969ms","start":"2026-05-05T02:31:46.851305Z","end":"2026-05-05T02:31:47.158716Z","steps":["trace[885121365] 'agreement among raft nodes before linearized reading' (duration: 306.942577ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:47.158783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:46.851279Z","time spent":"307.497661ms","remote":"127.0.0.1:44466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"info","ts":"2026-05-05T02:31:59.058129Z","caller":"traceutil/trace.go:171","msg":"trace[1157579571] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"102.310512ms","start":"2026-05-05T02:31:58.955793Z","end":"2026-05-05T02:31:59.058104Z","steps":["trace[1157579571] 'process raft request' (duration: 102.147038ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:32:01.450399Z","caller":"traceutil/trace.go:171","msg":"trace[713630635] linearizableReadLoop","detail":"{readStateIndex:1295; appliedIndex:1294; }","duration":"133.250611ms","start":"2026-05-05T02:32:01.317125Z","end":"2026-05-05T02:32:01.450375Z","steps":["trace[713630635] 'read index received' (duration: 133.039646ms)","trace[713630635] 'applied index is now lower than readState.Index' (duration: 210.065µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:32:01.450758Z","caller":"traceutil/trace.go:171","msg":"trace[623026947] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"183.968576ms","start":"2026-05-05T02:32:01.266775Z","end":"2026-05-05T02:32:01.450744Z","steps":["trace[623026947] 'process raft request' (duration: 183.466933ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:32:01.450975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.643404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T02:32:01.45122Z","caller":"traceutil/trace.go:171","msg":"trace[1328561748] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1244; }","duration":"122.745457ms","start":"2026-05-05T02:32:01.328285Z","end":"2026-05-05T02:32:01.45103Z","steps":["trace[1328561748] 'agreement among raft nodes before linearized reading' (duration: 122.609364ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:32:01.738868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.687478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T02:32:01.738889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.374633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-05T02:32:01.738959Z","caller":"traceutil/trace.go:171","msg":"trace[943790879] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1244; }","duration":"286.817632ms","start":"2026-05-05T02:32:01.452118Z","end":"2026-05-05T02:32:01.738936Z","steps":["trace[943790879] 'range keys from in-memory index tree' (duration: 286.601837ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:32:01.738974Z","caller":"traceutil/trace.go:171","msg":"trace[1515645728] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1244; }","duration":"333.499686ms","start":"2026-05-05T02:32:01.40545Z","end":"2026-05-05T02:32:01.73895Z","steps":["trace[1515645728] 'agreement among raft nodes before linearized reading' (duration: 46.008917ms)","trace[1515645728] 'range keys from in-memory index tree' (duration: 287.270074ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T02:32:01.739022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:32:01.405426Z","time spent":"333.585578ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-05T02:32:01.739067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.93127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-05-05T02:32:01.739142Z","caller":"traceutil/trace.go:171","msg":"trace[771063273] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1244; }","duration":"422.008652ms","start":"2026-05-05T02:32:01.317095Z","end":"2026-05-05T02:32:01.739103Z","steps":["trace[771063273] 'agreement among raft nodes before linearized reading' (duration: 134.391209ms)","trace[771063273] 'range keys from in-memory index tree' (duration: 287.474089ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T02:32:01.739187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:32:01.317063Z","time spent":"422.112755ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T02:32:01.739471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.199928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-05T02:32:01.739579Z","caller":"traceutil/trace.go:171","msg":"trace[1903525693] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1244; }","duration":"335.324301ms","start":"2026-05-05T02:32:01.404226Z","end":"2026-05-05T02:32:01.73955Z","steps":["trace[1903525693] 'agreement among raft nodes before linearized reading' (duration: 47.248357ms)","trace[1903525693] 'range keys from in-memory index tree' (duration: 287.444418ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T02:32:01.739648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:32:01.404171Z","time spent":"335.460144ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-05T02:32:01.874025Z","caller":"traceutil/trace.go:171","msg":"trace[897006206] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"412.481932ms","start":"2026-05-05T02:32:01.461523Z","end":"2026-05-05T02:32:01.874005Z","steps":["trace[897006206] 'process raft request' (duration: 412.357619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:32:01.874146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:32:01.461504Z","time spent":"412.580614ms","remote":"127.0.0.1:50486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11375,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T02:32:01.938072Z","caller":"traceutil/trace.go:171","msg":"trace[556194826] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"194.743226ms","start":"2026-05-05T02:32:01.743296Z","end":"2026-05-05T02:32:01.93804Z","steps":["trace[556194826] 'process raft request' (duration: 194.48181ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:32:01.938138Z","caller":"traceutil/trace.go:171","msg":"trace[102139103] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"194.681214ms","start":"2026-05-05T02:32:01.743448Z","end":"2026-05-05T02:32:01.938129Z","steps":["trace[102139103] 'process raft request' (duration: 194.476739ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:32:01.938077Z","caller":"traceutil/trace.go:171","msg":"trace[1639974151] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"194.562641ms","start":"2026-05-05T02:32:01.743488Z","end":"2026-05-05T02:32:01.938051Z","steps":["trace[1639974151] 'process raft request' (duration: 194.49311ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:32:02.749995Z","caller":"traceutil/trace.go:171","msg":"trace[1983218572] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"869.782672ms","start":"2026-05-05T02:32:01.880191Z","end":"2026-05-05T02:32:02.749973Z","steps":["trace[1983218572] 'process raft request' (duration: 869.71969ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:32:02.75003Z","caller":"traceutil/trace.go:171","msg":"trace[1127557033] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"870.53497ms","start":"2026-05-05T02:32:01.87947Z","end":"2026-05-05T02:32:02.750005Z","steps":["trace[1127557033] 'process raft request' (duration: 870.335805ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:32:02.750122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:32:01.880175Z","time spent":"869.882034ms","remote":"127.0.0.1:50474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":915,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T02:32:02.750148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:32:01.879444Z","time spent":"870.640942ms","remote":"127.0.0.1:50554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T02:32:02.750266Z","caller":"traceutil/trace.go:171","msg":"trace[1451384390] linearizableReadLoop","detail":"{readStateIndex:1302; appliedIndex:1299; }","duration":"300.709882ms","start":"2026-05-05T02:32:02.44955Z","end":"2026-05-05T02:32:02.75026Z","steps":["trace[1451384390] 'read index received' (duration: 300.208779ms)","trace[1451384390] 'applied index is now lower than readState.Index' (duration: 500.213µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T02:32:02.750463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.918956ms","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":"info","ts":"2026-05-05T02:32:02.750507Z","caller":"traceutil/trace.go:171","msg":"trace[749029171] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1251; }","duration":"301.005309ms","start":"2026-05-05T02:32:02.449494Z","end":"2026-05-05T02:32:02.7505Z","steps":["trace[749029171] 'agreement among raft nodes before linearized reading' (duration: 300.944747ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:32:02.75053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:32:02.449472Z","time spent":"301.052829ms","remote":"127.0.0.1:50658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"info","ts":"2026-05-05T02:32:02.750592Z","caller":"traceutil/trace.go:171","msg":"trace[941497573] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"865.654667ms","start":"2026-05-05T02:32:01.884932Z","end":"2026-05-05T02:32:02.750587Z","steps":["trace[941497573] 'process raft request' (duration: 865.008031ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:32:02.750534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.56548ms","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-05-05T02:32:02.750632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:32:01.884911Z","time spent":"865.703718ms","remote":"127.0.0.1:50782","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-05-05T02:32:02.750634Z","caller":"traceutil/trace.go:171","msg":"trace[1141575119] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1251; }","duration":"176.704114ms","start":"2026-05-05T02:32:02.573923Z","end":"2026-05-05T02:32:02.750627Z","steps":["trace[1141575119] 'agreement among raft nodes before linearized reading' (duration: 176.54421ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:32:03.628272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.826159ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T02:32:03.628397Z","caller":"traceutil/trace.go:171","msg":"trace[64682976] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"873.549884ms","start":"2026-05-05T02:32:02.754832Z","end":"2026-05-05T02:32:03.628382Z","steps":["trace[64682976] 'process raft request' (duration: 192.553201ms)","trace[64682976] 'compare' (duration: 680.749967ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:32:03.628625Z","caller":"traceutil/trace.go:171","msg":"trace[845318623] linearizableReadLoop","detail":"{readStateIndex:1304; appliedIndex:1302; }","duration":"302.911628ms","start":"2026-05-05T02:32:03.325683Z","end":"2026-05-05T02:32:03.628594Z","steps":["trace[845318623] 'read index received' (duration: 258.631044ms)","trace[845318623] 'applied index is now lower than readState.Index' (duration: 44.278964ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T02:32:03.628832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.149503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T02:32:03.628835Z","caller":"traceutil/trace.go:171","msg":"trace[983408994] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"561.057898ms","start":"2026-05-05T02:32:03.067765Z","end":"2026-05-05T02:32:03.628823Z","steps":["trace[983408994] 'process raft request' (duration: 560.583946ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:32:03.628863Z","caller":"traceutil/trace.go:171","msg":"trace[1157399765] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1253; }","duration":"303.207085ms","start":"2026-05-05T02:32:03.325648Z","end":"2026-05-05T02:32:03.628855Z","steps":["trace[1157399765] 'agreement among raft nodes before linearized reading' (duration: 303.153774ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:32:03.628883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:32:03.325631Z","time spent":"303.247726ms","remote":"127.0.0.1:50320","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-05-05T02:32:03.628908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:32:03.067738Z","time spent":"561.12384ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T02:32:03.628956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.11089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-05T02:32:03.628967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.393896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-05T02:32:03.629014Z","caller":"traceutil/trace.go:171","msg":"trace[786109255] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1253; }","duration":"151.208443ms","start":"2026-05-05T02:32:03.477792Z","end":"2026-05-05T02:32:03.629Z","steps":["trace[786109255] 'agreement among raft nodes before linearized reading' (duration: 151.043669ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:32:03.629021Z","caller":"traceutil/trace.go:171","msg":"trace[761427339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1253; }","duration":"174.498979ms","start":"2026-05-05T02:32:03.454509Z","end":"2026-05-05T02:32:03.629008Z","steps":["trace[761427339] 'agreement among raft nodes before linearized reading' (duration: 174.328225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:32:03.628442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:32:02.754815Z","time spent":"873.606685ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T02:32:04.086066Z","caller":"traceutil/trace.go:171","msg":"trace[170503952] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"138.638567ms","start":"2026-05-05T02:32:03.947402Z","end":"2026-05-05T02:32:04.08604Z","steps":["trace[170503952] 'process raft request' (duration: 138.570765ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:32:04.086084Z","caller":"traceutil/trace.go:171","msg":"trace[1628025226] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"139.559919ms","start":"2026-05-05T02:32:03.946499Z","end":"2026-05-05T02:32:04.086059Z","steps":["trace[1628025226] 'process raft request' (duration: 93.112261ms)","trace[1628025226] 'compare' (duration: 46.189412ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:32:05.663634Z","caller":"traceutil/trace.go:171","msg":"trace[375947960] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"242.275462ms","start":"2026-05-05T02:32:05.421331Z","end":"2026-05-05T02:32:05.663606Z","steps":["trace[375947960] 'process raft request' (duration: 242.063816ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:32:06.163888Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10348319723542241978,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T02:32:06.526695Z","caller":"traceutil/trace.go:171","msg":"trace[1794681072] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"892.976374ms","start":"2026-05-05T02:32:05.633687Z","end":"2026-05-05T02:32:06.526664Z","steps":["trace[1794681072] 'process raft request' (duration: 803.953375ms)","trace[1794681072] 'compare' (duration: 88.25303ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T02:32:06.527502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:32:05.633673Z","time spent":"893.088987ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T02:32:06.570888Z","caller":"traceutil/trace.go:171","msg":"trace[8296036] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"903.100969ms","start":"2026-05-05T02:32:05.667754Z","end":"2026-05-05T02:32:06.570855Z","steps":["trace[8296036] 'process raft request' (duration: 902.354511ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:32:06.571036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:32:05.667737Z","time spent":"903.222262ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T02:32:06.601126Z","caller":"traceutil/trace.go:171","msg":"trace[1883575636] linearizableReadLoop","detail":"{readStateIndex:1312; appliedIndex:1310; }","duration":"937.515284ms","start":"2026-05-05T02:32:05.663555Z","end":"2026-05-05T02:32:06.601071Z","steps":["trace[1883575636] 'read index received' (duration: 774.107225ms)","trace[1883575636] 'applied index is now lower than readState.Index' (duration: 163.406869ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T02:32:06.601729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"960.593364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-05T02:32:06.601783Z","caller":"traceutil/trace.go:171","msg":"trace[1464871568] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1262; }","duration":"960.713847ms","start":"2026-05-05T02:32:05.641054Z","end":"2026-05-05T02:32:06.601768Z","steps":["trace[1464871568] 'agreement among raft nodes before linearized reading' (duration: 960.480651ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:32:06.60178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.79843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-05T02:32:06.602268Z","caller":"traceutil/trace.go:171","msg":"trace[2134657426] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1262; }","duration":"625.333314ms","start":"2026-05-05T02:32:05.97691Z","end":"2026-05-05T02:32:06.602244Z","steps":["trace[2134657426] 'agreement among raft nodes before linearized reading' (duration: 624.688798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:32:06.60184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:32:05.641034Z","time spent":"960.796489ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T02:32:06.602336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:32:05.976883Z","time spent":"625.439716ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-05T02:32:06.601982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.492103ms","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-05-05T02:32:06.602499Z","caller":"traceutil/trace.go:171","msg":"trace[1337607408] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1262; }","duration":"454.052167ms","start":"2026-05-05T02:32:06.148436Z","end":"2026-05-05T02:32:06.602488Z","steps":["trace[1337607408] 'agreement among raft nodes before linearized reading' (duration: 453.466932ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:32:06.602537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:32:06.148422Z","time spent":"454.103718ms","remote":"127.0.0.1:50574","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-05-05T02:32:06.602044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.61914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T02:32:06.602637Z","caller":"traceutil/trace.go:171","msg":"trace[1945608663] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1262; }","duration":"276.215395ms","start":"2026-05-05T02:32:06.326382Z","end":"2026-05-05T02:32:06.602597Z","steps":["trace[1945608663] 'agreement among raft nodes before linearized reading' (duration: 275.645051ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:32:06.602139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.684747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-05T02:32:06.602753Z","caller":"traceutil/trace.go:171","msg":"trace[484533152] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1262; }","duration":"512.312303ms","start":"2026-05-05T02:32:06.090425Z","end":"2026-05-05T02:32:06.602737Z","steps":["trace[484533152] 'agreement among raft nodes before linearized reading' (duration: 511.622996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:32:06.602796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:32:06.09042Z","time spent":"512.364894ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T02:32:06.602176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.767589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T02:32:06.602858Z","caller":"traceutil/trace.go:171","msg":"trace[819708686] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1262; }","duration":"512.472566ms","start":"2026-05-05T02:32:06.09037Z","end":"2026-05-05T02:32:06.602843Z","steps":["trace[819708686] 'agreement among raft nodes before linearized reading' (duration: 511.705977ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:32:06.602919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:32:06.090351Z","time spent":"512.558519ms","remote":"127.0.0.1:50540","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-05-05T02:32:07.045262Z","caller":"traceutil/trace.go:171","msg":"trace[2062440020] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"287.737716ms","start":"2026-05-05T02:32:06.757505Z","end":"2026-05-05T02:32:07.045243Z","steps":["trace[2062440020] 'process raft request' (duration: 279.96538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:32:08.019391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.966236ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T02:32:08.019592Z","caller":"traceutil/trace.go:171","msg":"trace[1558047200] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"633.227603ms","start":"2026-05-05T02:32:07.386347Z","end":"2026-05-05T02:32:08.019575Z","steps":["trace[1558047200] 'process raft request' (duration: 633.14374ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:32:08.019656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:32:07.386323Z","time spent":"633.301735ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T02:32:08.019764Z","caller":"traceutil/trace.go:171","msg":"trace[1378433473] linearizableReadLoop","detail":"{readStateIndex:1323; appliedIndex:1321; }","duration":"1.162097411s","start":"2026-05-05T02:32:06.85762Z","end":"2026-05-05T02:32:08.019718Z","steps":["trace[1378433473] 'read index received' (duration: 179.867593ms)","trace[1378433473] 'applied index is now lower than readState.Index' (duration: 982.223548ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T02:32:08.019983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.162328447s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T02:32:08.020021Z","caller":"traceutil/trace.go:171","msg":"trace[1403295427] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1272; }","duration":"1.162430799s","start":"2026-05-05T02:32:06.857577Z","end":"2026-05-05T02:32:08.020008Z","steps":["trace[1403295427] 'agreement among raft nodes before linearized reading' (duration: 1.162324867s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:32:08.020061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:32:06.857557Z","time spent":"1.162494311s","remote":"127.0.0.1:44484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-05T02:32:08.020099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.318993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T02:32:08.020144Z","caller":"traceutil/trace.go:171","msg":"trace[1973185981] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1272; }","duration":"691.415786ms","start":"2026-05-05T02:32:07.328719Z","end":"2026-05-05T02:32:08.020134Z","steps":["trace[1973185981] 'agreement among raft nodes before linearized reading' (duration: 691.344805ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:32:08.020175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:32:07.328698Z","time spent":"691.471547ms","remote":"127.0.0.1:50320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T02:32:08.019724Z","caller":"traceutil/trace.go:171","msg":"trace[1958589000] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"1.261847729s","start":"2026-05-05T02:32:06.757834Z","end":"2026-05-05T02:32:08.019682Z","steps":["trace[1958589000] 'process raft request' (duration: 459.512695ms)","trace[1958589000] 'compare' (duration: 801.567785ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T02:32:08.020423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:32:06.757825Z","time spent":"1.262553846s","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T02:32:08.783203Z","caller":"traceutil/trace.go:171","msg":"trace[337480364] linearizableReadLoop","detail":"{readStateIndex:1325; appliedIndex:1324; }","duration":"208.856052ms","start":"2026-05-05T02:32:08.574323Z","end":"2026-05-05T02:32:08.783179Z","steps":["trace[337480364] 'read index received' (duration: 208.654687ms)","trace[337480364] 'applied index is now lower than readState.Index' (duration: 200.475µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:32:08.784083Z","caller":"traceutil/trace.go:171","msg":"trace[723413958] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"249.060333ms","start":"2026-05-05T02:32:08.535006Z","end":"2026-05-05T02:32:08.784066Z","steps":["trace[723413958] 'process raft request' (duration: 248.041278ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:32:08.78424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.612119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-05T02:32:08.784319Z","caller":"traceutil/trace.go:171","msg":"trace[569330793] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1273; }","duration":"162.735641ms","start":"2026-05-05T02:32:08.621564Z","end":"2026-05-05T02:32:08.784299Z","steps":["trace[569330793] 'agreement among raft nodes before linearized reading' (duration: 162.521876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:32:08.784636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.373542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-05-05T02:32:08.784682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.488655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-05T02:32:08.784804Z","caller":"traceutil/trace.go:171","msg":"trace[2047308861] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1273; }","duration":"119.638419ms","start":"2026-05-05T02:32:08.665154Z","end":"2026-05-05T02:32:08.784793Z","steps":["trace[2047308861] 'agreement among raft nodes before linearized reading' (duration: 119.454674ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:32:08.784961Z","caller":"traceutil/trace.go:171","msg":"trace[45206997] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1273; }","duration":"119.560217ms","start":"2026-05-05T02:32:08.665195Z","end":"2026-05-05T02:32:08.784756Z","steps":["trace[45206997] 'agreement among raft nodes before linearized reading' (duration: 119.330691ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:32:08.785247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.917643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-05T02:32:08.785368Z","caller":"traceutil/trace.go:171","msg":"trace[1719794975] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1273; }","duration":"211.076637ms","start":"2026-05-05T02:32:08.574278Z","end":"2026-05-05T02:32:08.785355Z","steps":["trace[1719794975] 'agreement among raft nodes before linearized reading' (duration: 210.889132ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:32:08.785522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.211913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-05T02:32:08.785567Z","caller":"traceutil/trace.go:171","msg":"trace[967957322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1273; }","duration":"120.273455ms","start":"2026-05-05T02:32:08.665282Z","end":"2026-05-05T02:32:08.785556Z","steps":["trace[967957322] 'agreement among raft nodes before linearized reading' (duration: 120.163092ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:32:08.938545Z","caller":"traceutil/trace.go:171","msg":"trace[2047053459] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"144.00515ms","start":"2026-05-05T02:32:08.794518Z","end":"2026-05-05T02:32:08.938524Z","steps":["trace[2047053459] 'process raft request' (duration: 143.831336ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:32:08.938724Z","caller":"traceutil/trace.go:171","msg":"trace[1723964645] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"144.323729ms","start":"2026-05-05T02:32:08.79439Z","end":"2026-05-05T02:32:08.938714Z","steps":["trace[1723964645] 'process raft request' (duration: 143.850077ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:32:08.938856Z","caller":"traceutil/trace.go:171","msg":"trace[1741875399] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"144.300728ms","start":"2026-05-05T02:32:08.794546Z","end":"2026-05-05T02:32:08.938847Z","steps":["trace[1741875399] 'process raft request' (duration: 143.937789ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:32:08.939042Z","caller":"traceutil/trace.go:171","msg":"trace[1175547899] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"122.646094ms","start":"2026-05-05T02:32:08.816385Z","end":"2026-05-05T02:32:08.939031Z","steps":["trace[1175547899] 'process raft request' (duration: 122.424608ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:32:11.074974Z","caller":"traceutil/trace.go:171","msg":"trace[1372859692] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"124.519372ms","start":"2026-05-05T02:32:10.950431Z","end":"2026-05-05T02:32:11.07495Z","steps":["trace[1372859692] 'process raft request' (duration: 124.346138ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:32:11.076488Z","caller":"traceutil/trace.go:171","msg":"trace[1138231241] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"125.598779ms","start":"2026-05-05T02:32:10.950876Z","end":"2026-05-05T02:32:11.076474Z","steps":["trace[1138231241] 'process raft request' (duration: 125.564338ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:32:11.076553Z","caller":"traceutil/trace.go:171","msg":"trace[1323081904] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"125.734742ms","start":"2026-05-05T02:32:10.950771Z","end":"2026-05-05T02:32:11.076506Z","steps":["trace[1323081904] 'process raft request' (duration: 125.563067ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:32:11.07678Z","caller":"traceutil/trace.go:171","msg":"trace[780958193] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"125.918197ms","start":"2026-05-05T02:32:10.950833Z","end":"2026-05-05T02:32:11.076751Z","steps":["trace[780958193] 'process raft request' (duration: 125.573168ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:32:17.093532Z","caller":"traceutil/trace.go:171","msg":"trace[793359051] linearizableReadLoop","detail":"{readStateIndex:1388; appliedIndex:1387; }","duration":"212.883663ms","start":"2026-05-05T02:32:16.880622Z","end":"2026-05-05T02:32:17.093506Z","steps":["trace[793359051] 'read index received' (duration: 212.686698ms)","trace[793359051] 'applied index is now lower than readState.Index' (duration: 180.054µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:32:17.093805Z","caller":"traceutil/trace.go:171","msg":"trace[411738467] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"247.34012ms","start":"2026-05-05T02:32:16.846452Z","end":"2026-05-05T02:32:17.093792Z","steps":["trace[411738467] 'process raft request' (duration: 246.886228ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:32:17.094167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.706271ms","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:535"} {"level":"warn","ts":"2026-05-05T02:32:17.094167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.526819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T02:32:17.094218Z","caller":"traceutil/trace.go:171","msg":"trace[886193924] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1334; }","duration":"197.787943ms","start":"2026-05-05T02:32:16.896414Z","end":"2026-05-05T02:32:17.094202Z","steps":["trace[886193924] 'agreement among raft nodes before linearized reading' (duration: 197.640179ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:32:17.094249Z","caller":"traceutil/trace.go:171","msg":"trace[414025305] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1334; }","duration":"213.675123ms","start":"2026-05-05T02:32:16.880555Z","end":"2026-05-05T02:32:17.09423Z","steps":["trace[414025305] 'agreement among raft nodes before linearized reading' (duration: 213.429937ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:32:17.094381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.437925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T02:32:17.094722Z","caller":"traceutil/trace.go:171","msg":"trace[2112808460] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1334; }","duration":"131.838896ms","start":"2026-05-05T02:32:16.962854Z","end":"2026-05-05T02:32:17.094693Z","steps":["trace[2112808460] 'agreement among raft nodes before linearized reading' (duration: 131.383854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:32:17.362038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.214423ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T02:32:17.362287Z","caller":"traceutil/trace.go:171","msg":"trace[136938750] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"262.554482ms","start":"2026-05-05T02:32:17.099681Z","end":"2026-05-05T02:32:17.362235Z","steps":["trace[136938750] 'process raft request' (duration: 105.058992ms)","trace[136938750] 'compare' (duration: 157.064739ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T02:32:17.819115Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10348319723542242117,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T02:32:18.320162Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10348319723542242117,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T02:32:18.648898Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.427055332s","expected-duration":"1s"} {"level":"info","ts":"2026-05-05T02:32:18.649443Z","caller":"traceutil/trace.go:171","msg":"trace[129913873] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"1.548053665s","start":"2026-05-05T02:32:17.101357Z","end":"2026-05-05T02:32:18.649411Z","steps":["trace[129913873] 'process raft request' (duration: 1.54784015s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:32:18.649662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:32:17.101339Z","time spent":"1.54821647s","remote":"127.0.0.1:50540","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-05-05T02:32:18.651856Z","caller":"traceutil/trace.go:171","msg":"trace[203429741] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"1.54980119s","start":"2026-05-05T02:32:17.102019Z","end":"2026-05-05T02:32:18.65182Z","steps":["trace[203429741] 'process raft request' (duration: 1.547342208s)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:32:18.651958Z","caller":"traceutil/trace.go:171","msg":"trace[2041859527] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"1.549109433s","start":"2026-05-05T02:32:17.102831Z","end":"2026-05-05T02:32:18.65194Z","steps":["trace[2041859527] 'process raft request' (duration: 1.549012501s)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:32:18.652035Z","caller":"traceutil/trace.go:171","msg":"trace[714839426] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"1.549853981s","start":"2026-05-05T02:32:17.102148Z","end":"2026-05-05T02:32:18.652002Z","steps":["trace[714839426] 'process raft request' (duration: 1.549539983s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:32:18.652056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:32:17.102008Z","time spent":"1.549923323s","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T02:32:18.652185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:32:17.102139Z","time spent":"1.549967984s","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T02:32:18.652236Z","caller":"traceutil/trace.go:171","msg":"trace[1606110217] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"1.549977574s","start":"2026-05-05T02:32:17.102242Z","end":"2026-05-05T02:32:18.652219Z","steps":["trace[1606110217] 'process raft request' (duration: 1.549563004s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:32:18.652324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:32:17.102235Z","time spent":"1.550037486s","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T02:32:18.652457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:32:17.102819Z","time spent":"1.549574505s","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T02:32:18.65783Z","caller":"traceutil/trace.go:171","msg":"trace[268797112] linearizableReadLoop","detail":"{readStateIndex:1395; appliedIndex:1389; }","duration":"1.338813363s","start":"2026-05-05T02:32:17.318994Z","end":"2026-05-05T02:32:18.657807Z","steps":["trace[268797112] 'read index received' (duration: 1.33031954s)","trace[268797112] 'applied index is now lower than readState.Index' (duration: 8.493053ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:32:18.657887Z","caller":"traceutil/trace.go:171","msg":"trace[1616876629] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"450.111888ms","start":"2026-05-05T02:32:18.207768Z","end":"2026-05-05T02:32:18.65788Z","steps":["trace[1616876629] 'process raft request' (duration: 449.978684ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:32:18.65794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:32:18.207741Z","time spent":"450.16389ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T02:32:18.658068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.383578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T02:32:18.658093Z","caller":"traceutil/trace.go:171","msg":"trace[174244893] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1341; }","duration":"326.445028ms","start":"2026-05-05T02:32:18.331641Z","end":"2026-05-05T02:32:18.658086Z","steps":["trace[174244893] 'agreement among raft nodes before linearized reading' (duration: 326.375577ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:32:18.658113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:32:18.331621Z","time spent":"326.48773ms","remote":"127.0.0.1:50320","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-05-05T02:32:18.658063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.270561388s","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":"info","ts":"2026-05-05T02:32:18.658171Z","caller":"traceutil/trace.go:171","msg":"trace[2049555758] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1341; }","duration":"1.270723323s","start":"2026-05-05T02:32:17.387435Z","end":"2026-05-05T02:32:18.658159Z","steps":["trace[2049555758] 'agreement among raft nodes before linearized reading' (duration: 1.270497838s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:32:18.658226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:32:17.38739Z","time spent":"1.270825106s","remote":"127.0.0.1:50540","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-05-05T02:32:18.658234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.294697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:524"} {"level":"warn","ts":"2026-05-05T02:32:18.658238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.339230665s","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-05-05T02:32:18.658303Z","caller":"traceutil/trace.go:171","msg":"trace[478789925] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1341; }","duration":"381.372089ms","start":"2026-05-05T02:32:18.276916Z","end":"2026-05-05T02:32:18.658288Z","steps":["trace[478789925] 'agreement among raft nodes before linearized reading' (duration: 381.172684ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:32:18.658341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:32:18.276858Z","time spent":"381.474322ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":547,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-05T02:32:18.658334Z","caller":"traceutil/trace.go:171","msg":"trace[810749446] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1341; }","duration":"1.339372189s","start":"2026-05-05T02:32:17.31894Z","end":"2026-05-05T02:32:18.658312Z","steps":["trace[810749446] 'agreement among raft nodes before linearized reading' (duration: 1.339207675s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:32:18.658376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.618566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"warn","ts":"2026-05-05T02:32:18.658399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:32:17.318917Z","time spent":"1.339462931s","remote":"127.0.0.1:50346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"info","ts":"2026-05-05T02:32:18.65842Z","caller":"traceutil/trace.go:171","msg":"trace[166794282] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1341; }","duration":"626.701298ms","start":"2026-05-05T02:32:18.031709Z","end":"2026-05-05T02:32:18.65841Z","steps":["trace[166794282] 'agreement among raft nodes before linearized reading' (duration: 626.554135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:32:18.658573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:32:18.031686Z","time spent":"626.869853ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-05T02:32:37.037247Z","caller":"traceutil/trace.go:171","msg":"trace[1451048903] transaction","detail":"{read_only:false; response_revision:1423; number_of_response:1; }","duration":"152.543285ms","start":"2026-05-05T02:32:36.884668Z","end":"2026-05-05T02:32:37.037211Z","steps":["trace[1451048903] 'process raft request' (duration: 119.437893ms)","trace[1451048903] 'compare' (duration: 32.893787ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:32:37.037354Z","caller":"traceutil/trace.go:171","msg":"trace[1658651159] transaction","detail":"{read_only:false; response_revision:1426; number_of_response:1; }","duration":"149.450768ms","start":"2026-05-05T02:32:36.887867Z","end":"2026-05-05T02:32:37.037318Z","steps":["trace[1658651159] 'process raft request' (duration: 149.375666ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:32:37.03751Z","caller":"traceutil/trace.go:171","msg":"trace[586530448] transaction","detail":"{read_only:false; response_revision:1424; number_of_response:1; }","duration":"151.92737ms","start":"2026-05-05T02:32:36.885565Z","end":"2026-05-05T02:32:37.037492Z","steps":["trace[586530448] 'process raft request' (duration: 151.596552ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:32:37.037629Z","caller":"traceutil/trace.go:171","msg":"trace[1950660675] transaction","detail":"{read_only:false; response_revision:1425; number_of_response:1; }","duration":"151.598742ms","start":"2026-05-05T02:32:36.885913Z","end":"2026-05-05T02:32:37.037511Z","steps":["trace[1950660675] 'process raft request' (duration: 151.290334ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:32:43.315787Z","caller":"traceutil/trace.go:171","msg":"trace[611225273] transaction","detail":"{read_only:false; response_revision:1452; number_of_response:1; }","duration":"168.732583ms","start":"2026-05-05T02:32:43.147006Z","end":"2026-05-05T02:32:43.315738Z","steps":["trace[611225273] 'process raft request' (duration: 71.278643ms)","trace[611225273] 'compare' (duration: 96.998928ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:32:43.315829Z","caller":"traceutil/trace.go:171","msg":"trace[851493688] transaction","detail":"{read_only:false; response_revision:1454; number_of_response:1; }","duration":"167.505391ms","start":"2026-05-05T02:32:43.148289Z","end":"2026-05-05T02:32:43.315795Z","steps":["trace[851493688] 'process raft request' (duration: 167.43248ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:32:43.316117Z","caller":"traceutil/trace.go:171","msg":"trace[1754825235] transaction","detail":"{read_only:false; response_revision:1453; number_of_response:1; }","duration":"167.915772ms","start":"2026-05-05T02:32:43.148192Z","end":"2026-05-05T02:32:43.316107Z","steps":["trace[1754825235] 'process raft request' (duration: 167.335647ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:32:43.342577Z","caller":"traceutil/trace.go:171","msg":"trace[1275021606] transaction","detail":"{read_only:false; response_revision:1455; number_of_response:1; }","duration":"121.465024ms","start":"2026-05-05T02:32:43.221097Z","end":"2026-05-05T02:32:43.342562Z","steps":["trace[1275021606] 'process raft request' (duration: 121.115946ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:33:09.047796Z","caller":"traceutil/trace.go:171","msg":"trace[958939875] transaction","detail":"{read_only:false; response_revision:1568; number_of_response:1; }","duration":"100.027327ms","start":"2026-05-05T02:33:08.947735Z","end":"2026-05-05T02:33:09.047762Z","steps":["trace[958939875] 'process raft request' (duration: 99.778021ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:35:23.063513Z","caller":"traceutil/trace.go:171","msg":"trace[1607569148] transaction","detail":"{read_only:false; response_revision:2207; number_of_response:1; }","duration":"232.274924ms","start":"2026-05-05T02:35:22.831215Z","end":"2026-05-05T02:35:23.06349Z","steps":["trace[1607569148] 'process raft request' (duration: 232.057379ms)"],"step_count":1}