{"level":"warn","ts":"2026-07-02T05:03:44.636517Z","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-02T05:03:44.636653Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.238: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.238:2380","--initial-cluster=instance=https://199.204.45.238:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.238:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.238: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-02T05:03:44.636748Z","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-02T05:03:44.636785Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-07-02T05:03:44.636816Z","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-02T05:03:44.637521Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"]} {"level":"info","ts":"2026-07-02T05:03:44.637635Z","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.238:2380"],"listen-peer-urls":["https://199.204.45.238:2380"],"advertise-client-urls":["https://199.204.45.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.238: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-02T05:03:44.642181Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.296562ms"} {"level":"info","ts":"2026-07-02T05:03:44.650094Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d7dbc9f5893cc1f6","cluster-id":"823d1d08ab21430c"} {"level":"info","ts":"2026-07-02T05:03:44.650175Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=()"} {"level":"info","ts":"2026-07-02T05:03:44.650312Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 0"} {"level":"info","ts":"2026-07-02T05:03:44.650343Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d7dbc9f5893cc1f6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-02T05:03:44.650431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 1"} {"level":"info","ts":"2026-07-02T05:03:44.650511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"warn","ts":"2026-07-02T05:03:44.667758Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-02T05:03:44.670508Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-02T05:03:44.671409Z","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-02T05:03:44.672945Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d7dbc9f5893cc1f6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-02T05:03:44.673161Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d7dbc9f5893cc1f6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-02T05:03:44.673201Z","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-02T05:03:44.673244Z","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-02T05:03:44.673276Z","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-02T05:03:44.677572Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"info","ts":"2026-07-02T05:03:44.677656Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"823d1d08ab21430c","local-member-id":"d7dbc9f5893cc1f6","added-peer-id":"d7dbc9f5893cc1f6","added-peer-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-07-02T05:03:44.678647Z","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-02T05:03:44.678808Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d7dbc9f5893cc1f6","initial-advertise-peer-urls":["https://199.204.45.238:2380"],"listen-peer-urls":["https://199.204.45.238:2380"],"advertise-client-urls":["https://199.204.45.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-02T05:03:44.678835Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-02T05:03:44.67897Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-07-02T05:03:44.678982Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-07-02T05:03:45.351101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 is starting a new election at term 1"} {"level":"info","ts":"2026-07-02T05:03:45.351158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-02T05:03:45.351203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgPreVoteResp from d7dbc9f5893cc1f6 at term 1"} {"level":"info","ts":"2026-07-02T05:03:45.35123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became candidate at term 2"} {"level":"info","ts":"2026-07-02T05:03:45.351252Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgVoteResp from d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-07-02T05:03:45.351272Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became leader at term 2"} {"level":"info","ts":"2026-07-02T05:03:45.35129Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d7dbc9f5893cc1f6 elected leader d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-07-02T05:03:45.352591Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d7dbc9f5893cc1f6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.238:2379]}","request-path":"/0/members/d7dbc9f5893cc1f6/attributes","cluster-id":"823d1d08ab21430c","publish-timeout":"7s"} {"level":"info","ts":"2026-07-02T05:03:45.352672Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T05:03:45.352906Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T05:03:45.353056Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T05:03:45.353376Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-02T05:03:45.353478Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-02T05:03:45.35631Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.238:2379"} {"level":"info","ts":"2026-07-02T05:03:45.35635Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-02T05:03:45.357266Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"823d1d08ab21430c","local-member-id":"d7dbc9f5893cc1f6","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T05:03:45.357368Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T05:03:45.357402Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T05:04:06.619982Z","caller":"traceutil/trace.go:171","msg":"trace[2028165090] linearizableReadLoop","detail":"{readStateIndex:434; appliedIndex:433; }","duration":"251.562836ms","start":"2026-07-02T05:04:06.368394Z","end":"2026-07-02T05:04:06.619956Z","steps":["trace[2028165090] 'read index received' (duration: 251.437834ms)","trace[2028165090] 'applied index is now lower than readState.Index' (duration: 122.311µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:04:06.62003Z","caller":"traceutil/trace.go:171","msg":"trace[1495637635] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"306.732085ms","start":"2026-07-02T05:04:06.313254Z","end":"2026-07-02T05:04:06.619986Z","steps":["trace[1495637635] 'process raft request' (duration: 306.57116ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:04:06.620145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.71645ms","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-02T05:04:06.620207Z","caller":"traceutil/trace.go:171","msg":"trace[791948446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:421; }","duration":"251.837782ms","start":"2026-07-02T05:04:06.368356Z","end":"2026-07-02T05:04:06.620194Z","steps":["trace[791948446] 'agreement among raft nodes before linearized reading' (duration: 251.71152ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:04:06.62067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:04:06.313236Z","time spent":"306.888219ms","remote":"127.0.0.1:51590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:04:24.220418Z","caller":"traceutil/trace.go:171","msg":"trace[877047902] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"395.334111ms","start":"2026-07-02T05:04:23.825059Z","end":"2026-07-02T05:04:24.220393Z","steps":["trace[877047902] 'process raft request' (duration: 395.204288ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:04:24.220553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:04:23.825038Z","time spent":"395.456093ms","remote":"127.0.0.1:51348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T05:04:24.229342Z","caller":"traceutil/trace.go:171","msg":"trace[1723702730] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"401.229083ms","start":"2026-07-02T05:04:23.828087Z","end":"2026-07-02T05:04:24.229316Z","steps":["trace[1723702730] 'process raft request' (duration: 401.040494ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:04:24.229388Z","caller":"traceutil/trace.go:171","msg":"trace[153827197] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"395.082478ms","start":"2026-07-02T05:04:23.834281Z","end":"2026-07-02T05:04:24.229363Z","steps":["trace[153827197] 'process raft request' (duration: 394.997489ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:04:24.229372Z","caller":"traceutil/trace.go:171","msg":"trace[524833787] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"395.152675ms","start":"2026-07-02T05:04:23.834193Z","end":"2026-07-02T05:04:24.229346Z","steps":["trace[524833787] 'process raft request' (duration: 395.042774ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:04:24.229469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:04:23.834255Z","time spent":"395.175287ms","remote":"127.0.0.1:51348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T05:04:24.229541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:04:23.828078Z","time spent":"401.333453ms","remote":"127.0.0.1:51348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T05:04:24.229537Z","caller":"traceutil/trace.go:171","msg":"trace[203158176] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"388.529073ms","start":"2026-07-02T05:04:23.840961Z","end":"2026-07-02T05:04:24.22949Z","steps":["trace[203158176] 'process raft request' (duration: 388.360246ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:04:24.22956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:04:23.834172Z","time spent":"395.316941ms","remote":"127.0.0.1:51348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T05:04:24.229687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:04:23.840946Z","time spent":"388.670157ms","remote":"127.0.0.1:51348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T05:04:24.230817Z","caller":"traceutil/trace.go:171","msg":"trace[1343860210] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:545; }","duration":"169.128556ms","start":"2026-07-02T05:04:24.061678Z","end":"2026-07-02T05:04:24.230806Z","steps":["trace[1343860210] 'read index received' (duration: 158.997318ms)","trace[1343860210] 'applied index is now lower than readState.Index' (duration: 10.130528ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:04:24.23097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.290853ms","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-07-02T05:04:24.231014Z","caller":"traceutil/trace.go:171","msg":"trace[638566598] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:538; }","duration":"169.36718ms","start":"2026-07-02T05:04:24.061633Z","end":"2026-07-02T05:04:24.231001Z","steps":["trace[638566598] 'agreement among raft nodes before linearized reading' (duration: 169.218636ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:04:24.231019Z","caller":"traceutil/trace.go:171","msg":"trace[530030901] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"372.973392ms","start":"2026-07-02T05:04:23.857994Z","end":"2026-07-02T05:04:24.230967Z","steps":["trace[530030901] 'process raft request' (duration: 372.460682ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:04:24.231117Z","caller":"traceutil/trace.go:171","msg":"trace[2084642546] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"359.251642ms","start":"2026-07-02T05:04:23.871822Z","end":"2026-07-02T05:04:24.231073Z","steps":["trace[2084642546] 'process raft request' (duration: 358.702277ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:04:24.23119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:04:23.871793Z","time spent":"359.371484ms","remote":"127.0.0.1:51348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T05:04:24.231211Z","caller":"traceutil/trace.go:171","msg":"trace[1801168717] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"335.904294ms","start":"2026-07-02T05:04:23.895295Z","end":"2026-07-02T05:04:24.231199Z","steps":["trace[1801168717] 'process raft request' (duration: 335.291303ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:04:24.231324Z","caller":"traceutil/trace.go:171","msg":"trace[1766915195] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"373.268912ms","start":"2026-07-02T05:04:23.858047Z","end":"2026-07-02T05:04:24.231316Z","steps":["trace[1766915195] 'process raft request' (duration: 372.44221ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:04:24.231386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:04:23.858043Z","time spent":"373.325628ms","remote":"127.0.0.1:51348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T05:04:24.231425Z","caller":"traceutil/trace.go:171","msg":"trace[2068755987] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"379.196636ms","start":"2026-07-02T05:04:23.852222Z","end":"2026-07-02T05:04:24.231419Z","steps":["trace[2068755987] 'process raft request' (duration: 378.184605ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:04:24.231464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:04:23.85221Z","time spent":"379.236229ms","remote":"127.0.0.1:51348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T05:04:24.231188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:04:23.857968Z","time spent":"373.130718ms","remote":"127.0.0.1:51348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T05:04:24.231399Z","caller":"traceutil/trace.go:171","msg":"trace[25545217] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"385.561651ms","start":"2026-07-02T05:04:23.845826Z","end":"2026-07-02T05:04:24.231388Z","steps":["trace[25545217] 'process raft request' (duration: 383.534791ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:04:24.23187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:04:23.845817Z","time spent":"386.031478ms","remote":"127.0.0.1:51348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T05:04:24.231353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:04:23.89528Z","time spent":"336.024305ms","remote":"127.0.0.1:51348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T05:04:24.232126Z","caller":"traceutil/trace.go:171","msg":"trace[18243198] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"240.179562ms","start":"2026-07-02T05:04:23.991927Z","end":"2026-07-02T05:04:24.232106Z","steps":["trace[18243198] 'process raft request' (duration: 238.771173ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:04:28.496576Z","caller":"traceutil/trace.go:171","msg":"trace[262155057] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:630; }","duration":"176.946659ms","start":"2026-07-02T05:04:28.319603Z","end":"2026-07-02T05:04:28.49655Z","steps":["trace[262155057] 'read index received' (duration: 176.672662ms)","trace[262155057] 'applied index is now lower than readState.Index' (duration: 272.667µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:04:28.496943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.278879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:04:28.497031Z","caller":"traceutil/trace.go:171","msg":"trace[895394130] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:610; }","duration":"124.427253ms","start":"2026-07-02T05:04:28.372592Z","end":"2026-07-02T05:04:28.497019Z","steps":["trace[895394130] 'agreement among raft nodes before linearized reading' (duration: 124.275708ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:04:28.496957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.305424ms","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-07-02T05:04:28.497141Z","caller":"traceutil/trace.go:171","msg":"trace[1224369907] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:610; }","duration":"177.547088ms","start":"2026-07-02T05:04:28.319573Z","end":"2026-07-02T05:04:28.49712Z","steps":["trace[1224369907] 'agreement among raft nodes before linearized reading' (duration: 177.101124ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:04:28.931178Z","caller":"traceutil/trace.go:171","msg":"trace[1710225733] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:633; }","duration":"130.719059ms","start":"2026-07-02T05:04:28.800406Z","end":"2026-07-02T05:04:28.931125Z","steps":["trace[1710225733] 'read index received' (duration: 130.364594ms)","trace[1710225733] 'applied index is now lower than readState.Index' (duration: 353.155µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:04:28.931217Z","caller":"traceutil/trace.go:171","msg":"trace[1079477350] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"133.591634ms","start":"2026-07-02T05:04:28.797587Z","end":"2026-07-02T05:04:28.931179Z","steps":["trace[1079477350] 'process raft request' (duration: 133.330579ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:04:28.931496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.060012ms","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-02T05:04:28.931591Z","caller":"traceutil/trace.go:171","msg":"trace[1999074105] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:613; }","duration":"131.180635ms","start":"2026-07-02T05:04:28.80038Z","end":"2026-07-02T05:04:28.931561Z","steps":["trace[1999074105] 'agreement among raft nodes before linearized reading' (duration: 130.921529ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:04:29.179056Z","caller":"traceutil/trace.go:171","msg":"trace[2130291893] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"243.431658ms","start":"2026-07-02T05:04:28.935577Z","end":"2026-07-02T05:04:29.179009Z","steps":["trace[2130291893] 'process raft request' (duration: 148.700397ms)","trace[2130291893] 'compare' (duration: 94.542932ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:04:30.659655Z","caller":"traceutil/trace.go:171","msg":"trace[52262181] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:642; }","duration":"101.546534ms","start":"2026-07-02T05:04:30.558094Z","end":"2026-07-02T05:04:30.659641Z","steps":["trace[52262181] 'read index received' (duration: 100.987568ms)","trace[52262181] 'applied index is now lower than readState.Index' (duration: 558.576µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:04:30.659714Z","caller":"traceutil/trace.go:171","msg":"trace[1732093261] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"118.779389ms","start":"2026-07-02T05:04:30.540909Z","end":"2026-07-02T05:04:30.659688Z","steps":["trace[1732093261] 'process raft request' (duration: 118.337425ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:04:30.659821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.720741ms","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-02T05:04:30.659844Z","caller":"traceutil/trace.go:171","msg":"trace[1543251410] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:622; }","duration":"101.780467ms","start":"2026-07-02T05:04:30.558055Z","end":"2026-07-02T05:04:30.659836Z","steps":["trace[1543251410] 'agreement among raft nodes before linearized reading' (duration: 101.662905ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:04:31.918963Z","caller":"traceutil/trace.go:171","msg":"trace[1859451425] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:654; }","duration":"280.31796ms","start":"2026-07-02T05:04:31.638619Z","end":"2026-07-02T05:04:31.918937Z","steps":["trace[1859451425] 'read index received' (duration: 279.62155ms)","trace[1859451425] 'applied index is now lower than readState.Index' (duration: 695.23µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:04:31.919022Z","caller":"traceutil/trace.go:171","msg":"trace[321785211] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"300.697283ms","start":"2026-07-02T05:04:31.618277Z","end":"2026-07-02T05:04:31.918975Z","steps":["trace[321785211] 'process raft request' (duration: 300.147397ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:04:31.919091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.447603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:04:31.919131Z","caller":"traceutil/trace.go:171","msg":"trace[2134773107] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:634; }","duration":"280.532481ms","start":"2026-07-02T05:04:31.638588Z","end":"2026-07-02T05:04:31.91912Z","steps":["trace[2134773107] 'agreement among raft nodes before linearized reading' (duration: 280.452174ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:04:31.919334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:04:31.618255Z","time spent":"300.842538ms","remote":"127.0.0.1:51396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036229,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:04:32.548691Z","caller":"traceutil/trace.go:171","msg":"trace[1944069516] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"575.894408ms","start":"2026-07-02T05:04:31.972778Z","end":"2026-07-02T05:04:32.548672Z","steps":["trace[1944069516] 'process raft request' (duration: 575.685767ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:04:32.548803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:04:31.972758Z","time spent":"575.984507ms","remote":"127.0.0.1:51670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T05:04:32.548611Z","caller":"traceutil/trace.go:171","msg":"trace[1244758073] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"403.599914ms","start":"2026-07-02T05:04:32.144988Z","end":"2026-07-02T05:04:32.548588Z","steps":["trace[1244758073] 'read index received' (duration: 403.425086ms)","trace[1244758073] 'applied index is now lower than readState.Index' (duration: 173.718µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:04:32.549183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.196784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-ndv29\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-02T05:04:32.549218Z","caller":"traceutil/trace.go:171","msg":"trace[380861762] range","detail":"{range_begin:/registry/pods/kube-system/cilium-ndv29; range_end:; response_count:1; response_revision:635; }","duration":"404.285023ms","start":"2026-07-02T05:04:32.144923Z","end":"2026-07-02T05:04:32.549209Z","steps":["trace[380861762] 'agreement among raft nodes before linearized reading' (duration: 404.066191ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:04:32.549248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:04:32.144901Z","time spent":"404.340359ms","remote":"127.0.0.1:51508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-ndv29\" "} {"level":"info","ts":"2026-07-02T05:04:32.978303Z","caller":"traceutil/trace.go:171","msg":"trace[830709303] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:656; }","duration":"338.515923ms","start":"2026-07-02T05:04:32.639767Z","end":"2026-07-02T05:04:32.978283Z","steps":["trace[830709303] 'read index received' (duration: 322.66709ms)","trace[830709303] 'applied index is now lower than readState.Index' (duration: 15.847963ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:04:32.978654Z","caller":"traceutil/trace.go:171","msg":"trace[2124600524] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"413.331835ms","start":"2026-07-02T05:04:32.565314Z","end":"2026-07-02T05:04:32.978646Z","steps":["trace[2124600524] 'process raft request' (duration: 397.221466ms)","trace[2124600524] 'compare' (duration: 15.644083ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:04:32.978776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:04:32.565294Z","time spent":"413.381771ms","remote":"127.0.0.1:51508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:04:32.978835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.65154ms","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-02T05:04:32.978912Z","caller":"traceutil/trace.go:171","msg":"trace[762864097] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:636; }","duration":"300.780323ms","start":"2026-07-02T05:04:32.678117Z","end":"2026-07-02T05:04:32.978898Z","steps":["trace[762864097] 'agreement among raft nodes before linearized reading' (duration: 300.631109ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:04:32.978946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:04:32.678097Z","time spent":"300.84347ms","remote":"127.0.0.1:51590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-02T05:04:32.979094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.300681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:04:32.979169Z","caller":"traceutil/trace.go:171","msg":"trace[2033296459] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:636; }","duration":"339.3909ms","start":"2026-07-02T05:04:32.639761Z","end":"2026-07-02T05:04:32.979152Z","steps":["trace[2033296459] 'agreement among raft nodes before linearized reading' (duration: 339.232484ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:04:32.979218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:04:32.639701Z","time spent":"339.510732ms","remote":"127.0.0.1:51290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T05:04:33.486271Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976533458582114190,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T05:04:33.987144Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976533458582114190,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T05:04:34.437517Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.449965885s","expected-duration":"1s"} {"level":"info","ts":"2026-07-02T05:04:34.458631Z","caller":"traceutil/trace.go:171","msg":"trace[1524010493] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"1.473296551s","start":"2026-07-02T05:04:32.985306Z","end":"2026-07-02T05:04:34.458603Z","steps":["trace[1524010493] 'process raft request' (duration: 1.452645313s)","trace[1524010493] 'compare' (duration: 20.293772ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:04:34.458775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:04:32.985277Z","time spent":"1.473421043s","remote":"127.0.0.1:51396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036265,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:04:34.48857Z","caller":"traceutil/trace.go:171","msg":"trace[1611238535] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"1.500358903s","start":"2026-07-02T05:04:32.988191Z","end":"2026-07-02T05:04:34.48855Z","steps":["trace[1611238535] 'process raft request' (duration: 1.500185845s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:04:34.488681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:04:32.988181Z","time spent":"1.500441171s","remote":"127.0.0.1:51590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:04:34.488863Z","caller":"traceutil/trace.go:171","msg":"trace[2087490195] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"1.500433009s","start":"2026-07-02T05:04:32.988425Z","end":"2026-07-02T05:04:34.488858Z","steps":["trace[2087490195] 'process raft request' (duration: 1.500057942s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:04:34.4889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:04:32.988418Z","time spent":"1.500464733s","remote":"127.0.0.1:51590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:04:34.546573Z","caller":"traceutil/trace.go:171","msg":"trace[78863654] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:657; }","duration":"1.560597585s","start":"2026-07-02T05:04:32.985956Z","end":"2026-07-02T05:04:34.546554Z","steps":["trace[78863654] 'read index received' (duration: 1.451871796s)","trace[78863654] 'applied index is now lower than readState.Index' (duration: 108.724669ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:04:34.546722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.56074029s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T05:04:34.547183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.358138556s","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-07-02T05:04:34.547524Z","caller":"traceutil/trace.go:171","msg":"trace[1992654657] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:640; }","duration":"1.358480931s","start":"2026-07-02T05:04:33.189007Z","end":"2026-07-02T05:04:34.547488Z","steps":["trace[1992654657] 'agreement among raft nodes before linearized reading' (duration: 1.358044297s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:04:34.547578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:04:33.188991Z","time spent":"1.35857126s","remote":"127.0.0.1:51590","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-02T05:04:34.547746Z","caller":"traceutil/trace.go:171","msg":"trace[506051483] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"1.56084808s","start":"2026-07-02T05:04:32.985935Z","end":"2026-07-02T05:04:34.546783Z","steps":["trace[506051483] 'agreement among raft nodes before linearized reading' (duration: 1.560702216s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:04:34.547818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:04:32.985923Z","time spent":"1.561878053s","remote":"127.0.0.1:51276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T05:04:34.548039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.323566068s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-02T05:04:34.548148Z","caller":"traceutil/trace.go:171","msg":"trace[364250421] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:640; }","duration":"1.323727865s","start":"2026-07-02T05:04:33.224391Z","end":"2026-07-02T05:04:34.548119Z","steps":["trace[364250421] 'agreement among raft nodes before linearized reading' (duration: 1.323410483s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:04:34.548202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:04:33.224367Z","time spent":"1.323823094s","remote":"127.0.0.1:51590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-02T05:04:36.496583Z","caller":"traceutil/trace.go:171","msg":"trace[481646484] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"133.354101ms","start":"2026-07-02T05:04:36.363201Z","end":"2026-07-02T05:04:36.496555Z","steps":["trace[481646484] 'process raft request' (duration: 133.133208ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:04:50.589946Z","caller":"traceutil/trace.go:171","msg":"trace[1590609292] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"539.782508ms","start":"2026-07-02T05:04:50.050142Z","end":"2026-07-02T05:04:50.589924Z","steps":["trace[1590609292] 'process raft request' (duration: 539.638974ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:04:50.590238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:04:50.050124Z","time spent":"540.013896ms","remote":"127.0.0.1:51590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:04:50.82777Z","caller":"traceutil/trace.go:171","msg":"trace[1609989807] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:826; }","duration":"324.694326ms","start":"2026-07-02T05:04:50.503053Z","end":"2026-07-02T05:04:50.827748Z","steps":["trace[1609989807] 'read index received' (duration: 86.699666ms)","trace[1609989807] 'applied index is now lower than readState.Index' (duration: 237.99351ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:04:50.827849Z","caller":"traceutil/trace.go:171","msg":"trace[2023089470] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"571.592859ms","start":"2026-07-02T05:04:50.256219Z","end":"2026-07-02T05:04:50.827812Z","steps":["trace[2023089470] 'process raft request' (duration: 571.397669ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:04:50.828153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.854459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-02T05:04:50.828219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.622225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T05:04:50.828247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.933464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-02T05:04:50.828268Z","caller":"traceutil/trace.go:171","msg":"trace[981475435] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:801; }","duration":"325.226871ms","start":"2026-07-02T05:04:50.50301Z","end":"2026-07-02T05:04:50.828237Z","steps":["trace[981475435] 'agreement among raft nodes before linearized reading' (duration: 324.82693ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:04:50.828281Z","caller":"traceutil/trace.go:171","msg":"trace[469836977] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:801; }","duration":"185.73517ms","start":"2026-07-02T05:04:50.642525Z","end":"2026-07-02T05:04:50.82826Z","steps":["trace[469836977] 'agreement among raft nodes before linearized reading' (duration: 185.613146ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:04:50.828296Z","caller":"traceutil/trace.go:171","msg":"trace[1929072388] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:801; }","duration":"264.038979ms","start":"2026-07-02T05:04:50.564242Z","end":"2026-07-02T05:04:50.828281Z","steps":["trace[1929072388] 'agreement among raft nodes before linearized reading' (duration: 263.869588ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:04:50.828286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:04:50.256196Z","time spent":"572.004329ms","remote":"127.0.0.1:51560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:04:50.828335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:04:50.502997Z","time spent":"325.322197ms","remote":"127.0.0.1:51788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":2,"response size":31,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-07-02T05:04:50.828345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.440933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-02T05:04:50.828366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.478055ms","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-02T05:04:50.828407Z","caller":"traceutil/trace.go:171","msg":"trace[609693977] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:801; }","duration":"194.535448ms","start":"2026-07-02T05:04:50.633855Z","end":"2026-07-02T05:04:50.82839Z","steps":["trace[609693977] 'agreement among raft nodes before linearized reading' (duration: 194.319919ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:04:50.828435Z","caller":"traceutil/trace.go:171","msg":"trace[750747804] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:801; }","duration":"146.574821ms","start":"2026-07-02T05:04:50.681843Z","end":"2026-07-02T05:04:50.828417Z","steps":["trace[750747804] 'agreement among raft nodes before linearized reading' (duration: 146.37663ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:04:50.82842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.749877ms","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-02T05:04:50.828528Z","caller":"traceutil/trace.go:171","msg":"trace[401538602] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:801; }","duration":"260.929409ms","start":"2026-07-02T05:04:50.567552Z","end":"2026-07-02T05:04:50.828482Z","steps":["trace[401538602] 'agreement among raft nodes before linearized reading' (duration: 260.634293ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:06:03.591113Z","caller":"traceutil/trace.go:171","msg":"trace[1093984835] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"309.278101ms","start":"2026-07-02T05:06:03.281807Z","end":"2026-07-02T05:06:03.591085Z","steps":["trace[1093984835] 'process raft request' (duration: 279.795252ms)","trace[1093984835] 'compare' (duration: 29.375814ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:06:03.591267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:06:03.281784Z","time spent":"309.412706ms","remote":"127.0.0.1:51590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"}