{"level":"warn","ts":"2026-08-15T02:30:18.162744Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-15T02:30:18.162863Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.174: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.174:2380","--initial-cluster=instance=https://199.19.213.174:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.174:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.174: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-08-15T02:30:18.162937Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-15T02:30:18.162947Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.174:2380"]} {"level":"info","ts":"2026-08-15T02:30:18.162967Z","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-08-15T02:30:18.163631Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.174:2379"]} {"level":"info","ts":"2026-08-15T02:30:18.163745Z","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.174:2380"],"listen-peer-urls":["https://199.19.213.174:2380"],"advertise-client-urls":["https://199.19.213.174:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.174:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.174: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-08-15T02:30:18.168167Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.149993ms"} {"level":"info","ts":"2026-08-15T02:30:18.176565Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b3617fc4a93ad58b","cluster-id":"2f224b65169306de"} {"level":"info","ts":"2026-08-15T02:30:18.17667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3617fc4a93ad58b switched to configuration voters=()"} {"level":"info","ts":"2026-08-15T02:30:18.176697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3617fc4a93ad58b became follower at term 0"} {"level":"info","ts":"2026-08-15T02:30:18.176728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b3617fc4a93ad58b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-15T02:30:18.176748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3617fc4a93ad58b became follower at term 1"} {"level":"info","ts":"2026-08-15T02:30:18.176804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3617fc4a93ad58b switched to configuration voters=(12925752888159557003)"} {"level":"warn","ts":"2026-08-15T02:30:18.190776Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-15T02:30:18.192932Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-15T02:30:18.194218Z","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-08-15T02:30:18.197008Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b3617fc4a93ad58b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-15T02:30:18.197442Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b3617fc4a93ad58b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-15T02:30:18.197591Z","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-08-15T02:30:18.197646Z","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-08-15T02:30:18.197656Z","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-08-15T02:30:18.198269Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3617fc4a93ad58b switched to configuration voters=(12925752888159557003)"} {"level":"info","ts":"2026-08-15T02:30:18.204539Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2f224b65169306de","local-member-id":"b3617fc4a93ad58b","added-peer-id":"b3617fc4a93ad58b","added-peer-peer-urls":["https://199.19.213.174:2380"]} {"level":"info","ts":"2026-08-15T02:30:18.205112Z","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-08-15T02:30:18.205434Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b3617fc4a93ad58b","initial-advertise-peer-urls":["https://199.19.213.174:2380"],"listen-peer-urls":["https://199.19.213.174:2380"],"advertise-client-urls":["https://199.19.213.174:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.174:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-15T02:30:18.205488Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-15T02:30:18.205507Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.174:2380"} {"level":"info","ts":"2026-08-15T02:30:18.205623Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.174:2380"} {"level":"info","ts":"2026-08-15T02:30:18.577902Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3617fc4a93ad58b is starting a new election at term 1"} {"level":"info","ts":"2026-08-15T02:30:18.577987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3617fc4a93ad58b became pre-candidate at term 1"} {"level":"info","ts":"2026-08-15T02:30:18.578017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3617fc4a93ad58b received MsgPreVoteResp from b3617fc4a93ad58b at term 1"} {"level":"info","ts":"2026-08-15T02:30:18.578049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3617fc4a93ad58b became candidate at term 2"} {"level":"info","ts":"2026-08-15T02:30:18.578091Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3617fc4a93ad58b received MsgVoteResp from b3617fc4a93ad58b at term 2"} {"level":"info","ts":"2026-08-15T02:30:18.578114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3617fc4a93ad58b became leader at term 2"} {"level":"info","ts":"2026-08-15T02:30:18.578134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b3617fc4a93ad58b elected leader b3617fc4a93ad58b at term 2"} {"level":"info","ts":"2026-08-15T02:30:18.578917Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-15T02:30:18.579699Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b3617fc4a93ad58b","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.174:2379]}","request-path":"/0/members/b3617fc4a93ad58b/attributes","cluster-id":"2f224b65169306de","publish-timeout":"7s"} {"level":"info","ts":"2026-08-15T02:30:18.579864Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-15T02:30:18.58006Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-15T02:30:18.58011Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2f224b65169306de","local-member-id":"b3617fc4a93ad58b","cluster-version":"3.5"} {"level":"info","ts":"2026-08-15T02:30:18.580326Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-15T02:30:18.580361Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-15T02:30:18.583346Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.174:2379"} {"level":"info","ts":"2026-08-15T02:30:18.583916Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-15T02:30:18.585176Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-15T02:30:18.585209Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-08-15T02:30:35.476726Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15387568487685375062,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-15T02:30:35.590687Z","caller":"traceutil/trace.go:171","msg":"trace[1658552795] linearizableReadLoop","detail":"{readStateIndex:383; appliedIndex:382; }","duration":"614.105299ms","start":"2026-08-15T02:30:34.976553Z","end":"2026-08-15T02:30:35.590658Z","steps":["trace[1658552795] 'read index received' (duration: 613.864027ms)","trace[1658552795] 'applied index is now lower than readState.Index' (duration: 240.162µs)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:30:35.59083Z","caller":"traceutil/trace.go:171","msg":"trace[1206069682] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"709.809456ms","start":"2026-08-15T02:30:34.880984Z","end":"2026-08-15T02:30:35.590793Z","steps":["trace[1206069682] 'process raft request' (duration: 709.496253ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:30:35.59147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.378041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-15T02:30:35.591523Z","caller":"traceutil/trace.go:171","msg":"trace[1685525612] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:369; }","duration":"615.008477ms","start":"2026-08-15T02:30:34.976506Z","end":"2026-08-15T02:30:35.591514Z","steps":["trace[1685525612] 'agreement among raft nodes before linearized reading' (duration: 614.329311ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:30:35.591542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:30:34.976484Z","time spent":"615.051757ms","remote":"127.0.0.1:50374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-15T02:30:35.591972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:30:34.880972Z","time spent":"710.001227ms","remote":"127.0.0.1:50278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6950,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T02:30:35.947346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.006156ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-15T02:30:35.947457Z","caller":"traceutil/trace.go:171","msg":"trace[869010259] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"350.417927ms","start":"2026-08-15T02:30:35.597022Z","end":"2026-08-15T02:30:35.94744Z","steps":["trace[869010259] 'process raft request' (duration: 58.113278ms)","trace[869010259] 'compare' (duration: 291.912146ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:30:35.947835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:30:35.597007Z","time spent":"350.724299ms","remote":"127.0.0.1:50374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T02:30:36.521795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.564148ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-15T02:30:36.522138Z","caller":"traceutil/trace.go:171","msg":"trace[672788385] linearizableReadLoop","detail":"{readStateIndex:387; appliedIndex:384; }","duration":"300.6521ms","start":"2026-08-15T02:30:36.221458Z","end":"2026-08-15T02:30:36.52211Z","steps":["trace[672788385] 'read index received' (duration: 50.851µs)","trace[672788385] 'applied index is now lower than readState.Index' (duration: 300.599649ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:30:36.522107Z","caller":"traceutil/trace.go:171","msg":"trace[96429710] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"924.838897ms","start":"2026-08-15T02:30:35.597213Z","end":"2026-08-15T02:30:36.522052Z","steps":["trace[96429710] 'process raft request' (duration: 480.891986ms)","trace[96429710] 'compare' (duration: 443.330756ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:30:36.522245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.789531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-08-15T02:30:36.522283Z","caller":"traceutil/trace.go:171","msg":"trace[2000871377] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:373; }","duration":"300.827722ms","start":"2026-08-15T02:30:36.221444Z","end":"2026-08-15T02:30:36.522272Z","steps":["trace[2000871377] 'agreement among raft nodes before linearized reading' (duration: 300.750231ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:30:36.522326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:30:36.221394Z","time spent":"300.922053ms","remote":"127.0.0.1:50374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-15T02:30:36.522354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:30:35.597169Z","time spent":"925.057428ms","remote":"127.0.0.1:50374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-15T02:30:36.52255Z","caller":"traceutil/trace.go:171","msg":"trace[995407655] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"901.05664ms","start":"2026-08-15T02:30:35.62148Z","end":"2026-08-15T02:30:36.522537Z","steps":["trace[995407655] 'process raft request' (duration: 900.562526ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:30:36.522529Z","caller":"traceutil/trace.go:171","msg":"trace[1059682239] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"919.971398ms","start":"2026-08-15T02:30:35.602522Z","end":"2026-08-15T02:30:36.522493Z","steps":["trace[1059682239] 'process raft request' (duration: 919.441624ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:30:36.522625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:30:35.621456Z","time spent":"901.13179ms","remote":"127.0.0.1:50374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T02:30:36.522686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:30:35.602502Z","time spent":"920.102189ms","remote":"127.0.0.1:50278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6740,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-15T02:30:37.154121Z","caller":"traceutil/trace.go:171","msg":"trace[840714512] linearizableReadLoop","detail":"{readStateIndex:388; appliedIndex:387; }","duration":"476.711578ms","start":"2026-08-15T02:30:36.677377Z","end":"2026-08-15T02:30:37.154089Z","steps":["trace[840714512] 'read index received' (duration: 390.049966ms)","trace[840714512] 'applied index is now lower than readState.Index' (duration: 86.660532ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:30:37.154292Z","caller":"traceutil/trace.go:171","msg":"trace[834825125] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"626.006795ms","start":"2026-08-15T02:30:36.528242Z","end":"2026-08-15T02:30:37.154248Z","steps":["trace[834825125] 'process raft request' (duration: 539.073009ms)","trace[834825125] 'compare' (duration: 86.615212ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:30:37.154388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.171434ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:30:37.154484Z","caller":"traceutil/trace.go:171","msg":"trace[1246834214] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:374; }","duration":"282.267145ms","start":"2026-08-15T02:30:36.872206Z","end":"2026-08-15T02:30:37.154473Z","steps":["trace[1246834214] 'agreement among raft nodes before linearized reading' (duration: 282.158124ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:30:37.154508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:30:36.528218Z","time spent":"626.163206ms","remote":"127.0.0.1:50374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T02:30:37.154336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.92796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:30:37.154658Z","caller":"traceutil/trace.go:171","msg":"trace[1162357662] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:374; }","duration":"477.298963ms","start":"2026-08-15T02:30:36.677322Z","end":"2026-08-15T02:30:37.154621Z","steps":["trace[1162357662] 'agreement among raft nodes before linearized reading' (duration: 476.94014ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:30:37.154762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:30:36.677299Z","time spent":"477.431383ms","remote":"127.0.0.1:50100","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-08-15T02:30:38.638354Z","caller":"traceutil/trace.go:171","msg":"trace[1758945498] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"106.84154ms","start":"2026-08-15T02:30:38.531485Z","end":"2026-08-15T02:30:38.638326Z","steps":["trace[1758945498] 'process raft request' (duration: 98.090501ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:30:56.382995Z","caller":"traceutil/trace.go:171","msg":"trace[652270664] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"345.557657ms","start":"2026-08-15T02:30:56.037392Z","end":"2026-08-15T02:30:56.38295Z","steps":["trace[652270664] 'process raft request' (duration: 345.260545ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:30:56.383221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:30:56.037376Z","time spent":"345.731999ms","remote":"127.0.0.1:50152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-15T02:30:56.504107Z","caller":"traceutil/trace.go:171","msg":"trace[693033145] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"465.474143ms","start":"2026-08-15T02:30:56.038605Z","end":"2026-08-15T02:30:56.504079Z","steps":["trace[693033145] 'process raft request' (duration: 465.277431ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:30:56.50425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:30:56.038588Z","time spent":"465.595513ms","remote":"127.0.0.1:50152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-15T02:30:56.504292Z","caller":"traceutil/trace.go:171","msg":"trace[9105237] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"464.904138ms","start":"2026-08-15T02:30:56.039341Z","end":"2026-08-15T02:30:56.504245Z","steps":["trace[9105237] 'process raft request' (duration: 464.665396ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:30:56.504507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:30:56.039334Z","time spent":"465.051489ms","remote":"127.0.0.1:50152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-15T02:30:56.50512Z","caller":"traceutil/trace.go:171","msg":"trace[48746897] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"456.355542ms","start":"2026-08-15T02:30:56.048737Z","end":"2026-08-15T02:30:56.505093Z","steps":["trace[48746897] 'process raft request' (duration: 456.06274ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:30:56.505241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:30:56.048713Z","time spent":"456.472523ms","remote":"127.0.0.1:50152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-15T02:30:56.505562Z","caller":"traceutil/trace.go:171","msg":"trace[931491280] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"456.715634ms","start":"2026-08-15T02:30:56.048827Z","end":"2026-08-15T02:30:56.505543Z","steps":["trace[931491280] 'process raft request' (duration: 456.11597ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:30:56.505722Z","caller":"traceutil/trace.go:171","msg":"trace[953648090] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"435.491431ms","start":"2026-08-15T02:30:56.070217Z","end":"2026-08-15T02:30:56.505708Z","steps":["trace[953648090] 'process raft request' (duration: 435.082168ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:30:56.505754Z","caller":"traceutil/trace.go:171","msg":"trace[1186585050] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"424.180964ms","start":"2026-08-15T02:30:56.081516Z","end":"2026-08-15T02:30:56.505697Z","steps":["trace[1186585050] 'process raft request' (duration: 423.941872ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:30:56.505793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:30:56.070202Z","time spent":"435.562151ms","remote":"127.0.0.1:50152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-15T02:30:56.505894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:30:56.081496Z","time spent":"424.327794ms","remote":"127.0.0.1:50152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-15T02:30:56.505913Z","caller":"traceutil/trace.go:171","msg":"trace[2118175965] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"423.56843ms","start":"2026-08-15T02:30:56.082334Z","end":"2026-08-15T02:30:56.505902Z","steps":["trace[2118175965] 'process raft request' (duration: 423.210336ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:30:56.50596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:30:56.08232Z","time spent":"423.61721ms","remote":"127.0.0.1:50152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-15T02:30:56.506062Z","caller":"traceutil/trace.go:171","msg":"trace[703552105] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"414.781091ms","start":"2026-08-15T02:30:56.091266Z","end":"2026-08-15T02:30:56.506048Z","steps":["trace[703552105] 'process raft request' (duration: 414.384797ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:30:56.506024Z","caller":"traceutil/trace.go:171","msg":"trace[1529876993] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"414.743701ms","start":"2026-08-15T02:30:56.09121Z","end":"2026-08-15T02:30:56.505954Z","steps":["trace[1529876993] 'process raft request' (duration: 414.384898ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:30:56.506178Z","caller":"traceutil/trace.go:171","msg":"trace[605814282] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"405.376439ms","start":"2026-08-15T02:30:56.10079Z","end":"2026-08-15T02:30:56.506166Z","steps":["trace[605814282] 'process raft request' (duration: 405.011485ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:30:56.506158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:30:56.091233Z","time spent":"414.893543ms","remote":"127.0.0.1:50152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-15T02:30:56.50624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:30:56.100776Z","time spent":"405.438209ms","remote":"127.0.0.1:50152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-15T02:30:56.506294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:30:56.091164Z","time spent":"414.999164ms","remote":"127.0.0.1:50152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-15T02:30:56.506394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:30:56.04882Z","time spent":"456.789146ms","remote":"127.0.0.1:50152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-15T02:30:56.506484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.521266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-15T02:30:56.50636Z","caller":"traceutil/trace.go:171","msg":"trace[641218927] linearizableReadLoop","detail":"{readStateIndex:505; appliedIndex:500; }","duration":"437.321005ms","start":"2026-08-15T02:30:56.068951Z","end":"2026-08-15T02:30:56.506272Z","steps":["trace[641218927] 'read index received' (duration: 313.763251ms)","trace[641218927] 'applied index is now lower than readState.Index' (duration: 123.556644ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:30:56.506473Z","caller":"traceutil/trace.go:171","msg":"trace[1044664387] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"314.561938ms","start":"2026-08-15T02:30:56.191781Z","end":"2026-08-15T02:30:56.506343Z","steps":["trace[1044664387] 'process raft request' (duration: 314.366217ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:30:56.506543Z","caller":"traceutil/trace.go:171","msg":"trace[1468923208] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:493; }","duration":"437.615397ms","start":"2026-08-15T02:30:56.068917Z","end":"2026-08-15T02:30:56.506532Z","steps":["trace[1468923208] 'agreement among raft nodes before linearized reading' (duration: 437.472296ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:30:56.506762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:30:56.068905Z","time spent":"437.846659ms","remote":"127.0.0.1:50374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-15T02:30:56.506766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:30:56.191761Z","time spent":"314.920321ms","remote":"127.0.0.1:50152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-15T02:30:56.708473Z","caller":"traceutil/trace.go:171","msg":"trace[1463748966] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"316.193161ms","start":"2026-08-15T02:30:56.392256Z","end":"2026-08-15T02:30:56.708449Z","steps":["trace[1463748966] 'process raft request' (duration: 316.093ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:30:56.708611Z","caller":"traceutil/trace.go:171","msg":"trace[1168725696] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"318.74644ms","start":"2026-08-15T02:30:56.389838Z","end":"2026-08-15T02:30:56.708585Z","steps":["trace[1168725696] 'process raft request' (duration: 232.521904ms)","trace[1168725696] 'compare' (duration: 85.853433ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:30:56.708834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:30:56.392241Z","time spent":"316.378442ms","remote":"127.0.0.1:50152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T02:30:56.708819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:30:56.38981Z","time spent":"318.867392ms","remote":"127.0.0.1:50640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-15T02:30:56.711972Z","caller":"traceutil/trace.go:171","msg":"trace[923469339] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"198.480952ms","start":"2026-08-15T02:30:56.51348Z","end":"2026-08-15T02:30:56.711961Z","steps":["trace[923469339] 'process raft request' (duration: 198.042689ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:30:56.712119Z","caller":"traceutil/trace.go:171","msg":"trace[1470591403] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"202.243071ms","start":"2026-08-15T02:30:56.509869Z","end":"2026-08-15T02:30:56.712112Z","steps":["trace[1470591403] 'process raft request' (duration: 201.561856ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:30:56.712388Z","caller":"traceutil/trace.go:171","msg":"trace[766322727] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"177.425879ms","start":"2026-08-15T02:30:56.534955Z","end":"2026-08-15T02:30:56.712381Z","steps":["trace[766322727] 'process raft request' (duration: 177.016376ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:30:56.712471Z","caller":"traceutil/trace.go:171","msg":"trace[2075258658] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"198.509123ms","start":"2026-08-15T02:30:56.513956Z","end":"2026-08-15T02:30:56.712465Z","steps":["trace[2075258658] 'process raft request' (duration: 197.626236ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:30:56.712543Z","caller":"traceutil/trace.go:171","msg":"trace[1592451245] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"172.819363ms","start":"2026-08-15T02:30:56.539688Z","end":"2026-08-15T02:30:56.712508Z","steps":["trace[1592451245] 'process raft request' (duration: 172.356089ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:30:56.712587Z","caller":"traceutil/trace.go:171","msg":"trace[1674794283] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"188.511224ms","start":"2026-08-15T02:30:56.524071Z","end":"2026-08-15T02:30:56.712582Z","steps":["trace[1674794283] 'process raft request' (duration: 187.752919ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:30:56.712661Z","caller":"traceutil/trace.go:171","msg":"trace[775675666] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"187.318326ms","start":"2026-08-15T02:30:56.525336Z","end":"2026-08-15T02:30:56.712654Z","steps":["trace[775675666] 'process raft request' (duration: 186.52595ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:30:56.71271Z","caller":"traceutil/trace.go:171","msg":"trace[1558731370] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"179.995679ms","start":"2026-08-15T02:30:56.532709Z","end":"2026-08-15T02:30:56.712705Z","steps":["trace[1558731370] 'process raft request' (duration: 179.200693ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:30:56.712569Z","caller":"traceutil/trace.go:171","msg":"trace[421716414] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"188.534884ms","start":"2026-08-15T02:30:56.524027Z","end":"2026-08-15T02:30:56.712562Z","steps":["trace[421716414] 'process raft request' (duration: 187.760639ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:30:56.713122Z","caller":"traceutil/trace.go:171","msg":"trace[1664616891] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"139.602136ms","start":"2026-08-15T02:30:56.573494Z","end":"2026-08-15T02:30:56.713096Z","steps":["trace[1664616891] 'process raft request' (duration: 138.655969ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:30:56.713327Z","caller":"traceutil/trace.go:171","msg":"trace[259448593] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"164.092927ms","start":"2026-08-15T02:30:56.549214Z","end":"2026-08-15T02:30:56.713307Z","steps":["trace[259448593] 'process raft request' (duration: 162.882167ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:30:59.431269Z","caller":"traceutil/trace.go:171","msg":"trace[1825852379] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"100.325855ms","start":"2026-08-15T02:30:59.330923Z","end":"2026-08-15T02:30:59.431249Z","steps":["trace[1825852379] 'process raft request' (duration: 100.188075ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:31:03.253102Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15387568487685375530,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-15T02:31:03.26303Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.307405925s","expected-duration":"1s"} {"level":"info","ts":"2026-08-15T02:31:03.265539Z","caller":"traceutil/trace.go:171","msg":"trace[253745632] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:602; }","duration":"512.634781ms","start":"2026-08-15T02:31:02.752882Z","end":"2026-08-15T02:31:03.265517Z","steps":["trace[253745632] 'read index received' (duration: 510.336518ms)","trace[253745632] 'applied index is now lower than readState.Index' (duration: 2.297173ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:31:03.265633Z","caller":"traceutil/trace.go:171","msg":"trace[992247420] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"1.311296653s","start":"2026-08-15T02:31:01.954317Z","end":"2026-08-15T02:31:03.265614Z","steps":["trace[992247420] 'process raft request' (duration: 1.309426265s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:31:03.266359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:31:01.954298Z","time spent":"1.31138264s","remote":"127.0.0.1:50196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036225,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T02:31:03.266506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.113088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-15T02:31:03.266701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.81722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-15T02:31:03.267014Z","caller":"traceutil/trace.go:171","msg":"trace[928517329] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:581; }","duration":"486.63485ms","start":"2026-08-15T02:31:02.780335Z","end":"2026-08-15T02:31:03.26697Z","steps":["trace[928517329] 'agreement among raft nodes before linearized reading' (duration: 485.566596ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:31:03.267084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:31:02.780323Z","time spent":"486.744416ms","remote":"127.0.0.1:50374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-15T02:31:03.267107Z","caller":"traceutil/trace.go:171","msg":"trace[566917898] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:581; }","duration":"514.225157ms","start":"2026-08-15T02:31:02.75283Z","end":"2026-08-15T02:31:03.267055Z","steps":["trace[566917898] 'agreement among raft nodes before linearized reading' (duration: 512.788476ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:31:03.26683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.779191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-15T02:31:03.267171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:31:02.752813Z","time spent":"514.338803ms","remote":"127.0.0.1:50374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-15T02:31:03.267332Z","caller":"traceutil/trace.go:171","msg":"trace[1156981687] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:581; }","duration":"488.160737ms","start":"2026-08-15T02:31:02.779006Z","end":"2026-08-15T02:31:03.267167Z","steps":["trace[1156981687] 'agreement among raft nodes before linearized reading' (duration: 486.689107ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:31:03.268332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:31:02.778984Z","time spent":"488.426489ms","remote":"127.0.0.1:50374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-15T02:31:03.550249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.633748ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-15T02:31:03.550539Z","caller":"traceutil/trace.go:171","msg":"trace[1736177986] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:604; }","duration":"103.848352ms","start":"2026-08-15T02:31:03.446667Z","end":"2026-08-15T02:31:03.550516Z","steps":["trace[1736177986] 'read index received' (duration: 87.267µs)","trace[1736177986] 'applied index is now lower than readState.Index' (duration: 103.759695ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:31:03.550521Z","caller":"traceutil/trace.go:171","msg":"trace[336852467] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"276.254863ms","start":"2026-08-15T02:31:03.274086Z","end":"2026-08-15T02:31:03.550469Z","steps":["trace[336852467] 'process raft request' (duration: 82.294396ms)","trace[336852467] 'compare' (duration: 193.470593ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:31:03.550582Z","caller":"traceutil/trace.go:171","msg":"trace[1062765537] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"276.187635ms","start":"2026-08-15T02:31:03.274361Z","end":"2026-08-15T02:31:03.550549Z","steps":["trace[1062765537] 'process raft request' (duration: 276.020291ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:31:03.550767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.101184ms","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-08-15T02:31:03.550777Z","caller":"traceutil/trace.go:171","msg":"trace[688041081] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"225.009944ms","start":"2026-08-15T02:31:03.325757Z","end":"2026-08-15T02:31:03.550767Z","steps":["trace[688041081] 'process raft request' (duration: 224.699094ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:31:03.550816Z","caller":"traceutil/trace.go:171","msg":"trace[762078501] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:585; }","duration":"104.192921ms","start":"2026-08-15T02:31:03.446607Z","end":"2026-08-15T02:31:03.5508Z","steps":["trace[762078501] 'agreement among raft nodes before linearized reading' (duration: 103.976298ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:31:04.662911Z","caller":"traceutil/trace.go:171","msg":"trace[154893619] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"693.012453ms","start":"2026-08-15T02:31:03.969874Z","end":"2026-08-15T02:31:04.662887Z","steps":["trace[154893619] 'process raft request' (duration: 692.443652ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:31:04.663064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:31:03.96984Z","time spent":"693.145328ms","remote":"127.0.0.1:50196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-15T02:31:05.040278Z","caller":"traceutil/trace.go:171","msg":"trace[3725836] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:611; }","duration":"603.155438ms","start":"2026-08-15T02:31:04.437096Z","end":"2026-08-15T02:31:05.040251Z","steps":["trace[3725836] 'read index received' (duration: 225.097839ms)","trace[3725836] 'applied index is now lower than readState.Index' (duration: 378.055989ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:31:05.040333Z","caller":"traceutil/trace.go:171","msg":"trace[343031269] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"862.11473ms","start":"2026-08-15T02:31:04.178187Z","end":"2026-08-15T02:31:05.040302Z","steps":["trace[343031269] 'process raft request' (duration: 861.915656ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:31:05.040597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:31:04.178162Z","time spent":"862.350992ms","remote":"127.0.0.1:50374","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-08-15T02:31:05.040618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.502796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-l5d5b\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-15T02:31:05.040706Z","caller":"traceutil/trace.go:171","msg":"trace[819990680] range","detail":"{range_begin:/registry/pods/kube-system/cilium-l5d5b; range_end:; response_count:1; response_revision:591; }","duration":"603.645682ms","start":"2026-08-15T02:31:04.437042Z","end":"2026-08-15T02:31:05.040688Z","steps":["trace[819990680] 'agreement among raft nodes before linearized reading' (duration: 603.337252ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:31:05.040757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:31:04.437016Z","time spent":"603.732049ms","remote":"127.0.0.1:50278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-l5d5b\" "} {"level":"warn","ts":"2026-08-15T02:31:05.540539Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15387568487685375551,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-15T02:31:06.604073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.975658ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-15T02:31:06.604283Z","caller":"traceutil/trace.go:171","msg":"trace[129218069] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:613; }","duration":"1.563821199s","start":"2026-08-15T02:31:05.040446Z","end":"2026-08-15T02:31:06.604268Z","steps":["trace[129218069] 'read index received' (duration: 930.611619ms)","trace[129218069] 'applied index is now lower than readState.Index' (duration: 633.20865ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:31:06.604436Z","caller":"traceutil/trace.go:171","msg":"trace[1363386900] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"1.889133842s","start":"2026-08-15T02:31:04.715283Z","end":"2026-08-15T02:31:06.604417Z","steps":["trace[1363386900] 'process raft request' (duration: 1.255757875s)","trace[1363386900] 'compare' (duration: 632.795654ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:31:06.604512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:31:04.715262Z","time spent":"1.889215419s","remote":"127.0.0.1:50462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-15T02:31:06.604853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.050066953s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-15T02:31:06.604841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.925585219s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:31:06.604892Z","caller":"traceutil/trace.go:171","msg":"trace[982623942] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:593; }","duration":"1.050148831s","start":"2026-08-15T02:31:05.554732Z","end":"2026-08-15T02:31:06.604881Z","steps":["trace[982623942] 'agreement among raft nodes before linearized reading' (duration: 1.050068953s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:31:06.604927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:31:05.554712Z","time spent":"1.050204738s","remote":"127.0.0.1:50374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-15T02:31:06.604924Z","caller":"traceutil/trace.go:171","msg":"trace[50543847] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:593; }","duration":"1.925705555s","start":"2026-08-15T02:31:04.679195Z","end":"2026-08-15T02:31:06.604901Z","steps":["trace[50543847] 'agreement among raft nodes before linearized reading' (duration: 1.925521611s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:31:06.604977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:31:04.679176Z","time spent":"1.925790732s","remote":"127.0.0.1:50100","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-08-15T02:31:06.604864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.040794205s","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-08-15T02:31:06.605115Z","caller":"traceutil/trace.go:171","msg":"trace[143197490] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"1.551433412s","start":"2026-08-15T02:31:05.053665Z","end":"2026-08-15T02:31:06.605099Z","steps":["trace[143197490] 'process raft request' (duration: 1.550505311s)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:31:06.60518Z","caller":"traceutil/trace.go:171","msg":"trace[1247221102] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:593; }","duration":"1.041101964s","start":"2026-08-15T02:31:05.564031Z","end":"2026-08-15T02:31:06.605133Z","steps":["trace[1247221102] 'agreement among raft nodes before linearized reading' (duration: 1.040767625s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:31:06.605207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:31:05.053648Z","time spent":"1.5515019s","remote":"127.0.0.1:50278","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-08-15T02:31:06.605299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:31:05.564019Z","time spent":"1.041230981s","remote":"127.0.0.1:50374","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-08-15T02:31:06.605628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.271942593s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-15T02:31:06.605668Z","caller":"traceutil/trace.go:171","msg":"trace[1843148409] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:593; }","duration":"1.27202108s","start":"2026-08-15T02:31:05.333637Z","end":"2026-08-15T02:31:06.605658Z","steps":["trace[1843148409] 'agreement among raft nodes before linearized reading' (duration: 1.271087981s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:31:06.6057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:31:05.333618Z","time spent":"1.272074998s","remote":"127.0.0.1:50374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-15T02:31:06.606083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.051781057s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-15T02:31:06.607254Z","caller":"traceutil/trace.go:171","msg":"trace[564851889] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:593; }","duration":"1.05326757s","start":"2026-08-15T02:31:05.55397Z","end":"2026-08-15T02:31:06.607238Z","steps":["trace[564851889] 'agreement among raft nodes before linearized reading' (duration: 1.050899576s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:31:06.607378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:31:05.553952Z","time spent":"1.053413505s","remote":"127.0.0.1:50374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-15T02:31:08.038948Z","caller":"traceutil/trace.go:171","msg":"trace[1500129668] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:628; }","duration":"362.499841ms","start":"2026-08-15T02:31:07.676427Z","end":"2026-08-15T02:31:08.038927Z","steps":["trace[1500129668] 'read index received' (duration: 362.330266ms)","trace[1500129668] 'applied index is now lower than readState.Index' (duration: 168.445µs)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:31:08.03909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.683795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:31:08.039139Z","caller":"traceutil/trace.go:171","msg":"trace[1732520827] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:606; }","duration":"362.793941ms","start":"2026-08-15T02:31:07.676334Z","end":"2026-08-15T02:31:08.039128Z","steps":["trace[1732520827] 'agreement among raft nodes before linearized reading' (duration: 362.679345ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:31:08.039189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:31:07.676315Z","time spent":"362.86703ms","remote":"127.0.0.1:50100","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-08-15T02:31:08.039206Z","caller":"traceutil/trace.go:171","msg":"trace[417646401] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"441.693877ms","start":"2026-08-15T02:31:07.597474Z","end":"2026-08-15T02:31:08.039168Z","steps":["trace[417646401] 'process raft request' (duration: 441.298969ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:31:08.039361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:31:07.597464Z","time spent":"441.824233ms","remote":"127.0.0.1:50278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-15T02:31:22.521689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.580252ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-15T02:31:22.52189Z","caller":"traceutil/trace.go:171","msg":"trace[456161848] linearizableReadLoop","detail":"{readStateIndex:785; appliedIndex:784; }","duration":"690.119ms","start":"2026-08-15T02:31:21.831742Z","end":"2026-08-15T02:31:22.521861Z","steps":["trace[456161848] 'read index received' (duration: 89.060198ms)","trace[456161848] 'applied index is now lower than readState.Index' (duration: 601.055342ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:31:22.522009Z","caller":"traceutil/trace.go:171","msg":"trace[647969918] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"812.370291ms","start":"2026-08-15T02:31:21.709609Z","end":"2026-08-15T02:31:22.521979Z","steps":["trace[647969918] 'process raft request' (duration: 211.358358ms)","trace[647969918] 'compare' (duration: 600.254961ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:31:22.522127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.353104ms","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":"warn","ts":"2026-08-15T02:31:22.522151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:31:21.709586Z","time spent":"812.489567ms","remote":"127.0.0.1:50140","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-08-15T02:31:22.522187Z","caller":"traceutil/trace.go:171","msg":"trace[891433579] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:758; }","duration":"690.457441ms","start":"2026-08-15T02:31:21.831716Z","end":"2026-08-15T02:31:22.522173Z","steps":["trace[891433579] 'agreement among raft nodes before linearized reading' (duration: 690.267726ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:31:22.522233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:31:21.831704Z","time spent":"690.52022ms","remote":"127.0.0.1:50606","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-08-15T02:31:22.522286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.556064ms","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-08-15T02:31:22.522331Z","caller":"traceutil/trace.go:171","msg":"trace[94673154] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:758; }","duration":"192.651521ms","start":"2026-08-15T02:31:22.329665Z","end":"2026-08-15T02:31:22.522317Z","steps":["trace[94673154] 'agreement among raft nodes before linearized reading' (duration: 192.356219ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:31:30.819518Z","caller":"traceutil/trace.go:171","msg":"trace[1685695920] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"170.513331ms","start":"2026-08-15T02:31:30.648969Z","end":"2026-08-15T02:31:30.819483Z","steps":["trace[1685695920] 'process raft request' (duration: 170.195097ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:31:31.053383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.085537ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-15T02:31:31.053638Z","caller":"traceutil/trace.go:171","msg":"trace[379404931] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"229.943983ms","start":"2026-08-15T02:31:30.823664Z","end":"2026-08-15T02:31:31.053608Z","steps":["trace[379404931] 'process raft request' (duration: 59.498732ms)","trace[379404931] 'compare' (duration: 169.895192ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:31:31.058119Z","caller":"traceutil/trace.go:171","msg":"trace[1100644640] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"227.077754ms","start":"2026-08-15T02:31:30.831027Z","end":"2026-08-15T02:31:31.058105Z","steps":["trace[1100644640] 'process raft request' (duration: 227.041945ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:31:31.05851Z","caller":"traceutil/trace.go:171","msg":"trace[1840753751] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"227.74527ms","start":"2026-08-15T02:31:30.83075Z","end":"2026-08-15T02:31:31.058495Z","steps":["trace[1840753751] 'process raft request' (duration: 227.212031ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:31:31.058677Z","caller":"traceutil/trace.go:171","msg":"trace[1704116448] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"227.71865ms","start":"2026-08-15T02:31:30.83095Z","end":"2026-08-15T02:31:31.058669Z","steps":["trace[1704116448] 'process raft request' (duration: 227.083114ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:31:32.054058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.300903ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-08-15T02:31:32.054349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:31:31.707421Z","time spent":"346.922195ms","remote":"127.0.0.1:50140","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-15T02:31:45.403119Z","caller":"traceutil/trace.go:171","msg":"trace[1905341120] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"197.319674ms","start":"2026-08-15T02:31:45.205768Z","end":"2026-08-15T02:31:45.403088Z","steps":["trace[1905341120] 'process raft request' (duration: 119.198874ms)","trace[1905341120] 'compare' (duration: 78.039582ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:33:21.121967Z","caller":"traceutil/trace.go:171","msg":"trace[951103340] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"279.892506ms","start":"2026-08-15T02:33:20.842029Z","end":"2026-08-15T02:33:21.121922Z","steps":["trace[951103340] 'process raft request' (duration: 279.593606ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:33:21.280596Z","caller":"traceutil/trace.go:171","msg":"trace[1134137758] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"151.075823ms","start":"2026-08-15T02:33:21.12949Z","end":"2026-08-15T02:33:21.280566Z","steps":["trace[1134137758] 'process raft request' (duration: 140.723088ms)","trace[1134137758] 'compare' (duration: 10.235005ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:33:22.254827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.765498ms","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-08-15T02:33:22.254906Z","caller":"traceutil/trace.go:171","msg":"trace[104058544] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1316; }","duration":"480.885278ms","start":"2026-08-15T02:33:21.774003Z","end":"2026-08-15T02:33:22.254888Z","steps":["trace[104058544] 'range keys from in-memory index tree' (duration: 480.581548ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:33:22.254949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:33:21.77399Z","time spent":"480.950038ms","remote":"127.0.0.1:50374","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-08-15T02:33:22.255136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.159652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-15T02:33:22.255228Z","caller":"traceutil/trace.go:171","msg":"trace[349380370] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1316; }","duration":"456.29576ms","start":"2026-08-15T02:33:21.798912Z","end":"2026-08-15T02:33:22.255208Z","steps":["trace[349380370] 'range keys from in-memory index tree' (duration: 455.908732ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:33:22.255282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:33:21.798902Z","time spent":"456.37109ms","remote":"127.0.0.1:50374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-15T02:33:22.255438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.165106ms","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-08-15T02:33:22.255486Z","caller":"traceutil/trace.go:171","msg":"trace[639954914] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1316; }","duration":"591.237396ms","start":"2026-08-15T02:33:21.664239Z","end":"2026-08-15T02:33:22.255476Z","steps":["trace[639954914] 'range keys from in-memory index tree' (duration: 591.067506ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:33:22.255517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:33:21.664221Z","time spent":"591.288696ms","remote":"127.0.0.1:50374","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-08-15T02:33:22.255559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.916418ms","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-08-15T02:33:22.255713Z","caller":"traceutil/trace.go:171","msg":"trace[586385539] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1316; }","duration":"830.160348ms","start":"2026-08-15T02:33:21.425515Z","end":"2026-08-15T02:33:22.255675Z","steps":["trace[586385539] 'count revisions from in-memory index tree' (duration: 829.808669ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:33:22.255796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:33:21.425488Z","time spent":"830.286468ms","remote":"127.0.0.1:53980","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":"warn","ts":"2026-08-15T02:33:22.255828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.483559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.174\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-15T02:33:22.255866Z","caller":"traceutil/trace.go:171","msg":"trace[1584593165] range","detail":"{range_begin:/registry/masterleases/199.19.213.174; range_end:; response_count:1; response_revision:1316; }","duration":"544.550429ms","start":"2026-08-15T02:33:21.711304Z","end":"2026-08-15T02:33:22.255854Z","steps":["trace[1584593165] 'range keys from in-memory index tree' (duration: 544.276351ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:33:22.255905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:33:21.711287Z","time spent":"544.611489ms","remote":"127.0.0.1:50140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.174\" "} {"level":"warn","ts":"2026-08-15T02:33:22.256035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.131438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-15T02:33:22.255639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.056635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-15T02:33:22.255907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.912239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-15T02:33:22.256134Z","caller":"traceutil/trace.go:171","msg":"trace[1153248125] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1316; }","duration":"453.560984ms","start":"2026-08-15T02:33:21.802553Z","end":"2026-08-15T02:33:22.256114Z","steps":["trace[1153248125] 'range keys from in-memory index tree' (duration: 452.967965ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:33:22.256196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.635868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-15T02:33:22.256206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:33:21.802542Z","time spent":"453.647825ms","remote":"127.0.0.1:50374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-15T02:33:22.256236Z","caller":"traceutil/trace.go:171","msg":"trace[887671003] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1316; }","duration":"116.732498ms","start":"2026-08-15T02:33:22.139495Z","end":"2026-08-15T02:33:22.256227Z","steps":["trace[887671003] 'range keys from in-memory index tree' (duration: 116.532218ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:33:22.256083Z","caller":"traceutil/trace.go:171","msg":"trace[803519288] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1316; }","duration":"193.256147ms","start":"2026-08-15T02:33:22.062812Z","end":"2026-08-15T02:33:22.256068Z","steps":["trace[803519288] 'count revisions from in-memory index tree' (duration: 193.042557ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:33:22.256146Z","caller":"traceutil/trace.go:171","msg":"trace[2061391742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1316; }","duration":"275.182399ms","start":"2026-08-15T02:33:21.980952Z","end":"2026-08-15T02:33:22.256134Z","steps":["trace[2061391742] 'range keys from in-memory index tree' (duration: 274.71814ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:33:22.475523Z","caller":"traceutil/trace.go:171","msg":"trace[828456514] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"213.544301ms","start":"2026-08-15T02:33:22.261942Z","end":"2026-08-15T02:33:22.475486Z","steps":["trace[828456514] 'process raft request' (duration: 213.327022ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:33:22.494064Z","caller":"traceutil/trace.go:171","msg":"trace[592082855] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"230.291759ms","start":"2026-08-15T02:33:22.263752Z","end":"2026-08-15T02:33:22.494043Z","steps":["trace[592082855] 'process raft request' (duration: 230.17488ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:33:22.494061Z","caller":"traceutil/trace.go:171","msg":"trace[246685154] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"231.983506ms","start":"2026-08-15T02:33:22.262051Z","end":"2026-08-15T02:33:22.494034Z","steps":["trace[246685154] 'process raft request' (duration: 231.765467ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:33:22.4943Z","caller":"traceutil/trace.go:171","msg":"trace[1303639927] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"230.352299ms","start":"2026-08-15T02:33:22.263887Z","end":"2026-08-15T02:33:22.494239Z","steps":["trace[1303639927] 'process raft request' (duration: 230.112549ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:33:22.494301Z","caller":"traceutil/trace.go:171","msg":"trace[567313321] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"230.367689ms","start":"2026-08-15T02:33:22.263845Z","end":"2026-08-15T02:33:22.494213Z","steps":["trace[567313321] 'process raft request' (duration: 230.118999ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:33:22.658576Z","caller":"traceutil/trace.go:171","msg":"trace[363787786] linearizableReadLoop","detail":"{readStateIndex:1375; appliedIndex:1374; }","duration":"158.493403ms","start":"2026-08-15T02:33:22.499984Z","end":"2026-08-15T02:33:22.658477Z","steps":["trace[363787786] 'read index received' (duration: 79.063427ms)","trace[363787786] 'applied index is now lower than readState.Index' (duration: 79.428436ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:33:22.658897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.868442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:33:22.658978Z","caller":"traceutil/trace.go:171","msg":"trace[315819159] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1322; }","duration":"159.059912ms","start":"2026-08-15T02:33:22.499902Z","end":"2026-08-15T02:33:22.658962Z","steps":["trace[315819159] 'agreement among raft nodes before linearized reading' (duration: 158.794322ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:33:22.658654Z","caller":"traceutil/trace.go:171","msg":"trace[2043123437] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"162.169039ms","start":"2026-08-15T02:33:22.496334Z","end":"2026-08-15T02:33:22.658503Z","steps":["trace[2043123437] 'process raft request' (duration: 82.831493ms)","trace[2043123437] 'compare' (duration: 79.062146ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:33:23.433684Z","caller":"traceutil/trace.go:171","msg":"trace[1633514953] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"303.990543ms","start":"2026-08-15T02:33:23.129665Z","end":"2026-08-15T02:33:23.433655Z","steps":["trace[1633514953] 'process raft request' (duration: 303.828033ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:33:23.433864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:33:23.129631Z","time spent":"304.147472ms","remote":"127.0.0.1:50374","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-08-15T02:33:30.693129Z","caller":"traceutil/trace.go:171","msg":"trace[1597952959] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"105.724883ms","start":"2026-08-15T02:33:30.587379Z","end":"2026-08-15T02:33:30.693104Z","steps":["trace[1597952959] 'process raft request' (duration: 104.810134ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:33:30.693202Z","caller":"traceutil/trace.go:171","msg":"trace[1548615136] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"105.485123ms","start":"2026-08-15T02:33:30.587682Z","end":"2026-08-15T02:33:30.693167Z","steps":["trace[1548615136] 'process raft request' (duration: 105.370673ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:33:30.693291Z","caller":"traceutil/trace.go:171","msg":"trace[1125244607] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"105.511133ms","start":"2026-08-15T02:33:30.587767Z","end":"2026-08-15T02:33:30.693278Z","steps":["trace[1125244607] 'process raft request' (duration: 105.325653ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:33:30.693211Z","caller":"traceutil/trace.go:171","msg":"trace[535937186] linearizableReadLoop","detail":"{readStateIndex:1407; appliedIndex:1403; }","duration":"101.952746ms","start":"2026-08-15T02:33:30.59124Z","end":"2026-08-15T02:33:30.693193Z","steps":["trace[535937186] 'read index received' (duration: 100.962157ms)","trace[535937186] 'applied index is now lower than readState.Index' (duration: 989.459µs)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:33:30.693386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.130015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:auth-delegator\" ","response":"range_response_count:1 size:651"} {"level":"info","ts":"2026-08-15T02:33:30.69345Z","caller":"traceutil/trace.go:171","msg":"trace[732768206] range","detail":"{range_begin:/registry/clusterroles/system:auth-delegator; range_end:; response_count:1; response_revision:1353; }","duration":"102.240345ms","start":"2026-08-15T02:33:30.591196Z","end":"2026-08-15T02:33:30.693436Z","steps":["trace[732768206] 'agreement among raft nodes before linearized reading' (duration: 102.064486ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:33:30.693471Z","caller":"traceutil/trace.go:171","msg":"trace[1703543067] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"105.613182ms","start":"2026-08-15T02:33:30.587841Z","end":"2026-08-15T02:33:30.693454Z","steps":["trace[1703543067] 'process raft request' (duration: 105.300973ms)"],"step_count":1}