{"level":"warn","ts":"2026-06-13T01:07:13.881133Z","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-13T01:07:13.881238Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.169: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.169:2380","--initial-cluster=instance=https://199.204.45.169:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.169:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.169: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-13T01:07:13.881314Z","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-13T01:07:13.881326Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-06-13T01:07:13.881345Z","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-13T01:07:13.882074Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"]} {"level":"info","ts":"2026-06-13T01:07:13.882163Z","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.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.169: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-13T01:07:13.885141Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.791222ms"} {"level":"info","ts":"2026-06-13T01:07:13.890614Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1c917701ce165bac","cluster-id":"bfb5247022b87cb1"} {"level":"info","ts":"2026-06-13T01:07:13.890717Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=()"} {"level":"info","ts":"2026-06-13T01:07:13.890761Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 0"} {"level":"info","ts":"2026-06-13T01:07:13.890776Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1c917701ce165bac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-13T01:07:13.890793Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 1"} {"level":"info","ts":"2026-06-13T01:07:13.890853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"warn","ts":"2026-06-13T01:07:13.895943Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-13T01:07:13.897283Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-13T01:07:13.898583Z","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-13T01:07:13.900734Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1c917701ce165bac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-13T01:07:13.900941Z","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-13T01:07:13.901004Z","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-13T01:07:13.901762Z","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-13T01:07:13.901047Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1c917701ce165bac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-13T01:07:13.902403Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"info","ts":"2026-06-13T01:07:13.902788Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","added-peer-id":"1c917701ce165bac","added-peer-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-06-13T01:07:13.905478Z","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-13T01:07:13.910167Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-06-13T01:07:13.91025Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-06-13T01:07:13.910533Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1c917701ce165bac","initial-advertise-peer-urls":["https://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-13T01:07:13.910684Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-13T01:07:13.991607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac is starting a new election at term 1"} {"level":"info","ts":"2026-06-13T01:07:13.991668Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became pre-candidate at term 1"} {"level":"info","ts":"2026-06-13T01:07:13.991694Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgPreVoteResp from 1c917701ce165bac at term 1"} {"level":"info","ts":"2026-06-13T01:07:13.991728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became candidate at term 2"} {"level":"info","ts":"2026-06-13T01:07:13.991747Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgVoteResp from 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-06-13T01:07:13.991765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became leader at term 2"} {"level":"info","ts":"2026-06-13T01:07:13.991786Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1c917701ce165bac elected leader 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-06-13T01:07:13.992917Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-13T01:07:13.99415Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1c917701ce165bac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.169:2379]}","request-path":"/0/members/1c917701ce165bac/attributes","cluster-id":"bfb5247022b87cb1","publish-timeout":"7s"} {"level":"info","ts":"2026-06-13T01:07:13.994215Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-13T01:07:13.994313Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-13T01:07:13.994933Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","cluster-version":"3.5"} {"level":"info","ts":"2026-06-13T01:07:13.995182Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-13T01:07:13.995304Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-13T01:07:13.999376Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.169:2379"} {"level":"info","ts":"2026-06-13T01:07:13.999983Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-13T01:07:14.001155Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-13T01:07:14.0012Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-06-13T01:07:34.072058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.21732ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-13T01:07:34.072292Z","caller":"traceutil/trace.go:171","msg":"trace[268784609] linearizableReadLoop","detail":"{readStateIndex:392; appliedIndex:391; }","duration":"349.943691ms","start":"2026-06-13T01:07:33.722281Z","end":"2026-06-13T01:07:34.072224Z","steps":["trace[268784609] 'read index received' (duration: 59.701µs)","trace[268784609] 'applied index is now lower than readState.Index' (duration: 349.88209ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T01:07:34.072492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.160507ms","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-13T01:07:34.0726Z","caller":"traceutil/trace.go:171","msg":"trace[2048811294] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:377; }","duration":"350.347112ms","start":"2026-06-13T01:07:33.722226Z","end":"2026-06-13T01:07:34.072573Z","steps":["trace[2048811294] 'agreement among raft nodes before linearized reading' (duration: 350.124206ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:07:34.072656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:07:33.722203Z","time spent":"350.441294ms","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-13T01:07:34.598655Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605829294554441077,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-13T01:07:34.757077Z","caller":"traceutil/trace.go:171","msg":"trace[1866838404] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"679.052741ms","start":"2026-06-13T01:07:34.07794Z","end":"2026-06-13T01:07:34.756992Z","steps":["trace[1866838404] 'process raft request' (duration: 678.830646ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:07:34.757847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:07:34.077928Z","time spent":"679.246597ms","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T01:07:35.357825Z","caller":"traceutil/trace.go:171","msg":"trace[1949493646] linearizableReadLoop","detail":"{readStateIndex:394; appliedIndex:392; }","duration":"1.25968201s","start":"2026-06-13T01:07:34.098117Z","end":"2026-06-13T01:07:35.357799Z","steps":["trace[1949493646] 'read index received' (duration: 658.715333ms)","trace[1949493646] 'applied index is now lower than readState.Index' (duration: 600.965467ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T01:07:35.357955Z","caller":"traceutil/trace.go:171","msg":"trace[980066005] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"1.279827134s","start":"2026-06-13T01:07:34.0781Z","end":"2026-06-13T01:07:35.357927Z","steps":["trace[980066005] 'process raft request' (duration: 1.274984967s)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:07:35.358015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.259887456s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-06-13T01:07:35.358053Z","caller":"traceutil/trace.go:171","msg":"trace[1611124449] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:379; }","duration":"1.259985588s","start":"2026-06-13T01:07:34.098055Z","end":"2026-06-13T01:07:35.358041Z","steps":["trace[1611124449] 'agreement among raft nodes before linearized reading' (duration: 1.259874316s)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:07:35.35812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:07:34.078095Z","time spent":"1.279922526s","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T01:07:35.358137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:07:34.098034Z","time spent":"1.260093541s","remote":"127.0.0.1:54860","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-13T01:07:35.358269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.014608493s","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-13T01:07:35.358293Z","caller":"traceutil/trace.go:171","msg":"trace[273848891] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:379; }","duration":"1.014687275s","start":"2026-06-13T01:07:34.343598Z","end":"2026-06-13T01:07:35.358285Z","steps":["trace[273848891] 'agreement among raft nodes before linearized reading' (duration: 1.014643384s)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:07:35.35832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:07:34.343571Z","time spent":"1.014740846s","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-13T01:07:39.435742Z","caller":"traceutil/trace.go:171","msg":"trace[833138896] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"150.35733ms","start":"2026-06-13T01:07:39.285332Z","end":"2026-06-13T01:07:39.435689Z","steps":["trace[833138896] 'process raft request' (duration: 150.029312ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:07:39.69187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.026656ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T01:07:39.692019Z","caller":"traceutil/trace.go:171","msg":"trace[419974419] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:420; }","duration":"141.206761ms","start":"2026-06-13T01:07:39.550788Z","end":"2026-06-13T01:07:39.691994Z","steps":["trace[419974419] 'range keys from in-memory index tree' (duration: 140.996696ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:07:39.703798Z","caller":"traceutil/trace.go:171","msg":"trace[543976730] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"258.439588ms","start":"2026-06-13T01:07:39.445326Z","end":"2026-06-13T01:07:39.703766Z","steps":["trace[543976730] 'process raft request' (duration: 258.350216ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:07:39.704306Z","caller":"traceutil/trace.go:171","msg":"trace[620369522] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"261.054357ms","start":"2026-06-13T01:07:39.443233Z","end":"2026-06-13T01:07:39.704287Z","steps":["trace[620369522] 'process raft request' (duration: 260.238446ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:07:45.841248Z","caller":"traceutil/trace.go:171","msg":"trace[7700980] linearizableReadLoop","detail":"{readStateIndex:450; appliedIndex:449; }","duration":"103.874899ms","start":"2026-06-13T01:07:45.737344Z","end":"2026-06-13T01:07:45.841219Z","steps":["trace[7700980] 'read index received' (duration: 103.719865ms)","trace[7700980] 'applied index is now lower than readState.Index' (duration: 153.974µs)"],"step_count":2} {"level":"info","ts":"2026-06-13T01:07:45.841327Z","caller":"traceutil/trace.go:171","msg":"trace[235053390] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"107.36393ms","start":"2026-06-13T01:07:45.733936Z","end":"2026-06-13T01:07:45.8413Z","steps":["trace[235053390] 'process raft request' (duration: 107.147655ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:07:45.841504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.142705ms","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-13T01:07:45.841577Z","caller":"traceutil/trace.go:171","msg":"trace[659900538] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:433; }","duration":"104.281339ms","start":"2026-06-13T01:07:45.737286Z","end":"2026-06-13T01:07:45.841567Z","steps":["trace[659900538] 'agreement among raft nodes before linearized reading' (duration: 104.085844ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:07:49.982666Z","caller":"traceutil/trace.go:171","msg":"trace[2002567848] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"120.85742ms","start":"2026-06-13T01:07:49.861778Z","end":"2026-06-13T01:07:49.982635Z","steps":["trace[2002567848] 'process raft request' (duration: 120.640055ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:07:50.060437Z","caller":"traceutil/trace.go:171","msg":"trace[315315603] linearizableReadLoop","detail":"{readStateIndex:463; appliedIndex:461; }","duration":"133.284415ms","start":"2026-06-13T01:07:49.927121Z","end":"2026-06-13T01:07:50.060406Z","steps":["trace[315315603] 'read index received' (duration: 55.276872ms)","trace[315315603] 'applied index is now lower than readState.Index' (duration: 78.006293ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T01:07:50.060616Z","caller":"traceutil/trace.go:171","msg":"trace[180504672] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"195.712003ms","start":"2026-06-13T01:07:49.864884Z","end":"2026-06-13T01:07:50.060596Z","steps":["trace[180504672] 'process raft request' (duration: 146.583182ms)","trace[180504672] 'compare' (duration: 48.808992ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T01:07:50.060869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.719017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12673"} {"level":"info","ts":"2026-06-13T01:07:50.060974Z","caller":"traceutil/trace.go:171","msg":"trace[1394234553] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:445; }","duration":"133.893591ms","start":"2026-06-13T01:07:49.927053Z","end":"2026-06-13T01:07:50.060947Z","steps":["trace[1394234553] 'agreement among raft nodes before linearized reading' (duration: 133.692456ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:07:59.179971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.213498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T01:07:59.180277Z","caller":"traceutil/trace.go:171","msg":"trace[639544273] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:581; }","duration":"175.522486ms","start":"2026-06-13T01:07:59.004662Z","end":"2026-06-13T01:07:59.180184Z","steps":["trace[639544273] 'range keys from in-memory index tree' (duration: 175.133946ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:07:59.488174Z","caller":"traceutil/trace.go:171","msg":"trace[924698439] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"153.235956ms","start":"2026-06-13T01:07:59.334912Z","end":"2026-06-13T01:07:59.488147Z","steps":["trace[924698439] 'process raft request' (duration: 152.526507ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:07:59.691592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.310368ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T01:07:59.69196Z","caller":"traceutil/trace.go:171","msg":"trace[1279960237] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:583; }","duration":"140.711899ms","start":"2026-06-13T01:07:59.551227Z","end":"2026-06-13T01:07:59.691939Z","steps":["trace[1279960237] 'range keys from in-memory index tree' (duration: 140.287488ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:07:59.692206Z","caller":"traceutil/trace.go:171","msg":"trace[377503214] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"187.066497ms","start":"2026-06-13T01:07:59.505107Z","end":"2026-06-13T01:07:59.692174Z","steps":["trace[377503214] 'process raft request' (duration: 180.259359ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:08:00.506016Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605829294554441545,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-13T01:08:00.845114Z","caller":"traceutil/trace.go:171","msg":"trace[316782335] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"908.27837ms","start":"2026-06-13T01:07:59.936785Z","end":"2026-06-13T01:08:00.845063Z","steps":["trace[316782335] 'process raft request' (duration: 901.255946ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:08:00.845184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.454555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T01:08:00.845125Z","caller":"traceutil/trace.go:171","msg":"trace[262377163] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"839.287812ms","start":"2026-06-13T01:08:00.0057Z","end":"2026-06-13T01:08:00.844988Z","steps":["trace[262377163] 'read index received' (duration: 830.166214ms)","trace[262377163] 'applied index is now lower than readState.Index' (duration: 9.120168ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T01:08:00.845254Z","caller":"traceutil/trace.go:171","msg":"trace[1006493812] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:587; }","duration":"839.59939ms","start":"2026-06-13T01:08:00.005633Z","end":"2026-06-13T01:08:00.845233Z","steps":["trace[1006493812] 'agreement among raft nodes before linearized reading' (duration: 839.434016ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:08:00.845324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:07:59.93674Z","time spent":"908.495356ms","remote":"127.0.0.1:54670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036161,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T01:08:00.845557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.012166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-06-13T01:08:00.845576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.324012ms","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-13T01:08:00.845619Z","caller":"traceutil/trace.go:171","msg":"trace[2053206498] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:587; }","duration":"732.403604ms","start":"2026-06-13T01:08:00.113204Z","end":"2026-06-13T01:08:00.845608Z","steps":["trace[2053206498] 'agreement among raft nodes before linearized reading' (duration: 732.253759ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:08:00.845655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:08:00.113183Z","time spent":"732.462707ms","remote":"127.0.0.1:54860","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-13T01:08:00.845461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:08:00.005611Z","time spent":"839.824145ms","remote":"127.0.0.1:54588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-13T01:08:00.845671Z","caller":"traceutil/trace.go:171","msg":"trace[796113635] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:587; }","duration":"767.244873ms","start":"2026-06-13T01:08:00.078404Z","end":"2026-06-13T01:08:00.845649Z","steps":["trace[796113635] 'agreement among raft nodes before linearized reading' (duration: 766.991806ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:08:00.845795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:08:00.078376Z","time spent":"767.367475ms","remote":"127.0.0.1:54860","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":"info","ts":"2026-06-13T01:08:02.054478Z","caller":"traceutil/trace.go:171","msg":"trace[2087601241] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"104.32962ms","start":"2026-06-13T01:08:01.950023Z","end":"2026-06-13T01:08:02.054353Z","steps":["trace[2087601241] 'process raft request' (duration: 78.334763ms)","trace[2087601241] 'compare' (duration: 25.803593ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T01:08:03.969694Z","caller":"traceutil/trace.go:171","msg":"trace[1703802012] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"279.318151ms","start":"2026-06-13T01:08:03.690337Z","end":"2026-06-13T01:08:03.969655Z","steps":["trace[1703802012] 'process raft request' (duration: 184.628663ms)","trace[1703802012] 'compare' (duration: 94.505664ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T01:08:03.972131Z","caller":"traceutil/trace.go:171","msg":"trace[957781391] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:667; }","duration":"144.442835ms","start":"2026-06-13T01:08:03.827458Z","end":"2026-06-13T01:08:03.971901Z","steps":["trace[957781391] 'read index received' (duration: 47.605411ms)","trace[957781391] 'applied index is now lower than readState.Index' (duration: 96.836654ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T01:08:03.972382Z","caller":"traceutil/trace.go:171","msg":"trace[1758625689] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"275.284966ms","start":"2026-06-13T01:08:03.697083Z","end":"2026-06-13T01:08:03.972367Z","steps":["trace[1758625689] 'process raft request' (duration: 274.663279ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:08:03.972496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.009321ms","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-13T01:08:03.972594Z","caller":"traceutil/trace.go:171","msg":"trace[1922502982] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:644; }","duration":"145.154065ms","start":"2026-06-13T01:08:03.827413Z","end":"2026-06-13T01:08:03.972567Z","steps":["trace[1922502982] 'agreement among raft nodes before linearized reading' (duration: 144.849697ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:08:04.26412Z","caller":"traceutil/trace.go:171","msg":"trace[440415479] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"107.798061ms","start":"2026-06-13T01:08:04.156284Z","end":"2026-06-13T01:08:04.264082Z","steps":["trace[440415479] 'process raft request' (duration: 62.418797ms)","trace[440415479] 'compare' (duration: 45.067135ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T01:08:11.621917Z","caller":"traceutil/trace.go:171","msg":"trace[924169165] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"276.454998ms","start":"2026-06-13T01:08:11.345428Z","end":"2026-06-13T01:08:11.621883Z","steps":["trace[924169165] 'process raft request' (duration: 276.303854ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:08:16.043678Z","caller":"traceutil/trace.go:171","msg":"trace[1231593382] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"506.068654ms","start":"2026-06-13T01:08:15.53756Z","end":"2026-06-13T01:08:16.043629Z","steps":["trace[1231593382] 'process raft request' (duration: 505.755946ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:08:16.044002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:08:15.53754Z","time spent":"506.241739ms","remote":"127.0.0.1:54774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T01:08:20.249073Z","caller":"traceutil/trace.go:171","msg":"trace[70599980] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"154.781966ms","start":"2026-06-13T01:08:20.094248Z","end":"2026-06-13T01:08:20.24903Z","steps":["trace[70599980] 'process raft request' (duration: 86.514046ms)","trace[70599980] 'compare' (duration: 67.942372ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T01:08:20.249104Z","caller":"traceutil/trace.go:171","msg":"trace[150531558] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"104.053543ms","start":"2026-06-13T01:08:20.145016Z","end":"2026-06-13T01:08:20.249069Z","steps":["trace[150531558] 'process raft request' (duration: 103.972861ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:08:20.249299Z","caller":"traceutil/trace.go:171","msg":"trace[725981760] linearizableReadLoop","detail":"{readStateIndex:834; appliedIndex:833; }","duration":"148.535502ms","start":"2026-06-13T01:08:20.10075Z","end":"2026-06-13T01:08:20.249286Z","steps":["trace[725981760] 'read index received' (duration: 80.004736ms)","trace[725981760] 'applied index is now lower than readState.Index' (duration: 68.529446ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T01:08:20.249607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.825151ms","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-13T01:08:20.249646Z","caller":"traceutil/trace.go:171","msg":"trace[1852631293] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:805; }","duration":"148.920122ms","start":"2026-06-13T01:08:20.100713Z","end":"2026-06-13T01:08:20.249634Z","steps":["trace[1852631293] 'agreement among raft nodes before linearized reading' (duration: 148.678447ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:08:24.839705Z","caller":"traceutil/trace.go:171","msg":"trace[1446358412] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"174.577801ms","start":"2026-06-13T01:08:24.665085Z","end":"2026-06-13T01:08:24.839662Z","steps":["trace[1446358412] 'process raft request' (duration: 164.390036ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:08:24.839754Z","caller":"traceutil/trace.go:171","msg":"trace[1489282941] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"107.175864ms","start":"2026-06-13T01:08:24.73253Z","end":"2026-06-13T01:08:24.839705Z","steps":["trace[1489282941] 'process raft request' (duration: 107.071931ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:08:25.118888Z","caller":"traceutil/trace.go:171","msg":"trace[1772856314] linearizableReadLoop","detail":"{readStateIndex:888; appliedIndex:885; }","duration":"113.565541ms","start":"2026-06-13T01:08:25.005296Z","end":"2026-06-13T01:08:25.118862Z","steps":["trace[1772856314] 'read index received' (duration: 31.806539ms)","trace[1772856314] 'applied index is now lower than readState.Index' (duration: 81.757522ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T01:08:25.118955Z","caller":"traceutil/trace.go:171","msg":"trace[128705089] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"130.80198ms","start":"2026-06-13T01:08:24.988125Z","end":"2026-06-13T01:08:25.118927Z","steps":["trace[128705089] 'process raft request' (duration: 130.664067ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:08:25.119125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.805647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T01:08:25.119266Z","caller":"traceutil/trace.go:171","msg":"trace[1714820850] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:857; }","duration":"114.003283ms","start":"2026-06-13T01:08:25.005238Z","end":"2026-06-13T01:08:25.119242Z","steps":["trace[1714820850] 'agreement among raft nodes before linearized reading' (duration: 113.770506ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:08:25.119185Z","caller":"traceutil/trace.go:171","msg":"trace[1314101701] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"134.041525ms","start":"2026-06-13T01:08:24.984824Z","end":"2026-06-13T01:08:25.118865Z","steps":["trace[1314101701] 'process raft request' (duration: 100.361807ms)","trace[1314101701] 'compare' (duration: 33.447082ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T01:08:35.305895Z","caller":"traceutil/trace.go:171","msg":"trace[1397298061] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"114.241258ms","start":"2026-06-13T01:08:35.191624Z","end":"2026-06-13T01:08:35.305865Z","steps":["trace[1397298061] 'process raft request' (duration: 114.198417ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:08:35.306177Z","caller":"traceutil/trace.go:171","msg":"trace[1384549328] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"115.754998ms","start":"2026-06-13T01:08:35.190388Z","end":"2026-06-13T01:08:35.306143Z","steps":["trace[1384549328] 'process raft request' (duration: 115.394219ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:08:35.306188Z","caller":"traceutil/trace.go:171","msg":"trace[1052107709] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"115.817539ms","start":"2026-06-13T01:08:35.190279Z","end":"2026-06-13T01:08:35.306096Z","steps":["trace[1052107709] 'process raft request' (duration: 58.544795ms)","trace[1052107709] 'compare' (duration: 56.765281ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T01:08:45.600453Z","caller":"traceutil/trace.go:171","msg":"trace[1421883811] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"128.206637ms","start":"2026-06-13T01:08:45.472214Z","end":"2026-06-13T01:08:45.600421Z","steps":["trace[1421883811] 'process raft request' (duration: 126.529873ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:08:45.600608Z","caller":"traceutil/trace.go:171","msg":"trace[1495208370] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"128.376742ms","start":"2026-06-13T01:08:45.472205Z","end":"2026-06-13T01:08:45.600582Z","steps":["trace[1495208370] 'process raft request' (duration: 128.105134ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:08:51.830452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.926388ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-13T01:08:51.830604Z","caller":"traceutil/trace.go:171","msg":"trace[1420490384] linearizableReadLoop","detail":"{readStateIndex:1011; appliedIndex:1010; }","duration":"605.015258ms","start":"2026-06-13T01:08:51.225571Z","end":"2026-06-13T01:08:51.830586Z","steps":["trace[1420490384] 'read index received' (duration: 65.774161ms)","trace[1420490384] 'applied index is now lower than readState.Index' (duration: 539.239777ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T01:08:51.830923Z","caller":"traceutil/trace.go:171","msg":"trace[1265442829] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"683.712351ms","start":"2026-06-13T01:08:51.147197Z","end":"2026-06-13T01:08:51.83091Z","steps":["trace[1265442829] 'process raft request' (duration: 144.226728ms)","trace[1265442829] 'compare' (duration: 538.772254ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T01:08:51.830992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:08:51.147165Z","time spent":"683.791173ms","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T01:08:51.83107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.470019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-13T01:08:51.831141Z","caller":"traceutil/trace.go:171","msg":"trace[2117247797] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:975; }","duration":"605.640123ms","start":"2026-06-13T01:08:51.225486Z","end":"2026-06-13T01:08:51.831126Z","steps":["trace[2117247797] 'agreement among raft nodes before linearized reading' (duration: 605.469069ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:08:51.831175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:08:51.22546Z","time spent":"605.707985ms","remote":"127.0.0.1:54744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-06-13T01:08:51.831148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.552558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-13T01:08:51.831146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.249101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-06-13T01:08:51.831176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.608788ms","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-13T01:08:51.831236Z","caller":"traceutil/trace.go:171","msg":"trace[1372764449] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:975; }","duration":"167.701291ms","start":"2026-06-13T01:08:51.663511Z","end":"2026-06-13T01:08:51.831212Z","steps":["trace[1372764449] 'agreement among raft nodes before linearized reading' (duration: 167.552157ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:08:51.83124Z","caller":"traceutil/trace.go:171","msg":"trace[1010697990] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:975; }","duration":"183.387494ms","start":"2026-06-13T01:08:51.647836Z","end":"2026-06-13T01:08:51.831223Z","steps":["trace[1010697990] 'agreement among raft nodes before linearized reading' (duration: 183.081367ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:08:51.831269Z","caller":"traceutil/trace.go:171","msg":"trace[1462749007] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:975; }","duration":"508.716921ms","start":"2026-06-13T01:08:51.322529Z","end":"2026-06-13T01:08:51.831246Z","steps":["trace[1462749007] 'agreement among raft nodes before linearized reading' (duration: 508.393843ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:08:51.831323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:08:51.322438Z","time spent":"508.876305ms","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-13T01:08:51.831349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.859593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-13T01:08:51.831375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.870258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-06-13T01:08:51.831384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.384259ms","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-13T01:08:51.831404Z","caller":"traceutil/trace.go:171","msg":"trace[739533469] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:975; }","duration":"422.965655ms","start":"2026-06-13T01:08:51.408425Z","end":"2026-06-13T01:08:51.83139Z","steps":["trace[739533469] 'agreement among raft nodes before linearized reading' (duration: 422.846922ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:08:51.831415Z","caller":"traceutil/trace.go:171","msg":"trace[1987754819] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:975; }","duration":"211.961661ms","start":"2026-06-13T01:08:51.619441Z","end":"2026-06-13T01:08:51.831403Z","steps":["trace[1987754819] 'agreement among raft nodes before linearized reading' (duration: 211.849088ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:08:51.831424Z","caller":"traceutil/trace.go:171","msg":"trace[525119022] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:975; }","duration":"257.42363ms","start":"2026-06-13T01:08:51.573988Z","end":"2026-06-13T01:08:51.831412Z","steps":["trace[525119022] 'agreement among raft nodes before linearized reading' (duration: 257.269615ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:08:51.831442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:08:51.408405Z","time spent":"423.028207ms","remote":"127.0.0.1:54834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-06-13T01:08:52.171882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.690858ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-13T01:08:52.172036Z","caller":"traceutil/trace.go:171","msg":"trace[1340350025] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"335.583359ms","start":"2026-06-13T01:08:51.836425Z","end":"2026-06-13T01:08:52.172009Z","steps":["trace[1340350025] 'process raft request' (duration: 187.46602ms)","trace[1340350025] 'compare' (duration: 147.274327ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T01:08:52.172109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:08:51.836406Z","time spent":"335.671361ms","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T01:08:52.173373Z","caller":"traceutil/trace.go:171","msg":"trace[1431231233] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"335.21112ms","start":"2026-06-13T01:08:51.838143Z","end":"2026-06-13T01:08:52.173354Z","steps":["trace[1431231233] 'process raft request' (duration: 335.147499ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:08:52.17348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:08:51.838129Z","time spent":"335.306162ms","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T01:08:52.173622Z","caller":"traceutil/trace.go:171","msg":"trace[230800983] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"336.965305ms","start":"2026-06-13T01:08:51.836617Z","end":"2026-06-13T01:08:52.173582Z","steps":["trace[230800983] 'process raft request' (duration: 336.505234ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:08:52.173723Z","caller":"traceutil/trace.go:171","msg":"trace[302295791] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"337.093359ms","start":"2026-06-13T01:08:51.836615Z","end":"2026-06-13T01:08:52.173708Z","steps":["trace[302295791] 'process raft request' (duration: 336.630207ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:08:52.173791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:08:51.836586Z","time spent":"337.113629ms","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T01:08:52.173799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:08:51.836588Z","time spent":"337.17286ms","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T01:09:18.926153Z","caller":"traceutil/trace.go:171","msg":"trace[1222975385] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"238.738949ms","start":"2026-06-13T01:09:18.68737Z","end":"2026-06-13T01:09:18.926109Z","steps":["trace[1222975385] 'process raft request' (duration: 238.563995ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:09:19.343559Z","caller":"traceutil/trace.go:171","msg":"trace[479954303] linearizableReadLoop","detail":"{readStateIndex:1108; appliedIndex:1107; }","duration":"338.35483ms","start":"2026-06-13T01:09:19.005169Z","end":"2026-06-13T01:09:19.343524Z","steps":["trace[479954303] 'read index received' (duration: 338.303659ms)","trace[479954303] 'applied index is now lower than readState.Index' (duration: 49.111µs)"],"step_count":2} {"level":"info","ts":"2026-06-13T01:09:19.34364Z","caller":"traceutil/trace.go:171","msg":"trace[1785033201] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"654.843209ms","start":"2026-06-13T01:09:18.688752Z","end":"2026-06-13T01:09:19.343596Z","steps":["trace[1785033201] 'process raft request' (duration: 654.388457ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:09:19.343711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.523796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T01:09:19.343749Z","caller":"traceutil/trace.go:171","msg":"trace[351502813] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1066; }","duration":"338.638678ms","start":"2026-06-13T01:09:19.005097Z","end":"2026-06-13T01:09:19.343735Z","steps":["trace[351502813] 'agreement among raft nodes before linearized reading' (duration: 338.546076ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:09:19.343779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:09:18.688731Z","time spent":"654.967582ms","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T01:09:19.343803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:09:19.005072Z","time spent":"338.719661ms","remote":"127.0.0.1:54588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-13T01:09:19.645381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.676378ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-13T01:09:19.645492Z","caller":"traceutil/trace.go:171","msg":"trace[1835957249] linearizableReadLoop","detail":"{readStateIndex:1109; appliedIndex:1108; }","duration":"300.647711ms","start":"2026-06-13T01:09:19.344826Z","end":"2026-06-13T01:09:19.645473Z","steps":["trace[1835957249] 'read index received' (duration: 143.742566ms)","trace[1835957249] 'applied index is now lower than readState.Index' (duration: 156.904245ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T01:09:19.645654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.550554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T01:09:19.64569Z","caller":"traceutil/trace.go:171","msg":"trace[1592218798] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1067; }","duration":"161.595925ms","start":"2026-06-13T01:09:19.484083Z","end":"2026-06-13T01:09:19.645679Z","steps":["trace[1592218798] 'agreement among raft nodes before linearized reading' (duration: 161.518373ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:09:19.645653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.796714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T01:09:19.64601Z","caller":"traceutil/trace.go:171","msg":"trace[1169302483] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"422.631687ms","start":"2026-06-13T01:09:19.223358Z","end":"2026-06-13T01:09:19.64599Z","steps":["trace[1169302483] 'process raft request' (duration: 265.24567ms)","trace[1169302483] 'compare' (duration: 156.486524ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T01:09:19.646134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:09:19.223325Z","time spent":"422.72608ms","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T01:09:19.645742Z","caller":"traceutil/trace.go:171","msg":"trace[90522296] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1067; }","duration":"300.941918ms","start":"2026-06-13T01:09:19.344779Z","end":"2026-06-13T01:09:19.645721Z","steps":["trace[90522296] 'agreement among raft nodes before linearized reading' (duration: 300.803925ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:09:19.646659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:09:19.344764Z","time spent":"301.879722ms","remote":"127.0.0.1:54604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-13T01:09:21.043665Z","caller":"traceutil/trace.go:171","msg":"trace[551964847] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"709.995986ms","start":"2026-06-13T01:09:20.333635Z","end":"2026-06-13T01:09:21.043631Z","steps":["trace[551964847] 'process raft request' (duration: 709.810311ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:09:21.043912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:09:20.333609Z","time spent":"710.14081ms","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T01:09:21.125133Z","caller":"traceutil/trace.go:171","msg":"trace[221472064] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"791.395299ms","start":"2026-06-13T01:09:20.333703Z","end":"2026-06-13T01:09:21.125098Z","steps":["trace[221472064] 'process raft request' (duration: 734.945198ms)","trace[221472064] 'compare' (duration: 56.209905ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T01:09:21.125364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:09:20.333684Z","time spent":"791.551533ms","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T01:09:21.125379Z","caller":"traceutil/trace.go:171","msg":"trace[1470376724] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"789.049549ms","start":"2026-06-13T01:09:20.3363Z","end":"2026-06-13T01:09:21.12535Z","steps":["trace[1470376724] 'process raft request' (duration: 788.748111ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:09:21.12555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:09:20.33627Z","time spent":"789.174323ms","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T01:09:21.125591Z","caller":"traceutil/trace.go:171","msg":"trace[959281539] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"788.253238ms","start":"2026-06-13T01:09:20.337306Z","end":"2026-06-13T01:09:21.125559Z","steps":["trace[959281539] 'process raft request' (duration: 787.991981ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:09:21.125695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:09:20.337293Z","time spent":"788.362322ms","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T01:09:21.125748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.764955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T01:09:21.125815Z","caller":"traceutil/trace.go:171","msg":"trace[1822577714] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1071; }","duration":"120.902629ms","start":"2026-06-13T01:09:21.004904Z","end":"2026-06-13T01:09:21.125807Z","steps":["trace[1822577714] 'agreement among raft nodes before linearized reading' (duration: 120.789436ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:09:21.125667Z","caller":"traceutil/trace.go:171","msg":"trace[2146510319] linearizableReadLoop","detail":"{readStateIndex:1113; appliedIndex:1109; }","duration":"120.509868ms","start":"2026-06-13T01:09:21.004965Z","end":"2026-06-13T01:09:21.125475Z","steps":["trace[2146510319] 'read index received' (duration: 38.618763ms)","trace[2146510319] 'applied index is now lower than readState.Index' (duration: 81.890035ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T01:09:37.389066Z","caller":"traceutil/trace.go:171","msg":"trace[1952671187] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"119.149463ms","start":"2026-06-13T01:09:37.269873Z","end":"2026-06-13T01:09:37.389023Z","steps":["trace[1952671187] 'process raft request' (duration: 118.885977ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:09:53.784495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.897755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-06-13T01:09:53.784495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.597943ms","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-13T01:09:53.78458Z","caller":"traceutil/trace.go:171","msg":"trace[645907318] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1233; }","duration":"165.011799ms","start":"2026-06-13T01:09:53.619551Z","end":"2026-06-13T01:09:53.784563Z","steps":["trace[645907318] 'range keys from in-memory index tree' (duration: 164.788702ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:09:53.784606Z","caller":"traceutil/trace.go:171","msg":"trace[744570560] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1233; }","duration":"171.754477ms","start":"2026-06-13T01:09:53.612833Z","end":"2026-06-13T01:09:53.784588Z","steps":["trace[744570560] 'range keys from in-memory index tree' (duration: 171.428087ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:09:53.784787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.772402ms","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-13T01:09:53.78486Z","caller":"traceutil/trace.go:171","msg":"trace[177079839] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1233; }","duration":"168.880596ms","start":"2026-06-13T01:09:53.615961Z","end":"2026-06-13T01:09:53.784841Z","steps":["trace[177079839] 'range keys from in-memory index tree' (duration: 168.596004ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:09:53.78503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.111832ms","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-13T01:09:53.785084Z","caller":"traceutil/trace.go:171","msg":"trace[201138286] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1233; }","duration":"186.209406ms","start":"2026-06-13T01:09:53.598863Z","end":"2026-06-13T01:09:53.785072Z","steps":["trace[201138286] 'range keys from in-memory index tree' (duration: 185.982678ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:09:53.935279Z","caller":"traceutil/trace.go:171","msg":"trace[1663340031] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"146.705677ms","start":"2026-06-13T01:09:53.788548Z","end":"2026-06-13T01:09:53.935253Z","steps":["trace[1663340031] 'process raft request' (duration: 140.333801ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:09:53.939933Z","caller":"traceutil/trace.go:171","msg":"trace[1701091506] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"149.020992ms","start":"2026-06-13T01:09:53.790888Z","end":"2026-06-13T01:09:53.939909Z","steps":["trace[1701091506] 'process raft request' (duration: 148.855347ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:09:53.940097Z","caller":"traceutil/trace.go:171","msg":"trace[1399646186] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"149.126715ms","start":"2026-06-13T01:09:53.790959Z","end":"2026-06-13T01:09:53.940086Z","steps":["trace[1399646186] 'process raft request' (duration: 148.906739ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:09:53.940315Z","caller":"traceutil/trace.go:171","msg":"trace[1475028798] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"149.303332ms","start":"2026-06-13T01:09:53.790992Z","end":"2026-06-13T01:09:53.940295Z","steps":["trace[1475028798] 'process raft request' (duration: 149.047883ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:09:59.53922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.978257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T01:09:59.539323Z","caller":"traceutil/trace.go:171","msg":"trace[76924235] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1279; }","duration":"402.141622ms","start":"2026-06-13T01:09:59.137158Z","end":"2026-06-13T01:09:59.5393Z","steps":["trace[76924235] 'range keys from in-memory index tree' (duration: 401.860683ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:09:59.539366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:09:59.137135Z","time spent":"402.221964ms","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-13T01:09:59.5397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.387733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T01:09:59.539717Z","caller":"traceutil/trace.go:171","msg":"trace[1758096664] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1279; }","duration":"441.434374ms","start":"2026-06-13T01:09:59.098277Z","end":"2026-06-13T01:09:59.539712Z","steps":["trace[1758096664] 'range keys from in-memory index tree' (duration: 441.312331ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:09:59.539734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:09:59.098258Z","time spent":"441.472165ms","remote":"127.0.0.1:54588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-13T01:10:00.184502Z","caller":"traceutil/trace.go:171","msg":"trace[47571465] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"634.031716ms","start":"2026-06-13T01:09:59.550449Z","end":"2026-06-13T01:10:00.18448Z","steps":["trace[47571465] 'process raft request' (duration: 632.813136ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:10:00.184706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:09:59.550413Z","time spent":"634.211663ms","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-13T01:10:00.369722Z","caller":"traceutil/trace.go:171","msg":"trace[1257617108] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"810.177726ms","start":"2026-06-13T01:09:59.55951Z","end":"2026-06-13T01:10:00.369688Z","steps":["trace[1257617108] 'process raft request' (duration: 727.074367ms)","trace[1257617108] 'compare' (duration: 82.80824ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T01:10:00.369729Z","caller":"traceutil/trace.go:171","msg":"trace[918975327] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"560.363192ms","start":"2026-06-13T01:09:59.809333Z","end":"2026-06-13T01:10:00.369696Z","steps":["trace[918975327] 'process raft request' (duration: 560.254939ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:10:00.369917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:09:59.559483Z","time spent":"810.30995ms","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T01:10:00.369928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:09:59.809299Z","time spent":"560.524397ms","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T01:10:00.370093Z","caller":"traceutil/trace.go:171","msg":"trace[716908882] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"204.103885ms","start":"2026-06-13T01:10:00.165978Z","end":"2026-06-13T01:10:00.370082Z","steps":["trace[716908882] 'process raft request' (duration: 203.66005ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:10:00.370161Z","caller":"traceutil/trace.go:171","msg":"trace[63481534] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"167.886753ms","start":"2026-06-13T01:10:00.202252Z","end":"2026-06-13T01:10:00.370139Z","steps":["trace[63481534] 'process raft request' (duration: 167.78965ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:10:00.370165Z","caller":"traceutil/trace.go:171","msg":"trace[566548046] linearizableReadLoop","detail":"{readStateIndex:1334; appliedIndex:1332; }","duration":"682.998631ms","start":"2026-06-13T01:09:59.687135Z","end":"2026-06-13T01:10:00.370134Z","steps":["trace[566548046] 'read index received' (duration: 496.164366ms)","trace[566548046] 'applied index is now lower than readState.Index' (duration: 186.832065ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T01:10:00.37036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.221868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T01:10:00.370395Z","caller":"traceutil/trace.go:171","msg":"trace[560662403] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1285; }","duration":"683.306851ms","start":"2026-06-13T01:09:59.687079Z","end":"2026-06-13T01:10:00.370386Z","steps":["trace[560662403] 'agreement among raft nodes before linearized reading' (duration: 683.210538ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:10:00.370425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:09:59.687057Z","time spent":"683.363232ms","remote":"127.0.0.1:36056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-06-13T01:10:00.370447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.151058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-13T01:10:00.370473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.715019ms","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-13T01:10:00.370517Z","caller":"traceutil/trace.go:171","msg":"trace[1200901451] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1285; }","duration":"410.812773ms","start":"2026-06-13T01:09:59.959691Z","end":"2026-06-13T01:10:00.370504Z","steps":["trace[1200901451] 'agreement among raft nodes before linearized reading' (duration: 410.639548ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:10:00.370517Z","caller":"traceutil/trace.go:171","msg":"trace[1659171162] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1285; }","duration":"366.292973ms","start":"2026-06-13T01:10:00.004206Z","end":"2026-06-13T01:10:00.370499Z","steps":["trace[1659171162] 'agreement among raft nodes before linearized reading' (duration: 366.166089ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:10:00.370554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:09:59.959667Z","time spent":"410.877504ms","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-13T01:10:00.370562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:10:00.004166Z","time spent":"366.389115ms","remote":"127.0.0.1:54604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-13T01:10:00.370655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.641293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-06-13T01:10:00.37068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.070163ms","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-13T01:10:00.370702Z","caller":"traceutil/trace.go:171","msg":"trace[42356144] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1285; }","duration":"403.155996ms","start":"2026-06-13T01:09:59.967541Z","end":"2026-06-13T01:10:00.370697Z","steps":["trace[42356144] 'agreement among raft nodes before linearized reading' (duration: 403.057313ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:10:00.370704Z","caller":"traceutil/trace.go:171","msg":"trace[63709380] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1285; }","duration":"404.701766ms","start":"2026-06-13T01:09:59.965988Z","end":"2026-06-13T01:10:00.37069Z","steps":["trace[63709380] 'agreement among raft nodes before linearized reading' (duration: 404.512709ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:10:00.370719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:09:59.96751Z","time spent":"403.205407ms","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-13T01:10:00.370719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.959591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-06-13T01:10:00.370739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:09:59.965984Z","time spent":"404.747677ms","remote":"127.0.0.1:54860","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-13T01:10:00.370753Z","caller":"traceutil/trace.go:171","msg":"trace[1994899055] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1285; }","duration":"408.050254ms","start":"2026-06-13T01:09:59.962694Z","end":"2026-06-13T01:10:00.370745Z","steps":["trace[1994899055] 'agreement among raft nodes before linearized reading' (duration: 407.8993ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:10:00.370783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:09:59.96267Z","time spent":"408.103375ms","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-13T01:10:00.370859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.744834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-06-13T01:10:00.370902Z","caller":"traceutil/trace.go:171","msg":"trace[1284960350] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1285; }","duration":"169.812026ms","start":"2026-06-13T01:10:00.201072Z","end":"2026-06-13T01:10:00.370884Z","steps":["trace[1284960350] 'agreement among raft nodes before linearized reading' (duration: 169.688212ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:10:00.510253Z","caller":"traceutil/trace.go:171","msg":"trace[246998689] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"131.472925ms","start":"2026-06-13T01:10:00.378735Z","end":"2026-06-13T01:10:00.510208Z","steps":["trace[246998689] 'process raft request' (duration: 91.091798ms)","trace[246998689] 'compare' (duration: 40.141279ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T01:10:00.5103Z","caller":"traceutil/trace.go:171","msg":"trace[1994682824] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"130.907466ms","start":"2026-06-13T01:10:00.379369Z","end":"2026-06-13T01:10:00.510277Z","steps":["trace[1994682824] 'process raft request' (duration: 130.839574ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:10:00.510503Z","caller":"traceutil/trace.go:171","msg":"trace[1323846478] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"131.182224ms","start":"2026-06-13T01:10:00.379266Z","end":"2026-06-13T01:10:00.510449Z","steps":["trace[1323846478] 'process raft request' (duration: 130.872595ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:10:00.512464Z","caller":"traceutil/trace.go:171","msg":"trace[688900392] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"133.024974ms","start":"2026-06-13T01:10:00.379422Z","end":"2026-06-13T01:10:00.512447Z","steps":["trace[688900392] 'process raft request' (duration: 132.89831ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:10:00.512936Z","caller":"traceutil/trace.go:171","msg":"trace[1222513553] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"133.50631ms","start":"2026-06-13T01:10:00.37938Z","end":"2026-06-13T01:10:00.512886Z","steps":["trace[1222513553] 'process raft request' (duration: 130.866185ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:10:01.1183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.645028ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-13T01:10:01.118413Z","caller":"traceutil/trace.go:171","msg":"trace[1554621852] linearizableReadLoop","detail":"{readStateIndex:1344; appliedIndex:1343; }","duration":"310.223868ms","start":"2026-06-13T01:10:00.808172Z","end":"2026-06-13T01:10:01.118396Z","steps":["trace[1554621852] 'read index received' (duration: 40.871µs)","trace[1554621852] 'applied index is now lower than readState.Index' (duration: 310.181177ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T01:10:01.118554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.385544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T01:10:01.11859Z","caller":"traceutil/trace.go:171","msg":"trace[166439961] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1292; }","duration":"310.444726ms","start":"2026-06-13T01:10:00.808133Z","end":"2026-06-13T01:10:01.118578Z","steps":["trace[166439961] 'agreement among raft nodes before linearized reading' (duration: 310.353043ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:10:01.118551Z","caller":"traceutil/trace.go:171","msg":"trace[199744618] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"511.015475ms","start":"2026-06-13T01:10:00.607482Z","end":"2026-06-13T01:10:01.118497Z","steps":["trace[199744618] 'process raft request' (duration: 193.076297ms)","trace[199744618] 'compare' (duration: 317.280397ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T01:10:01.118628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:10:00.808115Z","time spent":"310.501248ms","remote":"127.0.0.1:38576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-06-13T01:10:01.118624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.579525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T01:10:01.11872Z","caller":"traceutil/trace.go:171","msg":"trace[87554630] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1292; }","duration":"113.73661ms","start":"2026-06-13T01:10:01.004957Z","end":"2026-06-13T01:10:01.118693Z","steps":["trace[87554630] 'agreement among raft nodes before linearized reading' (duration: 113.573165ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:10:01.118745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:10:00.607451Z","time spent":"511.18157ms","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T01:10:04.544311Z","caller":"traceutil/trace.go:171","msg":"trace[1122771619] linearizableReadLoop","detail":"{readStateIndex:1374; appliedIndex:1373; }","duration":"158.924083ms","start":"2026-06-13T01:10:04.385362Z","end":"2026-06-13T01:10:04.544286Z","steps":["trace[1122771619] 'read index received' (duration: 158.694746ms)","trace[1122771619] 'applied index is now lower than readState.Index' (duration: 228.197µs)"],"step_count":2} {"level":"info","ts":"2026-06-13T01:10:04.544382Z","caller":"traceutil/trace.go:171","msg":"trace[1943771632] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"159.792981ms","start":"2026-06-13T01:10:04.384564Z","end":"2026-06-13T01:10:04.544357Z","steps":["trace[1943771632] 'process raft request' (duration: 159.550703ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:10:04.544596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.222161ms","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-13T01:10:04.544663Z","caller":"traceutil/trace.go:171","msg":"trace[1763740816] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1321; }","duration":"159.304316ms","start":"2026-06-13T01:10:04.385341Z","end":"2026-06-13T01:10:04.544646Z","steps":["trace[1763740816] 'agreement among raft nodes before linearized reading' (duration: 159.107999ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:10:04.544597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.514733ms","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-13T01:10:04.544852Z","caller":"traceutil/trace.go:171","msg":"trace[116793031] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1321; }","duration":"113.793583ms","start":"2026-06-13T01:10:04.430985Z","end":"2026-06-13T01:10:04.544778Z","steps":["trace[116793031] 'agreement among raft nodes before linearized reading' (duration: 113.493553ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:10:09.917563Z","caller":"traceutil/trace.go:171","msg":"trace[1275792425] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"217.287678ms","start":"2026-06-13T01:10:09.7002Z","end":"2026-06-13T01:10:09.917488Z","steps":["trace[1275792425] 'process raft request' (duration: 217.080383ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:10:18.732907Z","caller":"traceutil/trace.go:171","msg":"trace[287646212] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"109.184393ms","start":"2026-06-13T01:10:18.623682Z","end":"2026-06-13T01:10:18.732867Z","steps":["trace[287646212] 'process raft request' (duration: 108.974837ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:10:23.941897Z","caller":"traceutil/trace.go:171","msg":"trace[940737675] transaction","detail":"{read_only:false; response_revision:1411; number_of_response:1; }","duration":"160.114299ms","start":"2026-06-13T01:10:23.781749Z","end":"2026-06-13T01:10:23.941863Z","steps":["trace[940737675] 'process raft request' (duration: 159.976236ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:11:37.225443Z","caller":"traceutil/trace.go:171","msg":"trace[237199338] transaction","detail":"{read_only:false; response_revision:1745; number_of_response:1; }","duration":"107.186321ms","start":"2026-06-13T01:11:37.11822Z","end":"2026-06-13T01:11:37.225407Z","steps":["trace[237199338] 'process raft request' (duration: 106.953365ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:11:37.463878Z","caller":"traceutil/trace.go:171","msg":"trace[37758308] transaction","detail":"{read_only:false; response_revision:1749; number_of_response:1; }","duration":"112.06578ms","start":"2026-06-13T01:11:37.351745Z","end":"2026-06-13T01:11:37.463811Z","steps":["trace[37758308] 'process raft request' (duration: 56.77894ms)","trace[37758308] 'compare' (duration: 55.112666ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T01:11:37.669594Z","caller":"traceutil/trace.go:171","msg":"trace[993553499] transaction","detail":"{read_only:false; response_revision:1750; number_of_response:1; }","duration":"184.390457ms","start":"2026-06-13T01:11:37.485162Z","end":"2026-06-13T01:11:37.669553Z","steps":["trace[993553499] 'process raft request' (duration: 120.902902ms)","trace[993553499] 'compare' (duration: 63.29147ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T01:11:37.718665Z","caller":"traceutil/trace.go:171","msg":"trace[309773526] linearizableReadLoop","detail":"{readStateIndex:1822; appliedIndex:1820; }","duration":"203.94535ms","start":"2026-06-13T01:11:37.514696Z","end":"2026-06-13T01:11:37.718642Z","steps":["trace[309773526] 'read index received' (duration: 92.23357ms)","trace[309773526] 'applied index is now lower than readState.Index' (duration: 111.71024ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T01:11:37.71881Z","caller":"traceutil/trace.go:171","msg":"trace[109184859] transaction","detail":"{read_only:false; response_revision:1751; number_of_response:1; }","duration":"205.41994ms","start":"2026-06-13T01:11:37.513377Z","end":"2026-06-13T01:11:37.718797Z","steps":["trace[109184859] 'process raft request' (duration: 205.116841ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:11:37.71919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.524496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-06-13T01:11:37.719287Z","caller":"traceutil/trace.go:171","msg":"trace[972629109] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1751; }","duration":"196.673729ms","start":"2026-06-13T01:11:37.522592Z","end":"2026-06-13T01:11:37.719265Z","steps":["trace[972629109] 'agreement among raft nodes before linearized reading' (duration: 196.424093ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:11:37.719201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.474435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.169\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-13T01:11:37.719468Z","caller":"traceutil/trace.go:171","msg":"trace[847351128] range","detail":"{range_begin:/registry/masterleases/199.204.45.169; range_end:; response_count:1; response_revision:1751; }","duration":"204.745621ms","start":"2026-06-13T01:11:37.514669Z","end":"2026-06-13T01:11:37.719415Z","steps":["trace[847351128] 'agreement among raft nodes before linearized reading' (duration: 204.363751ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:12:11.50721Z","caller":"traceutil/trace.go:171","msg":"trace[365297715] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"142.515985ms","start":"2026-06-13T01:12:11.364629Z","end":"2026-06-13T01:12:11.507145Z","steps":["trace[365297715] 'process raft request' (duration: 142.29924ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:14:06.941187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.564905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-06-13T01:14:06.941349Z","caller":"traceutil/trace.go:171","msg":"trace[628364830] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2460; }","duration":"100.78518ms","start":"2026-06-13T01:14:06.840545Z","end":"2026-06-13T01:14:06.94133Z","steps":["trace[628364830] 'range keys from in-memory index tree' (duration: 100.373529ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:14:49.915047Z","caller":"traceutil/trace.go:171","msg":"trace[124829536] transaction","detail":"{read_only:false; response_revision:2664; number_of_response:1; }","duration":"113.891017ms","start":"2026-06-13T01:14:49.80111Z","end":"2026-06-13T01:14:49.915001Z","steps":["trace[124829536] 'process raft request' (duration: 113.663422ms)"],"step_count":1}