{"level":"warn","ts":"2026-05-30T01:44:33.559164Z","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-30T01:44:33.559274Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.162:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.162:2380","--initial-cluster=instance=https://199.204.45.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.162: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-30T01:44:33.559358Z","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-30T01:44:33.559372Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-05-30T01:44:33.559398Z","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-30T01:44:33.560136Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"]} {"level":"info","ts":"2026-05-30T01:44:33.560237Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.162: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-30T01:44:33.56469Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.228905ms"} {"level":"info","ts":"2026-05-30T01:44:33.569856Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2d372260bb4c37a","cluster-id":"52bb6ec15e8722ff"} {"level":"info","ts":"2026-05-30T01:44:33.569949Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=()"} {"level":"info","ts":"2026-05-30T01:44:33.569977Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 0"} {"level":"info","ts":"2026-05-30T01:44:33.570004Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2d372260bb4c37a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-30T01:44:33.570015Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 1"} {"level":"info","ts":"2026-05-30T01:44:33.570089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"warn","ts":"2026-05-30T01:44:33.579235Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-30T01:44:33.580749Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-30T01:44:33.582032Z","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-30T01:44:33.584151Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2d372260bb4c37a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-30T01:44:33.584601Z","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-30T01:44:33.585243Z","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-30T01:44:33.585278Z","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-30T01:44:33.584536Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2d372260bb4c37a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-30T01:44:33.587392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"info","ts":"2026-05-30T01:44:33.587899Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","added-peer-id":"2d372260bb4c37a","added-peer-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-05-30T01:44:33.591735Z","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-30T01:44:33.591855Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-05-30T01:44:33.592Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-05-30T01:44:33.592583Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2d372260bb4c37a","initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-30T01:44:33.592652Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-30T01:44:34.570635Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a is starting a new election at term 1"} {"level":"info","ts":"2026-05-30T01:44:34.570865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became pre-candidate at term 1"} {"level":"info","ts":"2026-05-30T01:44:34.570983Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgPreVoteResp from 2d372260bb4c37a at term 1"} {"level":"info","ts":"2026-05-30T01:44:34.571088Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became candidate at term 2"} {"level":"info","ts":"2026-05-30T01:44:34.571162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgVoteResp from 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-05-30T01:44:34.571245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became leader at term 2"} {"level":"info","ts":"2026-05-30T01:44:34.571296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2d372260bb4c37a elected leader 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-05-30T01:44:34.572364Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2d372260bb4c37a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.162:2379]}","request-path":"/0/members/2d372260bb4c37a/attributes","cluster-id":"52bb6ec15e8722ff","publish-timeout":"7s"} {"level":"info","ts":"2026-05-30T01:44:34.572465Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-30T01:44:34.572871Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-30T01:44:34.572959Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-30T01:44:34.572576Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T01:44:34.572627Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-30T01:44:34.573761Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T01:44:34.573968Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T01:44:34.574045Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T01:44:34.576085Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.162:2379"} {"level":"info","ts":"2026-05-30T01:44:34.576847Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-30T01:44:37.649251Z","caller":"traceutil/trace.go:171","msg":"trace[1447971302] linearizableReadLoop","detail":"{readStateIndex:169; appliedIndex:168; }","duration":"121.249302ms","start":"2026-05-30T01:44:37.527969Z","end":"2026-05-30T01:44:37.649219Z","steps":["trace[1447971302] 'read index received' (duration: 68.195114ms)","trace[1447971302] 'applied index is now lower than readState.Index' (duration: 53.053038ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:44:37.64934Z","caller":"traceutil/trace.go:171","msg":"trace[1947202364] transaction","detail":"{read_only:false; response_revision:165; number_of_response:1; }","duration":"121.434834ms","start":"2026-05-30T01:44:37.527877Z","end":"2026-05-30T01:44:37.649312Z","steps":["trace[1947202364] 'process raft request' (duration: 68.215514ms)","trace[1947202364] 'compare' (duration: 52.987578ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:44:37.649564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.511764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:replicaset-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T01:44:37.649712Z","caller":"traceutil/trace.go:171","msg":"trace[1404441376] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:replicaset-controller; range_end:; response_count:0; response_revision:165; }","duration":"121.751377ms","start":"2026-05-30T01:44:37.52793Z","end":"2026-05-30T01:44:37.649682Z","steps":["trace[1404441376] 'agreement among raft nodes before linearized reading' (duration: 121.439753ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:44:38.468124Z","caller":"traceutil/trace.go:171","msg":"trace[795496069] transaction","detail":"{read_only:false; response_revision:202; number_of_response:1; }","duration":"109.402631ms","start":"2026-05-30T01:44:38.358689Z","end":"2026-05-30T01:44:38.468092Z","steps":["trace[795496069] 'process raft request' (duration: 109.33372ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:44:38.468149Z","caller":"traceutil/trace.go:171","msg":"trace[1482019087] transaction","detail":"{read_only:false; response_revision:201; number_of_response:1; }","duration":"110.408821ms","start":"2026-05-30T01:44:38.357694Z","end":"2026-05-30T01:44:38.468102Z","steps":["trace[1482019087] 'process raft request' (duration: 40.347744ms)","trace[1482019087] 'compare' (duration: 69.789684ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:44:38.609696Z","caller":"traceutil/trace.go:171","msg":"trace[1434291053] linearizableReadLoop","detail":"{readStateIndex:212; appliedIndex:210; }","duration":"126.603167ms","start":"2026-05-30T01:44:38.483053Z","end":"2026-05-30T01:44:38.609656Z","steps":["trace[1434291053] 'read index received' (duration: 38.04497ms)","trace[1434291053] 'applied index is now lower than readState.Index' (duration: 88.556557ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:44:38.609763Z","caller":"traceutil/trace.go:171","msg":"trace[1227949585] transaction","detail":"{read_only:false; response_revision:206; number_of_response:1; }","duration":"129.140833ms","start":"2026-05-30T01:44:38.480561Z","end":"2026-05-30T01:44:38.609702Z","steps":["trace[1227949585] 'process raft request' (duration: 97.614501ms)","trace[1227949585] 'compare' (duration: 31.195878ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:44:38.610246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.143713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replication-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T01:44:38.610574Z","caller":"traceutil/trace.go:171","msg":"trace[1843498083] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replication-controller; range_end:; response_count:0; response_revision:207; }","duration":"127.513636ms","start":"2026-05-30T01:44:38.483018Z","end":"2026-05-30T01:44:38.610531Z","steps":["trace[1843498083] 'agreement among raft nodes before linearized reading' (duration: 127.02556ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:44:40.375801Z","caller":"traceutil/trace.go:171","msg":"trace[1087943374] linearizableReadLoop","detail":"{readStateIndex:302; appliedIndex:301; }","duration":"122.228754ms","start":"2026-05-30T01:44:40.253541Z","end":"2026-05-30T01:44:40.37577Z","steps":["trace[1087943374] 'read index received' (duration: 31.149205ms)","trace[1087943374] 'applied index is now lower than readState.Index' (duration: 91.078659ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:44:40.375822Z","caller":"traceutil/trace.go:171","msg":"trace[306563597] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"124.307235ms","start":"2026-05-30T01:44:40.251489Z","end":"2026-05-30T01:44:40.375796Z","steps":["trace[306563597] 'process raft request' (duration: 33.187205ms)","trace[306563597] 'compare' (duration: 90.928497ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:44:40.375977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.418605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-05-30T01:44:40.376006Z","caller":"traceutil/trace.go:171","msg":"trace[562113574] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:295; }","duration":"122.499457ms","start":"2026-05-30T01:44:40.253498Z","end":"2026-05-30T01:44:40.375998Z","steps":["trace[562113574] 'agreement among raft nodes before linearized reading' (duration: 122.396666ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:44:40.389577Z","caller":"traceutil/trace.go:171","msg":"trace[1454489057] transaction","detail":"{read_only:false; number_of_response:0; response_revision:295; }","duration":"115.255522ms","start":"2026-05-30T01:44:40.274296Z","end":"2026-05-30T01:44:40.389551Z","steps":["trace[1454489057] 'process raft request' (duration: 115.04731ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:44:40.38986Z","caller":"traceutil/trace.go:171","msg":"trace[1609196798] transaction","detail":"{read_only:false; number_of_response:0; response_revision:296; }","duration":"115.444206ms","start":"2026-05-30T01:44:40.274401Z","end":"2026-05-30T01:44:40.389845Z","steps":["trace[1609196798] 'process raft request' (duration: 115.184892ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:44:40.389868Z","caller":"traceutil/trace.go:171","msg":"trace[192369942] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"115.296524ms","start":"2026-05-30T01:44:40.274398Z","end":"2026-05-30T01:44:40.389694Z","steps":["trace[192369942] 'process raft request' (duration: 115.071371ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:44:40.389985Z","caller":"traceutil/trace.go:171","msg":"trace[297583833] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"115.237724ms","start":"2026-05-30T01:44:40.274732Z","end":"2026-05-30T01:44:40.389969Z","steps":["trace[297583833] 'process raft request' (duration: 114.976212ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:44:40.390002Z","caller":"traceutil/trace.go:171","msg":"trace[529338964] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"114.508616ms","start":"2026-05-30T01:44:40.275449Z","end":"2026-05-30T01:44:40.389957Z","steps":["trace[529338964] 'process raft request' (duration: 114.438155ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:44:40.411445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.819092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T01:44:40.411564Z","caller":"traceutil/trace.go:171","msg":"trace[1903794393] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:0; response_revision:300; }","duration":"147.995805ms","start":"2026-05-30T01:44:40.263539Z","end":"2026-05-30T01:44:40.411535Z","steps":["trace[1903794393] 'agreement among raft nodes before linearized reading' (duration: 147.754691ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:44:46.693502Z","caller":"traceutil/trace.go:171","msg":"trace[1348031391] linearizableReadLoop","detail":"{readStateIndex:411; appliedIndex:410; }","duration":"123.387205ms","start":"2026-05-30T01:44:46.570094Z","end":"2026-05-30T01:44:46.693481Z","steps":["trace[1348031391] 'read index received' (duration: 123.281534ms)","trace[1348031391] 'applied index is now lower than readState.Index' (duration: 104.931µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:44:46.693604Z","caller":"traceutil/trace.go:171","msg":"trace[1664445629] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"382.614867ms","start":"2026-05-30T01:44:46.310965Z","end":"2026-05-30T01:44:46.69358Z","steps":["trace[1664445629] 'process raft request' (duration: 382.386925ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:44:46.693678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.571816ms","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-30T01:44:46.693711Z","caller":"traceutil/trace.go:171","msg":"trace[704924737] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:397; }","duration":"123.655358ms","start":"2026-05-30T01:44:46.570045Z","end":"2026-05-30T01:44:46.693701Z","steps":["trace[704924737] 'agreement among raft nodes before linearized reading' (duration: 123.579147ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:44:46.694361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:44:46.310943Z","time spent":"382.716907ms","remote":"127.0.0.1:54998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T01:44:47.172722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.95026ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T01:44:47.172907Z","caller":"traceutil/trace.go:171","msg":"trace[478807674] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"474.812709ms","start":"2026-05-30T01:44:46.698074Z","end":"2026-05-30T01:44:47.172887Z","steps":["trace[478807674] 'process raft request' (duration: 342.466355ms)","trace[478807674] 'compare' (duration: 131.812059ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:44:47.172973Z","caller":"traceutil/trace.go:171","msg":"trace[1348061295] linearizableReadLoop","detail":"{readStateIndex:414; appliedIndex:411; }","duration":"444.179253ms","start":"2026-05-30T01:44:46.72878Z","end":"2026-05-30T01:44:47.172959Z","steps":["trace[1348061295] 'read index received' (duration: 311.780389ms)","trace[1348061295] 'applied index is now lower than readState.Index' (duration: 132.397364ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:44:47.173058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:44:46.698046Z","time spent":"474.96096ms","remote":"127.0.0.1:54998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T01:44:47.173205Z","caller":"traceutil/trace.go:171","msg":"trace[1037083118] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"474.666077ms","start":"2026-05-30T01:44:46.698424Z","end":"2026-05-30T01:44:47.17309Z","steps":["trace[1037083118] 'process raft request' (duration: 474.396415ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:44:47.173159Z","caller":"traceutil/trace.go:171","msg":"trace[940735062] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"474.215682ms","start":"2026-05-30T01:44:46.698919Z","end":"2026-05-30T01:44:47.173135Z","steps":["trace[940735062] 'process raft request' (duration: 473.95447ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:44:47.173275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.804672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-05-30T01:44:47.173311Z","caller":"traceutil/trace.go:171","msg":"trace[1043491373] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:400; }","duration":"363.872545ms","start":"2026-05-30T01:44:46.809429Z","end":"2026-05-30T01:44:47.173301Z","steps":["trace[1043491373] 'agreement among raft nodes before linearized reading' (duration: 363.819924ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:44:47.173219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.444985ms","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-05-30T01:44:47.173421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:44:46.698413Z","time spent":"474.842548ms","remote":"127.0.0.1:54998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T01:44:47.173418Z","caller":"traceutil/trace.go:171","msg":"trace[1948671769] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:400; }","duration":"444.673216ms","start":"2026-05-30T01:44:46.728721Z","end":"2026-05-30T01:44:47.173394Z","steps":["trace[1948671769] 'agreement among raft nodes before linearized reading' (duration: 444.455995ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:44:47.173556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:44:46.728699Z","time spent":"444.830858ms","remote":"127.0.0.1:54998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-30T01:44:47.173346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:44:46.809409Z","time spent":"363.929135ms","remote":"127.0.0.1:54938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4348,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-05-30T01:44:47.173369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:44:46.698902Z","time spent":"474.394694ms","remote":"127.0.0.1:54998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T01:44:49.395226Z","caller":"traceutil/trace.go:171","msg":"trace[854507138] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"215.098876ms","start":"2026-05-30T01:44:49.180102Z","end":"2026-05-30T01:44:49.395201Z","steps":["trace[854507138] 'process raft request' (duration: 214.948825ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:44:49.416682Z","caller":"traceutil/trace.go:171","msg":"trace[1185942325] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"236.495929ms","start":"2026-05-30T01:44:49.180165Z","end":"2026-05-30T01:44:49.416661Z","steps":["trace[1185942325] 'process raft request' (duration: 236.293897ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:44:49.416714Z","caller":"traceutil/trace.go:171","msg":"trace[548242534] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"229.279361ms","start":"2026-05-30T01:44:49.187414Z","end":"2026-05-30T01:44:49.416693Z","steps":["trace[548242534] 'process raft request' (duration: 229.192131ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:44:49.416682Z","caller":"traceutil/trace.go:171","msg":"trace[1304915931] linearizableReadLoop","detail":"{readStateIndex:423; appliedIndex:421; }","duration":"221.151134ms","start":"2026-05-30T01:44:49.195501Z","end":"2026-05-30T01:44:49.416652Z","steps":["trace[1304915931] 'read index received' (duration: 199.68477ms)","trace[1304915931] 'applied index is now lower than readState.Index' (duration: 21.465394ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:44:49.416897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.384296ms","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-05-30T01:44:49.416954Z","caller":"traceutil/trace.go:171","msg":"trace[2025831603] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:408; }","duration":"221.495646ms","start":"2026-05-30T01:44:49.195444Z","end":"2026-05-30T01:44:49.41694Z","steps":["trace[2025831603] 'agreement among raft nodes before linearized reading' (duration: 221.381926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:44:49.426096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.819209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T01:44:49.426189Z","caller":"traceutil/trace.go:171","msg":"trace[538573489] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:408; }","duration":"223.95481ms","start":"2026-05-30T01:44:49.202215Z","end":"2026-05-30T01:44:49.42617Z","steps":["trace[538573489] 'agreement among raft nodes before linearized reading' (duration: 215.263468ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:45:03.802914Z","caller":"traceutil/trace.go:171","msg":"trace[749119525] linearizableReadLoop","detail":"{readStateIndex:493; appliedIndex:491; }","duration":"295.105072ms","start":"2026-05-30T01:45:03.507775Z","end":"2026-05-30T01:45:03.80288Z","steps":["trace[749119525] 'read index received' (duration: 17.249794ms)","trace[749119525] 'applied index is now lower than readState.Index' (duration: 277.854398ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:45:03.803083Z","caller":"traceutil/trace.go:171","msg":"trace[699742534] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"299.623972ms","start":"2026-05-30T01:45:03.503446Z","end":"2026-05-30T01:45:03.80307Z","steps":["trace[699742534] 'process raft request' (duration: 216.584215ms)","trace[699742534] 'compare' (duration: 82.674904ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:45:03.803407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.615447ms","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-30T01:45:03.80345Z","caller":"traceutil/trace.go:171","msg":"trace[2113846946] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:475; }","duration":"295.701638ms","start":"2026-05-30T01:45:03.507737Z","end":"2026-05-30T01:45:03.803439Z","steps":["trace[2113846946] 'agreement among raft nodes before linearized reading' (duration: 295.617747ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:45:03.803598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.90305ms","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-05-30T01:45:03.803631Z","caller":"traceutil/trace.go:171","msg":"trace[128502016] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:475; }","duration":"294.94515ms","start":"2026-05-30T01:45:03.508677Z","end":"2026-05-30T01:45:03.803623Z","steps":["trace[128502016] 'agreement among raft nodes before linearized reading' (duration: 294.88984ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:45:17.057206Z","caller":"traceutil/trace.go:171","msg":"trace[517580600] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"186.725459ms","start":"2026-05-30T01:45:16.870462Z","end":"2026-05-30T01:45:17.057187Z","steps":["trace[517580600] 'process raft request' (duration: 186.605234ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:45:17.071016Z","caller":"traceutil/trace.go:171","msg":"trace[440507794] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"196.142798ms","start":"2026-05-30T01:45:16.874857Z","end":"2026-05-30T01:45:17.071Z","steps":["trace[440507794] 'process raft request' (duration: 195.986961ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:45:17.0711Z","caller":"traceutil/trace.go:171","msg":"trace[376414266] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"196.099896ms","start":"2026-05-30T01:45:16.874985Z","end":"2026-05-30T01:45:17.071085Z","steps":["trace[376414266] 'process raft request' (duration: 195.948399ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:45:17.071195Z","caller":"traceutil/trace.go:171","msg":"trace[1758397944] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"189.449774ms","start":"2026-05-30T01:45:16.881732Z","end":"2026-05-30T01:45:17.071182Z","steps":["trace[1758397944] 'process raft request' (duration: 189.300037ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:45:17.071187Z","caller":"traceutil/trace.go:171","msg":"trace[2067773203] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"192.601007ms","start":"2026-05-30T01:45:16.878562Z","end":"2026-05-30T01:45:17.071163Z","steps":["trace[2067773203] 'process raft request' (duration: 192.428389ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:45:17.071322Z","caller":"traceutil/trace.go:171","msg":"trace[100999135] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"189.520087ms","start":"2026-05-30T01:45:16.881791Z","end":"2026-05-30T01:45:17.071311Z","steps":["trace[100999135] 'process raft request' (duration: 189.271875ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:45:17.071516Z","caller":"traceutil/trace.go:171","msg":"trace[2128722919] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"189.644213ms","start":"2026-05-30T01:45:16.881864Z","end":"2026-05-30T01:45:17.071508Z","steps":["trace[2128722919] 'process raft request' (duration: 189.406501ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:45:17.071661Z","caller":"traceutil/trace.go:171","msg":"trace[533038569] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"175.650484ms","start":"2026-05-30T01:45:16.896003Z","end":"2026-05-30T01:45:17.071653Z","steps":["trace[533038569] 'process raft request' (duration: 175.523888ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:45:17.071636Z","caller":"traceutil/trace.go:171","msg":"trace[1768019321] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"175.720627ms","start":"2026-05-30T01:45:16.895885Z","end":"2026-05-30T01:45:17.071606Z","steps":["trace[1768019321] 'process raft request' (duration: 175.524577ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:45:17.071768Z","caller":"traceutil/trace.go:171","msg":"trace[1019967136] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"168.691485ms","start":"2026-05-30T01:45:16.903066Z","end":"2026-05-30T01:45:17.071758Z","steps":["trace[1019967136] 'process raft request' (duration: 168.508267ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:45:17.071859Z","caller":"traceutil/trace.go:171","msg":"trace[1324558921] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"136.237502ms","start":"2026-05-30T01:45:16.935608Z","end":"2026-05-30T01:45:17.071846Z","steps":["trace[1324558921] 'process raft request' (duration: 136.051983ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:45:20.3946Z","caller":"traceutil/trace.go:171","msg":"trace[2063317663] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"177.872081ms","start":"2026-05-30T01:45:20.216701Z","end":"2026-05-30T01:45:20.394573Z","steps":["trace[2063317663] 'process raft request' (duration: 177.743645ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:45:25.325716Z","caller":"traceutil/trace.go:171","msg":"trace[1932576644] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:678; }","duration":"122.663119ms","start":"2026-05-30T01:45:25.203026Z","end":"2026-05-30T01:45:25.32569Z","steps":["trace[1932576644] 'read index received' (duration: 122.374527ms)","trace[1932576644] 'applied index is now lower than readState.Index' (duration: 287.542µs)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:45:25.325946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.911731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T01:45:25.326032Z","caller":"traceutil/trace.go:171","msg":"trace[2122968909] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"479.426535ms","start":"2026-05-30T01:45:24.846588Z","end":"2026-05-30T01:45:25.326015Z","steps":["trace[2122968909] 'process raft request' (duration: 478.86867ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:45:25.326117Z","caller":"traceutil/trace.go:171","msg":"trace[1653896500] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:655; }","duration":"123.100249ms","start":"2026-05-30T01:45:25.20297Z","end":"2026-05-30T01:45:25.32607Z","steps":["trace[1653896500] 'agreement among raft nodes before linearized reading' (duration: 122.87262ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:45:25.326136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:45:24.84657Z","time spent":"479.513309ms","remote":"127.0.0.1:54938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-30T01:45:26.159722Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085745016579172210,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-30T01:45:26.231789Z","caller":"traceutil/trace.go:171","msg":"trace[902987466] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"901.025943ms","start":"2026-05-30T01:45:25.330728Z","end":"2026-05-30T01:45:26.231754Z","steps":["trace[902987466] 'process raft request' (duration: 880.218412ms)","trace[902987466] 'compare' (duration: 20.548659ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:45:26.231916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:45:25.330706Z","time spent":"901.151658ms","remote":"127.0.0.1:54842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-30T01:45:26.51905Z","caller":"traceutil/trace.go:171","msg":"trace[330958039] linearizableReadLoop","detail":"{readStateIndex:684; appliedIndex:679; }","duration":"859.644641ms","start":"2026-05-30T01:45:25.659383Z","end":"2026-05-30T01:45:26.519027Z","steps":["trace[330958039] 'read index received' (duration: 551.641868ms)","trace[330958039] 'applied index is now lower than readState.Index' (duration: 308.001863ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:45:26.519244Z","caller":"traceutil/trace.go:171","msg":"trace[1512146804] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"1.176514402s","start":"2026-05-30T01:45:25.342718Z","end":"2026-05-30T01:45:26.519232Z","steps":["trace[1512146804] 'process raft request' (duration: 1.176267791s)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:45:26.519333Z","caller":"traceutil/trace.go:171","msg":"trace[98233088] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"1.187290974s","start":"2026-05-30T01:45:25.331846Z","end":"2026-05-30T01:45:26.519137Z","steps":["trace[98233088] 'process raft request' (duration: 1.187093136s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:45:26.519456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.519168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-30T01:45:26.519522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.124642ms","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-05-30T01:45:26.519586Z","caller":"traceutil/trace.go:171","msg":"trace[253837454] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:660; }","duration":"860.221416ms","start":"2026-05-30T01:45:25.659345Z","end":"2026-05-30T01:45:26.519566Z","steps":["trace[253837454] 'agreement among raft nodes before linearized reading' (duration: 860.049378ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:45:26.519627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:45:25.659325Z","time spent":"860.293039ms","remote":"127.0.0.1:54998","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-05-30T01:45:26.519551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:45:25.331836Z","time spent":"1.18763744s","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T01:45:26.519771Z","caller":"traceutil/trace.go:171","msg":"trace[1277114616] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:660; }","duration":"318.576751ms","start":"2026-05-30T01:45:26.200896Z","end":"2026-05-30T01:45:26.519473Z","steps":["trace[1277114616] 'agreement among raft nodes before linearized reading' (duration: 318.540389ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:45:26.519801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:45:26.200875Z","time spent":"318.919586ms","remote":"127.0.0.1:54780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-30T01:45:26.519846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.047414ms","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-30T01:45:26.519927Z","caller":"traceutil/trace.go:171","msg":"trace[1442990700] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:660; }","duration":"499.20751ms","start":"2026-05-30T01:45:26.020707Z","end":"2026-05-30T01:45:26.519915Z","steps":["trace[1442990700] 'agreement among raft nodes before linearized reading' (duration: 498.694548ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:45:26.519138Z","caller":"traceutil/trace.go:171","msg":"trace[1147235213] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"1.188105809s","start":"2026-05-30T01:45:25.331022Z","end":"2026-05-30T01:45:26.519128Z","steps":["trace[1147235213] 'process raft request' (duration: 1.187831598s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:45:26.52019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.5059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-30T01:45:26.520233Z","caller":"traceutil/trace.go:171","msg":"trace[1167136804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:660; }","duration":"477.585025ms","start":"2026-05-30T01:45:26.042635Z","end":"2026-05-30T01:45:26.52022Z","steps":["trace[1167136804] 'agreement among raft nodes before linearized reading' (duration: 477.47326ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:45:26.520249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:45:25.331Z","time spent":"1.189176136s","remote":"127.0.0.1:54938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T01:45:26.520277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:45:26.042614Z","time spent":"477.654418ms","remote":"127.0.0.1:54998","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-30T01:45:26.519165Z","caller":"traceutil/trace.go:171","msg":"trace[260648169] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"1.188425843s","start":"2026-05-30T01:45:25.330733Z","end":"2026-05-30T01:45:26.519159Z","steps":["trace[260648169] 'process raft request' (duration: 1.168603305s)","trace[260648169] 'compare' (duration: 19.269074ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:45:26.520456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.386239ms","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-30T01:45:26.520502Z","caller":"traceutil/trace.go:171","msg":"trace[1979717056] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:660; }","duration":"478.469683ms","start":"2026-05-30T01:45:26.042022Z","end":"2026-05-30T01:45:26.520492Z","steps":["trace[1979717056] 'agreement among raft nodes before linearized reading' (duration: 478.352188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:45:26.520549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:45:26.042Z","time spent":"478.540635ms","remote":"127.0.0.1:54998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-30T01:45:26.520551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:45:25.330723Z","time spent":"1.189770872s","remote":"127.0.0.1:54842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-30T01:45:26.519366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:45:25.342698Z","time spent":"1.176607146s","remote":"127.0.0.1:54938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T01:45:26.520033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:45:26.020692Z","time spent":"499.327805ms","remote":"127.0.0.1:54998","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-30T01:45:27.852735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.436936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T01:45:27.852854Z","caller":"traceutil/trace.go:171","msg":"trace[767910414] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:664; }","duration":"650.602902ms","start":"2026-05-30T01:45:27.20222Z","end":"2026-05-30T01:45:27.852822Z","steps":["trace[767910414] 'range keys from in-memory index tree' (duration: 650.30432ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:45:27.852948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:45:27.202199Z","time spent":"650.713967ms","remote":"127.0.0.1:54780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-30T01:45:27.883018Z","caller":"traceutil/trace.go:171","msg":"trace[358487198] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"575.093589ms","start":"2026-05-30T01:45:27.307898Z","end":"2026-05-30T01:45:27.882992Z","steps":["trace[358487198] 'process raft request' (duration: 574.839789ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:45:27.883218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:45:27.307869Z","time spent":"575.230285ms","remote":"127.0.0.1:54998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T01:45:29.14498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.175415164s","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-05-30T01:45:29.145071Z","caller":"traceutil/trace.go:171","msg":"trace[481750901] linearizableReadLoop","detail":"{readStateIndex:690; appliedIndex:689; }","duration":"561.737428ms","start":"2026-05-30T01:45:28.583316Z","end":"2026-05-30T01:45:29.145053Z","steps":["trace[481750901] 'read index received' (duration: 49.403µs)","trace[481750901] 'applied index is now lower than readState.Index' (duration: 561.686595ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:45:29.145082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:45:27.90531Z","time spent":"1.23976721s","remote":"127.0.0.1:54804","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-30T01:45:29.145253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.940945ms","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-30T01:45:29.145282Z","caller":"traceutil/trace.go:171","msg":"trace[76694820] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:665; }","duration":"562.020928ms","start":"2026-05-30T01:45:28.583252Z","end":"2026-05-30T01:45:29.145272Z","steps":["trace[76694820] 'agreement among raft nodes before linearized reading' (duration: 561.883983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:45:29.145314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:45:28.583228Z","time spent":"562.078171ms","remote":"127.0.0.1:54998","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-30T01:45:29.145589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.892994ms","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-30T01:45:29.145637Z","caller":"traceutil/trace.go:171","msg":"trace[181412711] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:665; }","duration":"531.971287ms","start":"2026-05-30T01:45:28.613651Z","end":"2026-05-30T01:45:29.145622Z","steps":["trace[181412711] 'agreement among raft nodes before linearized reading' (duration: 531.845481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:45:29.145622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.016853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-30T01:45:29.145676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:45:28.613633Z","time spent":"532.033209ms","remote":"127.0.0.1:54998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-30T01:45:29.14568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.369701ms","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-05-30T01:45:29.145733Z","caller":"traceutil/trace.go:171","msg":"trace[1666263345] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:665; }","duration":"531.436453ms","start":"2026-05-30T01:45:28.614281Z","end":"2026-05-30T01:45:29.145717Z","steps":["trace[1666263345] 'agreement among raft nodes before linearized reading' (duration: 531.283997ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:45:29.145715Z","caller":"traceutil/trace.go:171","msg":"trace[1169841440] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:665; }","duration":"256.161429ms","start":"2026-05-30T01:45:28.889536Z","end":"2026-05-30T01:45:29.145697Z","steps":["trace[1169841440] 'agreement among raft nodes before linearized reading' (duration: 255.97661ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:45:29.145779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:45:28.614275Z","time spent":"531.492135ms","remote":"127.0.0.1:54998","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-05-30T01:45:29.14585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.656701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-30T01:45:29.145925Z","caller":"traceutil/trace.go:171","msg":"trace[1046325420] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:665; }","duration":"530.804287ms","start":"2026-05-30T01:45:28.615099Z","end":"2026-05-30T01:45:29.145903Z","steps":["trace[1046325420] 'agreement among raft nodes before linearized reading' (duration: 530.452873ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:45:29.145968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:45:28.615072Z","time spent":"530.88778ms","remote":"127.0.0.1:54998","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-30T01:45:30.763129Z","caller":"traceutil/trace.go:171","msg":"trace[1780738376] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"210.647334ms","start":"2026-05-30T01:45:30.552458Z","end":"2026-05-30T01:45:30.763105Z","steps":["trace[1780738376] 'process raft request' (duration: 202.553138ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:45:30.767972Z","caller":"traceutil/trace.go:171","msg":"trace[367218440] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"138.57158ms","start":"2026-05-30T01:45:30.629379Z","end":"2026-05-30T01:45:30.767951Z","steps":["trace[367218440] 'process raft request' (duration: 138.371572ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:45:42.183031Z","caller":"traceutil/trace.go:171","msg":"trace[1748916308] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"744.562339ms","start":"2026-05-30T01:45:41.438444Z","end":"2026-05-30T01:45:42.183007Z","steps":["trace[1748916308] 'process raft request' (duration: 744.410232ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:45:42.183291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:45:41.438427Z","time spent":"744.732664ms","remote":"127.0.0.1:54998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T01:45:42.340424Z","caller":"traceutil/trace.go:171","msg":"trace[228018585] linearizableReadLoop","detail":"{readStateIndex:835; appliedIndex:834; }","duration":"138.80865ms","start":"2026-05-30T01:45:42.201572Z","end":"2026-05-30T01:45:42.34038Z","steps":["trace[228018585] 'read index received' (duration: 138.693136ms)","trace[228018585] 'applied index is now lower than readState.Index' (duration: 114.564µs)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:45:42.340511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.940466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T01:45:42.340533Z","caller":"traceutil/trace.go:171","msg":"trace[2007219085] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:806; }","duration":"139.010388ms","start":"2026-05-30T01:45:42.201515Z","end":"2026-05-30T01:45:42.340526Z","steps":["trace[2007219085] 'agreement among raft nodes before linearized reading' (duration: 138.962987ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:45:42.340683Z","caller":"traceutil/trace.go:171","msg":"trace[751506103] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"832.885163ms","start":"2026-05-30T01:45:41.507791Z","end":"2026-05-30T01:45:42.340677Z","steps":["trace[751506103] 'process raft request' (duration: 832.482108ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:45:42.340743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:45:41.507763Z","time spent":"832.939954ms","remote":"127.0.0.1:54930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4905,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T01:45:46.306628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.507369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T01:45:46.306714Z","caller":"traceutil/trace.go:171","msg":"trace[141515407] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:824; }","duration":"104.635883ms","start":"2026-05-30T01:45:46.202061Z","end":"2026-05-30T01:45:46.306697Z","steps":["trace[141515407] 'range keys from in-memory index tree' (duration: 104.371713ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:45:46.306952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"899.143783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-zz7xg\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-30T01:45:46.306985Z","caller":"traceutil/trace.go:171","msg":"trace[73067779] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-zz7xg; range_end:; response_count:1; response_revision:824; }","duration":"899.211375ms","start":"2026-05-30T01:45:45.407766Z","end":"2026-05-30T01:45:46.306978Z","steps":["trace[73067779] 'range keys from in-memory index tree' (duration: 898.428518ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:45:46.307005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:45:45.407747Z","time spent":"899.252057ms","remote":"127.0.0.1:54938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-zz7xg\" "} {"level":"info","ts":"2026-05-30T01:45:46.498574Z","caller":"traceutil/trace.go:171","msg":"trace[525744322] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"183.742542ms","start":"2026-05-30T01:45:46.314813Z","end":"2026-05-30T01:45:46.498555Z","steps":["trace[525744322] 'process raft request' (duration: 183.596887ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:46:01.48921Z","caller":"traceutil/trace.go:171","msg":"trace[706682238] linearizableReadLoop","detail":"{readStateIndex:1031; appliedIndex:1030; }","duration":"120.385887ms","start":"2026-05-30T01:46:01.368807Z","end":"2026-05-30T01:46:01.489193Z","steps":["trace[706682238] 'read index received' (duration: 120.219412ms)","trace[706682238] 'applied index is now lower than readState.Index' (duration: 165.605µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:46:01.489281Z","caller":"traceutil/trace.go:171","msg":"trace[308160001] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"162.351138ms","start":"2026-05-30T01:46:01.326901Z","end":"2026-05-30T01:46:01.489252Z","steps":["trace[308160001] 'process raft request' (duration: 162.180413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:46:01.489366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.097565ms","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-30T01:46:01.489366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.552661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-30T01:46:01.489392Z","caller":"traceutil/trace.go:171","msg":"trace[1528886692] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:998; }","duration":"112.175157ms","start":"2026-05-30T01:46:01.377211Z","end":"2026-05-30T01:46:01.489386Z","steps":["trace[1528886692] 'agreement among raft nodes before linearized reading' (duration: 112.085495ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:46:01.4894Z","caller":"traceutil/trace.go:171","msg":"trace[1060605437] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:998; }","duration":"120.614833ms","start":"2026-05-30T01:46:01.368777Z","end":"2026-05-30T01:46:01.489391Z","steps":["trace[1060605437] 'agreement among raft nodes before linearized reading' (duration: 120.48865ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:46:01.489623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.024402ms","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-05-30T01:46:01.489681Z","caller":"traceutil/trace.go:171","msg":"trace[2072622912] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:998; }","duration":"109.120755ms","start":"2026-05-30T01:46:01.380545Z","end":"2026-05-30T01:46:01.489666Z","steps":["trace[2072622912] 'agreement among raft nodes before linearized reading' (duration: 108.894828ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:46:31.790376Z","caller":"traceutil/trace.go:171","msg":"trace[399261758] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"144.691621ms","start":"2026-05-30T01:46:31.64566Z","end":"2026-05-30T01:46:31.790351Z","steps":["trace[399261758] 'process raft request' (duration: 144.525426ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:46:31.815814Z","caller":"traceutil/trace.go:171","msg":"trace[1567598780] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"167.239523ms","start":"2026-05-30T01:46:31.648552Z","end":"2026-05-30T01:46:31.815791Z","steps":["trace[1567598780] 'process raft request' (duration: 163.807442ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:46:31.815956Z","caller":"traceutil/trace.go:171","msg":"trace[1513735278] linearizableReadLoop","detail":"{readStateIndex:1149; appliedIndex:1147; }","duration":"122.315352ms","start":"2026-05-30T01:46:31.693625Z","end":"2026-05-30T01:46:31.815941Z","steps":["trace[1513735278] 'read index received' (duration: 96.82431ms)","trace[1513735278] 'applied index is now lower than readState.Index' (duration: 25.489862ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:46:31.816003Z","caller":"traceutil/trace.go:171","msg":"trace[1597290393] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"137.555261ms","start":"2026-05-30T01:46:31.678438Z","end":"2026-05-30T01:46:31.815993Z","steps":["trace[1597290393] 'process raft request' (duration: 137.313916ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:46:31.816098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.466295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-30T01:46:31.81612Z","caller":"traceutil/trace.go:171","msg":"trace[1964022169] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1109; }","duration":"122.541357ms","start":"2026-05-30T01:46:31.693571Z","end":"2026-05-30T01:46:31.816113Z","steps":["trace[1964022169] 'agreement among raft nodes before linearized reading' (duration: 122.431015ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:46:31.81619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.630207ms","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-30T01:46:31.816222Z","caller":"traceutil/trace.go:171","msg":"trace[1728040471] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1109; }","duration":"119.70677ms","start":"2026-05-30T01:46:31.696505Z","end":"2026-05-30T01:46:31.816211Z","steps":["trace[1728040471] 'agreement among raft nodes before linearized reading' (duration: 119.618167ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:46:47.996369Z","caller":"traceutil/trace.go:171","msg":"trace[708349134] linearizableReadLoop","detail":"{readStateIndex:1202; appliedIndex:1201; }","duration":"108.570289ms","start":"2026-05-30T01:46:47.887774Z","end":"2026-05-30T01:46:47.996344Z","steps":["trace[708349134] 'read index received' (duration: 108.359525ms)","trace[708349134] 'applied index is now lower than readState.Index' (duration: 209.754µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:46:47.996409Z","caller":"traceutil/trace.go:171","msg":"trace[692192515] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"123.498266ms","start":"2026-05-30T01:46:47.872882Z","end":"2026-05-30T01:46:47.99638Z","steps":["trace[692192515] 'process raft request' (duration: 123.244911ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:46:47.996664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.868276ms","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-05-30T01:46:47.996739Z","caller":"traceutil/trace.go:171","msg":"trace[602372591] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1159; }","duration":"108.969528ms","start":"2026-05-30T01:46:47.887751Z","end":"2026-05-30T01:46:47.99672Z","steps":["trace[602372591] 'agreement among raft nodes before linearized reading' (duration: 108.733283ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:46:48.165531Z","caller":"traceutil/trace.go:171","msg":"trace[190786885] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"102.218942ms","start":"2026-05-30T01:46:48.063286Z","end":"2026-05-30T01:46:48.165505Z","steps":["trace[190786885] 'process raft request' (duration: 102.175091ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:46:48.165927Z","caller":"traceutil/trace.go:171","msg":"trace[1485518149] transaction","detail":"{read_only:false; response_revision:1161; number_of_response:1; }","duration":"165.28458ms","start":"2026-05-30T01:46:48.000623Z","end":"2026-05-30T01:46:48.165908Z","steps":["trace[1485518149] 'process raft request' (duration: 164.689777ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:46:48.165979Z","caller":"traceutil/trace.go:171","msg":"trace[1713103242] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"164.511783ms","start":"2026-05-30T01:46:48.001431Z","end":"2026-05-30T01:46:48.165943Z","steps":["trace[1713103242] 'process raft request' (duration: 163.922861ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:46:48.166077Z","caller":"traceutil/trace.go:171","msg":"trace[1208510395] transaction","detail":"{read_only:false; response_revision:1160; number_of_response:1; }","duration":"165.618006ms","start":"2026-05-30T01:46:48.000434Z","end":"2026-05-30T01:46:48.166052Z","steps":["trace[1208510395] 'process raft request' (duration: 98.204118ms)","trace[1208510395] 'compare' (duration: 66.54647ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:46:48.166035Z","caller":"traceutil/trace.go:171","msg":"trace[1784207246] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"164.366311ms","start":"2026-05-30T01:46:48.001607Z","end":"2026-05-30T01:46:48.165974Z","steps":["trace[1784207246] 'process raft request' (duration: 163.817139ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:46:49.279142Z","caller":"traceutil/trace.go:171","msg":"trace[2088452955] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"129.5201ms","start":"2026-05-30T01:46:49.149601Z","end":"2026-05-30T01:46:49.279121Z","steps":["trace[2088452955] 'process raft request' (duration: 106.681993ms)","trace[2088452955] 'compare' (duration: 22.717985ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:47:12.403206Z","caller":"traceutil/trace.go:171","msg":"trace[41555798] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"102.084817ms","start":"2026-05-30T01:47:12.301091Z","end":"2026-05-30T01:47:12.403176Z","steps":["trace[41555798] 'process raft request' (duration: 101.909104ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:47:12.40804Z","caller":"traceutil/trace.go:171","msg":"trace[211921673] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"105.530991ms","start":"2026-05-30T01:47:12.302493Z","end":"2026-05-30T01:47:12.408024Z","steps":["trace[211921673] 'process raft request' (duration: 105.50883ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:47:12.408086Z","caller":"traceutil/trace.go:171","msg":"trace[1626669684] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"106.956327ms","start":"2026-05-30T01:47:12.301112Z","end":"2026-05-30T01:47:12.408069Z","steps":["trace[1626669684] 'process raft request' (duration: 106.787814ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:47:12.408174Z","caller":"traceutil/trace.go:171","msg":"trace[1999748954] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"106.102912ms","start":"2026-05-30T01:47:12.302036Z","end":"2026-05-30T01:47:12.408139Z","steps":["trace[1999748954] 'process raft request' (duration: 105.950449ms)"],"step_count":1}