{"level":"warn","ts":"2026-07-22T05:18:03.192827Z","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-22T05:18:03.192906Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.59:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.59:2380","--initial-cluster=instance=https://199.19.213.59:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.59:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.59: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-22T05:18:03.192965Z","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-22T05:18:03.192978Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.59:2380"]} {"level":"info","ts":"2026-07-22T05:18:03.192998Z","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-22T05:18:03.193666Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.59:2379"]} {"level":"info","ts":"2026-07-22T05:18:03.193735Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.59:2380"],"listen-peer-urls":["https://199.19.213.59:2380"],"advertise-client-urls":["https://199.19.213.59:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.59:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.59: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-22T05:18:03.195906Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"1.995035ms"} {"level":"info","ts":"2026-07-22T05:18:03.202908Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"41c06612bf92f90","cluster-id":"7f792254ff9520b"} {"level":"info","ts":"2026-07-22T05:18:03.20298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41c06612bf92f90 switched to configuration voters=()"} {"level":"info","ts":"2026-07-22T05:18:03.203001Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41c06612bf92f90 became follower at term 0"} {"level":"info","ts":"2026-07-22T05:18:03.203014Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 41c06612bf92f90 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-22T05:18:03.203021Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41c06612bf92f90 became follower at term 1"} {"level":"info","ts":"2026-07-22T05:18:03.203053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41c06612bf92f90 switched to configuration voters=(296118689918955408)"} {"level":"warn","ts":"2026-07-22T05:18:03.207405Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-22T05:18:03.20875Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-22T05:18:03.209907Z","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-22T05:18:03.211966Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"41c06612bf92f90","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-22T05:18:03.212479Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"41c06612bf92f90","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-22T05:18:03.212556Z","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-22T05:18:03.212594Z","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-22T05:18:03.212601Z","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-22T05:18:03.214143Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41c06612bf92f90 switched to configuration voters=(296118689918955408)"} {"level":"info","ts":"2026-07-22T05:18:03.214466Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7f792254ff9520b","local-member-id":"41c06612bf92f90","added-peer-id":"41c06612bf92f90","added-peer-peer-urls":["https://199.19.213.59:2380"]} {"level":"info","ts":"2026-07-22T05:18:03.216132Z","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-22T05:18:03.216241Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.59:2380"} {"level":"info","ts":"2026-07-22T05:18:03.216262Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.59:2380"} {"level":"info","ts":"2026-07-22T05:18:03.216315Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"41c06612bf92f90","initial-advertise-peer-urls":["https://199.19.213.59:2380"],"listen-peer-urls":["https://199.19.213.59:2380"],"advertise-client-urls":["https://199.19.213.59:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.59:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-22T05:18:03.216351Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-22T05:18:03.403758Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41c06612bf92f90 is starting a new election at term 1"} {"level":"info","ts":"2026-07-22T05:18:03.40386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41c06612bf92f90 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-22T05:18:03.403912Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41c06612bf92f90 received MsgPreVoteResp from 41c06612bf92f90 at term 1"} {"level":"info","ts":"2026-07-22T05:18:03.403943Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41c06612bf92f90 became candidate at term 2"} {"level":"info","ts":"2026-07-22T05:18:03.403966Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41c06612bf92f90 received MsgVoteResp from 41c06612bf92f90 at term 2"} {"level":"info","ts":"2026-07-22T05:18:03.403993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41c06612bf92f90 became leader at term 2"} {"level":"info","ts":"2026-07-22T05:18:03.40413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 41c06612bf92f90 elected leader 41c06612bf92f90 at term 2"} {"level":"info","ts":"2026-07-22T05:18:03.405251Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"41c06612bf92f90","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.59:2379]}","request-path":"/0/members/41c06612bf92f90/attributes","cluster-id":"7f792254ff9520b","publish-timeout":"7s"} {"level":"info","ts":"2026-07-22T05:18:03.405505Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-22T05:18:03.405582Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-22T05:18:03.406043Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T05:18:03.412233Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-22T05:18:03.412256Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-22T05:18:03.411419Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-22T05:18:03.411624Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.59:2379"} {"level":"info","ts":"2026-07-22T05:18:03.41675Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7f792254ff9520b","local-member-id":"41c06612bf92f90","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T05:18:03.416825Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T05:18:03.416851Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-07-22T05:18:14.015217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.762484ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-22T05:18:14.015364Z","caller":"traceutil/trace.go:171","msg":"trace[665155792] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"270.280606ms","start":"2026-07-22T05:18:13.745066Z","end":"2026-07-22T05:18:14.015347Z","steps":["trace[665155792] 'process raft request' (duration: 131.883345ms)","trace[665155792] 'compare' (duration: 137.643321ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T05:18:14.380391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.560311ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T05:18:14.38056Z","caller":"traceutil/trace.go:171","msg":"trace[1586379591] linearizableReadLoop","detail":"{readStateIndex:329; appliedIndex:327; }","duration":"597.658121ms","start":"2026-07-22T05:18:13.782885Z","end":"2026-07-22T05:18:14.380544Z","steps":["trace[1586379591] 'read index received' (duration: 94.072801ms)","trace[1586379591] 'applied index is now lower than readState.Index' (duration: 503.583479ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T05:18:14.380685Z","caller":"traceutil/trace.go:171","msg":"trace[248529297] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"634.349975ms","start":"2026-07-22T05:18:13.7463Z","end":"2026-07-22T05:18:14.38065Z","steps":["trace[248529297] 'process raft request' (duration: 397.325011ms)","trace[248529297] 'compare' (duration: 236.139581ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T05:18:14.380734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.828996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-07-22T05:18:14.380849Z","caller":"traceutil/trace.go:171","msg":"trace[380234325] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:318; }","duration":"597.997779ms","start":"2026-07-22T05:18:13.782838Z","end":"2026-07-22T05:18:14.380836Z","steps":["trace[380234325] 'agreement among raft nodes before linearized reading' (duration: 597.816334ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:18:14.380848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:18:13.746291Z","time spent":"634.478448ms","remote":"127.0.0.1:60854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4777,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T05:18:14.380957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:18:13.782817Z","time spent":"598.126114ms","remote":"127.0.0.1:60864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-07-22T05:18:14.381201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.878071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-07-22T05:18:14.38127Z","caller":"traceutil/trace.go:171","msg":"trace[632179378] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:318; }","duration":"547.982364ms","start":"2026-07-22T05:18:13.833277Z","end":"2026-07-22T05:18:14.381259Z","steps":["trace[632179378] 'agreement among raft nodes before linearized reading' (duration: 547.882971ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:18:14.381357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:18:13.833257Z","time spent":"548.089807ms","remote":"127.0.0.1:60864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":219,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"warn","ts":"2026-07-22T05:18:14.381366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.803266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-07-22T05:18:14.381555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.616612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-07-22T05:18:14.381604Z","caller":"traceutil/trace.go:171","msg":"trace[18075639] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:318; }","duration":"447.699865ms","start":"2026-07-22T05:18:13.933893Z","end":"2026-07-22T05:18:14.381593Z","steps":["trace[18075639] 'agreement among raft nodes before linearized reading' (duration: 447.643724ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:18:14.38164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:18:13.933869Z","time spent":"447.764837ms","remote":"127.0.0.1:60864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":225,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"info","ts":"2026-07-22T05:18:14.381712Z","caller":"traceutil/trace.go:171","msg":"trace[666596119] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:318; }","duration":"397.031692ms","start":"2026-07-22T05:18:13.984544Z","end":"2026-07-22T05:18:14.381576Z","steps":["trace[666596119] 'agreement among raft nodes before linearized reading' (duration: 396.738954ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:18:14.381831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:18:13.984485Z","time spent":"397.32871ms","remote":"127.0.0.1:60864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-07-22T05:18:14.381516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.992698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-22T05:18:14.381969Z","caller":"traceutil/trace.go:171","msg":"trace[1831685207] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:318; }","duration":"498.480362ms","start":"2026-07-22T05:18:13.883477Z","end":"2026-07-22T05:18:14.381958Z","steps":["trace[1831685207] 'agreement among raft nodes before linearized reading' (duration: 498.001698ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:18:14.382024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:18:13.883463Z","time spent":"498.550043ms","remote":"127.0.0.1:60864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"info","ts":"2026-07-22T05:18:14.68836Z","caller":"traceutil/trace.go:171","msg":"trace[756621358] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"296.137168ms","start":"2026-07-22T05:18:14.392193Z","end":"2026-07-22T05:18:14.68833Z","steps":["trace[756621358] 'process raft request' (duration: 290.005491ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:18:14.76058Z","caller":"traceutil/trace.go:171","msg":"trace[1327092105] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"368.343555ms","start":"2026-07-22T05:18:14.392211Z","end":"2026-07-22T05:18:14.760555Z","steps":["trace[1327092105] 'process raft request' (duration: 368.056287ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:18:14.760676Z","caller":"traceutil/trace.go:171","msg":"trace[1240326658] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"368.297754ms","start":"2026-07-22T05:18:14.392353Z","end":"2026-07-22T05:18:14.760651Z","steps":["trace[1240326658] 'process raft request' (duration: 368.110929ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:18:14.760761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:18:14.392343Z","time spent":"368.374267ms","remote":"127.0.0.1:60782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-22T05:18:14.760765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:18:14.392191Z","time spent":"368.465959ms","remote":"127.0.0.1:60752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":654,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-22T05:18:14.760828Z","caller":"traceutil/trace.go:171","msg":"trace[2133473815] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"367.546864ms","start":"2026-07-22T05:18:14.393272Z","end":"2026-07-22T05:18:14.760819Z","steps":["trace[2133473815] 'process raft request' (duration: 367.226826ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:18:14.760926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:18:14.393265Z","time spent":"367.602925ms","remote":"127.0.0.1:32916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2150,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-22T05:18:14.760762Z","caller":"traceutil/trace.go:171","msg":"trace[921548012] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"368.382236ms","start":"2026-07-22T05:18:14.392355Z","end":"2026-07-22T05:18:14.760737Z","steps":["trace[921548012] 'process raft request' (duration: 368.061968ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:18:14.761153Z","caller":"traceutil/trace.go:171","msg":"trace[1042304546] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"367.550774ms","start":"2026-07-22T05:18:14.393592Z","end":"2026-07-22T05:18:14.761142Z","steps":["trace[1042304546] 'process raft request' (duration: 366.938558ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:18:14.76125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:18:14.393579Z","time spent":"367.626176ms","remote":"127.0.0.1:60782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2162,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T05:18:14.761503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:18:14.392335Z","time spent":"368.688634ms","remote":"127.0.0.1:60830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-22T05:18:14.971411Z","caller":"traceutil/trace.go:171","msg":"trace[1578084051] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"277.120353ms","start":"2026-07-22T05:18:14.694269Z","end":"2026-07-22T05:18:14.971389Z","steps":["trace[1578084051] 'process raft request' (duration: 277.016871ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:18:14.971682Z","caller":"traceutil/trace.go:171","msg":"trace[1100825019] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"279.264082ms","start":"2026-07-22T05:18:14.69233Z","end":"2026-07-22T05:18:14.971594Z","steps":["trace[1100825019] 'process raft request' (duration: 190.68043ms)","trace[1100825019] 'compare' (duration: 87.950304ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T05:18:14.973993Z","caller":"traceutil/trace.go:171","msg":"trace[1196943729] linearizableReadLoop","detail":"{readStateIndex:338; appliedIndex:335; }","duration":"208.894134ms","start":"2026-07-22T05:18:14.765078Z","end":"2026-07-22T05:18:14.973973Z","steps":["trace[1196943729] 'read index received' (duration: 117.945138ms)","trace[1196943729] 'applied index is now lower than readState.Index' (duration: 90.945916ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T05:18:14.974129Z","caller":"traceutil/trace.go:171","msg":"trace[631794190] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"209.11774ms","start":"2026-07-22T05:18:14.764991Z","end":"2026-07-22T05:18:14.974109Z","steps":["trace[631794190] 'process raft request' (duration: 208.855562ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:18:14.974132Z","caller":"traceutil/trace.go:171","msg":"trace[486306663] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"207.874756ms","start":"2026-07-22T05:18:14.766234Z","end":"2026-07-22T05:18:14.974109Z","steps":["trace[486306663] 'process raft request' (duration: 207.698612ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:18:14.974177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.088889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-22T05:18:14.974225Z","caller":"traceutil/trace.go:171","msg":"trace[148331050] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:328; }","duration":"209.163211ms","start":"2026-07-22T05:18:14.765044Z","end":"2026-07-22T05:18:14.974207Z","steps":["trace[148331050] 'agreement among raft nodes before linearized reading' (duration: 209.047928ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:18:14.974283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.913646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-22T05:18:14.974355Z","caller":"traceutil/trace.go:171","msg":"trace[624493533] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:328; }","duration":"197.995858ms","start":"2026-07-22T05:18:14.776341Z","end":"2026-07-22T05:18:14.974336Z","steps":["trace[624493533] 'agreement among raft nodes before linearized reading' (duration: 197.866435ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:18:14.974476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.624875ms","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-07-22T05:18:14.974587Z","caller":"traceutil/trace.go:171","msg":"trace[575491091] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:328; }","duration":"146.792609ms","start":"2026-07-22T05:18:14.827776Z","end":"2026-07-22T05:18:14.974568Z","steps":["trace[575491091] 'agreement among raft nodes before linearized reading' (duration: 146.538902ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:18:14.974477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.31642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-22T05:18:14.974747Z","caller":"traceutil/trace.go:171","msg":"trace[505750013] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:328; }","duration":"145.648679ms","start":"2026-07-22T05:18:14.829083Z","end":"2026-07-22T05:18:14.974731Z","steps":["trace[505750013] 'agreement among raft nodes before linearized reading' (duration: 145.157975ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:18:15.153007Z","caller":"traceutil/trace.go:171","msg":"trace[111960602] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"173.258166ms","start":"2026-07-22T05:18:14.979716Z","end":"2026-07-22T05:18:15.152974Z","steps":["trace[111960602] 'process raft request' (duration: 171.995163ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:18:15.153157Z","caller":"traceutil/trace.go:171","msg":"trace[1075419739] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"173.241907ms","start":"2026-07-22T05:18:14.979879Z","end":"2026-07-22T05:18:15.153121Z","steps":["trace[1075419739] 'process raft request' (duration: 173.047371ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:18:15.15323Z","caller":"traceutil/trace.go:171","msg":"trace[134798430] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"172.884706ms","start":"2026-07-22T05:18:14.980309Z","end":"2026-07-22T05:18:15.153193Z","steps":["trace[134798430] 'process raft request' (duration: 172.731021ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:18:15.153253Z","caller":"traceutil/trace.go:171","msg":"trace[1571247956] linearizableReadLoop","detail":"{readStateIndex:348; appliedIndex:340; }","duration":"121.598077ms","start":"2026-07-22T05:18:15.031633Z","end":"2026-07-22T05:18:15.153231Z","steps":["trace[1571247956] 'read index received' (duration: 120.192389ms)","trace[1571247956] 'applied index is now lower than readState.Index' (duration: 1.404418ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T05:18:15.15337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.72149ms","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-22T05:18:15.153401Z","caller":"traceutil/trace.go:171","msg":"trace[1813696169] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:337; }","duration":"121.771891ms","start":"2026-07-22T05:18:15.03162Z","end":"2026-07-22T05:18:15.153392Z","steps":["trace[1813696169] 'agreement among raft nodes before linearized reading' (duration: 121.689719ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:18:15.153578Z","caller":"traceutil/trace.go:171","msg":"trace[2139175072] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"173.414602ms","start":"2026-07-22T05:18:14.980152Z","end":"2026-07-22T05:18:15.153567Z","steps":["trace[2139175072] 'process raft request' (duration: 172.825525ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:18:15.15358Z","caller":"traceutil/trace.go:171","msg":"trace[330854300] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"171.572091ms","start":"2026-07-22T05:18:14.981982Z","end":"2026-07-22T05:18:15.153554Z","steps":["trace[330854300] 'process raft request' (duration: 171.14013ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:18:15.153665Z","caller":"traceutil/trace.go:171","msg":"trace[1986034616] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"172.690071ms","start":"2026-07-22T05:18:14.980962Z","end":"2026-07-22T05:18:15.153652Z","steps":["trace[1986034616] 'process raft request' (duration: 172.111845ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:18:15.153713Z","caller":"traceutil/trace.go:171","msg":"trace[1164967063] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"168.935499ms","start":"2026-07-22T05:18:14.984766Z","end":"2026-07-22T05:18:15.153702Z","steps":["trace[1164967063] 'process raft request' (duration: 168.422636ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:18:15.153629Z","caller":"traceutil/trace.go:171","msg":"trace[27656543] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"171.312563ms","start":"2026-07-22T05:18:14.982306Z","end":"2026-07-22T05:18:15.153619Z","steps":["trace[27656543] 'process raft request' (duration: 170.846081ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:18:39.656179Z","caller":"traceutil/trace.go:171","msg":"trace[311495799] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"317.915368ms","start":"2026-07-22T05:18:39.338238Z","end":"2026-07-22T05:18:39.656153Z","steps":["trace[311495799] 'process raft request' (duration: 317.749123ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:18:39.656332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:18:39.338212Z","time spent":"318.044891ms","remote":"127.0.0.1:60714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-22T05:18:39.950645Z","caller":"traceutil/trace.go:171","msg":"trace[10292807] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"606.616804ms","start":"2026-07-22T05:18:39.344002Z","end":"2026-07-22T05:18:39.950619Z","steps":["trace[10292807] 'process raft request' (duration: 606.381617ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:18:39.950792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:18:39.343983Z","time spent":"606.738318ms","remote":"127.0.0.1:60714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-22T05:18:39.950847Z","caller":"traceutil/trace.go:171","msg":"trace[1196647788] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"602.199894ms","start":"2026-07-22T05:18:39.348611Z","end":"2026-07-22T05:18:39.950811Z","steps":["trace[1196647788] 'process raft request' (duration: 601.937768ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:18:39.951001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:18:39.348592Z","time spent":"602.330427ms","remote":"127.0.0.1:60714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-22T05:18:39.95115Z","caller":"traceutil/trace.go:171","msg":"trace[1730129841] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"596.399986ms","start":"2026-07-22T05:18:39.354737Z","end":"2026-07-22T05:18:39.951137Z","steps":["trace[1730129841] 'process raft request' (duration: 596.252462ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:18:39.95122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:18:39.354704Z","time spent":"596.474268ms","remote":"127.0.0.1:60714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-22T05:18:39.951223Z","caller":"traceutil/trace.go:171","msg":"trace[903552640] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"595.897485ms","start":"2026-07-22T05:18:39.355313Z","end":"2026-07-22T05:18:39.951211Z","steps":["trace[903552640] 'process raft request' (duration: 595.773351ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:18:39.951282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:18:39.35529Z","time spent":"595.967266ms","remote":"127.0.0.1:60714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-22T05:18:39.951486Z","caller":"traceutil/trace.go:171","msg":"trace[61076697] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"595.651237ms","start":"2026-07-22T05:18:39.355783Z","end":"2026-07-22T05:18:39.951434Z","steps":["trace[61076697] 'process raft request' (duration: 595.367199ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:18:39.951516Z","caller":"traceutil/trace.go:171","msg":"trace[206304103] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"591.667909ms","start":"2026-07-22T05:18:39.359838Z","end":"2026-07-22T05:18:39.951505Z","steps":["trace[206304103] 'process raft request' (duration: 591.539966ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:18:39.951568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:18:39.359811Z","time spent":"591.73541ms","remote":"127.0.0.1:60714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-22T05:18:39.951629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:18:39.355662Z","time spent":"595.887783ms","remote":"127.0.0.1:60714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-22T05:18:39.951843Z","caller":"traceutil/trace.go:171","msg":"trace[2065884941] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"590.556199ms","start":"2026-07-22T05:18:39.361272Z","end":"2026-07-22T05:18:39.951828Z","steps":["trace[2065884941] 'process raft request' (duration: 590.181999ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:18:39.951916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:18:39.361265Z","time spent":"590.619281ms","remote":"127.0.0.1:60714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-22T05:18:39.952016Z","caller":"traceutil/trace.go:171","msg":"trace[1301718447] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"575.717048ms","start":"2026-07-22T05:18:39.376272Z","end":"2026-07-22T05:18:39.951989Z","steps":["trace[1301718447] 'process raft request' (duration: 575.580713ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:18:39.952102Z","caller":"traceutil/trace.go:171","msg":"trace[2124357646] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"567.943636ms","start":"2026-07-22T05:18:39.384136Z","end":"2026-07-22T05:18:39.95208Z","steps":["trace[2124357646] 'process raft request' (duration: 567.840763ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:18:39.952164Z","caller":"traceutil/trace.go:171","msg":"trace[983717349] linearizableReadLoop","detail":"{readStateIndex:517; appliedIndex:505; }","duration":"383.222079ms","start":"2026-07-22T05:18:39.568927Z","end":"2026-07-22T05:18:39.952149Z","steps":["trace[983717349] 'read index received' (duration: 87.071661ms)","trace[983717349] 'applied index is now lower than readState.Index' (duration: 296.149388ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T05:18:39.952188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:18:39.38411Z","time spent":"568.037688ms","remote":"127.0.0.1:60714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-22T05:18:39.952136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:18:39.376247Z","time spent":"575.84785ms","remote":"127.0.0.1:60714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-22T05:18:39.952228Z","caller":"traceutil/trace.go:171","msg":"trace[2011814514] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"541.674584ms","start":"2026-07-22T05:18:39.410543Z","end":"2026-07-22T05:18:39.952217Z","steps":["trace[2011814514] 'process raft request' (duration: 541.488159ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:18:39.952263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.336792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T05:18:39.952292Z","caller":"traceutil/trace.go:171","msg":"trace[327027187] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:500; }","duration":"383.397734ms","start":"2026-07-22T05:18:39.568886Z","end":"2026-07-22T05:18:39.952284Z","steps":["trace[327027187] 'agreement among raft nodes before linearized reading' (duration: 383.322612ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:18:39.952311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:18:39.41051Z","time spent":"541.774736ms","remote":"127.0.0.1:60714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-22T05:18:39.952353Z","caller":"traceutil/trace.go:171","msg":"trace[723045845] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"444.703405ms","start":"2026-07-22T05:18:39.507607Z","end":"2026-07-22T05:18:39.952311Z","steps":["trace[723045845] 'process raft request' (duration: 444.488209ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:18:39.952474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:18:39.507578Z","time spent":"444.841929ms","remote":"127.0.0.1:60714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-22T05:18:39.952671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:18:39.568874Z","time spent":"383.451755ms","remote":"127.0.0.1:60676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-22T05:18:39.953269Z","caller":"traceutil/trace.go:171","msg":"trace[1611722078] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"291.227344ms","start":"2026-07-22T05:18:39.662029Z","end":"2026-07-22T05:18:39.953256Z","steps":["trace[1611722078] 'process raft request' (duration: 290.649059ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:18:39.953557Z","caller":"traceutil/trace.go:171","msg":"trace[297706997] transaction","detail":"{read_only:false; number_of_response:1; response_revision:502; }","duration":"288.029498ms","start":"2026-07-22T05:18:39.665511Z","end":"2026-07-22T05:18:39.953541Z","steps":["trace[297706997] 'process raft request' (duration: 287.988397ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:18:39.953586Z","caller":"traceutil/trace.go:171","msg":"trace[1796946691] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"288.197692ms","start":"2026-07-22T05:18:39.665377Z","end":"2026-07-22T05:18:39.953575Z","steps":["trace[1796946691] 'process raft request' (duration: 288.035958ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:18:46.506446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.634025ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-22T05:18:46.506567Z","caller":"traceutil/trace.go:171","msg":"trace[1743965936] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:619; }","duration":"241.76822ms","start":"2026-07-22T05:18:46.264782Z","end":"2026-07-22T05:18:46.50655Z","steps":["trace[1743965936] 'read index received' (duration: 117.992841ms)","trace[1743965936] 'applied index is now lower than readState.Index' (duration: 123.774299ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T05:18:46.506669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.889053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.59\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-22T05:18:46.506702Z","caller":"traceutil/trace.go:171","msg":"trace[1895539567] range","detail":"{range_begin:/registry/masterleases/199.19.213.59; range_end:; response_count:1; response_revision:596; }","duration":"241.965905ms","start":"2026-07-22T05:18:46.264727Z","end":"2026-07-22T05:18:46.506692Z","steps":["trace[1895539567] 'agreement among raft nodes before linearized reading' (duration: 241.869512ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:18:46.506736Z","caller":"traceutil/trace.go:171","msg":"trace[487828781] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"467.149909ms","start":"2026-07-22T05:18:46.03955Z","end":"2026-07-22T05:18:46.5067Z","steps":["trace[487828781] 'process raft request' (duration: 343.190995ms)","trace[487828781] 'compare' (duration: 123.462031ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T05:18:46.506931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:18:46.039514Z","time spent":"467.356694ms","remote":"127.0.0.1:32920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-22T05:18:47.656732Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3427414724007938114,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-22T05:18:48.051134Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.086292336s","expected-duration":"1s"} {"level":"info","ts":"2026-07-22T05:18:48.10546Z","caller":"traceutil/trace.go:171","msg":"trace[1783050761] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:622; }","duration":"948.758087ms","start":"2026-07-22T05:18:47.156643Z","end":"2026-07-22T05:18:48.105401Z","steps":["trace[1783050761] 'read index received' (duration: 894.639712ms)","trace[1783050761] 'applied index is now lower than readState.Index' (duration: 54.117625ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T05:18:48.105754Z","caller":"traceutil/trace.go:171","msg":"trace[344061899] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"1.143748081s","start":"2026-07-22T05:18:46.961994Z","end":"2026-07-22T05:18:48.105742Z","steps":["trace[344061899] 'process raft request' (duration: 1.096361147s)","trace[344061899] 'compare' (duration: 46.212153ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T05:18:48.105859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:18:46.961967Z","time spent":"1.143812733s","remote":"127.0.0.1:60768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036317,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T05:18:48.106184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"949.538568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-07-22T05:18:48.106212Z","caller":"traceutil/trace.go:171","msg":"trace[901740670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:598; }","duration":"949.605129ms","start":"2026-07-22T05:18:47.156599Z","end":"2026-07-22T05:18:48.106204Z","steps":["trace[901740670] 'agreement among raft nodes before linearized reading' (duration: 949.413674ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:18:48.106243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:18:47.156577Z","time spent":"949.660871ms","remote":"127.0.0.1:60924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-22T05:18:48.106473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.767903ms","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-22T05:18:48.106494Z","caller":"traceutil/trace.go:171","msg":"trace[15847828] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:598; }","duration":"814.809764ms","start":"2026-07-22T05:18:47.291677Z","end":"2026-07-22T05:18:48.106487Z","steps":["trace[15847828] 'agreement among raft nodes before linearized reading' (duration: 814.747172ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:18:48.106514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:18:47.291664Z","time spent":"814.845265ms","remote":"127.0.0.1:60924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-22T05:18:48.106493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.411072ms","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-07-22T05:18:48.106571Z","caller":"traceutil/trace.go:171","msg":"trace[248163623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:598; }","duration":"808.512124ms","start":"2026-07-22T05:18:47.298037Z","end":"2026-07-22T05:18:48.106549Z","steps":["trace[248163623] 'agreement among raft nodes before linearized reading' (duration: 808.288648ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:18:48.106613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:18:47.298028Z","time spent":"808.576427ms","remote":"127.0.0.1:60924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-22T05:18:48.10662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.791891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-22T05:18:48.106674Z","caller":"traceutil/trace.go:171","msg":"trace[5183760] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:598; }","duration":"815.891874ms","start":"2026-07-22T05:18:47.290767Z","end":"2026-07-22T05:18:48.106659Z","steps":["trace[5183760] 'agreement among raft nodes before linearized reading' (duration: 815.703078ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:18:48.106711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:18:47.29075Z","time spent":"815.951756ms","remote":"127.0.0.1:60924","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-07-22T05:18:48.106727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.170911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T05:18:48.106753Z","caller":"traceutil/trace.go:171","msg":"trace[885365979] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:598; }","duration":"533.248262ms","start":"2026-07-22T05:18:47.573496Z","end":"2026-07-22T05:18:48.106744Z","steps":["trace[885365979] 'agreement among raft nodes before linearized reading' (duration: 533.210921ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:18:48.106777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:18:47.573456Z","time spent":"533.316544ms","remote":"127.0.0.1:60680","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-07-22T05:18:48.106743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"899.232775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T05:18:48.10686Z","caller":"traceutil/trace.go:171","msg":"trace[1386543927] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:598; }","duration":"899.8168ms","start":"2026-07-22T05:18:47.20701Z","end":"2026-07-22T05:18:48.106827Z","steps":["trace[1386543927] 'agreement among raft nodes before linearized reading' (duration: 899.166123ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:18:48.106915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:18:47.206996Z","time spent":"899.910183ms","remote":"127.0.0.1:60676","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-07-22T05:19:03.8241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.938314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-22T05:19:03.824187Z","caller":"traceutil/trace.go:171","msg":"trace[788072447] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:760; }","duration":"256.075637ms","start":"2026-07-22T05:19:03.568093Z","end":"2026-07-22T05:19:03.824169Z","steps":["trace[788072447] 'range keys from in-memory index tree' (duration: 255.767829ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:19:03.824221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.364299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T05:19:03.824317Z","caller":"traceutil/trace.go:171","msg":"trace[7179687] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:760; }","duration":"255.521333ms","start":"2026-07-22T05:19:03.568775Z","end":"2026-07-22T05:19:03.824296Z","steps":["trace[7179687] 'range keys from in-memory index tree' (duration: 255.263295ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:19:38.376752Z","caller":"traceutil/trace.go:171","msg":"trace[245569127] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"346.015046ms","start":"2026-07-22T05:19:38.030715Z","end":"2026-07-22T05:19:38.37673Z","steps":["trace[245569127] 'process raft request' (duration: 345.861012ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:19:38.376878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:19:38.030706Z","time spent":"346.116308ms","remote":"127.0.0.1:60924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T05:19:38.615152Z","caller":"traceutil/trace.go:171","msg":"trace[303942511] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"234.507778ms","start":"2026-07-22T05:19:38.380619Z","end":"2026-07-22T05:19:38.615126Z","steps":["trace[303942511] 'process raft request' (duration: 230.012286ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:19:39.131979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.639803ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T05:19:39.132108Z","caller":"traceutil/trace.go:171","msg":"trace[1521121748] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:975; }","duration":"101.787517ms","start":"2026-07-22T05:19:39.030302Z","end":"2026-07-22T05:19:39.13209Z","steps":["trace[1521121748] 'range keys from in-memory index tree' (duration: 101.624153ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:19:39.132165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.220233ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T05:19:39.132425Z","caller":"traceutil/trace.go:171","msg":"trace[699035822] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"750.837925ms","start":"2026-07-22T05:19:38.381566Z","end":"2026-07-22T05:19:39.132404Z","steps":["trace[699035822] 'process raft request' (duration: 750.704331ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:19:39.13252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:19:38.381562Z","time spent":"750.911307ms","remote":"127.0.0.1:60924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T05:19:39.132551Z","caller":"traceutil/trace.go:171","msg":"trace[295219051] linearizableReadLoop","detail":"{readStateIndex:1016; appliedIndex:1012; }","duration":"563.439344ms","start":"2026-07-22T05:19:38.569093Z","end":"2026-07-22T05:19:39.132532Z","steps":["trace[295219051] 'read index received' (duration: 41.513416ms)","trace[295219051] 'applied index is now lower than readState.Index' (duration: 521.924218ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T05:19:39.132602Z","caller":"traceutil/trace.go:171","msg":"trace[1877680284] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"740.169117ms","start":"2026-07-22T05:19:38.392418Z","end":"2026-07-22T05:19:39.132587Z","steps":["trace[1877680284] 'process raft request' (duration: 739.92183ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:19:39.132678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.575547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T05:19:39.132709Z","caller":"traceutil/trace.go:171","msg":"trace[715555034] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:978; }","duration":"563.656789ms","start":"2026-07-22T05:19:38.56904Z","end":"2026-07-22T05:19:39.132697Z","steps":["trace[715555034] 'agreement among raft nodes before linearized reading' (duration: 563.586648ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:19:39.132734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:19:38.568994Z","time spent":"563.734971ms","remote":"127.0.0.1:60676","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-07-22T05:19:39.132749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.396336ms","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-22T05:19:39.132733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:19:38.392399Z","time spent":"740.26211ms","remote":"127.0.0.1:60924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T05:19:39.132487Z","caller":"traceutil/trace.go:171","msg":"trace[682506950] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"750.943347ms","start":"2026-07-22T05:19:38.381506Z","end":"2026-07-22T05:19:39.132449Z","steps":["trace[682506950] 'process raft request' (duration: 459.325484ms)","trace[682506950] 'compare' (duration: 291.060408ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T05:19:39.132804Z","caller":"traceutil/trace.go:171","msg":"trace[1539919247] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:978; }","duration":"317.485759ms","start":"2026-07-22T05:19:38.815302Z","end":"2026-07-22T05:19:39.132788Z","steps":["trace[1539919247] 'agreement among raft nodes before linearized reading' (duration: 317.390516ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:19:39.132842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:19:38.815281Z","time spent":"317.55077ms","remote":"127.0.0.1:32874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":29,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-07-22T05:19:39.132874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:19:38.381486Z","time spent":"751.333808ms","remote":"127.0.0.1:60924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T05:20:00.850639Z","caller":"traceutil/trace.go:171","msg":"trace[320877165] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"128.024042ms","start":"2026-07-22T05:20:00.722593Z","end":"2026-07-22T05:20:00.850617Z","steps":["trace[320877165] 'process raft request' (duration: 127.887909ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:20:02.334497Z","caller":"traceutil/trace.go:171","msg":"trace[360756178] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"407.616876ms","start":"2026-07-22T05:20:01.926853Z","end":"2026-07-22T05:20:02.33447Z","steps":["trace[360756178] 'process raft request' (duration: 407.433321ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:20:02.334658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:20:01.926828Z","time spent":"407.74749ms","remote":"127.0.0.1:60924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T05:20:18.888155Z","caller":"traceutil/trace.go:171","msg":"trace[1363397958] linearizableReadLoop","detail":"{readStateIndex:1204; appliedIndex:1203; }","duration":"245.127731ms","start":"2026-07-22T05:20:18.643004Z","end":"2026-07-22T05:20:18.888132Z","steps":["trace[1363397958] 'read index received' (duration: 244.874813ms)","trace[1363397958] 'applied index is now lower than readState.Index' (duration: 251.408µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T05:20:18.888231Z","caller":"traceutil/trace.go:171","msg":"trace[1896706025] transaction","detail":"{read_only:false; response_revision:1156; number_of_response:1; }","duration":"478.345389ms","start":"2026-07-22T05:20:18.409849Z","end":"2026-07-22T05:20:18.888194Z","steps":["trace[1896706025] 'process raft request' (duration: 478.084532ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:20:18.88835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.328426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-22T05:20:18.888397Z","caller":"traceutil/trace.go:171","msg":"trace[1776220801] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1156; }","duration":"245.430858ms","start":"2026-07-22T05:20:18.642951Z","end":"2026-07-22T05:20:18.888382Z","steps":["trace[1776220801] 'agreement among raft nodes before linearized reading' (duration: 245.278824ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:20:18.888406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:20:18.409829Z","time spent":"478.480312ms","remote":"127.0.0.1:60924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T05:20:19.192674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.176067ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T05:20:19.192787Z","caller":"traceutil/trace.go:171","msg":"trace[3400495] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1156; }","duration":"162.30296ms","start":"2026-07-22T05:20:19.030469Z","end":"2026-07-22T05:20:19.192772Z","steps":["trace[3400495] 'range keys from in-memory index tree' (duration: 162.134106ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:20:19.192786Z","caller":"traceutil/trace.go:171","msg":"trace[505426489] linearizableReadLoop","detail":"{readStateIndex:1205; appliedIndex:1204; }","duration":"269.224363ms","start":"2026-07-22T05:20:18.923519Z","end":"2026-07-22T05:20:19.192743Z","steps":["trace[505426489] 'read index received' (duration: 183.876284ms)","trace[505426489] 'applied index is now lower than readState.Index' (duration: 85.346848ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T05:20:19.192839Z","caller":"traceutil/trace.go:171","msg":"trace[480110538] transaction","detail":"{read_only:false; response_revision:1157; number_of_response:1; }","duration":"300.521268ms","start":"2026-07-22T05:20:18.892293Z","end":"2026-07-22T05:20:19.192815Z","steps":["trace[480110538] 'process raft request' (duration: 215.094408ms)","trace[480110538] 'compare' (duration: 84.66694ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T05:20:19.192979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:20:18.89227Z","time spent":"300.615371ms","remote":"127.0.0.1:60924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T05:20:19.192996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.454269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-22T05:20:19.193033Z","caller":"traceutil/trace.go:171","msg":"trace[1289871113] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1157; }","duration":"269.554521ms","start":"2026-07-22T05:20:18.923466Z","end":"2026-07-22T05:20:19.193021Z","steps":["trace[1289871113] 'agreement among raft nodes before linearized reading' (duration: 269.384926ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:20:35.10033Z","caller":"traceutil/trace.go:171","msg":"trace[56198956] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"160.571714ms","start":"2026-07-22T05:20:34.939735Z","end":"2026-07-22T05:20:35.100307Z","steps":["trace[56198956] 'process raft request' (duration: 160.4365ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:20:35.554418Z","caller":"traceutil/trace.go:171","msg":"trace[237147448] linearizableReadLoop","detail":"{readStateIndex:1283; appliedIndex:1282; }","duration":"124.674673ms","start":"2026-07-22T05:20:35.429717Z","end":"2026-07-22T05:20:35.554392Z","steps":["trace[237147448] 'read index received' (duration: 112.418751ms)","trace[237147448] 'applied index is now lower than readState.Index' (duration: 12.254652ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T05:20:35.554428Z","caller":"traceutil/trace.go:171","msg":"trace[1997232215] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"149.263248ms","start":"2026-07-22T05:20:35.40514Z","end":"2026-07-22T05:20:35.554404Z","steps":["trace[1997232215] 'process raft request' (duration: 136.983566ms)","trace[1997232215] 'compare' (duration: 12.144518ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T05:20:35.554584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.857048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-22T05:20:35.554611Z","caller":"traceutil/trace.go:171","msg":"trace[1768085367] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1232; }","duration":"124.944521ms","start":"2026-07-22T05:20:35.429661Z","end":"2026-07-22T05:20:35.554606Z","steps":["trace[1768085367] 'agreement among raft nodes before linearized reading' (duration: 124.819577ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:20:35.554755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.436691ms","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-22T05:20:35.554833Z","caller":"traceutil/trace.go:171","msg":"trace[645440462] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1232; }","duration":"119.582975ms","start":"2026-07-22T05:20:35.43523Z","end":"2026-07-22T05:20:35.554813Z","steps":["trace[645440462] 'agreement among raft nodes before linearized reading' (duration: 119.352209ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:20:36.06433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.347227ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T05:20:36.06444Z","caller":"traceutil/trace.go:171","msg":"trace[1273920947] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"506.08228ms","start":"2026-07-22T05:20:35.558339Z","end":"2026-07-22T05:20:36.064421Z","steps":["trace[1273920947] 'process raft request' (duration: 240.559418ms)","trace[1273920947] 'compare' (duration: 265.121731ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T05:20:36.064522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:20:35.558317Z","time spent":"506.162632ms","remote":"127.0.0.1:60924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T05:20:36.90872Z","caller":"traceutil/trace.go:171","msg":"trace[1568866530] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"214.77835ms","start":"2026-07-22T05:20:36.693927Z","end":"2026-07-22T05:20:36.908705Z","steps":["trace[1568866530] 'process raft request' (duration: 214.740299ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:20:36.908767Z","caller":"traceutil/trace.go:171","msg":"trace[1136780995] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"215.048227ms","start":"2026-07-22T05:20:36.693693Z","end":"2026-07-22T05:20:36.908741Z","steps":["trace[1136780995] 'process raft request' (duration: 206.729692ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:20:46.455742Z","caller":"traceutil/trace.go:171","msg":"trace[1166142062] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"352.198467ms","start":"2026-07-22T05:20:46.103517Z","end":"2026-07-22T05:20:46.455716Z","steps":["trace[1166142062] 'process raft request' (duration: 351.98561ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:20:46.455899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:20:46.103501Z","time spent":"352.319711ms","remote":"127.0.0.1:60924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T05:20:46.456315Z","caller":"traceutil/trace.go:171","msg":"trace[1438104923] linearizableReadLoop","detail":"{readStateIndex:1365; appliedIndex:1365; }","duration":"185.300133ms","start":"2026-07-22T05:20:46.271001Z","end":"2026-07-22T05:20:46.456301Z","steps":["trace[1438104923] 'read index received' (duration: 185.294673ms)","trace[1438104923] 'applied index is now lower than readState.Index' (duration: 4.17µs)"],"step_count":2} {"level":"warn","ts":"2026-07-22T05:20:46.456453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.456087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.59\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-22T05:20:46.456492Z","caller":"traceutil/trace.go:171","msg":"trace[607180936] range","detail":"{range_begin:/registry/masterleases/199.19.213.59; range_end:; response_count:1; response_revision:1312; }","duration":"185.535189ms","start":"2026-07-22T05:20:46.270946Z","end":"2026-07-22T05:20:46.456481Z","steps":["trace[607180936] 'agreement among raft nodes before linearized reading' (duration: 185.404285ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:20:47.121603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.267935ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T05:20:47.121896Z","caller":"traceutil/trace.go:171","msg":"trace[258648633] linearizableReadLoop","detail":"{readStateIndex:1367; appliedIndex:1366; }","duration":"558.663322ms","start":"2026-07-22T05:20:46.563216Z","end":"2026-07-22T05:20:47.12188Z","steps":["trace[258648633] 'read index received' (duration: 452.072089ms)","trace[258648633] 'applied index is now lower than readState.Index' (duration: 106.589873ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T05:20:47.12198Z","caller":"traceutil/trace.go:171","msg":"trace[940341758] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"659.488689ms","start":"2026-07-22T05:20:46.46248Z","end":"2026-07-22T05:20:47.121969Z","steps":["trace[940341758] 'process raft request' (duration: 552.779943ms)","trace[940341758] 'compare' (duration: 106.121251ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T05:20:47.122032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:20:46.462459Z","time spent":"659.54488ms","remote":"127.0.0.1:60694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T05:20:47.122304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.084154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-07-22T05:20:47.122341Z","caller":"traceutil/trace.go:171","msg":"trace[144350336] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1313; }","duration":"559.157785ms","start":"2026-07-22T05:20:46.563173Z","end":"2026-07-22T05:20:47.122331Z","steps":["trace[144350336] 'agreement among raft nodes before linearized reading' (duration: 559.015652ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:20:47.12237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:20:46.563151Z","time spent":"559.211917ms","remote":"127.0.0.1:60924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":548,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-22T05:20:47.122496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.480749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-22T05:20:47.122591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.641529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-07-22T05:20:47.122595Z","caller":"traceutil/trace.go:171","msg":"trace[2013872227] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1313; }","duration":"554.666214ms","start":"2026-07-22T05:20:46.56791Z","end":"2026-07-22T05:20:47.122576Z","steps":["trace[2013872227] 'agreement among raft nodes before linearized reading' (duration: 554.467529ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T05:20:47.122676Z","caller":"traceutil/trace.go:171","msg":"trace[2111894280] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1313; }","duration":"199.773303ms","start":"2026-07-22T05:20:46.922884Z","end":"2026-07-22T05:20:47.122657Z","steps":["trace[2111894280] 'agreement among raft nodes before linearized reading' (duration: 199.541397ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T05:20:47.122709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T05:20:46.567889Z","time spent":"554.808437ms","remote":"127.0.0.1:60676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "}