{"level":"warn","ts":"2026-06-17T02:49:39.259999Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-17T02:49:39.260165Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.38: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.38:2380","--initial-cluster=instance=https://199.204.45.38:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.38:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.38: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-06-17T02:49:39.260288Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-17T02:49:39.260307Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-06-17T02:49:39.260338Z","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-06-17T02:49:39.261381Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"]} {"level":"info","ts":"2026-06-17T02:49:39.261523Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.38: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-06-17T02:49:39.264271Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.393343ms"} {"level":"info","ts":"2026-06-17T02:49:39.284987Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"331455d6226d3423","cluster-id":"25c7803fb83a1455"} {"level":"info","ts":"2026-06-17T02:49:39.285142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=()"} {"level":"info","ts":"2026-06-17T02:49:39.285235Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 0"} {"level":"info","ts":"2026-06-17T02:49:39.285253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 331455d6226d3423 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-17T02:49:39.285284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 1"} {"level":"info","ts":"2026-06-17T02:49:39.28534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"warn","ts":"2026-06-17T02:49:39.291462Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-17T02:49:39.29304Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-17T02:49:39.294147Z","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-06-17T02:49:39.296636Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"331455d6226d3423","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-17T02:49:39.296978Z","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-06-17T02:49:39.297204Z","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-06-17T02:49:39.297222Z","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-06-17T02:49:39.297198Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"331455d6226d3423","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-17T02:49:39.298667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"info","ts":"2026-06-17T02:49:39.298796Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","added-peer-id":"331455d6226d3423","added-peer-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-06-17T02:49:39.307846Z","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-06-17T02:49:39.308038Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-06-17T02:49:39.308087Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-06-17T02:49:39.308325Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"331455d6226d3423","initial-advertise-peer-urls":["https://199.204.45.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-17T02:49:39.308358Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-17T02:49:39.585649Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 is starting a new election at term 1"} {"level":"info","ts":"2026-06-17T02:49:39.585732Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-17T02:49:39.585766Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgPreVoteResp from 331455d6226d3423 at term 1"} {"level":"info","ts":"2026-06-17T02:49:39.585788Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became candidate at term 2"} {"level":"info","ts":"2026-06-17T02:49:39.585796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgVoteResp from 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-06-17T02:49:39.58582Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became leader at term 2"} {"level":"info","ts":"2026-06-17T02:49:39.585831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 331455d6226d3423 elected leader 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-06-17T02:49:39.615321Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-17T02:49:39.616932Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","cluster-version":"3.5"} {"level":"info","ts":"2026-06-17T02:49:39.617036Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-17T02:49:39.617064Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-17T02:49:39.617175Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"331455d6226d3423","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.38:2379]}","request-path":"/0/members/331455d6226d3423/attributes","cluster-id":"25c7803fb83a1455","publish-timeout":"7s"} {"level":"info","ts":"2026-06-17T02:49:39.617408Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-17T02:49:39.617682Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-17T02:49:39.617774Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-17T02:49:39.621647Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.38:2379"} {"level":"info","ts":"2026-06-17T02:49:39.621919Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-17T02:49:39.624545Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-06-17T02:50:20.607911Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757021145312713138,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-17T02:50:20.670683Z","caller":"traceutil/trace.go:171","msg":"trace[1993729903] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"704.438718ms","start":"2026-06-17T02:50:19.966209Z","end":"2026-06-17T02:50:20.670648Z","steps":["trace[1993729903] 'process raft request' (duration: 703.534638ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:50:20.676167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:50:19.966183Z","time spent":"704.682953ms","remote":"127.0.0.1:48588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T02:50:20.681312Z","caller":"traceutil/trace.go:171","msg":"trace[313520508] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"713.838218ms","start":"2026-06-17T02:50:19.967442Z","end":"2026-06-17T02:50:20.68128Z","steps":["trace[313520508] 'process raft request' (duration: 713.534302ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:50:20.681502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:50:19.967398Z","time spent":"714.005122ms","remote":"127.0.0.1:48588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T02:50:20.681569Z","caller":"traceutil/trace.go:171","msg":"trace[1076465816] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"713.764826ms","start":"2026-06-17T02:50:19.967772Z","end":"2026-06-17T02:50:20.681537Z","steps":["trace[1076465816] 'process raft request' (duration: 713.421288ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:50:20.681727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:50:19.96774Z","time spent":"713.899179ms","remote":"127.0.0.1:48588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29452,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T02:50:20.681864Z","caller":"traceutil/trace.go:171","msg":"trace[656807885] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"713.261946ms","start":"2026-06-17T02:50:19.968577Z","end":"2026-06-17T02:50:20.681839Z","steps":["trace[656807885] 'process raft request' (duration: 713.01481ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:50:20.682185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:50:19.968563Z","time spent":"713.513531ms","remote":"127.0.0.1:48588","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-06-17T02:50:20.682383Z","caller":"traceutil/trace.go:171","msg":"trace[1689468908] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"712.924797ms","start":"2026-06-17T02:50:19.969436Z","end":"2026-06-17T02:50:20.682361Z","steps":["trace[1689468908] 'process raft request' (duration: 712.339804ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:50:20.682443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:50:19.969422Z","time spent":"712.985608ms","remote":"127.0.0.1:48588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T02:50:20.682509Z","caller":"traceutil/trace.go:171","msg":"trace[748084531] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"633.1122ms","start":"2026-06-17T02:50:20.049387Z","end":"2026-06-17T02:50:20.682499Z","steps":["trace[748084531] 'process raft request' (duration: 632.939526ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:50:20.682545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:50:20.049346Z","time spent":"633.183252ms","remote":"127.0.0.1:48588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-17T02:50:20.682537Z","caller":"traceutil/trace.go:171","msg":"trace[2106390974] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:574; }","duration":"575.050057ms","start":"2026-06-17T02:50:20.10739Z","end":"2026-06-17T02:50:20.68244Z","steps":["trace[2106390974] 'read index received' (duration: 562.446866ms)","trace[2106390974] 'applied index is now lower than readState.Index' (duration: 12.602281ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T02:50:20.682777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.389734ms","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-06-17T02:50:20.682844Z","caller":"traceutil/trace.go:171","msg":"trace[1720899369] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:558; }","duration":"575.486576ms","start":"2026-06-17T02:50:20.107348Z","end":"2026-06-17T02:50:20.682834Z","steps":["trace[1720899369] 'agreement among raft nodes before linearized reading' (duration: 575.366624ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:50:20.68287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:50:20.107334Z","time spent":"575.529837ms","remote":"127.0.0.1:48816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-17T02:50:20.682959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.995976ms","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-06-17T02:50:20.683004Z","caller":"traceutil/trace.go:171","msg":"trace[133924682] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:558; }","duration":"492.16201ms","start":"2026-06-17T02:50:20.19083Z","end":"2026-06-17T02:50:20.682992Z","steps":["trace[133924682] 'agreement among raft nodes before linearized reading' (duration: 491.984456ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:50:20.683076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.283168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-06-17T02:50:20.683092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:50:20.190811Z","time spent":"492.269763ms","remote":"127.0.0.1:48816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-17T02:50:20.683117Z","caller":"traceutil/trace.go:171","msg":"trace[2114448629] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:558; }","duration":"459.406511ms","start":"2026-06-17T02:50:20.223701Z","end":"2026-06-17T02:50:20.683108Z","steps":["trace[2114448629] 'agreement among raft nodes before linearized reading' (duration: 459.321669ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:50:20.683139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:50:20.223533Z","time spent":"459.601766ms","remote":"127.0.0.1:48816","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":"info","ts":"2026-06-17T02:50:22.164134Z","caller":"traceutil/trace.go:171","msg":"trace[831580916] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"241.997519ms","start":"2026-06-17T02:50:21.922116Z","end":"2026-06-17T02:50:22.164114Z","steps":["trace[831580916] 'process raft request' (duration: 241.808665ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T02:50:22.170304Z","caller":"traceutil/trace.go:171","msg":"trace[1995201480] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"239.742128ms","start":"2026-06-17T02:50:21.93052Z","end":"2026-06-17T02:50:22.170262Z","steps":["trace[1995201480] 'process raft request' (duration: 238.556912ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T02:50:27.436296Z","caller":"traceutil/trace.go:171","msg":"trace[1510942784] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"250.074099ms","start":"2026-06-17T02:50:27.186192Z","end":"2026-06-17T02:50:27.436266Z","steps":["trace[1510942784] 'process raft request' (duration: 249.885255ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T02:50:28.92513Z","caller":"traceutil/trace.go:171","msg":"trace[406428144] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:665; }","duration":"359.947727ms","start":"2026-06-17T02:50:28.565145Z","end":"2026-06-17T02:50:28.925092Z","steps":["trace[406428144] 'read index received' (duration: 358.349571ms)","trace[406428144] 'applied index is now lower than readState.Index' (duration: 1.596436ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T02:50:28.925351Z","caller":"traceutil/trace.go:171","msg":"trace[1185145004] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"395.885817ms","start":"2026-06-17T02:50:28.529433Z","end":"2026-06-17T02:50:28.925318Z","steps":["trace[1185145004] 'process raft request' (duration: 394.68312ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:50:28.925563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:50:28.529379Z","time spent":"396.01834ms","remote":"127.0.0.1:48642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T02:50:28.925888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.593971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-r2r55\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-06-17T02:50:28.926212Z","caller":"traceutil/trace.go:171","msg":"trace[1235693458] range","detail":"{range_begin:/registry/pods/kube-system/cilium-r2r55; range_end:; response_count:1; response_revision:643; }","duration":"361.03657ms","start":"2026-06-17T02:50:28.565112Z","end":"2026-06-17T02:50:28.926148Z","steps":["trace[1235693458] 'agreement among raft nodes before linearized reading' (duration: 360.303494ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:50:28.92652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:50:28.56509Z","time spent":"361.412529ms","remote":"127.0.0.1:48722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16983,"request content":"key:\"/registry/pods/kube-system/cilium-r2r55\" "} {"level":"warn","ts":"2026-06-17T02:50:29.311212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.725846ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-17T02:50:29.311577Z","caller":"traceutil/trace.go:171","msg":"trace[1913679013] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:666; }","duration":"386.274672ms","start":"2026-06-17T02:50:28.925239Z","end":"2026-06-17T02:50:29.311514Z","steps":["trace[1913679013] 'read index received' (duration: 213.047075ms)","trace[1913679013] 'applied index is now lower than readState.Index' (duration: 173.222897ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T02:50:29.311767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.565567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-17T02:50:29.311769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.776821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T02:50:29.311841Z","caller":"traceutil/trace.go:171","msg":"trace[1873076857] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:643; }","duration":"561.764371ms","start":"2026-06-17T02:50:28.750055Z","end":"2026-06-17T02:50:29.31182Z","steps":["trace[1873076857] 'agreement among raft nodes before linearized reading' (duration: 561.490045ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:50:29.311919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:50:28.750004Z","time spent":"561.906024ms","remote":"127.0.0.1:48816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-17T02:50:29.311851Z","caller":"traceutil/trace.go:171","msg":"trace[1121994367] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:644; }","duration":"508.908664ms","start":"2026-06-17T02:50:28.802921Z","end":"2026-06-17T02:50:29.31183Z","steps":["trace[1121994367] 'agreement among raft nodes before linearized reading' (duration: 508.663908ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T02:50:29.312081Z","caller":"traceutil/trace.go:171","msg":"trace[955006227] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"324.487747ms","start":"2026-06-17T02:50:28.987573Z","end":"2026-06-17T02:50:29.312061Z","steps":["trace[955006227] 'process raft request' (duration: 324.062568ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:50:29.312107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:50:28.802908Z","time spent":"509.163139ms","remote":"127.0.0.1:48530","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-06-17T02:50:29.31225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:50:28.98753Z","time spent":"324.62637ms","remote":"127.0.0.1:48776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-17T02:50:29.312401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.443441ms","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-06-17T02:50:29.312442Z","caller":"traceutil/trace.go:171","msg":"trace[746768917] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:645; }","duration":"573.543013ms","start":"2026-06-17T02:50:28.738889Z","end":"2026-06-17T02:50:29.312432Z","steps":["trace[746768917] 'agreement among raft nodes before linearized reading' (duration: 572.818107ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:50:29.312479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:50:28.73886Z","time spent":"573.612855ms","remote":"127.0.0.1:48816","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":"info","ts":"2026-06-17T02:50:29.311784Z","caller":"traceutil/trace.go:171","msg":"trace[846833332] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"361.586912ms","start":"2026-06-17T02:50:28.950176Z","end":"2026-06-17T02:50:29.311763Z","steps":["trace[846833332] 'process raft request' (duration: 361.211823ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:50:29.312602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.537442ms","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-06-17T02:50:29.312659Z","caller":"traceutil/trace.go:171","msg":"trace[825266768] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:645; }","duration":"564.653905ms","start":"2026-06-17T02:50:28.747983Z","end":"2026-06-17T02:50:29.312637Z","steps":["trace[825266768] 'agreement among raft nodes before linearized reading' (duration: 563.748766ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:50:29.312722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:50:28.747946Z","time spent":"564.768407ms","remote":"127.0.0.1:48816","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-06-17T02:50:29.314487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:50:28.950143Z","time spent":"362.474633ms","remote":"127.0.0.1:48722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T02:50:30.747516Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.408609981s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-17T02:50:30.800884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.463677967s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-17T02:50:30.801212Z","caller":"traceutil/trace.go:171","msg":"trace[1197821820] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:669; }","duration":"1.479478409s","start":"2026-06-17T02:50:29.321695Z","end":"2026-06-17T02:50:30.801174Z","steps":["trace[1197821820] 'read index received' (duration: 15.356982ms)","trace[1197821820] 'applied index is now lower than readState.Index' (duration: 1.464117247s)"],"step_count":2} {"level":"info","ts":"2026-06-17T02:50:30.801207Z","caller":"traceutil/trace.go:171","msg":"trace[1239189958] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"1.482827033s","start":"2026-06-17T02:50:29.318267Z","end":"2026-06-17T02:50:30.801094Z","steps":["trace[1239189958] 'process raft request' (duration: 18.771078ms)","trace[1239189958] 'compare' (duration: 1.463406311s)"],"step_count":2} {"level":"info","ts":"2026-06-17T02:50:30.801267Z","caller":"traceutil/trace.go:171","msg":"trace[180446199] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"1.477644698s","start":"2026-06-17T02:50:29.323591Z","end":"2026-06-17T02:50:30.801235Z","steps":["trace[180446199] 'process raft request' (duration: 1.477567287s)"],"step_count":1} {"level":"info","ts":"2026-06-17T02:50:30.801384Z","caller":"traceutil/trace.go:171","msg":"trace[79402049] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"1.479601752s","start":"2026-06-17T02:50:29.321711Z","end":"2026-06-17T02:50:30.801313Z","steps":["trace[79402049] 'process raft request' (duration: 1.479359447s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:50:30.801478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.479742835s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-06-17T02:50:30.801417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:50:29.323584Z","time spent":"1.477762641s","remote":"127.0.0.1:48816","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-06-17T02:50:30.801594Z","caller":"traceutil/trace.go:171","msg":"trace[1219336796] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:648; }","duration":"1.479906429s","start":"2026-06-17T02:50:29.321661Z","end":"2026-06-17T02:50:30.801567Z","steps":["trace[1219336796] 'agreement among raft nodes before linearized reading' (duration: 1.479707714s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:50:30.80163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:50:29.321697Z","time spent":"1.479799986s","remote":"127.0.0.1:48816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T02:50:30.801624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:50:29.317972Z","time spent":"1.483363605s","remote":"127.0.0.1:48816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T02:50:30.801662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:50:29.321647Z","time spent":"1.48000486s","remote":"127.0.0.1:48748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":215,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-06-17T02:50:30.803255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.361945762s","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-06-17T02:50:30.803304Z","caller":"traceutil/trace.go:171","msg":"trace[555361828] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:648; }","duration":"1.362071854s","start":"2026-06-17T02:50:29.44122Z","end":"2026-06-17T02:50:30.803292Z","steps":["trace[555361828] 'agreement among raft nodes before linearized reading' (duration: 1.361825719s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:50:30.803337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:50:29.441191Z","time spent":"1.362138595s","remote":"127.0.0.1:48816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-17T02:50:35.978665Z","caller":"traceutil/trace.go:171","msg":"trace[650509033] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"158.617691ms","start":"2026-06-17T02:50:35.820013Z","end":"2026-06-17T02:50:35.978631Z","steps":["trace[650509033] 'process raft request' (duration: 158.317465ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:50:47.303455Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757021145312713617,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-17T02:50:47.549661Z","caller":"traceutil/trace.go:171","msg":"trace[480608774] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"844.58611ms","start":"2026-06-17T02:50:46.705002Z","end":"2026-06-17T02:50:47.549588Z","steps":["trace[480608774] 'process raft request' (duration: 844.297273ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:50:47.550152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:50:46.704971Z","time spent":"844.835766ms","remote":"127.0.0.1:48722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T02:50:47.561196Z","caller":"traceutil/trace.go:171","msg":"trace[459816436] linearizableReadLoop","detail":"{readStateIndex:839; appliedIndex:837; }","duration":"757.908239ms","start":"2026-06-17T02:50:46.803255Z","end":"2026-06-17T02:50:47.561163Z","steps":["trace[459816436] 'read index received' (duration: 746.122297ms)","trace[459816436] 'applied index is now lower than readState.Index' (duration: 11.784372ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T02:50:47.561347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.084974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T02:50:47.561212Z","caller":"traceutil/trace.go:171","msg":"trace[1142708640] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"460.178008ms","start":"2026-06-17T02:50:47.101006Z","end":"2026-06-17T02:50:47.561184Z","steps":["trace[1142708640] 'process raft request' (duration: 459.945553ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T02:50:47.561396Z","caller":"traceutil/trace.go:171","msg":"trace[358362890] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:809; }","duration":"758.222996ms","start":"2026-06-17T02:50:46.803163Z","end":"2026-06-17T02:50:47.561386Z","steps":["trace[358362890] 'agreement among raft nodes before linearized reading' (duration: 758.112084ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:50:47.561435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:50:46.803123Z","time spent":"758.305568ms","remote":"127.0.0.1:48530","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-06-17T02:50:47.561733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.864017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-17T02:50:47.561759Z","caller":"traceutil/trace.go:171","msg":"trace[1099168604] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:809; }","duration":"673.936738ms","start":"2026-06-17T02:50:46.887816Z","end":"2026-06-17T02:50:47.561753Z","steps":["trace[1099168604] 'agreement among raft nodes before linearized reading' (duration: 673.640332ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:50:47.561779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:50:46.887785Z","time spent":"673.9888ms","remote":"127.0.0.1:48816","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-06-17T02:50:47.561755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.962314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-17T02:50:47.561767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.974548ms","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-06-17T02:50:47.561832Z","caller":"traceutil/trace.go:171","msg":"trace[1647388555] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:809; }","duration":"667.059335ms","start":"2026-06-17T02:50:46.894753Z","end":"2026-06-17T02:50:47.561812Z","steps":["trace[1647388555] 'agreement among raft nodes before linearized reading' (duration: 666.770149ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:50:47.56189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:50:46.894742Z","time spent":"667.137927ms","remote":"127.0.0.1:48816","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-06-17T02:50:47.561827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.076371ms","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-06-17T02:50:47.561894Z","caller":"traceutil/trace.go:171","msg":"trace[554280524] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:809; }","duration":"669.173103ms","start":"2026-06-17T02:50:46.892692Z","end":"2026-06-17T02:50:47.561865Z","steps":["trace[554280524] 'agreement among raft nodes before linearized reading' (duration: 668.757603ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T02:50:47.561983Z","caller":"traceutil/trace.go:171","msg":"trace[1773476110] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:809; }","duration":"669.317686ms","start":"2026-06-17T02:50:46.892629Z","end":"2026-06-17T02:50:47.561947Z","steps":["trace[1773476110] 'agreement among raft nodes before linearized reading' (duration: 668.863766ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:50:47.562058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:50:46.892611Z","time spent":"669.437129ms","remote":"127.0.0.1:48816","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-06-17T02:50:47.561476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:50:47.100982Z","time spent":"460.432794ms","remote":"127.0.0.1:48710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4904,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T02:50:47.56198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:50:46.892668Z","time spent":"669.298826ms","remote":"127.0.0.1:48816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-17T02:51:32.365661Z","caller":"traceutil/trace.go:171","msg":"trace[1374486344] linearizableReadLoop","detail":"{readStateIndex:1150; appliedIndex:1149; }","duration":"460.502167ms","start":"2026-06-17T02:51:31.905122Z","end":"2026-06-17T02:51:32.365624Z","steps":["trace[1374486344] 'read index received' (duration: 460.127698ms)","trace[1374486344] 'applied index is now lower than readState.Index' (duration: 373.319µs)"],"step_count":2} {"level":"info","ts":"2026-06-17T02:51:32.365951Z","caller":"traceutil/trace.go:171","msg":"trace[1101378820] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"473.353302ms","start":"2026-06-17T02:51:31.892498Z","end":"2026-06-17T02:51:32.365851Z","steps":["trace[1101378820] 'process raft request' (duration: 472.926392ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:51:32.366248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.077889ms","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-06-17T02:51:32.366391Z","caller":"traceutil/trace.go:171","msg":"trace[1124149696] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1109; }","duration":"461.275083ms","start":"2026-06-17T02:51:31.905085Z","end":"2026-06-17T02:51:32.36636Z","steps":["trace[1124149696] 'agreement among raft nodes before linearized reading' (duration: 460.785072ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:51:32.366465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:51:31.905069Z","time spent":"461.384096ms","remote":"127.0.0.1:48816","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-06-17T02:51:32.366969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:51:31.892461Z","time spent":"473.759801ms","remote":"127.0.0.1:48816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T02:51:32.717371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.027071ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-17T02:51:32.717667Z","caller":"traceutil/trace.go:171","msg":"trace[792261697] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"343.06465ms","start":"2026-06-17T02:51:32.374573Z","end":"2026-06-17T02:51:32.717638Z","steps":["trace[792261697] 'process raft request' (duration: 342.971677ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T02:51:32.717647Z","caller":"traceutil/trace.go:171","msg":"trace[1480689003] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"540.284712ms","start":"2026-06-17T02:51:32.177316Z","end":"2026-06-17T02:51:32.7176Z","steps":["trace[1480689003] 'process raft request' (duration: 279.862952ms)","trace[1480689003] 'compare' (duration: 259.850407ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T02:51:32.717773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:51:32.374549Z","time spent":"343.181913ms","remote":"127.0.0.1:48816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T02:51:32.717859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:51:32.177291Z","time spent":"540.452845ms","remote":"127.0.0.1:48816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T02:51:32.718038Z","caller":"traceutil/trace.go:171","msg":"trace[57767144] linearizableReadLoop","detail":"{readStateIndex:1151; appliedIndex:1150; }","duration":"352.275746ms","start":"2026-06-17T02:51:32.365754Z","end":"2026-06-17T02:51:32.718029Z","steps":["trace[57767144] 'read index received' (duration: 91.435036ms)","trace[57767144] 'applied index is now lower than readState.Index' (duration: 260.84ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T02:51:32.718163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.123471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T02:51:32.71819Z","caller":"traceutil/trace.go:171","msg":"trace[934645818] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1111; }","duration":"585.169192ms","start":"2026-06-17T02:51:32.133013Z","end":"2026-06-17T02:51:32.718182Z","steps":["trace[934645818] 'agreement among raft nodes before linearized reading' (duration: 585.112021ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:51:32.718213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:51:32.133002Z","time spent":"585.206293ms","remote":"127.0.0.1:48530","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-06-17T02:51:32.718438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.362403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-17T02:51:32.718458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.30773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-17T02:51:32.718478Z","caller":"traceutil/trace.go:171","msg":"trace[1826417584] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1111; }","duration":"218.466076ms","start":"2026-06-17T02:51:32.499999Z","end":"2026-06-17T02:51:32.718465Z","steps":["trace[1826417584] 'agreement among raft nodes before linearized reading' (duration: 218.383284ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T02:51:32.718482Z","caller":"traceutil/trace.go:171","msg":"trace[822425837] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1111; }","duration":"812.398623ms","start":"2026-06-17T02:51:31.906075Z","end":"2026-06-17T02:51:32.718473Z","steps":["trace[822425837] 'agreement among raft nodes before linearized reading' (duration: 812.27462ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:51:32.718531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:51:31.906048Z","time spent":"812.478344ms","remote":"127.0.0.1:48816","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-06-17T02:51:32.718799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.72748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-17T02:51:32.718818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.950254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-17T02:51:32.718838Z","caller":"traceutil/trace.go:171","msg":"trace[239504786] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1111; }","duration":"196.030646ms","start":"2026-06-17T02:51:32.522801Z","end":"2026-06-17T02:51:32.718831Z","steps":["trace[239504786] 'agreement among raft nodes before linearized reading' (duration: 195.966414ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T02:51:32.718831Z","caller":"traceutil/trace.go:171","msg":"trace[1205162023] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1111; }","duration":"812.799791ms","start":"2026-06-17T02:51:31.906021Z","end":"2026-06-17T02:51:32.718821Z","steps":["trace[1205162023] 'agreement among raft nodes before linearized reading' (duration: 812.642458ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:51:32.718864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:51:31.906006Z","time spent":"812.849692ms","remote":"127.0.0.1:48816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-17T02:51:47.025639Z","caller":"traceutil/trace.go:171","msg":"trace[402503472] linearizableReadLoop","detail":"{readStateIndex:1201; appliedIndex:1200; }","duration":"223.763014ms","start":"2026-06-17T02:51:46.80183Z","end":"2026-06-17T02:51:47.025593Z","steps":["trace[402503472] 'read index received' (duration: 223.550229ms)","trace[402503472] 'applied index is now lower than readState.Index' (duration: 211.205µs)"],"step_count":2} {"level":"info","ts":"2026-06-17T02:51:47.025653Z","caller":"traceutil/trace.go:171","msg":"trace[1646049533] transaction","detail":"{read_only:false; response_revision:1157; number_of_response:1; }","duration":"249.67145ms","start":"2026-06-17T02:51:46.775953Z","end":"2026-06-17T02:51:47.025625Z","steps":["trace[1646049533] 'process raft request' (duration: 249.407935ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:51:47.025787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.924277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T02:51:47.025886Z","caller":"traceutil/trace.go:171","msg":"trace[917729638] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1157; }","duration":"224.084351ms","start":"2026-06-17T02:51:46.801776Z","end":"2026-06-17T02:51:47.02586Z","steps":["trace[917729638] 'agreement among raft nodes before linearized reading' (duration: 223.923097ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:51:47.025935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.566716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-06-17T02:51:47.025934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.634191ms","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-06-17T02:51:47.025963Z","caller":"traceutil/trace.go:171","msg":"trace[2104020896] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1157; }","duration":"217.629908ms","start":"2026-06-17T02:51:46.808326Z","end":"2026-06-17T02:51:47.025956Z","steps":["trace[2104020896] 'agreement among raft nodes before linearized reading' (duration: 217.489115ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T02:51:47.02598Z","caller":"traceutil/trace.go:171","msg":"trace[191813112] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1157; }","duration":"223.711883ms","start":"2026-06-17T02:51:46.802254Z","end":"2026-06-17T02:51:47.025966Z","steps":["trace[191813112] 'agreement among raft nodes before linearized reading' (duration: 223.529119ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:51:47.026213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.267171ms","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-06-17T02:51:47.026346Z","caller":"traceutil/trace.go:171","msg":"trace[1629259001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1157; }","duration":"218.494787ms","start":"2026-06-17T02:51:46.807823Z","end":"2026-06-17T02:51:47.026318Z","steps":["trace[1629259001] 'agreement among raft nodes before linearized reading' (duration: 218.034257ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:51:47.026222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.81814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-17T02:51:47.026514Z","caller":"traceutil/trace.go:171","msg":"trace[1042767599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1157; }","duration":"222.192228ms","start":"2026-06-17T02:51:46.8043Z","end":"2026-06-17T02:51:47.026492Z","steps":["trace[1042767599] 'agreement among raft nodes before linearized reading' (duration: 221.635766ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T02:51:51.422683Z","caller":"traceutil/trace.go:171","msg":"trace[738619250] linearizableReadLoop","detail":"{readStateIndex:1236; appliedIndex:1235; }","duration":"371.040481ms","start":"2026-06-17T02:51:51.051623Z","end":"2026-06-17T02:51:51.422663Z","steps":["trace[738619250] 'read index received' (duration: 370.879346ms)","trace[738619250] 'applied index is now lower than readState.Index' (duration: 160.345µs)"],"step_count":2} {"level":"info","ts":"2026-06-17T02:51:51.42278Z","caller":"traceutil/trace.go:171","msg":"trace[1266683473] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"371.719326ms","start":"2026-06-17T02:51:51.051029Z","end":"2026-06-17T02:51:51.422748Z","steps":["trace[1266683473] 'process raft request' (duration: 371.516741ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:51:51.422919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.263956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-06-17T02:51:51.422944Z","caller":"traceutil/trace.go:171","msg":"trace[150985624] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1190; }","duration":"371.362968ms","start":"2026-06-17T02:51:51.051574Z","end":"2026-06-17T02:51:51.422937Z","steps":["trace[150985624] 'agreement among raft nodes before linearized reading' (duration: 371.204134ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:51:51.422968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:51:51.051551Z","time spent":"371.41165ms","remote":"127.0.0.1:48816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-17T02:51:51.422998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:51:51.050998Z","time spent":"371.88407ms","remote":"127.0.0.1:48816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T02:51:51.42304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.391267ms","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-06-17T02:51:51.42309Z","caller":"traceutil/trace.go:171","msg":"trace[1142493855] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1190; }","duration":"370.467328ms","start":"2026-06-17T02:51:51.052608Z","end":"2026-06-17T02:51:51.423076Z","steps":["trace[1142493855] 'agreement among raft nodes before linearized reading' (duration: 370.330205ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:51:51.423118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:51:51.0526Z","time spent":"370.51282ms","remote":"127.0.0.1:48816","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-06-17T02:51:51.423174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.080989ms","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-06-17T02:51:51.423196Z","caller":"traceutil/trace.go:171","msg":"trace[459856267] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1190; }","duration":"370.11362ms","start":"2026-06-17T02:51:51.053076Z","end":"2026-06-17T02:51:51.42319Z","steps":["trace[459856267] 'agreement among raft nodes before linearized reading' (duration: 370.056298ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:51:51.423215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:51:51.053072Z","time spent":"370.13733ms","remote":"127.0.0.1:48816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-17T02:51:51.42323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.16693ms","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-06-17T02:51:51.423248Z","caller":"traceutil/trace.go:171","msg":"trace[1481457015] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1190; }","duration":"370.199792ms","start":"2026-06-17T02:51:51.053042Z","end":"2026-06-17T02:51:51.423242Z","steps":["trace[1481457015] 'agreement among raft nodes before linearized reading' (duration: 370.14279ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:51:51.423265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:51:51.053035Z","time spent":"370.226592ms","remote":"127.0.0.1:48816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-17T02:51:51.423276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.02768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T02:51:51.423294Z","caller":"traceutil/trace.go:171","msg":"trace[1471830332] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1190; }","duration":"206.075262ms","start":"2026-06-17T02:51:51.217212Z","end":"2026-06-17T02:51:51.423288Z","steps":["trace[1471830332] 'agreement among raft nodes before linearized reading' (duration: 206.044471ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T02:51:51.869996Z","caller":"traceutil/trace.go:171","msg":"trace[1419154933] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"441.827878ms","start":"2026-06-17T02:51:51.428147Z","end":"2026-06-17T02:51:51.869975Z","steps":["trace[1419154933] 'process raft request' (duration: 441.689855ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:51:51.870183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:51:51.428128Z","time spent":"441.928211ms","remote":"127.0.0.1:48816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T02:51:51.870973Z","caller":"traceutil/trace.go:171","msg":"trace[1763021419] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"442.317619ms","start":"2026-06-17T02:51:51.428639Z","end":"2026-06-17T02:51:51.870957Z","steps":["trace[1763021419] 'process raft request' (duration: 442.108953ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T02:51:51.870972Z","caller":"traceutil/trace.go:171","msg":"trace[1656996279] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"442.312879ms","start":"2026-06-17T02:51:51.428634Z","end":"2026-06-17T02:51:51.870947Z","steps":["trace[1656996279] 'process raft request' (duration: 442.243197ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:51:51.871044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:51:51.428611Z","time spent":"442.402501ms","remote":"127.0.0.1:48816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T02:51:51.871138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:51:51.428598Z","time spent":"442.452462ms","remote":"127.0.0.1:48816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T02:51:51.871359Z","caller":"traceutil/trace.go:171","msg":"trace[1289244624] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"442.655887ms","start":"2026-06-17T02:51:51.428695Z","end":"2026-06-17T02:51:51.87135Z","steps":["trace[1289244624] 'process raft request' (duration: 442.216937ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:51:51.8714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:51:51.428682Z","time spent":"442.693127ms","remote":"127.0.0.1:48816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T02:51:52.948479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.450695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T02:51:52.948566Z","caller":"traceutil/trace.go:171","msg":"trace[628157047] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1196; }","duration":"258.586448ms","start":"2026-06-17T02:51:52.68996Z","end":"2026-06-17T02:51:52.948546Z","steps":["trace[628157047] 'range keys from in-memory index tree' (duration: 258.386804ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:51:52.948814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.341609ms","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-06-17T02:51:52.948963Z","caller":"traceutil/trace.go:171","msg":"trace[773599255] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1196; }","duration":"306.543184ms","start":"2026-06-17T02:51:52.642389Z","end":"2026-06-17T02:51:52.948932Z","steps":["trace[773599255] 'range keys from in-memory index tree' (duration: 306.075103ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:51:52.949026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:51:52.642373Z","time spent":"306.640486ms","remote":"127.0.0.1:48816","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":"info","ts":"2026-06-17T02:51:53.000764Z","caller":"traceutil/trace.go:171","msg":"trace[1057081262] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"142.337122ms","start":"2026-06-17T02:51:52.858404Z","end":"2026-06-17T02:51:53.000741Z","steps":["trace[1057081262] 'process raft request' (duration: 142.189059ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T02:51:53.524509Z","caller":"traceutil/trace.go:171","msg":"trace[877584482] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"498.698703ms","start":"2026-06-17T02:51:53.025779Z","end":"2026-06-17T02:51:53.524478Z","steps":["trace[877584482] 'process raft request' (duration: 498.53588ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:51:53.524684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:51:53.025752Z","time spent":"498.851477ms","remote":"127.0.0.1:48624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-17T02:51:53.551311Z","caller":"traceutil/trace.go:171","msg":"trace[1808328979] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"510.956488ms","start":"2026-06-17T02:51:53.040315Z","end":"2026-06-17T02:51:53.551271Z","steps":["trace[1808328979] 'process raft request' (duration: 510.639851ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T02:51:53.551459Z","caller":"traceutil/trace.go:171","msg":"trace[555495828] linearizableReadLoop","detail":"{readStateIndex:1249; appliedIndex:1247; }","duration":"107.143412ms","start":"2026-06-17T02:51:53.444249Z","end":"2026-06-17T02:51:53.551393Z","steps":["trace[555495828] 'read index received' (duration: 80.24932ms)","trace[555495828] 'applied index is now lower than readState.Index' (duration: 26.891262ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T02:51:53.551532Z","caller":"traceutil/trace.go:171","msg":"trace[1736850589] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"121.74296ms","start":"2026-06-17T02:51:53.42976Z","end":"2026-06-17T02:51:53.551503Z","steps":["trace[1736850589] 'process raft request' (duration: 121.456693ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:51:53.551562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:51:53.040288Z","time spent":"511.137492ms","remote":"127.0.0.1:48816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T02:51:53.551682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.428489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.38\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-17T02:51:53.551731Z","caller":"traceutil/trace.go:171","msg":"trace[1525948896] range","detail":"{range_begin:/registry/masterleases/199.204.45.38; range_end:; response_count:1; response_revision:1203; }","duration":"107.522961ms","start":"2026-06-17T02:51:53.444195Z","end":"2026-06-17T02:51:53.551718Z","steps":["trace[1525948896] 'agreement among raft nodes before linearized reading' (duration: 107.341296ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:51:54.01245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.437398ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-17T02:51:54.012642Z","caller":"traceutil/trace.go:171","msg":"trace[1302138256] linearizableReadLoop","detail":"{readStateIndex:1251; appliedIndex:1250; }","duration":"211.305259ms","start":"2026-06-17T02:51:53.80131Z","end":"2026-06-17T02:51:54.012615Z","steps":["trace[1302138256] 'read index received' (duration: 44.841µs)","trace[1302138256] 'applied index is now lower than readState.Index' (duration: 211.258338ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T02:51:54.012654Z","caller":"traceutil/trace.go:171","msg":"trace[1155635958] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"447.701891ms","start":"2026-06-17T02:51:53.564918Z","end":"2026-06-17T02:51:54.01262Z","steps":["trace[1155635958] 'process raft request' (duration: 80.969687ms)","trace[1155635958] 'compare' (duration: 366.208652ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T02:51:54.012824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:51:53.564883Z","time spent":"447.840663ms","remote":"127.0.0.1:48572","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-06-17T02:51:54.013091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.781179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T02:51:54.013136Z","caller":"traceutil/trace.go:171","msg":"trace[1453211908] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1204; }","duration":"211.83042ms","start":"2026-06-17T02:51:53.801293Z","end":"2026-06-17T02:51:54.013123Z","steps":["trace[1453211908] 'agreement among raft nodes before linearized reading' (duration: 211.464342ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:51:54.013163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.134006ms","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-06-17T02:51:54.013191Z","caller":"traceutil/trace.go:171","msg":"trace[1719177876] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1204; }","duration":"137.204707ms","start":"2026-06-17T02:51:53.87598Z","end":"2026-06-17T02:51:54.013185Z","steps":["trace[1719177876] 'agreement among raft nodes before linearized reading' (duration: 137.085765ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:51:54.013325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.439329ms","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-06-17T02:51:54.013389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.47789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-06-17T02:51:54.013409Z","caller":"traceutil/trace.go:171","msg":"trace[1245467521] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1204; }","duration":"136.557662ms","start":"2026-06-17T02:51:53.876847Z","end":"2026-06-17T02:51:54.013404Z","steps":["trace[1245467521] 'agreement among raft nodes before linearized reading' (duration: 136.506481ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T02:51:54.013442Z","caller":"traceutil/trace.go:171","msg":"trace[1464516293] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1204; }","duration":"136.573132ms","start":"2026-06-17T02:51:53.876829Z","end":"2026-06-17T02:51:54.013403Z","steps":["trace[1464516293] 'agreement among raft nodes before linearized reading' (duration: 136.299786ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:51:54.013477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.754846ms","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-06-17T02:51:54.013589Z","caller":"traceutil/trace.go:171","msg":"trace[1968473678] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1204; }","duration":"136.90399ms","start":"2026-06-17T02:51:53.876658Z","end":"2026-06-17T02:51:54.013562Z","steps":["trace[1968473678] 'agreement among raft nodes before linearized reading' (duration: 136.525421ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T02:51:54.142538Z","caller":"traceutil/trace.go:171","msg":"trace[786120958] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"123.302785ms","start":"2026-06-17T02:51:54.019202Z","end":"2026-06-17T02:51:54.142504Z","steps":["trace[786120958] 'process raft request' (duration: 123.181733ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T02:51:54.142558Z","caller":"traceutil/trace.go:171","msg":"trace[710734464] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"123.375547ms","start":"2026-06-17T02:51:54.019151Z","end":"2026-06-17T02:51:54.142526Z","steps":["trace[710734464] 'process raft request' (duration: 118.200501ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T02:51:54.142772Z","caller":"traceutil/trace.go:171","msg":"trace[39784079] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"120.369269ms","start":"2026-06-17T02:51:54.022375Z","end":"2026-06-17T02:51:54.142745Z","steps":["trace[39784079] 'process raft request' (duration: 120.065842ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T02:51:55.240048Z","caller":"traceutil/trace.go:171","msg":"trace[984070050] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"210.377328ms","start":"2026-06-17T02:51:55.029642Z","end":"2026-06-17T02:51:55.24002Z","steps":["trace[984070050] 'process raft request' (duration: 210.213705ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T02:51:56.260954Z","caller":"traceutil/trace.go:171","msg":"trace[184545772] linearizableReadLoop","detail":"{readStateIndex:1259; appliedIndex:1258; }","duration":"113.622429ms","start":"2026-06-17T02:51:56.147301Z","end":"2026-06-17T02:51:56.260924Z","steps":["trace[184545772] 'read index received' (duration: 113.279641ms)","trace[184545772] 'applied index is now lower than readState.Index' (duration: 341.318µs)"],"step_count":2} {"level":"info","ts":"2026-06-17T02:51:56.261053Z","caller":"traceutil/trace.go:171","msg":"trace[1898492068] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"223.476912ms","start":"2026-06-17T02:51:56.037536Z","end":"2026-06-17T02:51:56.261013Z","steps":["trace[1898492068] 'process raft request' (duration: 223.137984ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:51:56.261266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.881397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-17T02:51:56.261256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.926385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-06-17T02:51:56.261306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.552107ms","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-06-17T02:51:56.261337Z","caller":"traceutil/trace.go:171","msg":"trace[2020251509] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1212; }","duration":"110.973498ms","start":"2026-06-17T02:51:56.150343Z","end":"2026-06-17T02:51:56.261317Z","steps":["trace[2020251509] 'agreement among raft nodes before linearized reading' (duration: 110.835325ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T02:51:56.261338Z","caller":"traceutil/trace.go:171","msg":"trace[1431528478] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1212; }","duration":"114.099929ms","start":"2026-06-17T02:51:56.147225Z","end":"2026-06-17T02:51:56.261325Z","steps":["trace[1431528478] 'agreement among raft nodes before linearized reading' (duration: 113.830884ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T02:51:56.261354Z","caller":"traceutil/trace.go:171","msg":"trace[972323587] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1212; }","duration":"113.67942ms","start":"2026-06-17T02:51:56.147659Z","end":"2026-06-17T02:51:56.261339Z","steps":["trace[972323587] 'agreement among raft nodes before linearized reading' (duration: 113.457955ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T02:52:00.425208Z","caller":"traceutil/trace.go:171","msg":"trace[1237468558] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"134.466005ms","start":"2026-06-17T02:52:00.290686Z","end":"2026-06-17T02:52:00.425152Z","steps":["trace[1237468558] 'process raft request' (duration: 134.399824ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T02:52:00.425516Z","caller":"traceutil/trace.go:171","msg":"trace[1937774507] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"135.773844ms","start":"2026-06-17T02:52:00.289686Z","end":"2026-06-17T02:52:00.42546Z","steps":["trace[1937774507] 'process raft request' (duration: 135.170731ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T02:52:04.702161Z","caller":"traceutil/trace.go:171","msg":"trace[1821593257] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"410.362603ms","start":"2026-06-17T02:52:04.291731Z","end":"2026-06-17T02:52:04.702093Z","steps":["trace[1821593257] 'process raft request' (duration: 410.172528ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:52:04.702386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:52:04.291694Z","time spent":"410.565627ms","remote":"127.0.0.1:48816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T02:52:05.664443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.184024ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-17T02:52:05.664804Z","caller":"traceutil/trace.go:171","msg":"trace[1987641603] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"1.371764543s","start":"2026-06-17T02:52:04.292994Z","end":"2026-06-17T02:52:05.664758Z","steps":["trace[1987641603] 'process raft request' (duration: 1.173172609s)","trace[1987641603] 'compare' (duration: 197.617091ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T02:52:05.664993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:52:04.292955Z","time spent":"1.371937096s","remote":"127.0.0.1:48816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T02:52:05.664795Z","caller":"traceutil/trace.go:171","msg":"trace[579577968] linearizableReadLoop","detail":"{readStateIndex:1325; appliedIndex:1323; }","duration":"1.361096573s","start":"2026-06-17T02:52:04.303658Z","end":"2026-06-17T02:52:05.664754Z","steps":["trace[579577968] 'read index received' (duration: 398.368743ms)","trace[579577968] 'applied index is now lower than readState.Index' (duration: 962.7257ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T02:52:05.665162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.361471501s","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-06-17T02:52:05.665222Z","caller":"traceutil/trace.go:171","msg":"trace[1008440468] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1277; }","duration":"1.361593804s","start":"2026-06-17T02:52:04.303611Z","end":"2026-06-17T02:52:05.665205Z","steps":["trace[1008440468] 'agreement among raft nodes before linearized reading' (duration: 1.361250496s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:52:05.665325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:52:04.303598Z","time spent":"1.361712246s","remote":"127.0.0.1:48816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-17T02:52:05.665335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.32972952s","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-06-17T02:52:05.665415Z","caller":"traceutil/trace.go:171","msg":"trace[187502796] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1277; }","duration":"1.329853162s","start":"2026-06-17T02:52:04.335538Z","end":"2026-06-17T02:52:05.665391Z","steps":["trace[187502796] 'agreement among raft nodes before linearized reading' (duration: 1.329627197s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:52:05.665463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.609195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-17T02:52:05.665465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.248459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-06-17T02:52:05.665483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:52:04.335519Z","time spent":"1.329955265s","remote":"127.0.0.1:48816","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-06-17T02:52:05.665492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.225162185s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-17T02:52:05.665508Z","caller":"traceutil/trace.go:171","msg":"trace[1910649654] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1277; }","duration":"380.706047ms","start":"2026-06-17T02:52:05.28479Z","end":"2026-06-17T02:52:05.665496Z","steps":["trace[1910649654] 'agreement among raft nodes before linearized reading' (duration: 380.504063ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T02:52:05.665519Z","caller":"traceutil/trace.go:171","msg":"trace[2070686297] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1277; }","duration":"253.353591ms","start":"2026-06-17T02:52:05.412152Z","end":"2026-06-17T02:52:05.665505Z","steps":["trace[2070686297] 'agreement among raft nodes before linearized reading' (duration: 253.187298ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T02:52:05.665553Z","caller":"traceutil/trace.go:171","msg":"trace[21212520] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1277; }","duration":"1.225251787s","start":"2026-06-17T02:52:04.440287Z","end":"2026-06-17T02:52:05.665539Z","steps":["trace[21212520] 'agreement among raft nodes before linearized reading' (duration: 1.225062103s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:52:05.66556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:52:05.284761Z","time spent":"380.790109ms","remote":"127.0.0.1:48816","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-06-17T02:52:05.665591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:52:04.440278Z","time spent":"1.225304028s","remote":"127.0.0.1:48816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-17T02:52:05.665631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.226228849s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-17T02:52:05.665713Z","caller":"traceutil/trace.go:171","msg":"trace[2071302711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1277; }","duration":"1.226328791s","start":"2026-06-17T02:52:04.439333Z","end":"2026-06-17T02:52:05.665662Z","steps":["trace[2071302711] 'agreement among raft nodes before linearized reading' (duration: 1.226147357s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:52:05.665747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T02:52:04.439307Z","time spent":"1.226431052s","remote":"127.0.0.1:48816","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":"info","ts":"2026-06-17T02:54:46.82226Z","caller":"traceutil/trace.go:171","msg":"trace[573108306] transaction","detail":"{read_only:false; response_revision:2015; number_of_response:1; }","duration":"177.129282ms","start":"2026-06-17T02:54:46.644963Z","end":"2026-06-17T02:54:46.822092Z","steps":["trace[573108306] 'process raft request' (duration: 176.867545ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T02:54:46.822459Z","caller":"traceutil/trace.go:171","msg":"trace[1713062129] transaction","detail":"{read_only:false; response_revision:2016; number_of_response:1; }","duration":"177.196143ms","start":"2026-06-17T02:54:46.645238Z","end":"2026-06-17T02:54:46.822435Z","steps":["trace[1713062129] 'process raft request' (duration: 176.804204ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T02:54:46.822507Z","caller":"traceutil/trace.go:171","msg":"trace[1978677329] linearizableReadLoop","detail":"{readStateIndex:2096; appliedIndex:2095; }","duration":"176.952818ms","start":"2026-06-17T02:54:46.645523Z","end":"2026-06-17T02:54:46.822476Z","steps":["trace[1978677329] 'read index received' (duration: 424.371µs)","trace[1978677329] 'applied index is now lower than readState.Index' (duration: 176.527127ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T02:54:46.822791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.246174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-06-17T02:54:46.822672Z","caller":"traceutil/trace.go:171","msg":"trace[321118490] transaction","detail":"{read_only:false; response_revision:2017; number_of_response:1; }","duration":"177.164462ms","start":"2026-06-17T02:54:46.645415Z","end":"2026-06-17T02:54:46.822579Z","steps":["trace[321118490] 'process raft request' (duration: 176.926497ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T02:54:46.822939Z","caller":"traceutil/trace.go:171","msg":"trace[1362116647] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2017; }","duration":"177.420488ms","start":"2026-06-17T02:54:46.645498Z","end":"2026-06-17T02:54:46.822918Z","steps":["trace[1362116647] 'agreement among raft nodes before linearized reading' (duration: 177.113871ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T02:54:46.823516Z","caller":"traceutil/trace.go:171","msg":"trace[1559769839] transaction","detail":"{read_only:false; response_revision:2018; number_of_response:1; }","duration":"177.13497ms","start":"2026-06-17T02:54:46.646358Z","end":"2026-06-17T02:54:46.823493Z","steps":["trace[1559769839] 'process raft request' (duration: 176.988287ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T02:54:46.823669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.361995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-17T02:54:46.823815Z","caller":"traceutil/trace.go:171","msg":"trace[1977382035] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2019; }","duration":"177.505738ms","start":"2026-06-17T02:54:46.646258Z","end":"2026-06-17T02:54:46.823764Z","steps":["trace[1977382035] 'agreement among raft nodes before linearized reading' (duration: 177.228732ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T02:54:46.823998Z","caller":"traceutil/trace.go:171","msg":"trace[1410527825] transaction","detail":"{read_only:false; response_revision:2019; number_of_response:1; }","duration":"176.491356ms","start":"2026-06-17T02:54:46.647496Z","end":"2026-06-17T02:54:46.823987Z","steps":["trace[1410527825] 'process raft request' (duration: 175.933294ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T02:54:46.938332Z","caller":"traceutil/trace.go:171","msg":"trace[1925076710] transaction","detail":"{read_only:false; response_revision:2021; number_of_response:1; }","duration":"109.830845ms","start":"2026-06-17T02:54:46.828479Z","end":"2026-06-17T02:54:46.938309Z","steps":["trace[1925076710] 'process raft request' (duration: 107.698658ms)"],"step_count":1}