{"level":"warn","ts":"2026-08-31T07:17:35.685499Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-31T07:17:35.685639Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.250: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://162.253.55.250:2380","--initial-cluster=instance=https://162.253.55.250:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.250:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.250:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-31T07:17:35.685727Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-31T07:17:35.685858Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.250:2380"]} {"level":"info","ts":"2026-08-31T07:17:35.685928Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-31T07:17:35.686913Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.250:2379"]} {"level":"info","ts":"2026-08-31T07:17:35.687034Z","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://162.253.55.250:2380"],"listen-peer-urls":["https://162.253.55.250:2380"],"advertise-client-urls":["https://162.253.55.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.250:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-31T07:17:35.691358Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.075909ms"} {"level":"info","ts":"2026-08-31T07:17:35.699724Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1e4a0bccff66c64d","cluster-id":"8ba64f6e83da771"} {"level":"info","ts":"2026-08-31T07:17:35.699819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d switched to configuration voters=()"} {"level":"info","ts":"2026-08-31T07:17:35.699839Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d became follower at term 0"} {"level":"info","ts":"2026-08-31T07:17:35.699852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1e4a0bccff66c64d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-31T07:17:35.699858Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d became follower at term 1"} {"level":"info","ts":"2026-08-31T07:17:35.699895Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d switched to configuration voters=(2182569944500586061)"} {"level":"warn","ts":"2026-08-31T07:17:35.707534Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-31T07:17:35.708984Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-31T07:17:35.710469Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-31T07:17:35.712538Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1e4a0bccff66c64d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-31T07:17:35.713181Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-31T07:17:35.713283Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-31T07:17:35.713295Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-31T07:17:35.713152Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1e4a0bccff66c64d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-31T07:17:35.716452Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d switched to configuration voters=(2182569944500586061)"} {"level":"info","ts":"2026-08-31T07:17:35.716625Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8ba64f6e83da771","local-member-id":"1e4a0bccff66c64d","added-peer-id":"1e4a0bccff66c64d","added-peer-peer-urls":["https://162.253.55.250:2380"]} {"level":"info","ts":"2026-08-31T07:17:35.717099Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-31T07:17:35.717265Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.250:2380"} {"level":"info","ts":"2026-08-31T07:17:35.717425Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1e4a0bccff66c64d","initial-advertise-peer-urls":["https://162.253.55.250:2380"],"listen-peer-urls":["https://162.253.55.250:2380"],"advertise-client-urls":["https://162.253.55.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-31T07:17:35.717445Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.250:2380"} {"level":"info","ts":"2026-08-31T07:17:35.717479Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-31T07:17:36.100489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d is starting a new election at term 1"} {"level":"info","ts":"2026-08-31T07:17:36.100619Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d became pre-candidate at term 1"} {"level":"info","ts":"2026-08-31T07:17:36.1007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d received MsgPreVoteResp from 1e4a0bccff66c64d at term 1"} {"level":"info","ts":"2026-08-31T07:17:36.10078Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d became candidate at term 2"} {"level":"info","ts":"2026-08-31T07:17:36.100815Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d received MsgVoteResp from 1e4a0bccff66c64d at term 2"} {"level":"info","ts":"2026-08-31T07:17:36.100875Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d became leader at term 2"} {"level":"info","ts":"2026-08-31T07:17:36.100928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1e4a0bccff66c64d elected leader 1e4a0bccff66c64d at term 2"} {"level":"info","ts":"2026-08-31T07:17:36.102782Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1e4a0bccff66c64d","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.250:2379]}","request-path":"/0/members/1e4a0bccff66c64d/attributes","cluster-id":"8ba64f6e83da771","publish-timeout":"7s"} {"level":"info","ts":"2026-08-31T07:17:36.103001Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-31T07:17:36.103087Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-31T07:17:36.103478Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-31T07:17:36.104285Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-31T07:17:36.104328Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-31T07:17:36.104474Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8ba64f6e83da771","local-member-id":"1e4a0bccff66c64d","cluster-version":"3.5"} {"level":"info","ts":"2026-08-31T07:17:36.104589Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-31T07:17:36.10464Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-31T07:17:36.105062Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.250:2379"} {"level":"info","ts":"2026-08-31T07:17:36.106978Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-31T07:17:38.767167Z","caller":"traceutil/trace.go:171","msg":"trace[498693423] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"121.325494ms","start":"2026-08-31T07:17:38.64581Z","end":"2026-08-31T07:17:38.767136Z","steps":["trace[498693423] 'read index received' (duration: 121.09144ms)","trace[498693423] 'applied index is now lower than readState.Index' (duration: 233.154µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:17:38.767216Z","caller":"traceutil/trace.go:171","msg":"trace[183977776] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"121.987636ms","start":"2026-08-31T07:17:38.645207Z","end":"2026-08-31T07:17:38.767195Z","steps":["trace[183977776] 'process raft request' (duration: 121.798282ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:17:38.767591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.724741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-31T07:17:38.767674Z","caller":"traceutil/trace.go:171","msg":"trace[442543542] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:72; }","duration":"121.862404ms","start":"2026-08-31T07:17:38.645794Z","end":"2026-08-31T07:17:38.767657Z","steps":["trace[442543542] 'agreement among raft nodes before linearized reading' (duration: 121.494867ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:17:41.868991Z","caller":"traceutil/trace.go:171","msg":"trace[1815515617] transaction","detail":"{read_only:false; number_of_response:0; response_revision:248; }","duration":"360.200579ms","start":"2026-08-31T07:17:41.508771Z","end":"2026-08-31T07:17:41.868971Z","steps":["trace[1815515617] 'process raft request' (duration: 360.081956ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:17:41.869052Z","caller":"traceutil/trace.go:171","msg":"trace[448240607] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"361.468343ms","start":"2026-08-31T07:17:41.507537Z","end":"2026-08-31T07:17:41.869006Z","steps":["trace[448240607] 'process raft request' (duration: 361.238478ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:17:41.868974Z","caller":"traceutil/trace.go:171","msg":"trace[521895141] transaction","detail":"{read_only:false; response_revision:246; number_of_response:1; }","duration":"384.016681ms","start":"2026-08-31T07:17:41.484926Z","end":"2026-08-31T07:17:41.868942Z","steps":["trace[521895141] 'process raft request' (duration: 383.693206ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:17:41.869252Z","caller":"traceutil/trace.go:171","msg":"trace[1490521843] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"361.192466ms","start":"2026-08-31T07:17:41.507757Z","end":"2026-08-31T07:17:41.868949Z","steps":["trace[1490521843] 'process raft request' (duration: 361.064814ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:17:41.869406Z","caller":"traceutil/trace.go:171","msg":"trace[257388931] transaction","detail":"{read_only:false; number_of_response:0; response_revision:248; }","duration":"360.579375ms","start":"2026-08-31T07:17:41.508814Z","end":"2026-08-31T07:17:41.869393Z","steps":["trace[257388931] 'process raft request' (duration: 360.067615ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:17:41.869584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:17:41.508808Z","time spent":"360.624117ms","remote":"127.0.0.1:51318","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-31T07:17:41.869566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:17:41.508764Z","time spent":"360.30205ms","remote":"127.0.0.1:51318","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-31T07:17:41.869643Z","caller":"traceutil/trace.go:171","msg":"trace[1225259467] linearizableReadLoop","detail":"{readStateIndex:253; appliedIndex:251; }","duration":"378.641291ms","start":"2026-08-31T07:17:41.490986Z","end":"2026-08-31T07:17:41.869627Z","steps":["trace[1225259467] 'read index received' (duration: 24.923213ms)","trace[1225259467] 'applied index is now lower than readState.Index' (duration: 353.716968ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:17:41.869672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:17:41.50775Z","time spent":"361.587624ms","remote":"127.0.0.1:51318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-31T07:17:41.869753Z","caller":"traceutil/trace.go:171","msg":"trace[1703825527] transaction","detail":"{read_only:false; number_of_response:0; response_revision:248; }","duration":"360.479754ms","start":"2026-08-31T07:17:41.50926Z","end":"2026-08-31T07:17:41.86974Z","steps":["trace[1703825527] 'process raft request' (duration: 359.647969ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:17:41.86981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:17:41.509253Z","time spent":"360.522425ms","remote":"127.0.0.1:51318","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-31T07:17:41.869848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:17:41.507518Z","time spent":"361.677577ms","remote":"127.0.0.1:51318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-31T07:17:41.869867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.866825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-08-31T07:17:41.869891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:17:41.484906Z","time spent":"384.403229ms","remote":"127.0.0.1:51206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-31T07:17:41.86992Z","caller":"traceutil/trace.go:171","msg":"trace[1009533799] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:248; }","duration":"378.953758ms","start":"2026-08-31T07:17:41.49095Z","end":"2026-08-31T07:17:41.869903Z","steps":["trace[1009533799] 'agreement among raft nodes before linearized reading' (duration: 378.828165ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:17:41.86996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:17:41.490941Z","time spent":"379.008429ms","remote":"127.0.0.1:51338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-08-31T07:17:42.456952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.689375ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-31T07:17:42.457107Z","caller":"traceutil/trace.go:171","msg":"trace[1008887338] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"584.377698ms","start":"2026-08-31T07:17:41.872714Z","end":"2026-08-31T07:17:42.457091Z","steps":["trace[1008887338] 'process raft request' (duration: 584.327757ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:17:42.457174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:17:41.872689Z","time spent":"584.459571ms","remote":"127.0.0.1:51206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":638,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-31T07:17:42.457341Z","caller":"traceutil/trace.go:171","msg":"trace[1157079943] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"845.066206ms","start":"2026-08-31T07:17:41.612263Z","end":"2026-08-31T07:17:42.457329Z","steps":["trace[1157079943] 'process raft request' (duration: 345.740549ms)","trace[1157079943] 'compare' (duration: 498.477242ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:17:42.457391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:17:41.612244Z","time spent":"845.126158ms","remote":"127.0.0.1:51232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1934,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-31T07:17:42.457528Z","caller":"traceutil/trace.go:171","msg":"trace[576431341] linearizableReadLoop","detail":"{readStateIndex:259; appliedIndex:258; }","duration":"587.801753ms","start":"2026-08-31T07:17:41.869688Z","end":"2026-08-31T07:17:42.457489Z","steps":["trace[576431341] 'read index received' (duration: 88.329164ms)","trace[576431341] 'applied index is now lower than readState.Index' (duration: 499.459269ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:17:42.457797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.782448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-08-31T07:17:42.457886Z","caller":"traceutil/trace.go:171","msg":"trace[71669554] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:250; }","duration":"932.911761ms","start":"2026-08-31T07:17:41.524951Z","end":"2026-08-31T07:17:42.457862Z","steps":["trace[71669554] 'agreement among raft nodes before linearized reading' (duration: 932.696217ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:17:42.457924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:17:41.524932Z","time spent":"932.982672ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4039,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-08-31T07:17:42.588525Z","caller":"traceutil/trace.go:171","msg":"trace[120524759] transaction","detail":"{read_only:false; response_revision:251; number_of_response:1; }","duration":"127.8997ms","start":"2026-08-31T07:17:42.460607Z","end":"2026-08-31T07:17:42.588506Z","steps":["trace[120524759] 'process raft request' (duration: 127.787138ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:17:42.588645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.69876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"warn","ts":"2026-08-31T07:17:42.588657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.312065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:17:42.588688Z","caller":"traceutil/trace.go:171","msg":"trace[1181217078] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:252; }","duration":"716.790771ms","start":"2026-08-31T07:17:41.871887Z","end":"2026-08-31T07:17:42.588677Z","steps":["trace[1181217078] 'agreement among raft nodes before linearized reading' (duration: 716.673209ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:17:42.58874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.633253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:17:42.58876Z","caller":"traceutil/trace.go:171","msg":"trace[244837199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:252; }","duration":"483.454256ms","start":"2026-08-31T07:17:42.105273Z","end":"2026-08-31T07:17:42.588727Z","steps":["trace[244837199] 'agreement among raft nodes before linearized reading' (duration: 483.256033ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:17:42.588883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:17:42.105252Z","time spent":"483.622841ms","remote":"127.0.0.1:51424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-31T07:17:42.58893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.65642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:5513"} {"level":"warn","ts":"2026-08-31T07:17:42.588682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.21082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/persistent-volume-binder\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:17:42.589094Z","caller":"traceutil/trace.go:171","msg":"trace[997655135] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/persistent-volume-binder; range_end:; response_count:0; response_revision:252; }","duration":"716.629868ms","start":"2026-08-31T07:17:41.872444Z","end":"2026-08-31T07:17:42.589074Z","steps":["trace[997655135] 'agreement among raft nodes before linearized reading' (duration: 716.126089ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:17:42.589112Z","caller":"traceutil/trace.go:171","msg":"trace[1011770805] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:252; }","duration":"156.871034ms","start":"2026-08-31T07:17:42.432229Z","end":"2026-08-31T07:17:42.5891Z","steps":["trace[1011770805] 'agreement among raft nodes before linearized reading' (duration: 156.62273ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:17:42.589149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:17:41.872439Z","time spent":"716.697301ms","remote":"127.0.0.1:51338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/kube-system/persistent-volume-binder\" "} {"level":"warn","ts":"2026-08-31T07:17:42.58874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:17:41.871867Z","time spent":"716.864682ms","remote":"127.0.0.1:51318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":3488,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"info","ts":"2026-08-31T07:17:42.588772Z","caller":"traceutil/trace.go:171","msg":"trace[475643426] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:252; }","duration":"507.660763ms","start":"2026-08-31T07:17:42.0811Z","end":"2026-08-31T07:17:42.588761Z","steps":["trace[475643426] 'agreement among raft nodes before linearized reading' (duration: 507.620492ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:17:42.589296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:17:42.081032Z","time spent":"508.256565ms","remote":"127.0.0.1:51424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-31T07:17:42.588516Z","caller":"traceutil/trace.go:171","msg":"trace[649625575] transaction","detail":"{read_only:false; number_of_response:0; response_revision:250; }","duration":"153.831218ms","start":"2026-08-31T07:17:42.434645Z","end":"2026-08-31T07:17:42.588476Z","steps":["trace[649625575] 'process raft request' (duration: 145.583045ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:17:42.58879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.73929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"info","ts":"2026-08-31T07:17:42.589485Z","caller":"traceutil/trace.go:171","msg":"trace[1469538370] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:252; }","duration":"717.457123ms","start":"2026-08-31T07:17:41.872018Z","end":"2026-08-31T07:17:42.589475Z","steps":["trace[1469538370] 'agreement among raft nodes before linearized reading' (duration: 716.74055ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:17:42.589511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:17:41.872003Z","time spent":"717.500624ms","remote":"127.0.0.1:51318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":2942,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-08-31T07:17:42.588509Z","caller":"traceutil/trace.go:171","msg":"trace[558347652] linearizableReadLoop","detail":"{readStateIndex:262; appliedIndex:260; }","duration":"130.931095ms","start":"2026-08-31T07:17:42.457561Z","end":"2026-08-31T07:17:42.588492Z","steps":["trace[558347652] 'read index received' (duration: 122.731674ms)","trace[558347652] 'applied index is now lower than readState.Index' (duration: 8.198481ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:17:42.588815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.830043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3980"} {"level":"info","ts":"2026-08-31T07:17:42.58966Z","caller":"traceutil/trace.go:171","msg":"trace[691512648] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:252; }","duration":"717.688788ms","start":"2026-08-31T07:17:41.871963Z","end":"2026-08-31T07:17:42.589652Z","steps":["trace[691512648] 'agreement among raft nodes before linearized reading' (duration: 716.825962ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:17:42.589688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:17:41.871934Z","time spent":"717.746278ms","remote":"127.0.0.1:51318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":4002,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"info","ts":"2026-08-31T07:17:42.588827Z","caller":"traceutil/trace.go:171","msg":"trace[370459155] transaction","detail":"{read_only:false; number_of_response:0; response_revision:250; }","duration":"153.573573ms","start":"2026-08-31T07:17:42.435247Z","end":"2026-08-31T07:17:42.58882Z","steps":["trace[370459155] 'process raft request' (duration: 153.125144ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:17:42.589808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.161073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-08-31T07:17:42.589871Z","caller":"traceutil/trace.go:171","msg":"trace[1184616668] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:252; }","duration":"129.263205ms","start":"2026-08-31T07:17:42.460591Z","end":"2026-08-31T07:17:42.589854Z","steps":["trace[1184616668] 'agreement among raft nodes before linearized reading' (duration: 129.123303ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:17:42.588869Z","caller":"traceutil/trace.go:171","msg":"trace[435571392] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"124.526148ms","start":"2026-08-31T07:17:42.464323Z","end":"2026-08-31T07:17:42.588849Z","steps":["trace[435571392] 'process raft request' (duration: 124.13396ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:17:46.573938Z","caller":"traceutil/trace.go:171","msg":"trace[1318411872] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"114.815131ms","start":"2026-08-31T07:17:46.459087Z","end":"2026-08-31T07:17:46.573902Z","steps":["trace[1318411872] 'process raft request' (duration: 49.864031ms)","trace[1318411872] 'compare' (duration: 64.759107ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:17:46.574261Z","caller":"traceutil/trace.go:171","msg":"trace[350785287] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"114.913154ms","start":"2026-08-31T07:17:46.459328Z","end":"2026-08-31T07:17:46.574241Z","steps":["trace[350785287] 'process raft request' (duration: 114.552567ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:17:46.574326Z","caller":"traceutil/trace.go:171","msg":"trace[458992214] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"114.952234ms","start":"2026-08-31T07:17:46.459356Z","end":"2026-08-31T07:17:46.574308Z","steps":["trace[458992214] 'process raft request' (duration: 114.608437ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:17:46.57448Z","caller":"traceutil/trace.go:171","msg":"trace[651443275] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"114.129258ms","start":"2026-08-31T07:17:46.460338Z","end":"2026-08-31T07:17:46.574467Z","steps":["trace[651443275] 'process raft request' (duration: 113.727311ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:17:46.574522Z","caller":"traceutil/trace.go:171","msg":"trace[547386298] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"115.215869ms","start":"2026-08-31T07:17:46.45929Z","end":"2026-08-31T07:17:46.574506Z","steps":["trace[547386298] 'process raft request' (duration: 114.526097ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:17:46.5749Z","caller":"traceutil/trace.go:171","msg":"trace[1126372571] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"115.455071ms","start":"2026-08-31T07:17:46.459427Z","end":"2026-08-31T07:17:46.574882Z","steps":["trace[1126372571] 'process raft request' (duration: 114.588226ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:17:46.717156Z","caller":"traceutil/trace.go:171","msg":"trace[575051111] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"126.318737ms","start":"2026-08-31T07:17:46.590804Z","end":"2026-08-31T07:17:46.717122Z","steps":["trace[575051111] 'process raft request' (duration: 96.33897ms)","trace[575051111] 'compare' (duration: 29.748652ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:17:46.7173Z","caller":"traceutil/trace.go:171","msg":"trace[968077786] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"123.424645ms","start":"2026-08-31T07:17:46.593767Z","end":"2026-08-31T07:17:46.717192Z","steps":["trace[968077786] 'process raft request' (duration: 123.232731ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:17:46.717471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.226932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-08-31T07:17:46.717515Z","caller":"traceutil/trace.go:171","msg":"trace[2020973387] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:328; }","duration":"127.310303ms","start":"2026-08-31T07:17:46.590191Z","end":"2026-08-31T07:17:46.717502Z","steps":["trace[2020973387] 'agreement among raft nodes before linearized reading' (duration: 127.214852ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:18:17.084093Z","caller":"traceutil/trace.go:171","msg":"trace[1094376829] linearizableReadLoop","detail":"{readStateIndex:526; appliedIndex:525; }","duration":"117.953662ms","start":"2026-08-31T07:18:16.966086Z","end":"2026-08-31T07:18:17.08404Z","steps":["trace[1094376829] 'read index received' (duration: 11.10422ms)","trace[1094376829] 'applied index is now lower than readState.Index' (duration: 106.848252ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:18:17.084254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.472032ms","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-08-31T07:18:17.08429Z","caller":"traceutil/trace.go:171","msg":"trace[1499798763] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:507; }","duration":"192.545513ms","start":"2026-08-31T07:18:16.891734Z","end":"2026-08-31T07:18:17.084279Z","steps":["trace[1499798763] 'agreement among raft nodes before linearized reading' (duration: 192.433122ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:18:17.084314Z","caller":"traceutil/trace.go:171","msg":"trace[2066689351] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"118.840734ms","start":"2026-08-31T07:18:16.965442Z","end":"2026-08-31T07:18:17.084283Z","steps":["trace[2066689351] 'process raft request' (duration: 110.582365ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:18:17.084429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.618853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-31T07:18:17.084468Z","caller":"traceutil/trace.go:171","msg":"trace[1100908705] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:507; }","duration":"145.689624ms","start":"2026-08-31T07:18:16.938767Z","end":"2026-08-31T07:18:17.084457Z","steps":["trace[1100908705] 'agreement among raft nodes before linearized reading' (duration: 145.555292ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:18:17.091849Z","caller":"traceutil/trace.go:171","msg":"trace[1498820547] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"113.03445ms","start":"2026-08-31T07:18:16.978797Z","end":"2026-08-31T07:18:17.091831Z","steps":["trace[1498820547] 'process raft request' (duration: 111.472088ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:18:17.091983Z","caller":"traceutil/trace.go:171","msg":"trace[1240878905] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"103.844057ms","start":"2026-08-31T07:18:16.988132Z","end":"2026-08-31T07:18:17.091976Z","steps":["trace[1240878905] 'process raft request' (duration: 102.286015ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:18:20.111521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.833875ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-31T07:18:20.111631Z","caller":"traceutil/trace.go:171","msg":"trace[587688212] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"381.944507ms","start":"2026-08-31T07:18:19.72967Z","end":"2026-08-31T07:18:20.111615Z","steps":["trace[587688212] 'process raft request' (duration: 252.93709ms)","trace[587688212] 'compare' (duration: 128.688643ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:18:20.111686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:18:19.72966Z","time spent":"382.000238ms","remote":"127.0.0.1:51338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":182,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-31T07:18:21.224239Z","caller":"traceutil/trace.go:171","msg":"trace[823595189] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"107.583224ms","start":"2026-08-31T07:18:21.116601Z","end":"2026-08-31T07:18:21.224184Z","steps":["trace[823595189] 'process raft request' (duration: 96.402497ms)","trace[823595189] 'compare' (duration: 10.583119ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:18:24.113503Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14289253486867601429,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-31T07:18:24.201595Z","caller":"traceutil/trace.go:171","msg":"trace[183200317] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"644.342196ms","start":"2026-08-31T07:18:23.557224Z","end":"2026-08-31T07:18:24.201566Z","steps":["trace[183200317] 'process raft request' (duration: 644.162794ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:18:24.201963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:18:23.557205Z","time spent":"644.465297ms","remote":"127.0.0.1:51318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T07:18:24.345095Z","caller":"traceutil/trace.go:171","msg":"trace[358053460] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:625; }","duration":"732.178787ms","start":"2026-08-31T07:18:23.612865Z","end":"2026-08-31T07:18:24.345044Z","steps":["trace[358053460] 'read index received' (duration: 588.514261ms)","trace[358053460] 'applied index is now lower than readState.Index' (duration: 143.663566ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:18:24.345191Z","caller":"traceutil/trace.go:171","msg":"trace[811178671] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"626.122182ms","start":"2026-08-31T07:18:23.719044Z","end":"2026-08-31T07:18:24.345166Z","steps":["trace[811178671] 'process raft request' (duration: 621.564079ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:18:24.345319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.4199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-31T07:18:24.345352Z","caller":"traceutil/trace.go:171","msg":"trace[1753168637] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:603; }","duration":"732.512841ms","start":"2026-08-31T07:18:23.612829Z","end":"2026-08-31T07:18:24.345342Z","steps":["trace[1753168637] 'agreement among raft nodes before linearized reading' (duration: 732.367269ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:18:24.345346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:18:23.719017Z","time spent":"626.225113ms","remote":"127.0.0.1:51228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T07:18:24.345379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:18:23.612816Z","time spent":"732.555322ms","remote":"127.0.0.1:51424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-31T07:18:24.345404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.102261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:18:24.345449Z","caller":"traceutil/trace.go:171","msg":"trace[155935648] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:603; }","duration":"690.164232ms","start":"2026-08-31T07:18:23.655269Z","end":"2026-08-31T07:18:24.345433Z","steps":["trace[155935648] 'agreement among raft nodes before linearized reading' (duration: 690.092791ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:18:24.345479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:18:23.655257Z","time spent":"690.215482ms","remote":"127.0.0.1:51124","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-08-31T07:18:25.00626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.497622ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-31T07:18:25.006509Z","caller":"traceutil/trace.go:171","msg":"trace[1105484835] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"586.306538ms","start":"2026-08-31T07:18:24.420168Z","end":"2026-08-31T07:18:25.006475Z","steps":["trace[1105484835] 'process raft request' (duration: 208.527354ms)","trace[1105484835] 'compare' (duration: 377.294797ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:18:25.00663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:18:24.420144Z","time spent":"586.43882ms","remote":"127.0.0.1:51386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-31T07:18:25.501067Z","caller":"traceutil/trace.go:171","msg":"trace[1212551387] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"425.39123ms","start":"2026-08-31T07:18:25.07565Z","end":"2026-08-31T07:18:25.501041Z","steps":["trace[1212551387] 'process raft request' (duration: 425.239368ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:18:25.501257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:18:25.075634Z","time spent":"425.548082ms","remote":"127.0.0.1:51318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-31T07:18:25.501538Z","caller":"traceutil/trace.go:171","msg":"trace[263664205] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:631; }","duration":"362.035509ms","start":"2026-08-31T07:18:25.139481Z","end":"2026-08-31T07:18:25.501516Z","steps":["trace[263664205] 'read index received' (duration: 361.285449ms)","trace[263664205] 'applied index is now lower than readState.Index' (duration: 748.09µs)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:18:25.501773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.377269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-08-31T07:18:25.501774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.284652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-08-31T07:18:25.501828Z","caller":"traceutil/trace.go:171","msg":"trace[1342559012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:606; }","duration":"233.473532ms","start":"2026-08-31T07:18:25.268339Z","end":"2026-08-31T07:18:25.501813Z","steps":["trace[1342559012] 'agreement among raft nodes before linearized reading' (duration: 233.309989ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:18:25.501835Z","caller":"traceutil/trace.go:171","msg":"trace[844151400] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:606; }","duration":"362.398365ms","start":"2026-08-31T07:18:25.139423Z","end":"2026-08-31T07:18:25.501821Z","steps":["trace[844151400] 'agreement among raft nodes before linearized reading' (duration: 362.181621ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:18:25.501877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:18:25.139405Z","time spent":"362.461586ms","remote":"127.0.0.1:51424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-31T07:18:25.50212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.592133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-31T07:18:25.502194Z","caller":"traceutil/trace.go:171","msg":"trace[1995364917] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:606; }","duration":"225.681724ms","start":"2026-08-31T07:18:25.276479Z","end":"2026-08-31T07:18:25.502161Z","steps":["trace[1995364917] 'agreement among raft nodes before linearized reading' (duration: 225.547153ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:18:25.757194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.220256ms","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-08-31T07:18:25.757332Z","caller":"traceutil/trace.go:171","msg":"trace[426900650] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:606; }","duration":"166.378078ms","start":"2026-08-31T07:18:25.590936Z","end":"2026-08-31T07:18:25.757314Z","steps":["trace[426900650] 'range keys from in-memory index tree' (duration: 166.201896ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:18:25.757404Z","caller":"traceutil/trace.go:171","msg":"trace[1575135260] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"250.768038ms","start":"2026-08-31T07:18:25.506584Z","end":"2026-08-31T07:18:25.757352Z","steps":["trace[1575135260] 'process raft request' (duration: 188.873867ms)","trace[1575135260] 'compare' (duration: 61.753429ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:18:25.769673Z","caller":"traceutil/trace.go:171","msg":"trace[1950533687] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"262.825364ms","start":"2026-08-31T07:18:25.506823Z","end":"2026-08-31T07:18:25.769648Z","steps":["trace[1950533687] 'process raft request' (duration: 262.656631ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:18:25.769858Z","caller":"traceutil/trace.go:171","msg":"trace[549734846] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"262.638103ms","start":"2026-08-31T07:18:25.507203Z","end":"2026-08-31T07:18:25.769841Z","steps":["trace[549734846] 'process raft request' (duration: 262.404619ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:18:25.769992Z","caller":"traceutil/trace.go:171","msg":"trace[752007271] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:631; }","duration":"114.12653ms","start":"2026-08-31T07:18:25.655845Z","end":"2026-08-31T07:18:25.769972Z","steps":["trace[752007271] 'read index received' (duration: 39.538994ms)","trace[752007271] 'applied index is now lower than readState.Index' (duration: 74.586216ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:18:25.770087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.232191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:18:25.770115Z","caller":"traceutil/trace.go:171","msg":"trace[1661042350] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:613; }","duration":"114.347483ms","start":"2026-08-31T07:18:25.655758Z","end":"2026-08-31T07:18:25.770106Z","steps":["trace[1661042350] 'agreement among raft nodes before linearized reading' (duration: 114.286483ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:18:25.770147Z","caller":"traceutil/trace.go:171","msg":"trace[1828308456] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"261.973593ms","start":"2026-08-31T07:18:25.508165Z","end":"2026-08-31T07:18:25.770139Z","steps":["trace[1828308456] 'process raft request' (duration: 261.660919ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:18:25.770226Z","caller":"traceutil/trace.go:171","msg":"trace[761172709] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"260.742276ms","start":"2026-08-31T07:18:25.509476Z","end":"2026-08-31T07:18:25.770218Z","steps":["trace[761172709] 'process raft request' (duration: 260.416812ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:18:25.770115Z","caller":"traceutil/trace.go:171","msg":"trace[380938442] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"261.589157ms","start":"2026-08-31T07:18:25.508514Z","end":"2026-08-31T07:18:25.770103Z","steps":["trace[380938442] 'process raft request' (duration: 261.345484ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:18:25.770565Z","caller":"traceutil/trace.go:171","msg":"trace[740890943] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"262.894385ms","start":"2026-08-31T07:18:25.507661Z","end":"2026-08-31T07:18:25.770556Z","steps":["trace[740890943] 'process raft request' (duration: 262.078885ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:18:27.885757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.087384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-31T07:18:27.885827Z","caller":"traceutil/trace.go:171","msg":"trace[667251543] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:637; }","duration":"109.213956ms","start":"2026-08-31T07:18:27.776597Z","end":"2026-08-31T07:18:27.885811Z","steps":["trace[667251543] 'range keys from in-memory index tree' (duration: 108.906822ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:18:27.88613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.821087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-08-31T07:18:27.886165Z","caller":"traceutil/trace.go:171","msg":"trace[2019284683] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:637; }","duration":"110.924509ms","start":"2026-08-31T07:18:27.775231Z","end":"2026-08-31T07:18:27.886156Z","steps":["trace[2019284683] 'range keys from in-memory index tree' (duration: 110.671015ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:18:27.886514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.914336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-31T07:18:27.886589Z","caller":"traceutil/trace.go:171","msg":"trace[1451067454] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:637; }","duration":"113.051568ms","start":"2026-08-31T07:18:27.773519Z","end":"2026-08-31T07:18:27.886571Z","steps":["trace[1451067454] 'range keys from in-memory index tree' (duration: 112.090435ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:18:43.056421Z","caller":"traceutil/trace.go:171","msg":"trace[1416575962] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"234.810068ms","start":"2026-08-31T07:18:42.821581Z","end":"2026-08-31T07:18:43.056391Z","steps":["trace[1416575962] 'process raft request' (duration: 234.572646ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:18:43.196295Z","caller":"traceutil/trace.go:171","msg":"trace[140888484] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"208.126404ms","start":"2026-08-31T07:18:42.988129Z","end":"2026-08-31T07:18:43.196256Z","steps":["trace[140888484] 'process raft request' (duration: 196.176504ms)","trace[140888484] 'compare' (duration: 11.646006ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:18:43.200494Z","caller":"traceutil/trace.go:171","msg":"trace[1997537522] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"136.061678ms","start":"2026-08-31T07:18:43.064366Z","end":"2026-08-31T07:18:43.200427Z","steps":["trace[1997537522] 'process raft request' (duration: 135.883766ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:18:43.200815Z","caller":"traceutil/trace.go:171","msg":"trace[389749178] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"136.280321ms","start":"2026-08-31T07:18:43.06442Z","end":"2026-08-31T07:18:43.200701Z","steps":["trace[389749178] 'process raft request' (duration: 135.959806ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:18:43.201024Z","caller":"traceutil/trace.go:171","msg":"trace[1500591680] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"134.433279ms","start":"2026-08-31T07:18:43.066566Z","end":"2026-08-31T07:18:43.200999Z","steps":["trace[1500591680] 'process raft request' (duration: 133.979552ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:20:48.645322Z","caller":"traceutil/trace.go:171","msg":"trace[2101558634] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"131.115843ms","start":"2026-08-31T07:20:48.514188Z","end":"2026-08-31T07:20:48.645304Z","steps":["trace[2101558634] 'process raft request' (duration: 131.000888ms)"],"step_count":1}