{"level":"warn","ts":"2026-02-06T05:55:40.385553Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-06T05:55:40.385781Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.35: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.35:2380","--initial-cluster=instance=https://199.204.45.35:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.35:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.35: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-02-06T05:55:40.38593Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-06T05:55:40.386017Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-02-06T05:55:40.386078Z","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-02-06T05:55:40.386854Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"]} {"level":"info","ts":"2026-02-06T05:55:40.386997Z","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":2,"max-cpu-available":2,"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.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.35: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-02-06T05:55:40.394185Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.880043ms"} {"level":"info","ts":"2026-02-06T05:55:40.425941Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fb85913747d92cac","cluster-id":"636a84d5a3ba9c31"} {"level":"info","ts":"2026-02-06T05:55:40.426027Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=()"} {"level":"info","ts":"2026-02-06T05:55:40.426076Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 0"} {"level":"info","ts":"2026-02-06T05:55:40.426102Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fb85913747d92cac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-06T05:55:40.426124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 1"} {"level":"info","ts":"2026-02-06T05:55:40.42618Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"warn","ts":"2026-02-06T05:55:40.447749Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-06T05:55:40.467455Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-06T05:55:40.483141Z","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-02-06T05:55:40.492181Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fb85913747d92cac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-06T05:55:40.492608Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fb85913747d92cac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-06T05:55:40.492795Z","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-02-06T05:55:40.49291Z","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-02-06T05:55:40.497185Z","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-02-06T05:55:40.497413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"info","ts":"2026-02-06T05:55:40.497538Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","added-peer-id":"fb85913747d92cac","added-peer-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-02-06T05:55:40.513179Z","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-02-06T05:55:40.513389Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-02-06T05:55:40.513421Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-02-06T05:55:40.513495Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fb85913747d92cac","initial-advertise-peer-urls":["https://199.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-06T05:55:40.513576Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-06T05:55:40.527862Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac is starting a new election at term 1"} {"level":"info","ts":"2026-02-06T05:55:40.527982Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became pre-candidate at term 1"} {"level":"info","ts":"2026-02-06T05:55:40.528053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgPreVoteResp from fb85913747d92cac at term 1"} {"level":"info","ts":"2026-02-06T05:55:40.528111Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became candidate at term 2"} {"level":"info","ts":"2026-02-06T05:55:40.52814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgVoteResp from fb85913747d92cac at term 2"} {"level":"info","ts":"2026-02-06T05:55:40.528187Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became leader at term 2"} {"level":"info","ts":"2026-02-06T05:55:40.52822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fb85913747d92cac elected leader fb85913747d92cac at term 2"} {"level":"info","ts":"2026-02-06T05:55:40.528952Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T05:55:40.530821Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fb85913747d92cac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.35:2379]}","request-path":"/0/members/fb85913747d92cac/attributes","cluster-id":"636a84d5a3ba9c31","publish-timeout":"7s"} {"level":"info","ts":"2026-02-06T05:55:40.530963Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-06T05:55:40.532647Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-06T05:55:40.539326Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T05:55:40.539455Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T05:55:40.53949Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T05:55:40.541021Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-06T05:55:40.542092Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-06T05:55:40.542108Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-06T05:55:40.556052Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.35:2379"} {"level":"info","ts":"2026-02-06T05:55:50.34646Z","caller":"traceutil/trace.go:171","msg":"trace[1366468906] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"101.879357ms","start":"2026-02-06T05:55:50.244556Z","end":"2026-02-06T05:55:50.346435Z","steps":["trace[1366468906] 'process raft request' (duration: 101.807536ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T05:55:50.348089Z","caller":"traceutil/trace.go:171","msg":"trace[836921896] linearizableReadLoop","detail":"{readStateIndex:386; appliedIndex:385; }","duration":"105.286259ms","start":"2026-02-06T05:55:50.242772Z","end":"2026-02-06T05:55:50.348059Z","steps":["trace[836921896] 'read index received' (duration: 48.596172ms)","trace[836921896] 'applied index is now lower than readState.Index' (duration: 56.687787ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T05:55:50.348199Z","caller":"traceutil/trace.go:171","msg":"trace[757221513] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"106.264459ms","start":"2026-02-06T05:55:50.241923Z","end":"2026-02-06T05:55:50.348187Z","steps":["trace[757221513] 'process raft request' (duration: 49.384428ms)","trace[757221513] 'compare' (duration: 54.88723ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T05:55:50.348457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.667767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-x9db5\" ","response":"range_response_count:1 size:3416"} {"level":"info","ts":"2026-02-06T05:55:50.348516Z","caller":"traceutil/trace.go:171","msg":"trace[1088657390] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-x9db5; range_end:; response_count:1; response_revision:376; }","duration":"105.800849ms","start":"2026-02-06T05:55:50.242705Z","end":"2026-02-06T05:55:50.348505Z","steps":["trace[1088657390] 'agreement among raft nodes before linearized reading' (duration: 105.660286ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T05:55:59.239289Z","caller":"traceutil/trace.go:171","msg":"trace[1979224785] linearizableReadLoop","detail":"{readStateIndex:436; appliedIndex:435; }","duration":"290.75483ms","start":"2026-02-06T05:55:58.948515Z","end":"2026-02-06T05:55:59.23927Z","steps":["trace[1979224785] 'read index received' (duration: 290.469224ms)","trace[1979224785] 'applied index is now lower than readState.Index' (duration: 284.616µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T05:55:59.239399Z","caller":"traceutil/trace.go:171","msg":"trace[2091119255] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"298.696533ms","start":"2026-02-06T05:55:58.940692Z","end":"2026-02-06T05:55:59.239388Z","steps":["trace[2091119255] 'process raft request' (duration: 298.406467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:55:59.239762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.23047ms","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-02-06T05:55:59.239808Z","caller":"traceutil/trace.go:171","msg":"trace[824493258] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:423; }","duration":"291.307941ms","start":"2026-02-06T05:55:58.948489Z","end":"2026-02-06T05:55:59.239797Z","steps":["trace[824493258] 'agreement among raft nodes before linearized reading' (duration: 291.194869ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:55:59.240059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.148095ms","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-02-06T05:55:59.240098Z","caller":"traceutil/trace.go:171","msg":"trace[1964690486] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:423; }","duration":"200.245868ms","start":"2026-02-06T05:55:59.039843Z","end":"2026-02-06T05:55:59.240089Z","steps":["trace[1964690486] 'agreement among raft nodes before linearized reading' (duration: 200.179787ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:55:59.240482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.280693ms","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-02-06T05:55:59.240522Z","caller":"traceutil/trace.go:171","msg":"trace[1442616434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:423; }","duration":"138.372545ms","start":"2026-02-06T05:55:59.102141Z","end":"2026-02-06T05:55:59.240514Z","steps":["trace[1442616434] 'agreement among raft nodes before linearized reading' (duration: 138.254853ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:55:59.240738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.718622ms","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-02-06T05:55:59.240775Z","caller":"traceutil/trace.go:171","msg":"trace[493700109] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:423; }","duration":"138.788584ms","start":"2026-02-06T05:55:59.101979Z","end":"2026-02-06T05:55:59.240767Z","steps":["trace[493700109] 'agreement among raft nodes before linearized reading' (duration: 138.728003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:56:03.764925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.720273ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T05:56:03.764988Z","caller":"traceutil/trace.go:171","msg":"trace[118137182] linearizableReadLoop","detail":"{readStateIndex:479; appliedIndex:478; }","duration":"289.138323ms","start":"2026-02-06T05:56:03.475839Z","end":"2026-02-06T05:56:03.764978Z","steps":["trace[118137182] 'read index received' (duration: 38.075033ms)","trace[118137182] 'applied index is now lower than readState.Index' (duration: 251.06268ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T05:56:03.76503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.188764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T05:56:03.765041Z","caller":"traceutil/trace.go:171","msg":"trace[735172013] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:464; }","duration":"289.221174ms","start":"2026-02-06T05:56:03.475816Z","end":"2026-02-06T05:56:03.765037Z","steps":["trace[735172013] 'agreement among raft nodes before linearized reading' (duration: 289.187693ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T05:56:03.765217Z","caller":"traceutil/trace.go:171","msg":"trace[187132501] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"333.462994ms","start":"2026-02-06T05:56:03.431748Z","end":"2026-02-06T05:56:03.765211Z","steps":["trace[187132501] 'process raft request' (duration: 82.15696ms)","trace[187132501] 'compare' (duration: 250.627071ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T05:56:03.765247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T05:56:03.43166Z","time spent":"333.571436ms","remote":"127.0.0.1:47118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T05:56:03.811282Z","caller":"traceutil/trace.go:171","msg":"trace[1917338613] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"335.379864ms","start":"2026-02-06T05:56:03.475884Z","end":"2026-02-06T05:56:03.811264Z","steps":["trace[1917338613] 'process raft request' (duration: 335.284852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:56:03.811392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T05:56:03.475877Z","time spent":"335.454965ms","remote":"127.0.0.1:47118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T05:56:07.922854Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219119570165501702,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-06T05:56:08.009109Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.126789318s","expected-duration":"1s"} {"level":"info","ts":"2026-02-06T05:56:08.009384Z","caller":"traceutil/trace.go:171","msg":"trace[1787204783] linearizableReadLoop","detail":"{readStateIndex:487; appliedIndex:486; }","duration":"586.54701ms","start":"2026-02-06T05:56:07.422819Z","end":"2026-02-06T05:56:08.009366Z","steps":["trace[1787204783] 'read index received' (duration: 586.440418ms)","trace[1787204783] 'applied index is now lower than readState.Index' (duration: 105.812µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T05:56:08.009513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.679073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T05:56:08.009499Z","caller":"traceutil/trace.go:171","msg":"trace[1188936364] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"1.127253359s","start":"2026-02-06T05:56:06.882221Z","end":"2026-02-06T05:56:08.009475Z","steps":["trace[1188936364] 'process raft request' (duration: 1.127036144s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:56:08.009601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.803534ms","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-02-06T05:56:08.009616Z","caller":"traceutil/trace.go:171","msg":"trace[1007180749] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:471; }","duration":"546.836205ms","start":"2026-02-06T05:56:07.462776Z","end":"2026-02-06T05:56:08.009612Z","steps":["trace[1007180749] 'agreement among raft nodes before linearized reading' (duration: 546.768783ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:56:08.009633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T05:56:07.462768Z","time spent":"546.860756ms","remote":"127.0.0.1:47118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-06T05:56:08.009668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T05:56:06.882195Z","time spent":"1.127374792s","remote":"127.0.0.1:47118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T05:56:08.009532Z","caller":"traceutil/trace.go:171","msg":"trace[1088958099] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:471; }","duration":"586.733635ms","start":"2026-02-06T05:56:07.422792Z","end":"2026-02-06T05:56:08.009525Z","steps":["trace[1088958099] 'agreement among raft nodes before linearized reading' (duration: 586.675344ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:56:08.009842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T05:56:07.422781Z","time spent":"587.057261ms","remote":"127.0.0.1:46844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T05:56:08.140045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.844526ms","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-02-06T05:56:08.1401Z","caller":"traceutil/trace.go:171","msg":"trace[1885217371] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:471; }","duration":"182.930548ms","start":"2026-02-06T05:56:07.957154Z","end":"2026-02-06T05:56:08.140084Z","steps":["trace[1885217371] 'agreement among raft nodes before linearized reading' (duration: 52.602872ms)","trace[1885217371] 'range keys from in-memory index tree' (duration: 130.242904ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T05:56:08.140223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.71183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-06T05:56:08.140361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.476937ms","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-02-06T05:56:08.140378Z","caller":"traceutil/trace.go:171","msg":"trace[203333246] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:471; }","duration":"620.510687ms","start":"2026-02-06T05:56:07.519861Z","end":"2026-02-06T05:56:08.140372Z","steps":["trace[203333246] 'agreement among raft nodes before linearized reading' (duration: 489.906415ms)","trace[203333246] 'range keys from in-memory index tree' (duration: 130.577611ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T05:56:08.140392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T05:56:07.519851Z","time spent":"620.536297ms","remote":"127.0.0.1:47118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-06T05:56:08.140489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.399716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T05:56:08.140501Z","caller":"traceutil/trace.go:171","msg":"trace[679373079] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:471; }","duration":"127.423147ms","start":"2026-02-06T05:56:08.013074Z","end":"2026-02-06T05:56:08.140497Z","steps":["trace[679373079] 'range keys from in-memory index tree' (duration: 127.373166ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T05:56:08.140781Z","caller":"traceutil/trace.go:171","msg":"trace[1737448199] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"126.003508ms","start":"2026-02-06T05:56:08.01477Z","end":"2026-02-06T05:56:08.140774Z","steps":["trace[1737448199] 'process raft request' (duration: 77.103444ms)","trace[1737448199] 'compare' (duration: 48.810272ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T05:56:08.140237Z","caller":"traceutil/trace.go:171","msg":"trace[372815414] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:471; }","duration":"286.755041ms","start":"2026-02-06T05:56:07.853477Z","end":"2026-02-06T05:56:08.140232Z","steps":["trace[372815414] 'agreement among raft nodes before linearized reading' (duration: 156.285302ms)","trace[372815414] 'range keys from in-memory index tree' (duration: 130.444259ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T05:56:10.315568Z","caller":"traceutil/trace.go:171","msg":"trace[52771907] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"111.560041ms","start":"2026-02-06T05:56:10.203986Z","end":"2026-02-06T05:56:10.315546Z","steps":["trace[52771907] 'process raft request' (duration: 80.872101ms)","trace[52771907] 'compare' (duration: 30.382394ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T05:56:13.881441Z","caller":"traceutil/trace.go:171","msg":"trace[1518764404] linearizableReadLoop","detail":"{readStateIndex:534; appliedIndex:533; }","duration":"450.396278ms","start":"2026-02-06T05:56:13.43103Z","end":"2026-02-06T05:56:13.881426Z","steps":["trace[1518764404] 'read index received' (duration: 450.233974ms)","trace[1518764404] 'applied index is now lower than readState.Index' (duration: 161.884µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T05:56:13.881514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.476799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T05:56:13.881531Z","caller":"traceutil/trace.go:171","msg":"trace[1310493857] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:517; }","duration":"450.52393ms","start":"2026-02-06T05:56:13.431001Z","end":"2026-02-06T05:56:13.881525Z","steps":["trace[1310493857] 'agreement among raft nodes before linearized reading' (duration: 450.484189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:56:13.881547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T05:56:13.430994Z","time spent":"450.54945ms","remote":"127.0.0.1:46844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-06T05:56:13.881607Z","caller":"traceutil/trace.go:171","msg":"trace[2120503773] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"452.151683ms","start":"2026-02-06T05:56:13.429446Z","end":"2026-02-06T05:56:13.881598Z","steps":["trace[2120503773] 'process raft request' (duration: 451.836397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:56:13.881663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T05:56:13.429425Z","time spent":"452.200595ms","remote":"127.0.0.1:47118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T05:56:14.367525Z","caller":"traceutil/trace.go:171","msg":"trace[1078211397] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"103.603377ms","start":"2026-02-06T05:56:14.263901Z","end":"2026-02-06T05:56:14.367504Z","steps":["trace[1078211397] 'process raft request' (duration: 103.554506ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T05:56:14.368654Z","caller":"traceutil/trace.go:171","msg":"trace[1636766603] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"113.887968ms","start":"2026-02-06T05:56:14.25475Z","end":"2026-02-06T05:56:14.368638Z","steps":["trace[1636766603] 'process raft request' (duration: 112.587151ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T05:56:14.536031Z","caller":"traceutil/trace.go:171","msg":"trace[673763555] linearizableReadLoop","detail":"{readStateIndex:539; appliedIndex:538; }","duration":"204.158172ms","start":"2026-02-06T05:56:14.331829Z","end":"2026-02-06T05:56:14.535987Z","steps":["trace[673763555] 'read index received' (duration: 39.625544ms)","trace[673763555] 'applied index is now lower than readState.Index' (duration: 164.531758ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T05:56:14.536116Z","caller":"traceutil/trace.go:171","msg":"trace[989484387] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"260.928527ms","start":"2026-02-06T05:56:14.27518Z","end":"2026-02-06T05:56:14.536108Z","steps":["trace[989484387] 'process raft request' (duration: 192.40575ms)","trace[989484387] 'compare' (duration: 68.108859ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T05:56:14.536398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.56315ms","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-02-06T05:56:14.536428Z","caller":"traceutil/trace.go:171","msg":"trace[997661206] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:521; }","duration":"204.603691ms","start":"2026-02-06T05:56:14.331816Z","end":"2026-02-06T05:56:14.53642Z","steps":["trace[997661206] 'agreement among raft nodes before linearized reading' (duration: 204.512479ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:56:14.536605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.106457ms","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-02-06T05:56:14.536626Z","caller":"traceutil/trace.go:171","msg":"trace[809799590] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:521; }","duration":"160.157269ms","start":"2026-02-06T05:56:14.376463Z","end":"2026-02-06T05:56:14.53662Z","steps":["trace[809799590] 'agreement among raft nodes before linearized reading' (duration: 160.116648ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:56:14.537061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.28242ms","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-02-06T05:56:14.537084Z","caller":"traceutil/trace.go:171","msg":"trace[292729048] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:521; }","duration":"158.329192ms","start":"2026-02-06T05:56:14.378748Z","end":"2026-02-06T05:56:14.537077Z","steps":["trace[292729048] 'agreement among raft nodes before linearized reading' (duration: 158.24112ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T05:56:14.735634Z","caller":"traceutil/trace.go:171","msg":"trace[1516594174] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"190.560452ms","start":"2026-02-06T05:56:14.545054Z","end":"2026-02-06T05:56:14.735614Z","steps":["trace[1516594174] 'process raft request' (duration: 98.282288ms)","trace[1516594174] 'compare' (duration: 92.154021ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T05:56:14.735799Z","caller":"traceutil/trace.go:171","msg":"trace[1066599560] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"189.396659ms","start":"2026-02-06T05:56:14.546387Z","end":"2026-02-06T05:56:14.735784Z","steps":["trace[1066599560] 'process raft request' (duration: 189.201555ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T05:56:14.735985Z","caller":"traceutil/trace.go:171","msg":"trace[1533643481] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"183.912067ms","start":"2026-02-06T05:56:14.552065Z","end":"2026-02-06T05:56:14.735977Z","steps":["trace[1533643481] 'process raft request' (duration: 183.671452ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T05:56:16.89363Z","caller":"traceutil/trace.go:171","msg":"trace[1185045228] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"149.353565ms","start":"2026-02-06T05:56:16.744261Z","end":"2026-02-06T05:56:16.893615Z","steps":["trace[1185045228] 'process raft request' (duration: 149.327195ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T05:56:16.893791Z","caller":"traceutil/trace.go:171","msg":"trace[699890574] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"150.223934ms","start":"2026-02-06T05:56:16.743551Z","end":"2026-02-06T05:56:16.893775Z","steps":["trace[699890574] 'process raft request' (duration: 149.50927ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T05:56:17.355463Z","caller":"traceutil/trace.go:171","msg":"trace[235849469] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"126.488256ms","start":"2026-02-06T05:56:17.22896Z","end":"2026-02-06T05:56:17.355448Z","steps":["trace[235849469] 'process raft request' (duration: 126.450435ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T05:56:17.355498Z","caller":"traceutil/trace.go:171","msg":"trace[908515369] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"128.080918ms","start":"2026-02-06T05:56:17.227383Z","end":"2026-02-06T05:56:17.355464Z","steps":["trace[908515369] 'process raft request' (duration: 113.53054ms)","trace[908515369] 'compare' (duration: 14.428256ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T05:56:17.504828Z","caller":"traceutil/trace.go:171","msg":"trace[84647356] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"144.489616ms","start":"2026-02-06T05:56:17.360321Z","end":"2026-02-06T05:56:17.50481Z","steps":["trace[84647356] 'process raft request' (duration: 142.948755ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:56:18.046735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.238594ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-06T05:56:18.046879Z","caller":"traceutil/trace.go:171","msg":"trace[1123639765] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"524.778771ms","start":"2026-02-06T05:56:17.522078Z","end":"2026-02-06T05:56:18.046856Z","steps":["trace[1123639765] 'process raft request' (duration: 372.334111ms)","trace[1123639765] 'compare' (duration: 152.147753ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T05:56:18.046936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T05:56:17.522068Z","time spent":"524.844113ms","remote":"127.0.0.1:47200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1091,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-06T05:56:22.899894Z","caller":"traceutil/trace.go:171","msg":"trace[1321425794] linearizableReadLoop","detail":"{readStateIndex:582; appliedIndex:581; }","duration":"478.201601ms","start":"2026-02-06T05:56:22.421662Z","end":"2026-02-06T05:56:22.899864Z","steps":["trace[1321425794] 'read index received' (duration: 478.052678ms)","trace[1321425794] 'applied index is now lower than readState.Index' (duration: 148.273µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T05:56:22.900137Z","caller":"traceutil/trace.go:171","msg":"trace[565555666] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"494.6864ms","start":"2026-02-06T05:56:22.405444Z","end":"2026-02-06T05:56:22.90013Z","steps":["trace[565555666] 'process raft request' (duration: 494.316943ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:56:22.900213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T05:56:22.405428Z","time spent":"494.727981ms","remote":"127.0.0.1:47022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T05:56:22.900768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.711031ms","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-02-06T05:56:22.900792Z","caller":"traceutil/trace.go:171","msg":"trace[1880508989] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:563; }","duration":"479.15898ms","start":"2026-02-06T05:56:22.421626Z","end":"2026-02-06T05:56:22.900785Z","steps":["trace[1880508989] 'agreement among raft nodes before linearized reading' (duration: 478.687891ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:56:22.90081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T05:56:22.421609Z","time spent":"479.196481ms","remote":"127.0.0.1:47118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-06T05:56:23.056363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.670727ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T05:56:23.056477Z","caller":"traceutil/trace.go:171","msg":"trace[887174514] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:582; }","duration":"120.598844ms","start":"2026-02-06T05:56:22.935867Z","end":"2026-02-06T05:56:23.056465Z","steps":["trace[887174514] 'read index received' (duration: 15.777443ms)","trace[887174514] 'applied index is now lower than readState.Index' (duration: 104.820681ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T05:56:23.056529Z","caller":"traceutil/trace.go:171","msg":"trace[145904813] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"149.042498ms","start":"2026-02-06T05:56:22.907479Z","end":"2026-02-06T05:56:23.056521Z","steps":["trace[145904813] 'process raft request' (duration: 44.158536ms)","trace[145904813] 'compare' (duration: 104.591266ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T05:56:23.056604Z","caller":"traceutil/trace.go:171","msg":"trace[1546508100] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"148.150849ms","start":"2026-02-06T05:56:22.908449Z","end":"2026-02-06T05:56:23.0566Z","steps":["trace[1546508100] 'process raft request' (duration: 147.989626ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:56:23.056695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.826329ms","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-02-06T05:56:23.056711Z","caller":"traceutil/trace.go:171","msg":"trace[412949597] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:565; }","duration":"120.85711ms","start":"2026-02-06T05:56:22.935846Z","end":"2026-02-06T05:56:23.056703Z","steps":["trace[412949597] 'agreement among raft nodes before linearized reading' (duration: 120.791668ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:56:23.056759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.15756ms","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-02-06T05:56:23.056769Z","caller":"traceutil/trace.go:171","msg":"trace[1686999590] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:565; }","duration":"109.19727ms","start":"2026-02-06T05:56:22.947568Z","end":"2026-02-06T05:56:23.056766Z","steps":["trace[1686999590] 'agreement among raft nodes before linearized reading' (duration: 109.178059ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:56:24.060857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.463657ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T05:56:24.06095Z","caller":"traceutil/trace.go:171","msg":"trace[1766005793] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:584; }","duration":"635.16256ms","start":"2026-02-06T05:56:23.425777Z","end":"2026-02-06T05:56:24.06094Z","steps":["trace[1766005793] 'read index received' (duration: 491.820829ms)","trace[1766005793] 'applied index is now lower than readState.Index' (duration: 143.341221ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T05:56:24.061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.230121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T05:56:24.061012Z","caller":"traceutil/trace.go:171","msg":"trace[234548333] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:567; }","duration":"635.276201ms","start":"2026-02-06T05:56:23.425732Z","end":"2026-02-06T05:56:24.061008Z","steps":["trace[234548333] 'agreement among raft nodes before linearized reading' (duration: 635.232741ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:56:24.06103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T05:56:23.425713Z","time spent":"635.312043ms","remote":"127.0.0.1:46844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-06T05:56:24.061188Z","caller":"traceutil/trace.go:171","msg":"trace[1864374017] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"1.000401063s","start":"2026-02-06T05:56:23.060781Z","end":"2026-02-06T05:56:24.061182Z","steps":["trace[1864374017] 'process raft request' (duration: 322.567948ms)","trace[1864374017] 'compare' (duration: 677.253993ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T05:56:24.061219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T05:56:23.060768Z","time spent":"1.000437823s","remote":"127.0.0.1:47118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T05:56:24.061273Z","caller":"traceutil/trace.go:171","msg":"trace[1945750402] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"999.73712ms","start":"2026-02-06T05:56:23.061531Z","end":"2026-02-06T05:56:24.061268Z","steps":["trace[1945750402] 'process raft request' (duration: 999.385122ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:56:24.061295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T05:56:23.061526Z","time spent":"999.75658ms","remote":"127.0.0.1:47118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T05:56:25.560684Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219119570165501900,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-06T05:56:25.928616Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.633322664s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-06T05:56:25.929049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T05:56:24.211863Z","time spent":"1.717182534s","remote":"127.0.0.1:46866","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-06T05:56:26.386974Z","caller":"traceutil/trace.go:171","msg":"trace[1394563294] linearizableReadLoop","detail":"{readStateIndex:588; appliedIndex:587; }","duration":"1.326321597s","start":"2026-02-06T05:56:25.060636Z","end":"2026-02-06T05:56:26.386958Z","steps":["trace[1394563294] 'read index received' (duration: 869.034156ms)","trace[1394563294] 'applied index is now lower than readState.Index' (duration: 457.286671ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T05:56:26.387155Z","caller":"traceutil/trace.go:171","msg":"trace[280258167] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"2.125879558s","start":"2026-02-06T05:56:24.261269Z","end":"2026-02-06T05:56:26.387149Z","steps":["trace[280258167] 'process raft request' (duration: 2.125576972s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:56:26.387237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T05:56:24.261251Z","time spent":"2.125923699s","remote":"127.0.0.1:47118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T05:56:26.387353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.326715635s","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-02-06T05:56:26.387369Z","caller":"traceutil/trace.go:171","msg":"trace[228042399] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:568; }","duration":"1.326757395s","start":"2026-02-06T05:56:25.060607Z","end":"2026-02-06T05:56:26.387364Z","steps":["trace[228042399] 'agreement among raft nodes before linearized reading' (duration: 1.326723514s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:56:26.387382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T05:56:25.060592Z","time spent":"1.326787816s","remote":"127.0.0.1:47118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-06T05:56:26.39084Z","caller":"traceutil/trace.go:171","msg":"trace[1316631085] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"460.776712ms","start":"2026-02-06T05:56:25.930057Z","end":"2026-02-06T05:56:26.390834Z","steps":["trace[1316631085] 'process raft request' (duration: 460.735651ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:56:26.391002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T05:56:25.930036Z","time spent":"460.928226ms","remote":"127.0.0.1:46866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T05:56:26.392142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.330445051s","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-02-06T05:56:26.392246Z","caller":"traceutil/trace.go:171","msg":"trace[830664761] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:569; }","duration":"1.330563565s","start":"2026-02-06T05:56:25.061676Z","end":"2026-02-06T05:56:26.392239Z","steps":["trace[830664761] 'agreement among raft nodes before linearized reading' (duration: 1.329538633s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:56:26.392325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T05:56:25.06167Z","time spent":"1.330645326s","remote":"127.0.0.1:47118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-06T05:56:26.392486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.13524ms","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-02-06T05:56:26.393351Z","caller":"traceutil/trace.go:171","msg":"trace[485977422] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:570; }","duration":"326.008319ms","start":"2026-02-06T05:56:26.067336Z","end":"2026-02-06T05:56:26.393344Z","steps":["trace[485977422] 'agreement among raft nodes before linearized reading' (duration: 325.105181ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:56:26.393484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T05:56:26.067331Z","time spent":"326.128671ms","remote":"127.0.0.1:47118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-06T05:56:26.393114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"955.990381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T05:56:26.395825Z","caller":"traceutil/trace.go:171","msg":"trace[1478444356] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:570; }","duration":"958.723947ms","start":"2026-02-06T05:56:25.437077Z","end":"2026-02-06T05:56:26.395801Z","steps":["trace[1478444356] 'agreement among raft nodes before linearized reading' (duration: 956.004321ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:56:26.395953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T05:56:25.43706Z","time spent":"958.870021ms","remote":"127.0.0.1:46844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T05:56:26.393196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.483589ms","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-02-06T05:56:26.396217Z","caller":"traceutil/trace.go:171","msg":"trace[1197780412] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:570; }","duration":"329.525612ms","start":"2026-02-06T05:56:26.066685Z","end":"2026-02-06T05:56:26.39621Z","steps":["trace[1197780412] 'agreement among raft nodes before linearized reading' (duration: 326.459929ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:56:26.39631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T05:56:26.06667Z","time spent":"329.632644ms","remote":"127.0.0.1:47118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-06T05:56:28.839906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.254789ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T05:56:28.83997Z","caller":"traceutil/trace.go:171","msg":"trace[485489931] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"177.207858ms","start":"2026-02-06T05:56:28.662752Z","end":"2026-02-06T05:56:28.839959Z","steps":["trace[485489931] 'process raft request' (duration: 69.853677ms)","trace[485489931] 'compare' (duration: 107.177517ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T05:56:28.854768Z","caller":"traceutil/trace.go:171","msg":"trace[1137681928] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"188.063864ms","start":"2026-02-06T05:56:28.66669Z","end":"2026-02-06T05:56:28.854754Z","steps":["trace[1137681928] 'process raft request' (duration: 188.044393ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T05:56:28.855002Z","caller":"traceutil/trace.go:171","msg":"trace[364652307] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"189.865944ms","start":"2026-02-06T05:56:28.665128Z","end":"2026-02-06T05:56:28.854994Z","steps":["trace[364652307] 'process raft request' (duration: 189.538167ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T05:56:29.400778Z","caller":"traceutil/trace.go:171","msg":"trace[324788316] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"147.430013ms","start":"2026-02-06T05:56:29.253334Z","end":"2026-02-06T05:56:29.400764Z","steps":["trace[324788316] 'process raft request' (duration: 102.640684ms)","trace[324788316] 'compare' (duration: 44.674595ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T05:56:29.403354Z","caller":"traceutil/trace.go:171","msg":"trace[2083387302] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"137.906837ms","start":"2026-02-06T05:56:29.265441Z","end":"2026-02-06T05:56:29.403347Z","steps":["trace[2083387302] 'process raft request' (duration: 137.846486ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:56:33.923468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.60181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T05:56:33.923568Z","caller":"traceutil/trace.go:171","msg":"trace[922596648] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:675; }","duration":"106.720993ms","start":"2026-02-06T05:56:33.816831Z","end":"2026-02-06T05:56:33.923552Z","steps":["trace[922596648] 'range keys from in-memory index tree' (duration: 106.564739ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:56:33.924157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.346457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T05:56:33.924269Z","caller":"traceutil/trace.go:171","msg":"trace[704278671] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:675; }","duration":"107.49292ms","start":"2026-02-06T05:56:33.816765Z","end":"2026-02-06T05:56:33.924258Z","steps":["trace[704278671] 'range keys from in-memory index tree' (duration: 107.248675ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:56:33.924459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.964179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T05:56:33.924518Z","caller":"traceutil/trace.go:171","msg":"trace[1202816719] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:675; }","duration":"103.002959ms","start":"2026-02-06T05:56:33.821483Z","end":"2026-02-06T05:56:33.924486Z","steps":["trace[1202816719] 'count revisions from in-memory index tree' (duration: 102.923447ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T05:56:34.015305Z","caller":"traceutil/trace.go:171","msg":"trace[644788770] linearizableReadLoop","detail":"{readStateIndex:698; appliedIndex:697; }","duration":"108.367699ms","start":"2026-02-06T05:56:33.906922Z","end":"2026-02-06T05:56:34.01529Z","steps":["trace[644788770] 'read index received' (duration: 108.236497ms)","trace[644788770] 'applied index is now lower than readState.Index' (duration: 130.672µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T05:56:34.015605Z","caller":"traceutil/trace.go:171","msg":"trace[1039429675] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"177.226593ms","start":"2026-02-06T05:56:33.838371Z","end":"2026-02-06T05:56:34.015597Z","steps":["trace[1039429675] 'process raft request' (duration: 176.832395ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:56:34.015811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.891641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T05:56:34.015835Z","caller":"traceutil/trace.go:171","msg":"trace[872639179] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:0; response_revision:676; }","duration":"108.944112ms","start":"2026-02-06T05:56:33.906885Z","end":"2026-02-06T05:56:34.015829Z","steps":["trace[872639179] 'agreement among raft nodes before linearized reading' (duration: 108.878381ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:56:34.015996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.237524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T05:56:34.016015Z","caller":"traceutil/trace.go:171","msg":"trace[1876492734] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:0; response_revision:676; }","duration":"107.275604ms","start":"2026-02-06T05:56:33.908734Z","end":"2026-02-06T05:56:34.01601Z","steps":["trace[1876492734] 'agreement among raft nodes before linearized reading' (duration: 107.245794ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T05:56:44.830257Z","caller":"traceutil/trace.go:171","msg":"trace[1832237416] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"156.581288ms","start":"2026-02-06T05:56:44.673635Z","end":"2026-02-06T05:56:44.830217Z","steps":["trace[1832237416] 'process raft request' (duration: 131.912313ms)","trace[1832237416] 'compare' (duration: 24.489242ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T05:56:48.75946Z","caller":"traceutil/trace.go:171","msg":"trace[2042133633] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"107.684616ms","start":"2026-02-06T05:56:48.651757Z","end":"2026-02-06T05:56:48.759442Z","steps":["trace[2042133633] 'process raft request' (duration: 107.600114ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T05:57:05.052645Z","caller":"traceutil/trace.go:171","msg":"trace[1122472053] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"118.707982ms","start":"2026-02-06T05:57:04.93392Z","end":"2026-02-06T05:57:05.052628Z","steps":["trace[1122472053] 'process raft request' (duration: 116.698688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:57:05.054072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.108722ms","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-02-06T05:57:05.054129Z","caller":"traceutil/trace.go:171","msg":"trace[78883280] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:832; }","duration":"102.204534ms","start":"2026-02-06T05:57:04.951909Z","end":"2026-02-06T05:57:05.054113Z","steps":["trace[78883280] 'agreement among raft nodes before linearized reading' (duration: 101.071219ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T05:57:05.052135Z","caller":"traceutil/trace.go:171","msg":"trace[1431688891] linearizableReadLoop","detail":"{readStateIndex:863; appliedIndex:862; }","duration":"100.164269ms","start":"2026-02-06T05:57:04.951952Z","end":"2026-02-06T05:57:05.052117Z","steps":["trace[1431688891] 'read index received' (duration: 70.079745ms)","trace[1431688891] 'applied index is now lower than readState.Index' (duration: 30.083784ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T05:57:49.157171Z","caller":"traceutil/trace.go:171","msg":"trace[1012515677] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"124.974383ms","start":"2026-02-06T05:57:49.032178Z","end":"2026-02-06T05:57:49.157152Z","steps":["trace[1012515677] 'process raft request' (duration: 124.792659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:57:49.157474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.48358ms","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-02-06T05:57:49.157554Z","caller":"traceutil/trace.go:171","msg":"trace[380240290] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:987; }","duration":"122.615773ms","start":"2026-02-06T05:57:49.034926Z","end":"2026-02-06T05:57:49.157541Z","steps":["trace[380240290] 'agreement among raft nodes before linearized reading' (duration: 122.290406ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T05:57:49.157159Z","caller":"traceutil/trace.go:171","msg":"trace[1081363044] linearizableReadLoop","detail":"{readStateIndex:1027; appliedIndex:1026; }","duration":"122.154683ms","start":"2026-02-06T05:57:49.034977Z","end":"2026-02-06T05:57:49.157132Z","steps":["trace[1081363044] 'read index received' (duration: 121.970519ms)","trace[1081363044] 'applied index is now lower than readState.Index' (duration: 183.084µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T05:57:49.30849Z","caller":"traceutil/trace.go:171","msg":"trace[6423491] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"145.798474ms","start":"2026-02-06T05:57:49.162662Z","end":"2026-02-06T05:57:49.30846Z","steps":["trace[6423491] 'process raft request' (duration: 63.561919ms)","trace[6423491] 'compare' (duration: 82.135453ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T05:57:54.497669Z","caller":"traceutil/trace.go:171","msg":"trace[998082544] linearizableReadLoop","detail":"{readStateIndex:1050; appliedIndex:1049; }","duration":"111.189665ms","start":"2026-02-06T05:57:54.386451Z","end":"2026-02-06T05:57:54.497641Z","steps":["trace[998082544] 'read index received' (duration: 25.654244ms)","trace[998082544] 'applied index is now lower than readState.Index' (duration: 85.534111ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T05:57:54.497701Z","caller":"traceutil/trace.go:171","msg":"trace[479924117] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"225.110019ms","start":"2026-02-06T05:57:54.272553Z","end":"2026-02-06T05:57:54.497663Z","steps":["trace[479924117] 'process raft request' (duration: 139.614428ms)","trace[479924117] 'compare' (duration: 85.346328ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T05:57:54.500009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.541815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T05:57:54.500054Z","caller":"traceutil/trace.go:171","msg":"trace[764605759] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1008; }","duration":"113.628017ms","start":"2026-02-06T05:57:54.386409Z","end":"2026-02-06T05:57:54.500037Z","steps":["trace[764605759] 'agreement among raft nodes before linearized reading' (duration: 111.39746ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T05:57:55.468022Z","caller":"traceutil/trace.go:171","msg":"trace[829180571] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"101.282225ms","start":"2026-02-06T05:57:55.366711Z","end":"2026-02-06T05:57:55.467994Z","steps":["trace[829180571] 'process raft request' (duration: 100.759024ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T05:57:55.634826Z","caller":"traceutil/trace.go:171","msg":"trace[1906412787] linearizableReadLoop","detail":"{readStateIndex:1053; appliedIndex:1050; }","duration":"234.754121ms","start":"2026-02-06T05:57:55.400044Z","end":"2026-02-06T05:57:55.634799Z","steps":["trace[1906412787] 'read index received' (duration: 67.673483ms)","trace[1906412787] 'applied index is now lower than readState.Index' (duration: 167.079588ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T05:57:55.634888Z","caller":"traceutil/trace.go:171","msg":"trace[1309663715] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"267.909963ms","start":"2026-02-06T05:57:55.366961Z","end":"2026-02-06T05:57:55.63487Z","steps":["trace[1309663715] 'process raft request' (duration: 259.077166ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T05:57:55.63493Z","caller":"traceutil/trace.go:171","msg":"trace[2033843183] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"266.201687ms","start":"2026-02-06T05:57:55.368719Z","end":"2026-02-06T05:57:55.634921Z","steps":["trace[2033843183] 'process raft request' (duration: 266.037004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:57:55.635352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.255421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T05:57:55.635404Z","caller":"traceutil/trace.go:171","msg":"trace[1048308337] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1011; }","duration":"208.368793ms","start":"2026-02-06T05:57:55.427021Z","end":"2026-02-06T05:57:55.63539Z","steps":["trace[1048308337] 'agreement among raft nodes before linearized reading' (duration: 208.272011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:57:55.635678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.105812ms","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-02-06T05:57:55.63571Z","caller":"traceutil/trace.go:171","msg":"trace[2094194412] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1011; }","duration":"186.171053ms","start":"2026-02-06T05:57:55.449529Z","end":"2026-02-06T05:57:55.6357Z","steps":["trace[2094194412] 'agreement among raft nodes before linearized reading' (duration: 186.02412ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:57:55.636478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.430927ms","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-02-06T05:57:55.636516Z","caller":"traceutil/trace.go:171","msg":"trace[1804957092] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1011; }","duration":"236.501188ms","start":"2026-02-06T05:57:55.400003Z","end":"2026-02-06T05:57:55.636504Z","steps":["trace[1804957092] 'agreement among raft nodes before linearized reading' (duration: 235.075868ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T05:58:05.833186Z","caller":"traceutil/trace.go:171","msg":"trace[974552339] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"122.904805ms","start":"2026-02-06T05:58:05.710239Z","end":"2026-02-06T05:58:05.833144Z","steps":["trace[974552339] 'process raft request' (duration: 50.475936ms)","trace[974552339] 'compare' (duration: 72.084382ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T05:58:05.833607Z","caller":"traceutil/trace.go:171","msg":"trace[1098479374] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"122.151419ms","start":"2026-02-06T05:58:05.71144Z","end":"2026-02-06T05:58:05.833591Z","steps":["trace[1098479374] 'process raft request' (duration: 121.483225ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:58:27.806976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.933234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T05:58:27.807164Z","caller":"traceutil/trace.go:171","msg":"trace[1472812850] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1103; }","duration":"105.161459ms","start":"2026-02-06T05:58:27.701984Z","end":"2026-02-06T05:58:27.807146Z","steps":["trace[1472812850] 'count revisions from in-memory index tree' (duration: 104.876262ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T05:58:29.961795Z","caller":"traceutil/trace.go:171","msg":"trace[1310694455] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"108.107279ms","start":"2026-02-06T05:58:29.853655Z","end":"2026-02-06T05:58:29.961762Z","steps":["trace[1310694455] 'process raft request' (duration: 107.594118ms)"],"step_count":1}