{"level":"warn","ts":"2026-07-30T04:31:02.510501Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-30T04:31:02.510574Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.248: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.248:2380","--initial-cluster=instance=https://199.204.45.248:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.248:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.248:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-30T04:31:02.510645Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-30T04:31:02.510657Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-07-30T04:31:02.510673Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-30T04:31:02.511664Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"]} {"level":"info","ts":"2026-07-30T04:31:02.511733Z","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.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.248:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-30T04:31:02.515225Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.226447ms"} {"level":"info","ts":"2026-07-30T04:31:02.524425Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"39582465ceaa2f67","cluster-id":"87fd8ddedde3be45"} {"level":"info","ts":"2026-07-30T04:31:02.524489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=()"} {"level":"info","ts":"2026-07-30T04:31:02.52451Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 0"} {"level":"info","ts":"2026-07-30T04:31:02.524522Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 39582465ceaa2f67 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-30T04:31:02.524533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 1"} {"level":"info","ts":"2026-07-30T04:31:02.524568Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"warn","ts":"2026-07-30T04:31:02.531393Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-30T04:31:02.533795Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-30T04:31:02.534968Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-30T04:31:02.539777Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"39582465ceaa2f67","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-30T04:31:02.540041Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"39582465ceaa2f67","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-30T04:31:02.540167Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-30T04:31:02.540293Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-30T04:31:02.540309Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-30T04:31:02.541139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"info","ts":"2026-07-30T04:31:02.541319Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","added-peer-id":"39582465ceaa2f67","added-peer-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-07-30T04:31:02.556883Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-30T04:31:02.557043Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-07-30T04:31:02.55712Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-07-30T04:31:02.557236Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"39582465ceaa2f67","initial-advertise-peer-urls":["https://199.204.45.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-30T04:31:02.557298Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-30T04:31:03.025533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 is starting a new election at term 1"} {"level":"info","ts":"2026-07-30T04:31:03.025596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-30T04:31:03.025633Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgPreVoteResp from 39582465ceaa2f67 at term 1"} {"level":"info","ts":"2026-07-30T04:31:03.025653Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became candidate at term 2"} {"level":"info","ts":"2026-07-30T04:31:03.02567Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgVoteResp from 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-07-30T04:31:03.025687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became leader at term 2"} {"level":"info","ts":"2026-07-30T04:31:03.025706Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 39582465ceaa2f67 elected leader 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-07-30T04:31:03.026703Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"39582465ceaa2f67","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.248:2379]}","request-path":"/0/members/39582465ceaa2f67/attributes","cluster-id":"87fd8ddedde3be45","publish-timeout":"7s"} {"level":"info","ts":"2026-07-30T04:31:03.026839Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T04:31:03.026903Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T04:31:03.027191Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-30T04:31:03.027281Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-30T04:31:03.026755Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T04:31:03.029113Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T04:31:03.029233Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T04:31:03.029267Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T04:31:03.030375Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-30T04:31:03.034768Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.248:2379"} {"level":"info","ts":"2026-07-30T04:31:16.698577Z","caller":"traceutil/trace.go:171","msg":"trace[710927976] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"109.128246ms","start":"2026-07-30T04:31:16.589422Z","end":"2026-07-30T04:31:16.69855Z","steps":["trace[710927976] 'process raft request' (duration: 108.944352ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T04:31:23.098092Z","caller":"traceutil/trace.go:171","msg":"trace[1110106572] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"374.40577ms","start":"2026-07-30T04:31:22.723661Z","end":"2026-07-30T04:31:23.098066Z","steps":["trace[1110106572] 'process raft request' (duration: 374.255477ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T04:31:23.098688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T04:31:22.723651Z","time spent":"374.534902ms","remote":"127.0.0.1:44562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T04:31:29.245746Z","caller":"traceutil/trace.go:171","msg":"trace[544187639] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"118.77176ms","start":"2026-07-30T04:31:29.126938Z","end":"2026-07-30T04:31:29.24571Z","steps":["trace[544187639] 'process raft request' (duration: 118.485723ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T04:31:29.491149Z","caller":"traceutil/trace.go:171","msg":"trace[140498644] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"240.835486ms","start":"2026-07-30T04:31:29.250287Z","end":"2026-07-30T04:31:29.491122Z","steps":["trace[140498644] 'process raft request' (duration: 236.398934ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T04:31:47.126157Z","caller":"traceutil/trace.go:171","msg":"trace[2061740693] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"342.339064ms","start":"2026-07-30T04:31:46.783789Z","end":"2026-07-30T04:31:47.126128Z","steps":["trace[2061740693] 'process raft request' (duration: 329.726138ms)","trace[2061740693] 'compare' (duration: 12.2831ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T04:31:47.126319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T04:31:46.78377Z","time spent":"342.468607ms","remote":"127.0.0.1:44370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T04:31:47.127022Z","caller":"traceutil/trace.go:171","msg":"trace[614756666] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"287.177085ms","start":"2026-07-30T04:31:46.839833Z","end":"2026-07-30T04:31:47.12701Z","steps":["trace[614756666] 'process raft request' (duration: 287.056163ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T04:31:47.127205Z","caller":"traceutil/trace.go:171","msg":"trace[1293718999] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"324.488635ms","start":"2026-07-30T04:31:46.802706Z","end":"2026-07-30T04:31:47.127195Z","steps":["trace[1293718999] 'process raft request' (duration: 323.351313ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T04:31:47.12727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T04:31:46.802678Z","time spent":"324.554016ms","remote":"127.0.0.1:44370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29546,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T04:31:47.556515Z","caller":"traceutil/trace.go:171","msg":"trace[499544834] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:596; }","duration":"229.03337ms","start":"2026-07-30T04:31:47.327457Z","end":"2026-07-30T04:31:47.556491Z","steps":["trace[499544834] 'read index received' (duration: 222.595945ms)","trace[499544834] 'applied index is now lower than readState.Index' (duration: 6.436275ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T04:31:47.556541Z","caller":"traceutil/trace.go:171","msg":"trace[1005028731] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"382.752809ms","start":"2026-07-30T04:31:47.173757Z","end":"2026-07-30T04:31:47.55651Z","steps":["trace[1005028731] 'process raft request' (duration: 382.448233ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T04:31:47.556694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.211853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-30T04:31:47.556775Z","caller":"traceutil/trace.go:171","msg":"trace[1359328756] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"384.960972ms","start":"2026-07-30T04:31:47.171761Z","end":"2026-07-30T04:31:47.556722Z","steps":["trace[1359328756] 'process raft request' (duration: 378.13774ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T04:31:47.556821Z","caller":"traceutil/trace.go:171","msg":"trace[934116514] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"361.194699ms","start":"2026-07-30T04:31:47.195601Z","end":"2026-07-30T04:31:47.556796Z","steps":["trace[934116514] 'process raft request' (duration: 360.774561ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T04:31:47.556933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T04:31:47.195571Z","time spent":"361.297491ms","remote":"127.0.0.1:44370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T04:31:47.55698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T04:31:47.17174Z","time spent":"385.085514ms","remote":"127.0.0.1:44370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23066,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T04:31:47.556756Z","caller":"traceutil/trace.go:171","msg":"trace[974328538] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:582; }","duration":"229.331356ms","start":"2026-07-30T04:31:47.327414Z","end":"2026-07-30T04:31:47.556745Z","steps":["trace[974328538] 'agreement among raft nodes before linearized reading' (duration: 229.171933ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T04:31:47.557361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T04:31:47.17374Z","time spent":"383.519685ms","remote":"127.0.0.1:44370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10846,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T04:31:47.556739Z","caller":"traceutil/trace.go:171","msg":"trace[865939052] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"379.489056ms","start":"2026-07-30T04:31:47.177202Z","end":"2026-07-30T04:31:47.556691Z","steps":["trace[865939052] 'process raft request' (duration: 379.060538ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T04:31:47.557776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T04:31:47.177173Z","time spent":"380.516555ms","remote":"127.0.0.1:44370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T04:31:53.646216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.52085ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T04:31:53.646386Z","caller":"traceutil/trace.go:171","msg":"trace[1107501973] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:646; }","duration":"276.682243ms","start":"2026-07-30T04:31:53.369654Z","end":"2026-07-30T04:31:53.646337Z","steps":["trace[1107501973] 'range keys from in-memory index tree' (duration: 276.501009ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T04:31:53.758607Z","caller":"traceutil/trace.go:171","msg":"trace[529017500] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"101.234811ms","start":"2026-07-30T04:31:53.657332Z","end":"2026-07-30T04:31:53.758567Z","steps":["trace[529017500] 'process raft request' (duration: 100.945225ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T04:31:53.901044Z","caller":"traceutil/trace.go:171","msg":"trace[1764974794] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:666; }","duration":"142.536922ms","start":"2026-07-30T04:31:53.758481Z","end":"2026-07-30T04:31:53.901018Z","steps":["trace[1764974794] 'read index received' (duration: 136.806172ms)","trace[1764974794] 'applied index is now lower than readState.Index' (duration: 5.72991ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T04:31:53.901204Z","caller":"traceutil/trace.go:171","msg":"trace[1111293205] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"202.122704ms","start":"2026-07-30T04:31:53.699044Z","end":"2026-07-30T04:31:53.901166Z","steps":["trace[1111293205] 'process raft request' (duration: 196.452445ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T04:31:53.901279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.783559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-30T04:31:53.901321Z","caller":"traceutil/trace.go:171","msg":"trace[1271021097] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:648; }","duration":"210.865451ms","start":"2026-07-30T04:31:53.690444Z","end":"2026-07-30T04:31:53.901309Z","steps":["trace[1271021097] 'agreement among raft nodes before linearized reading' (duration: 210.723849ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T04:31:54.401919Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874426187907407,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T04:31:55.082519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.306993ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T04:31:55.082627Z","caller":"traceutil/trace.go:171","msg":"trace[1613743963] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"1.30775047s","start":"2026-07-30T04:31:53.774859Z","end":"2026-07-30T04:31:55.082609Z","steps":["trace[1613743963] 'process raft request' (duration: 690.147341ms)","trace[1613743963] 'compare' (duration: 616.965956ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T04:31:55.082687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T04:31:53.774774Z","time spent":"1.307884753s","remote":"127.0.0.1:44508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T04:31:55.107716Z","caller":"traceutil/trace.go:171","msg":"trace[658685638] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:667; }","duration":"1.206549568s","start":"2026-07-30T04:31:53.901145Z","end":"2026-07-30T04:31:55.107695Z","steps":["trace[658685638] 'read index received' (duration: 563.870052ms)","trace[658685638] 'applied index is now lower than readState.Index' (duration: 642.678876ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T04:31:55.107777Z","caller":"traceutil/trace.go:171","msg":"trace[203445371] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"1.201991521s","start":"2026-07-30T04:31:53.905758Z","end":"2026-07-30T04:31:55.10775Z","steps":["trace[203445371] 'process raft request' (duration: 1.201835887s)"],"step_count":1} {"level":"info","ts":"2026-07-30T04:31:55.107847Z","caller":"traceutil/trace.go:171","msg":"trace[388722652] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"1.148387613s","start":"2026-07-30T04:31:53.959453Z","end":"2026-07-30T04:31:55.10784Z","steps":["trace[388722652] 'process raft request' (duration: 1.148219329s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T04:31:55.107898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T04:31:53.905735Z","time spent":"1.202093414s","remote":"127.0.0.1:44562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T04:31:55.107906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T04:31:53.959419Z","time spent":"1.148449735s","remote":"127.0.0.1:44544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T04:31:55.108022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.123787153s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-30T04:31:55.108091Z","caller":"traceutil/trace.go:171","msg":"trace[986191395] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:651; }","duration":"1.123921875s","start":"2026-07-30T04:31:53.98415Z","end":"2026-07-30T04:31:55.108072Z","steps":["trace[986191395] 'agreement among raft nodes before linearized reading' (duration: 1.123807393s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T04:31:55.10811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.233013055s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-30T04:31:55.108132Z","caller":"traceutil/trace.go:171","msg":"trace[1454692233] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:651; }","duration":"1.233066927s","start":"2026-07-30T04:31:53.875058Z","end":"2026-07-30T04:31:55.108125Z","steps":["trace[1454692233] 'agreement among raft nodes before linearized reading' (duration: 1.233011276s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T04:31:55.108127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.894373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T04:31:55.108131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T04:31:53.984135Z","time spent":"1.123987886s","remote":"127.0.0.1:44562","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-07-30T04:31:55.108147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T04:31:53.875037Z","time spent":"1.233106748s","remote":"127.0.0.1:44562","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-07-30T04:31:55.10815Z","caller":"traceutil/trace.go:171","msg":"trace[641915960] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:651; }","duration":"537.958735ms","start":"2026-07-30T04:31:54.570185Z","end":"2026-07-30T04:31:55.108144Z","steps":["trace[641915960] 'agreement among raft nodes before linearized reading' (duration: 537.917894ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T04:31:55.108167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T04:31:54.570158Z","time spent":"538.006066ms","remote":"127.0.0.1:44298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T04:31:56.070711Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874426187907417,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T04:31:56.197801Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.042863033s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-30T04:31:56.640857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.784214ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-30T04:31:56.640987Z","caller":"traceutil/trace.go:171","msg":"trace[640553945] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:672; }","duration":"1.070381014s","start":"2026-07-30T04:31:55.570594Z","end":"2026-07-30T04:31:56.640975Z","steps":["trace[640553945] 'read index received' (duration: 627.448657ms)","trace[640553945] 'applied index is now lower than readState.Index' (duration: 442.931337ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T04:31:56.641045Z","caller":"traceutil/trace.go:171","msg":"trace[1180760124] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"1.217375406s","start":"2026-07-30T04:31:55.423642Z","end":"2026-07-30T04:31:56.641018Z","steps":["trace[1180760124] 'process raft request' (duration: 1.217290774s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T04:31:56.64115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.070462305s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T04:31:56.641174Z","caller":"traceutil/trace.go:171","msg":"trace[891116711] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:655; }","duration":"1.070617737s","start":"2026-07-30T04:31:55.570549Z","end":"2026-07-30T04:31:56.641167Z","steps":["trace[891116711] 'agreement among raft nodes before linearized reading' (duration: 1.070487935s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T04:31:56.641202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T04:31:55.423615Z","time spent":"1.217511728s","remote":"127.0.0.1:44562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T04:31:56.641211Z","caller":"traceutil/trace.go:171","msg":"trace[2006665805] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"1.486346741s","start":"2026-07-30T04:31:55.154835Z","end":"2026-07-30T04:31:56.641182Z","steps":["trace[2006665805] 'process raft request' (duration: 1.04318689s)","trace[2006665805] 'compare' (duration: 442.681302ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T04:31:56.641237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T04:31:55.570529Z","time spent":"1.0707012s","remote":"127.0.0.1:44298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T04:31:56.641262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.169001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-30T04:31:56.64133Z","caller":"traceutil/trace.go:171","msg":"trace[1784564152] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:655; }","duration":"878.282683ms","start":"2026-07-30T04:31:55.763029Z","end":"2026-07-30T04:31:56.641312Z","steps":["trace[1784564152] 'agreement among raft nodes before linearized reading' (duration: 878.11366ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T04:31:56.641327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T04:31:55.154814Z","time spent":"1.486461262s","remote":"127.0.0.1:44508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T04:31:56.641392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T04:31:55.763005Z","time spent":"878.379205ms","remote":"127.0.0.1:44562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-30T04:31:56.641459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.269324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.248\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-30T04:31:56.641503Z","caller":"traceutil/trace.go:171","msg":"trace[637872247] range","detail":"{range_begin:/registry/masterleases/199.204.45.248; range_end:; response_count:1; response_revision:655; }","duration":"739.345515ms","start":"2026-07-30T04:31:55.902146Z","end":"2026-07-30T04:31:56.641491Z","steps":["trace[637872247] 'agreement among raft nodes before linearized reading' (duration: 739.172052ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T04:31:56.641532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T04:31:55.902128Z","time spent":"739.397896ms","remote":"127.0.0.1:44344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.248\" "} {"level":"info","ts":"2026-07-30T04:32:02.023278Z","caller":"traceutil/trace.go:171","msg":"trace[211666428] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"149.344429ms","start":"2026-07-30T04:32:01.873911Z","end":"2026-07-30T04:32:02.023255Z","steps":["trace[211666428] 'process raft request' (duration: 149.079384ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T04:32:09.180688Z","caller":"traceutil/trace.go:171","msg":"trace[876679483] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"159.834078ms","start":"2026-07-30T04:32:09.020832Z","end":"2026-07-30T04:32:09.180666Z","steps":["trace[876679483] 'process raft request' (duration: 159.654594ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T04:32:09.596159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.793169ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T04:32:09.596377Z","caller":"traceutil/trace.go:171","msg":"trace[1889765983] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"348.983294ms","start":"2026-07-30T04:32:09.247356Z","end":"2026-07-30T04:32:09.596339Z","steps":["trace[1889765983] 'process raft request' (duration: 60.884019ms)","trace[1889765983] 'compare' (duration: 287.601405ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T04:32:09.596578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T04:32:09.247344Z","time spent":"349.124128ms","remote":"127.0.0.1:44562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T04:32:09.846108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.307653ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-30T04:32:09.84621Z","caller":"traceutil/trace.go:171","msg":"trace[1255112527] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"183.950101ms","start":"2026-07-30T04:32:09.662242Z","end":"2026-07-30T04:32:09.846192Z","steps":["trace[1255112527] 'process raft request' (duration: 83.491305ms)","trace[1255112527] 'compare' (duration: 100.180641ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T04:32:16.071736Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874426187907835,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T04:32:16.270707Z","caller":"traceutil/trace.go:171","msg":"trace[1179392037] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"930.613424ms","start":"2026-07-30T04:32:15.340073Z","end":"2026-07-30T04:32:16.270686Z","steps":["trace[1179392037] 'process raft request' (duration: 930.444271ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T04:32:16.270827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T04:32:15.340037Z","time spent":"930.726195ms","remote":"127.0.0.1:44562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T04:32:16.39592Z","caller":"traceutil/trace.go:171","msg":"trace[1886002360] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"1.054604617s","start":"2026-07-30T04:32:15.341291Z","end":"2026-07-30T04:32:16.395896Z","steps":["trace[1886002360] 'process raft request' (duration: 1.052837745s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T04:32:16.396308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T04:32:15.341269Z","time spent":"1.05479683s","remote":"127.0.0.1:44562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T04:32:16.396488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"825.635795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T04:32:16.396455Z","caller":"traceutil/trace.go:171","msg":"trace[1740571597] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"592.867474ms","start":"2026-07-30T04:32:15.803537Z","end":"2026-07-30T04:32:16.396405Z","steps":["trace[1740571597] 'process raft request' (duration: 592.309304ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T04:32:16.396556Z","caller":"traceutil/trace.go:171","msg":"trace[1306963736] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:827; }","duration":"825.778898ms","start":"2026-07-30T04:32:15.570762Z","end":"2026-07-30T04:32:16.396541Z","steps":["trace[1306963736] 'agreement among raft nodes before linearized reading' (duration: 825.639835ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T04:32:16.396593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T04:32:15.570729Z","time spent":"825.85598ms","remote":"127.0.0.1:44298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-30T04:32:16.39631Z","caller":"traceutil/trace.go:171","msg":"trace[1915450527] linearizableReadLoop","detail":"{readStateIndex:852; appliedIndex:849; }","duration":"825.440502ms","start":"2026-07-30T04:32:15.570826Z","end":"2026-07-30T04:32:16.396266Z","steps":["trace[1915450527] 'read index received' (duration: 699.806839ms)","trace[1915450527] 'applied index is now lower than readState.Index' (duration: 125.630743ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T04:32:16.39668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T04:32:15.803492Z","time spent":"593.088968ms","remote":"127.0.0.1:44544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T04:32:16.396879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.784605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-30T04:32:16.396942Z","caller":"traceutil/trace.go:171","msg":"trace[1844237876] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:827; }","duration":"773.870897ms","start":"2026-07-30T04:32:15.623054Z","end":"2026-07-30T04:32:16.396925Z","steps":["trace[1844237876] 'agreement among raft nodes before linearized reading' (duration: 773.697874ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T04:32:16.396982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T04:32:15.623042Z","time spent":"773.931597ms","remote":"127.0.0.1:44562","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-07-30T04:32:16.396982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.470891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T04:32:16.397113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.573651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-30T04:32:16.397282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.742517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.248\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-30T04:32:16.397414Z","caller":"traceutil/trace.go:171","msg":"trace[416539396] range","detail":"{range_begin:/registry/masterleases/199.204.45.248; range_end:; response_count:1; response_revision:827; }","duration":"493.949381ms","start":"2026-07-30T04:32:15.903432Z","end":"2026-07-30T04:32:16.397381Z","steps":["trace[416539396] 'agreement among raft nodes before linearized reading' (duration: 493.440232ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T04:32:16.397477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T04:32:15.903406Z","time spent":"494.061392ms","remote":"127.0.0.1:44344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.248\" "} {"level":"info","ts":"2026-07-30T04:32:16.397501Z","caller":"traceutil/trace.go:171","msg":"trace[1182112182] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:827; }","duration":"549.06447ms","start":"2026-07-30T04:32:15.848281Z","end":"2026-07-30T04:32:16.397345Z","steps":["trace[1182112182] 'agreement among raft nodes before linearized reading' (duration: 548.591822ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T04:32:16.397685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T04:32:15.848251Z","time spent":"549.414357ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-07-30T04:32:16.398827Z","caller":"traceutil/trace.go:171","msg":"trace[869724322] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:827; }","duration":"198.750396ms","start":"2026-07-30T04:32:16.198381Z","end":"2026-07-30T04:32:16.397131Z","steps":["trace[869724322] 'agreement among raft nodes before linearized reading' (duration: 198.38366ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T04:32:30.65435Z","caller":"traceutil/trace.go:171","msg":"trace[366622504] linearizableReadLoop","detail":"{readStateIndex:1023; appliedIndex:1022; }","duration":"196.46727ms","start":"2026-07-30T04:32:30.457858Z","end":"2026-07-30T04:32:30.654325Z","steps":["trace[366622504] 'read index received' (duration: 196.243076ms)","trace[366622504] 'applied index is now lower than readState.Index' (duration: 223.194µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T04:32:30.654397Z","caller":"traceutil/trace.go:171","msg":"trace[2005053617] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"196.677194ms","start":"2026-07-30T04:32:30.457671Z","end":"2026-07-30T04:32:30.654349Z","steps":["trace[2005053617] 'process raft request' (duration: 196.486131ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T04:32:30.654616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.448846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-30T04:32:30.654662Z","caller":"traceutil/trace.go:171","msg":"trace[578871570] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:995; }","duration":"147.541568ms","start":"2026-07-30T04:32:30.507107Z","end":"2026-07-30T04:32:30.654649Z","steps":["trace[578871570] 'agreement among raft nodes before linearized reading' (duration: 147.359295ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T04:32:30.654681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.805526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-30T04:32:30.654764Z","caller":"traceutil/trace.go:171","msg":"trace[447297234] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:995; }","duration":"196.902878ms","start":"2026-07-30T04:32:30.457841Z","end":"2026-07-30T04:32:30.654744Z","steps":["trace[447297234] 'agreement among raft nodes before linearized reading' (duration: 196.612693ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T04:32:31.049302Z","caller":"traceutil/trace.go:171","msg":"trace[611832839] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"173.066336ms","start":"2026-07-30T04:32:30.876214Z","end":"2026-07-30T04:32:31.04928Z","steps":["trace[611832839] 'process raft request' (duration: 172.930424ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T04:32:31.050332Z","caller":"traceutil/trace.go:171","msg":"trace[1639435502] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"107.409989ms","start":"2026-07-30T04:32:30.942911Z","end":"2026-07-30T04:32:31.050321Z","steps":["trace[1639435502] 'process raft request' (duration: 107.190045ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T04:32:31.264384Z","caller":"traceutil/trace.go:171","msg":"trace[743886766] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"205.793297ms","start":"2026-07-30T04:32:31.05857Z","end":"2026-07-30T04:32:31.264363Z","steps":["trace[743886766] 'process raft request' (duration: 205.063844ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T04:32:31.283992Z","caller":"traceutil/trace.go:171","msg":"trace[995681427] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"189.949217ms","start":"2026-07-30T04:32:31.094026Z","end":"2026-07-30T04:32:31.283975Z","steps":["trace[995681427] 'process raft request' (duration: 189.864256ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T04:33:13.172529Z","caller":"traceutil/trace.go:171","msg":"trace[1788327133] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"157.263956ms","start":"2026-07-30T04:33:13.015247Z","end":"2026-07-30T04:33:13.172511Z","steps":["trace[1788327133] 'process raft request' (duration: 147.426703ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T04:33:13.174769Z","caller":"traceutil/trace.go:171","msg":"trace[1679915314] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"157.410649ms","start":"2026-07-30T04:33:13.017351Z","end":"2026-07-30T04:33:13.174761Z","steps":["trace[1679915314] 'process raft request' (duration: 157.363728ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T04:34:22.508955Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874426187909253,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T04:34:22.732781Z","caller":"traceutil/trace.go:171","msg":"trace[153005597] linearizableReadLoop","detail":"{readStateIndex:1468; appliedIndex:1467; }","duration":"724.567434ms","start":"2026-07-30T04:34:22.00819Z","end":"2026-07-30T04:34:22.732758Z","steps":["trace[153005597] 'read index received' (duration: 724.31056ms)","trace[153005597] 'applied index is now lower than readState.Index' (duration: 255.864µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T04:34:22.732951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.717086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T04:34:22.732993Z","caller":"traceutil/trace.go:171","msg":"trace[1499774501] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1413; }","duration":"724.849328ms","start":"2026-07-30T04:34:22.008134Z","end":"2026-07-30T04:34:22.732983Z","steps":["trace[1499774501] 'agreement among raft nodes before linearized reading' (duration: 724.714846ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T04:34:22.733109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T04:34:22.008106Z","time spent":"724.98905ms","remote":"127.0.0.1:44598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-07-30T04:34:22.733171Z","caller":"traceutil/trace.go:171","msg":"trace[257899517] transaction","detail":"{read_only:false; response_revision:1413; number_of_response:1; }","duration":"856.117086ms","start":"2026-07-30T04:34:21.877017Z","end":"2026-07-30T04:34:22.733134Z","steps":["trace[257899517] 'process raft request' (duration: 855.589057ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T04:34:22.733356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T04:34:21.877Z","time spent":"856.256627ms","remote":"127.0.0.1:44562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}