{"level":"warn","ts":"2026-02-06T04:34:04.468194Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-06T04:34:04.46834Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.227: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://162.253.55.227:2380","--initial-cluster=instance=https://162.253.55.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.227:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-06T04:34:04.468426Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-06T04:34:04.468443Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.227:2380"]} {"level":"info","ts":"2026-02-06T04:34:04.468468Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-06T04:34:04.469697Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"]} {"level":"info","ts":"2026-02-06T04:34:04.46983Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.227:2380"],"listen-peer-urls":["https://162.253.55.227:2380"],"advertise-client-urls":["https://162.253.55.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.227:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-06T04:34:04.47463Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.543328ms"} {"level":"info","ts":"2026-02-06T04:34:04.504579Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9bd37ed2859641e3","cluster-id":"47d0f4f0a1c3b0a9"} {"level":"info","ts":"2026-02-06T04:34:04.504852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=()"} {"level":"info","ts":"2026-02-06T04:34:04.504877Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became follower at term 0"} {"level":"info","ts":"2026-02-06T04:34:04.504926Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9bd37ed2859641e3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-06T04:34:04.504941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became follower at term 1"} {"level":"info","ts":"2026-02-06T04:34:04.505008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=(11228457738614227427)"} {"level":"warn","ts":"2026-02-06T04:34:04.512074Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-06T04:34:04.515832Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-06T04:34:04.521596Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-06T04:34:04.525797Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9bd37ed2859641e3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-06T04:34:04.526185Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9bd37ed2859641e3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-06T04:34:04.526345Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-06T04:34:04.526385Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-06T04:34:04.526395Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-06T04:34:04.528589Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=(11228457738614227427)"} {"level":"info","ts":"2026-02-06T04:34:04.52874Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"47d0f4f0a1c3b0a9","local-member-id":"9bd37ed2859641e3","added-peer-id":"9bd37ed2859641e3","added-peer-peer-urls":["https://162.253.55.227:2380"]} {"level":"info","ts":"2026-02-06T04:34:04.537608Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-06T04:34:04.547834Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9bd37ed2859641e3","initial-advertise-peer-urls":["https://162.253.55.227:2380"],"listen-peer-urls":["https://162.253.55.227:2380"],"advertise-client-urls":["https://162.253.55.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-06T04:34:04.547939Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-06T04:34:04.537759Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.227:2380"} {"level":"info","ts":"2026-02-06T04:34:04.548939Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.227:2380"} {"level":"info","ts":"2026-02-06T04:34:05.306905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 is starting a new election at term 1"} {"level":"info","ts":"2026-02-06T04:34:05.306955Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-06T04:34:05.306973Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 received MsgPreVoteResp from 9bd37ed2859641e3 at term 1"} {"level":"info","ts":"2026-02-06T04:34:05.306985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became candidate at term 2"} {"level":"info","ts":"2026-02-06T04:34:05.30699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 received MsgVoteResp from 9bd37ed2859641e3 at term 2"} {"level":"info","ts":"2026-02-06T04:34:05.306999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became leader at term 2"} {"level":"info","ts":"2026-02-06T04:34:05.30701Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9bd37ed2859641e3 elected leader 9bd37ed2859641e3 at term 2"} {"level":"info","ts":"2026-02-06T04:34:05.307761Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T04:34:05.309802Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9bd37ed2859641e3","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.227:2379]}","request-path":"/0/members/9bd37ed2859641e3/attributes","cluster-id":"47d0f4f0a1c3b0a9","publish-timeout":"7s"} {"level":"info","ts":"2026-02-06T04:34:05.310519Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"47d0f4f0a1c3b0a9","local-member-id":"9bd37ed2859641e3","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T04:34:05.310562Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T04:34:05.310589Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T04:34:05.310597Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-06T04:34:05.313118Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-06T04:34:05.31344Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.227:2379"} {"level":"info","ts":"2026-02-06T04:34:05.314565Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-06T04:34:05.314578Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-06T04:34:05.317716Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-06T04:34:17.62585Z","caller":"traceutil/trace.go:171","msg":"trace[2005883130] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"181.467429ms","start":"2026-02-06T04:34:17.44436Z","end":"2026-02-06T04:34:17.625828Z","steps":["trace[2005883130] 'process raft request' (duration: 181.327866ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T04:34:23.440566Z","caller":"traceutil/trace.go:171","msg":"trace[1374242602] linearizableReadLoop","detail":"{readStateIndex:423; appliedIndex:422; }","duration":"173.676055ms","start":"2026-02-06T04:34:23.266875Z","end":"2026-02-06T04:34:23.440551Z","steps":["trace[1374242602] 'read index received' (duration: 173.532512ms)","trace[1374242602] 'applied index is now lower than readState.Index' (duration: 143.003µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T04:34:23.44076Z","caller":"traceutil/trace.go:171","msg":"trace[1392002656] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"174.111693ms","start":"2026-02-06T04:34:23.266643Z","end":"2026-02-06T04:34:23.440754Z","steps":["trace[1392002656] 'process raft request' (duration: 173.810137ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:34:23.440884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.995641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-06T04:34:23.440908Z","caller":"traceutil/trace.go:171","msg":"trace[115720423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:409; }","duration":"174.041842ms","start":"2026-02-06T04:34:23.26686Z","end":"2026-02-06T04:34:23.440902Z","steps":["trace[115720423] 'agreement among raft nodes before linearized reading' (duration: 173.992671ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:34:23.44098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.39563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-06T04:34:23.440993Z","caller":"traceutil/trace.go:171","msg":"trace[1612684227] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:409; }","duration":"168.420981ms","start":"2026-02-06T04:34:23.272567Z","end":"2026-02-06T04:34:23.440988Z","steps":["trace[1612684227] 'agreement among raft nodes before linearized reading' (duration: 168.39761ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T04:34:37.698586Z","caller":"traceutil/trace.go:171","msg":"trace[2083560418] linearizableReadLoop","detail":"{readStateIndex:493; appliedIndex:492; }","duration":"202.414754ms","start":"2026-02-06T04:34:37.496149Z","end":"2026-02-06T04:34:37.698564Z","steps":["trace[2083560418] 'read index received' (duration: 202.221229ms)","trace[2083560418] 'applied index is now lower than readState.Index' (duration: 192.605µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T04:34:37.698868Z","caller":"traceutil/trace.go:171","msg":"trace[1153422758] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"202.951294ms","start":"2026-02-06T04:34:37.495906Z","end":"2026-02-06T04:34:37.698857Z","steps":["trace[1153422758] 'process raft request' (duration: 202.542695ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:34:37.699054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.886613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-06T04:34:37.699088Z","caller":"traceutil/trace.go:171","msg":"trace[875523294] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:476; }","duration":"202.941875ms","start":"2026-02-06T04:34:37.496136Z","end":"2026-02-06T04:34:37.699078Z","steps":["trace[875523294] 'agreement among raft nodes before linearized reading' (duration: 202.873713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:34:37.699218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.647492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-06T04:34:37.699243Z","caller":"traceutil/trace.go:171","msg":"trace[1241099319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:476; }","duration":"186.690663ms","start":"2026-02-06T04:34:37.512545Z","end":"2026-02-06T04:34:37.699236Z","steps":["trace[1241099319] 'agreement among raft nodes before linearized reading' (duration: 186.649042ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:34:37.699412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.424582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-06T04:34:37.699438Z","caller":"traceutil/trace.go:171","msg":"trace[994146108] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:476; }","duration":"122.475723ms","start":"2026-02-06T04:34:37.576955Z","end":"2026-02-06T04:34:37.699431Z","steps":["trace[994146108] 'agreement among raft nodes before linearized reading' (duration: 122.429332ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T04:34:37.9627Z","caller":"traceutil/trace.go:171","msg":"trace[1735393563] linearizableReadLoop","detail":"{readStateIndex:496; appliedIndex:493; }","duration":"118.468514ms","start":"2026-02-06T04:34:37.844212Z","end":"2026-02-06T04:34:37.962681Z","steps":["trace[1735393563] 'read index received' (duration: 84.237248ms)","trace[1735393563] 'applied index is now lower than readState.Index' (duration: 34.228546ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T04:34:37.96291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.671378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T04:34:37.962944Z","caller":"traceutil/trace.go:171","msg":"trace[1954892294] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:479; }","duration":"118.757299ms","start":"2026-02-06T04:34:37.844174Z","end":"2026-02-06T04:34:37.962932Z","steps":["trace[1954892294] 'agreement among raft nodes before linearized reading' (duration: 118.607356ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T04:34:37.963252Z","caller":"traceutil/trace.go:171","msg":"trace[242779583] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"258.36254ms","start":"2026-02-06T04:34:37.704875Z","end":"2026-02-06T04:34:37.963238Z","steps":["trace[242779583] 'process raft request' (duration: 223.568843ms)","trace[242779583] 'compare' (duration: 33.863549ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T04:34:37.963373Z","caller":"traceutil/trace.go:171","msg":"trace[1320534905] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"258.35753ms","start":"2026-02-06T04:34:37.705008Z","end":"2026-02-06T04:34:37.963365Z","steps":["trace[1320534905] 'process raft request' (duration: 257.596595ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T04:34:37.963469Z","caller":"traceutil/trace.go:171","msg":"trace[1152978905] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"257.37426ms","start":"2026-02-06T04:34:37.706088Z","end":"2026-02-06T04:34:37.963462Z","steps":["trace[1152978905] 'process raft request' (duration: 256.561175ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:34:39.440427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.440572ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T04:34:39.440521Z","caller":"traceutil/trace.go:171","msg":"trace[1612408682] linearizableReadLoop","detail":"{readStateIndex:499; appliedIndex:498; }","duration":"595.150381ms","start":"2026-02-06T04:34:38.845355Z","end":"2026-02-06T04:34:39.440506Z","steps":["trace[1612408682] 'read index received' (duration: 203.978664ms)","trace[1612408682] 'applied index is now lower than readState.Index' (duration: 391.170477ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T04:34:39.440602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.251303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T04:34:39.440629Z","caller":"traceutil/trace.go:171","msg":"trace[293779022] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:481; }","duration":"595.301794ms","start":"2026-02-06T04:34:38.845319Z","end":"2026-02-06T04:34:39.440621Z","steps":["trace[293779022] 'agreement among raft nodes before linearized reading' (duration: 595.230273ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:34:39.44066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:34:38.845309Z","time spent":"595.344005ms","remote":"127.0.0.1:38274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-06T04:34:39.440836Z","caller":"traceutil/trace.go:171","msg":"trace[1430531336] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"707.932162ms","start":"2026-02-06T04:34:38.732889Z","end":"2026-02-06T04:34:39.440821Z","steps":["trace[1430531336] 'process raft request' (duration: 316.527301ms)","trace[1430531336] 'compare' (duration: 390.303419ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T04:34:39.440905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:34:38.732866Z","time spent":"708.001673ms","remote":"127.0.0.1:38312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T04:34:44.222647Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810167427718468,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-06T04:34:44.308477Z","caller":"traceutil/trace.go:171","msg":"trace[2031414755] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"807.094964ms","start":"2026-02-06T04:34:43.501363Z","end":"2026-02-06T04:34:44.308458Z","steps":["trace[2031414755] 'process raft request' (duration: 806.999102ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:34:44.30858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:34:43.501349Z","time spent":"807.177645ms","remote":"127.0.0.1:38466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T04:34:44.329452Z","caller":"traceutil/trace.go:171","msg":"trace[2089451371] linearizableReadLoop","detail":"{readStateIndex:513; appliedIndex:512; }","duration":"607.107509ms","start":"2026-02-06T04:34:43.722328Z","end":"2026-02-06T04:34:44.329435Z","steps":["trace[2089451371] 'read index received' (duration: 586.99839ms)","trace[2089451371] 'applied index is now lower than readState.Index' (duration: 20.107989ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T04:34:44.329714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.372124ms","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-02-06T04:34:44.329747Z","caller":"traceutil/trace.go:171","msg":"trace[2073095003] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:494; }","duration":"607.448295ms","start":"2026-02-06T04:34:43.722289Z","end":"2026-02-06T04:34:44.329737Z","steps":["trace[2073095003] 'agreement among raft nodes before linearized reading' (duration: 607.246931ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:34:44.329791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:34:43.722271Z","time spent":"607.512176ms","remote":"127.0.0.1:38546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-06T04:34:44.330033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.004984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T04:34:44.330062Z","caller":"traceutil/trace.go:171","msg":"trace[614851727] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:494; }","duration":"483.065305ms","start":"2026-02-06T04:34:43.846988Z","end":"2026-02-06T04:34:44.330053Z","steps":["trace[614851727] 'agreement among raft nodes before linearized reading' (duration: 483.012874ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:34:44.330099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:34:43.846972Z","time spent":"483.121177ms","remote":"127.0.0.1:38274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T04:34:44.330332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.704772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-06T04:34:44.330373Z","caller":"traceutil/trace.go:171","msg":"trace[507556740] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:494; }","duration":"280.759763ms","start":"2026-02-06T04:34:44.049593Z","end":"2026-02-06T04:34:44.330353Z","steps":["trace[507556740] 'agreement among raft nodes before linearized reading' (duration: 280.697701ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:34:44.330549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.595227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-06T04:34:44.330575Z","caller":"traceutil/trace.go:171","msg":"trace[1816390176] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:494; }","duration":"291.653878ms","start":"2026-02-06T04:34:44.038914Z","end":"2026-02-06T04:34:44.330568Z","steps":["trace[1816390176] 'agreement among raft nodes before linearized reading' (duration: 291.561436ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:34:44.330765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.226967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-06T04:34:44.330792Z","caller":"traceutil/trace.go:171","msg":"trace[567245583] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:494; }","duration":"304.288199ms","start":"2026-02-06T04:34:44.026497Z","end":"2026-02-06T04:34:44.330785Z","steps":["trace[567245583] 'agreement among raft nodes before linearized reading' (duration: 304.215538ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:34:44.330816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:34:44.026479Z","time spent":"304.332389ms","remote":"127.0.0.1:38546","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":"info","ts":"2026-02-06T04:34:45.628142Z","caller":"traceutil/trace.go:171","msg":"trace[977648249] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"122.108285ms","start":"2026-02-06T04:34:45.506004Z","end":"2026-02-06T04:34:45.628112Z","steps":["trace[977648249] 'process raft request' (duration: 121.986082ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T04:34:45.628529Z","caller":"traceutil/trace.go:171","msg":"trace[1252882034] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"121.205427ms","start":"2026-02-06T04:34:45.507318Z","end":"2026-02-06T04:34:45.628523Z","steps":["trace[1252882034] 'process raft request' (duration: 121.167407ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:34:45.974713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.612534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T04:34:45.974788Z","caller":"traceutil/trace.go:171","msg":"trace[1392281792] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:506; }","duration":"127.729687ms","start":"2026-02-06T04:34:45.847042Z","end":"2026-02-06T04:34:45.974772Z","steps":["trace[1392281792] 'range keys from in-memory index tree' (duration: 127.557583ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:34:56.834684Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810167427718713,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-06T04:34:57.335509Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810167427718713,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-06T04:34:57.626789Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.350594014s","expected-duration":"1s"} {"level":"info","ts":"2026-02-06T04:34:57.627061Z","caller":"traceutil/trace.go:171","msg":"trace[319278104] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"1.293444183s","start":"2026-02-06T04:34:56.333601Z","end":"2026-02-06T04:34:57.627045Z","steps":["trace[319278104] 'read index received' (duration: 1.2933089s)","trace[319278104] 'applied index is now lower than readState.Index' (duration: 134.573µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T04:34:57.627133Z","caller":"traceutil/trace.go:171","msg":"trace[1336164261] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"1.351006981s","start":"2026-02-06T04:34:56.276121Z","end":"2026-02-06T04:34:57.627128Z","steps":["trace[1336164261] 'process raft request' (duration: 1.350804887s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:34:57.627181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:34:56.276106Z","time spent":"1.351040112s","remote":"127.0.0.1:38350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-06T04:34:57.627384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.293777879s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-06T04:34:57.627401Z","caller":"traceutil/trace.go:171","msg":"trace[1446865057] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:618; }","duration":"1.29382267s","start":"2026-02-06T04:34:56.333573Z","end":"2026-02-06T04:34:57.627396Z","steps":["trace[1446865057] 'agreement among raft nodes before linearized reading' (duration: 1.293729618s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:34:57.627415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:34:56.333559Z","time spent":"1.29385221s","remote":"127.0.0.1:38546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-06T04:34:57.627511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.215328238s","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-02-06T04:34:57.627538Z","caller":"traceutil/trace.go:171","msg":"trace[1341027535] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:618; }","duration":"1.215361149s","start":"2026-02-06T04:34:56.412158Z","end":"2026-02-06T04:34:57.62752Z","steps":["trace[1341027535] 'agreement among raft nodes before linearized reading' (duration: 1.215324588s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:34:57.627551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:34:56.412142Z","time spent":"1.21540541s","remote":"127.0.0.1:38546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-06T04:34:57.627666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.0836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T04:34:57.627681Z","caller":"traceutil/trace.go:171","msg":"trace[1722188647] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"780.099ms","start":"2026-02-06T04:34:56.847579Z","end":"2026-02-06T04:34:57.627678Z","steps":["trace[1722188647] 'agreement among raft nodes before linearized reading' (duration: 780.0758ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:34:57.627693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:34:56.847543Z","time spent":"780.147271ms","remote":"127.0.0.1:38274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T04:34:57.627777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.183204113s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-06T04:34:57.627789Z","caller":"traceutil/trace.go:171","msg":"trace[1730886777] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:618; }","duration":"1.183225413s","start":"2026-02-06T04:34:56.44456Z","end":"2026-02-06T04:34:57.627785Z","steps":["trace[1730886777] 'agreement among raft nodes before linearized reading' (duration: 1.183188662s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:34:57.6278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:34:56.444555Z","time spent":"1.183241853s","remote":"127.0.0.1:38546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-06T04:34:57.627907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.193524756s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-06T04:34:57.627928Z","caller":"traceutil/trace.go:171","msg":"trace[839772912] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:618; }","duration":"1.193555037s","start":"2026-02-06T04:34:56.434369Z","end":"2026-02-06T04:34:57.627925Z","steps":["trace[839772912] 'agreement among raft nodes before linearized reading' (duration: 1.193510486s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:34:57.62794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:34:56.434365Z","time spent":"1.193572657s","remote":"127.0.0.1:38546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-06T04:34:57.628011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.194489036s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-06T04:34:57.628024Z","caller":"traceutil/trace.go:171","msg":"trace[409545968] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:618; }","duration":"1.194521667s","start":"2026-02-06T04:34:56.433498Z","end":"2026-02-06T04:34:57.62802Z","steps":["trace[409545968] 'agreement among raft nodes before linearized reading' (duration: 1.194487566s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:34:57.628037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:34:56.43349Z","time spent":"1.194544047s","remote":"127.0.0.1:38546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-06T04:34:58.13295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.074796ms","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-02-06T04:34:58.133046Z","caller":"traceutil/trace.go:171","msg":"trace[992722630] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:618; }","duration":"205.185738ms","start":"2026-02-06T04:34:57.927844Z","end":"2026-02-06T04:34:58.13303Z","steps":["trace[992722630] 'range keys from in-memory index tree' (duration: 205.057116ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:34:58.133313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.141266ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-06T04:34:58.133361Z","caller":"traceutil/trace.go:171","msg":"trace[436839053] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"470.148631ms","start":"2026-02-06T04:34:57.663204Z","end":"2026-02-06T04:34:58.133352Z","steps":["trace[436839053] 'process raft request' (duration: 213.932983ms)","trace[436839053] 'compare' (duration: 256.060665ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T04:34:58.133395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:34:57.663194Z","time spent":"470.185761ms","remote":"127.0.0.1:38350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-06T04:34:58.18948Z","caller":"traceutil/trace.go:171","msg":"trace[1429390553] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"523.489816ms","start":"2026-02-06T04:34:57.665979Z","end":"2026-02-06T04:34:58.189469Z","steps":["trace[1429390553] 'process raft request' (duration: 523.453275ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:34:58.189545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:34:57.665973Z","time spent":"523.539126ms","remote":"127.0.0.1:38546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T04:34:58.189757Z","caller":"traceutil/trace.go:171","msg":"trace[844293844] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"525.094187ms","start":"2026-02-06T04:34:57.664655Z","end":"2026-02-06T04:34:58.18975Z","steps":["trace[844293844] 'process raft request' (duration: 524.638358ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:34:58.189795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:34:57.664637Z","time spent":"525.137507ms","remote":"127.0.0.1:38546","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-02-06T04:34:58.189889Z","caller":"traceutil/trace.go:171","msg":"trace[751764191] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"525.172249ms","start":"2026-02-06T04:34:57.664711Z","end":"2026-02-06T04:34:58.189883Z","steps":["trace[751764191] 'process raft request' (duration: 524.643848ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:34:58.189919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:34:57.664708Z","time spent":"525.195779ms","remote":"127.0.0.1:38546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T04:34:58.189989Z","caller":"traceutil/trace.go:171","msg":"trace[1171971907] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"525.24814ms","start":"2026-02-06T04:34:57.664735Z","end":"2026-02-06T04:34:58.189983Z","steps":["trace[1171971907] 'process raft request' (duration: 524.655708ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:34:58.190017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:34:57.664732Z","time spent":"525.27064ms","remote":"127.0.0.1:38546","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-02-06T04:34:58.190096Z","caller":"traceutil/trace.go:171","msg":"trace[915267575] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"525.332312ms","start":"2026-02-06T04:34:57.664757Z","end":"2026-02-06T04:34:58.190089Z","steps":["trace[915267575] 'process raft request' (duration: 524.658999ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:34:58.190134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:34:57.664752Z","time spent":"525.366272ms","remote":"127.0.0.1:38546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T04:35:02.514285Z","caller":"traceutil/trace.go:171","msg":"trace[121138460] linearizableReadLoop","detail":"{readStateIndex:699; appliedIndex:698; }","duration":"285.827293ms","start":"2026-02-06T04:35:02.228441Z","end":"2026-02-06T04:35:02.514268Z","steps":["trace[121138460] 'read index received' (duration: 285.64872ms)","trace[121138460] 'applied index is now lower than readState.Index' (duration: 177.713µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T04:35:02.514457Z","caller":"traceutil/trace.go:171","msg":"trace[666088524] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"306.619844ms","start":"2026-02-06T04:35:02.207829Z","end":"2026-02-06T04:35:02.514449Z","steps":["trace[666088524] 'process raft request' (duration: 306.313108ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:35:02.514527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:35:02.207814Z","time spent":"306.661594ms","remote":"127.0.0.1:38546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T04:35:02.514697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.249082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-06T04:35:02.514719Z","caller":"traceutil/trace.go:171","msg":"trace[359267010] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:676; }","duration":"286.296303ms","start":"2026-02-06T04:35:02.228415Z","end":"2026-02-06T04:35:02.514711Z","steps":["trace[359267010] 'agreement among raft nodes before linearized reading' (duration: 286.216881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:35:02.51484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.300552ms","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-02-06T04:35:02.514857Z","caller":"traceutil/trace.go:171","msg":"trace[1405979200] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:676; }","duration":"286.326423ms","start":"2026-02-06T04:35:02.228525Z","end":"2026-02-06T04:35:02.514852Z","steps":["trace[1405979200] 'agreement among raft nodes before linearized reading' (duration: 286.275982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:35:02.514988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.1496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-06T04:35:02.515876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.399505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-06T04:35:02.515932Z","caller":"traceutil/trace.go:171","msg":"trace[1614945669] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:676; }","duration":"286.486347ms","start":"2026-02-06T04:35:02.229431Z","end":"2026-02-06T04:35:02.515917Z","steps":["trace[1614945669] 'agreement among raft nodes before linearized reading' (duration: 286.300293ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T04:35:02.515005Z","caller":"traceutil/trace.go:171","msg":"trace[1546751663] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:676; }","duration":"285.183651ms","start":"2026-02-06T04:35:02.229816Z","end":"2026-02-06T04:35:02.514999Z","steps":["trace[1546751663] 'agreement among raft nodes before linearized reading' (duration: 285.13528ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T04:35:02.718312Z","caller":"traceutil/trace.go:171","msg":"trace[1274615562] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"197.438816ms","start":"2026-02-06T04:35:02.520858Z","end":"2026-02-06T04:35:02.718297Z","steps":["trace[1274615562] 'process raft request' (duration: 196.977897ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T04:35:02.72234Z","caller":"traceutil/trace.go:171","msg":"trace[208344334] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"201.159418ms","start":"2026-02-06T04:35:02.521169Z","end":"2026-02-06T04:35:02.722328Z","steps":["trace[208344334] 'process raft request' (duration: 201.135898ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T04:35:02.722576Z","caller":"traceutil/trace.go:171","msg":"trace[1402173972] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"201.518386ms","start":"2026-02-06T04:35:02.521049Z","end":"2026-02-06T04:35:02.722567Z","steps":["trace[1402173972] 'process raft request' (duration: 201.179459ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T04:35:02.72266Z","caller":"traceutil/trace.go:171","msg":"trace[1871604001] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"201.519296ms","start":"2026-02-06T04:35:02.521133Z","end":"2026-02-06T04:35:02.722653Z","steps":["trace[1871604001] 'process raft request' (duration: 201.145818ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T04:35:06.72586Z","caller":"traceutil/trace.go:171","msg":"trace[1048362501] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"197.785351ms","start":"2026-02-06T04:35:06.528055Z","end":"2026-02-06T04:35:06.725841Z","steps":["trace[1048362501] 'process raft request' (duration: 197.696639ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T04:35:08.861591Z","caller":"traceutil/trace.go:171","msg":"trace[137606655] linearizableReadLoop","detail":"{readStateIndex:722; appliedIndex:721; }","duration":"132.200714ms","start":"2026-02-06T04:35:08.729373Z","end":"2026-02-06T04:35:08.861574Z","steps":["trace[137606655] 'read index received' (duration: 132.064231ms)","trace[137606655] 'applied index is now lower than readState.Index' (duration: 135.883µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T04:35:08.861685Z","caller":"traceutil/trace.go:171","msg":"trace[342735743] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"161.408313ms","start":"2026-02-06T04:35:08.700252Z","end":"2026-02-06T04:35:08.861661Z","steps":["trace[342735743] 'process raft request' (duration: 161.230269ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:35:08.86176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.372038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-06T04:35:08.86178Z","caller":"traceutil/trace.go:171","msg":"trace[2000340775] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:697; }","duration":"132.444669ms","start":"2026-02-06T04:35:08.72933Z","end":"2026-02-06T04:35:08.861774Z","steps":["trace[2000340775] 'agreement among raft nodes before linearized reading' (duration: 132.320256ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:35:23.968724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.546247ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-06T04:35:23.969009Z","caller":"traceutil/trace.go:171","msg":"trace[1097326250] linearizableReadLoop","detail":"{readStateIndex:770; appliedIndex:769; }","duration":"120.333451ms","start":"2026-02-06T04:35:23.848662Z","end":"2026-02-06T04:35:23.968996Z","steps":["trace[1097326250] 'read index received' (duration: 26.471µs)","trace[1097326250] 'applied index is now lower than readState.Index' (duration: 120.30616ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T04:35:23.969212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.543516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T04:35:23.969255Z","caller":"traceutil/trace.go:171","msg":"trace[1614118895] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:742; }","duration":"120.617138ms","start":"2026-02-06T04:35:23.848632Z","end":"2026-02-06T04:35:23.969249Z","steps":["trace[1614118895] 'agreement among raft nodes before linearized reading' (duration: 120.552366ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T04:35:25.365152Z","caller":"traceutil/trace.go:171","msg":"trace[490978088] linearizableReadLoop","detail":"{readStateIndex:771; appliedIndex:770; }","duration":"277.617712ms","start":"2026-02-06T04:35:25.087512Z","end":"2026-02-06T04:35:25.36513Z","steps":["trace[490978088] 'read index received' (duration: 277.403357ms)","trace[490978088] 'applied index is now lower than readState.Index' (duration: 213.425µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T04:35:25.365775Z","caller":"traceutil/trace.go:171","msg":"trace[1470211549] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"282.869493ms","start":"2026-02-06T04:35:25.082891Z","end":"2026-02-06T04:35:25.36576Z","steps":["trace[1470211549] 'process raft request' (duration: 282.099187ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:35:25.366237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.708044ms","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-02-06T04:35:25.366379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.373647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-06T04:35:25.366457Z","caller":"traceutil/trace.go:171","msg":"trace[1307805494] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:743; }","duration":"277.49775ms","start":"2026-02-06T04:35:25.08894Z","end":"2026-02-06T04:35:25.366438Z","steps":["trace[1307805494] 'agreement among raft nodes before linearized reading' (duration: 277.245675ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:35:25.366776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.121602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-06T04:35:25.366811Z","caller":"traceutil/trace.go:171","msg":"trace[1808119829] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:743; }","duration":"277.169272ms","start":"2026-02-06T04:35:25.089633Z","end":"2026-02-06T04:35:25.366802Z","steps":["trace[1808119829] 'agreement among raft nodes before linearized reading' (duration: 277.082581ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T04:35:25.366905Z","caller":"traceutil/trace.go:171","msg":"trace[1158974685] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:743; }","duration":"278.877778ms","start":"2026-02-06T04:35:25.087487Z","end":"2026-02-06T04:35:25.366365Z","steps":["trace[1158974685] 'agreement among raft nodes before linearized reading' (duration: 278.650154ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:35:25.366969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.349127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-06T04:35:25.367663Z","caller":"traceutil/trace.go:171","msg":"trace[1611157610] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:743; }","duration":"278.052721ms","start":"2026-02-06T04:35:25.089594Z","end":"2026-02-06T04:35:25.367647Z","steps":["trace[1611157610] 'agreement among raft nodes before linearized reading' (duration: 277.321426ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T04:35:25.790847Z","caller":"traceutil/trace.go:171","msg":"trace[1772633441] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"404.659823ms","start":"2026-02-06T04:35:25.386162Z","end":"2026-02-06T04:35:25.790822Z","steps":["trace[1772633441] 'process raft request' (duration: 358.034658ms)","trace[1772633441] 'compare' (duration: 46.287758ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T04:35:25.790989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:35:25.386149Z","time spent":"404.760205ms","remote":"127.0.0.1:38546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T04:35:25.792725Z","caller":"traceutil/trace.go:171","msg":"trace[1472379750] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"403.819626ms","start":"2026-02-06T04:35:25.38888Z","end":"2026-02-06T04:35:25.7927Z","steps":["trace[1472379750] 'process raft request' (duration: 403.647352ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:35:25.792891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:35:25.388873Z","time spent":"403.922928ms","remote":"127.0.0.1:38546","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-02-06T04:35:25.79291Z","caller":"traceutil/trace.go:171","msg":"trace[172961714] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"403.924278ms","start":"2026-02-06T04:35:25.388971Z","end":"2026-02-06T04:35:25.792895Z","steps":["trace[172961714] 'process raft request' (duration: 403.690063ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:35:25.793019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:35:25.388966Z","time spent":"404.01189ms","remote":"127.0.0.1:38546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T04:35:25.793222Z","caller":"traceutil/trace.go:171","msg":"trace[1136296222] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"404.187073ms","start":"2026-02-06T04:35:25.389023Z","end":"2026-02-06T04:35:25.79321Z","steps":["trace[1136296222] 'process raft request' (duration: 403.819375ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:35:25.796667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:35:25.389018Z","time spent":"407.577414ms","remote":"127.0.0.1:38546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T04:35:38.01489Z","caller":"traceutil/trace.go:171","msg":"trace[1230681941] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"590.729533ms","start":"2026-02-06T04:35:37.424153Z","end":"2026-02-06T04:35:38.014882Z","steps":["trace[1230681941] 'process raft request' (duration: 590.436786ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:35:38.014981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:35:37.424144Z","time spent":"590.781285ms","remote":"127.0.0.1:38546","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-02-06T04:35:38.014692Z","caller":"traceutil/trace.go:171","msg":"trace[1188109852] linearizableReadLoop","detail":"{readStateIndex:860; appliedIndex:859; }","duration":"169.688673ms","start":"2026-02-06T04:35:37.844989Z","end":"2026-02-06T04:35:38.014678Z","steps":["trace[1188109852] 'read index received' (duration: 169.5498ms)","trace[1188109852] 'applied index is now lower than readState.Index' (duration: 138.353µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T04:35:38.015201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.210045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T04:35:38.015217Z","caller":"traceutil/trace.go:171","msg":"trace[1155426303] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:828; }","duration":"170.248685ms","start":"2026-02-06T04:35:37.844963Z","end":"2026-02-06T04:35:38.015212Z","steps":["trace[1155426303] 'agreement among raft nodes before linearized reading' (duration: 170.219374ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:35:38.015448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.009087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-06T04:35:38.015462Z","caller":"traceutil/trace.go:171","msg":"trace[633048204] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:828; }","duration":"131.037957ms","start":"2026-02-06T04:35:37.88442Z","end":"2026-02-06T04:35:38.015458Z","steps":["trace[633048204] 'agreement among raft nodes before linearized reading' (duration: 130.993046ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:35:38.015636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.016921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-06T04:35:38.015659Z","caller":"traceutil/trace.go:171","msg":"trace[660783826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:828; }","duration":"135.049862ms","start":"2026-02-06T04:35:37.880604Z","end":"2026-02-06T04:35:38.015654Z","steps":["trace[660783826] 'agreement among raft nodes before linearized reading' (duration: 135.002331ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:35:38.015789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.949301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-06T04:35:38.015803Z","caller":"traceutil/trace.go:171","msg":"trace[1805108962] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:828; }","duration":"135.993312ms","start":"2026-02-06T04:35:37.879805Z","end":"2026-02-06T04:35:38.015798Z","steps":["trace[1805108962] 'agreement among raft nodes before linearized reading' (duration: 135.956091ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:35:38.036169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.71893ms","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-02-06T04:35:38.036214Z","caller":"traceutil/trace.go:171","msg":"trace[78350797] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:828; }","duration":"184.784571ms","start":"2026-02-06T04:35:37.851418Z","end":"2026-02-06T04:35:38.036203Z","steps":["trace[78350797] 'agreement among raft nodes before linearized reading' (duration: 163.956932ms)","trace[78350797] 'range keys from bolt db' (duration: 20.757708ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T04:35:38.57576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.234126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T04:35:38.575828Z","caller":"traceutil/trace.go:171","msg":"trace[367193103] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:828; }","duration":"539.324788ms","start":"2026-02-06T04:35:38.036487Z","end":"2026-02-06T04:35:38.575812Z","steps":["trace[367193103] 'range keys from in-memory index tree' (duration: 539.186675ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:35:38.575859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:35:38.036478Z","time spent":"539.375099ms","remote":"127.0.0.1:38280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T04:35:38.575949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.949633ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T04:35:38.576163Z","caller":"traceutil/trace.go:171","msg":"trace[43088698] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"522.680346ms","start":"2026-02-06T04:35:38.053471Z","end":"2026-02-06T04:35:38.576151Z","steps":["trace[43088698] 'process raft request' (duration: 522.654406ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T04:35:38.576168Z","caller":"traceutil/trace.go:171","msg":"trace[496232556] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"523.123605ms","start":"2026-02-06T04:35:38.053034Z","end":"2026-02-06T04:35:38.576157Z","steps":["trace[496232556] 'process raft request' (duration: 133.906787ms)","trace[496232556] 'compare' (duration: 388.722138ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T04:35:38.576208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:35:38.053455Z","time spent":"522.731127ms","remote":"127.0.0.1:38546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T04:35:38.576237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:35:38.053019Z","time spent":"523.172306ms","remote":"127.0.0.1:38546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T04:35:38.576347Z","caller":"traceutil/trace.go:171","msg":"trace[1197825037] transaction","detail":"{read_only:false; response_revision:830; number_of_response:1; }","duration":"523.178206ms","start":"2026-02-06T04:35:38.053163Z","end":"2026-02-06T04:35:38.576341Z","steps":["trace[1197825037] 'process raft request' (duration: 522.87069ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:35:38.576385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:35:38.053158Z","time spent":"523.210128ms","remote":"127.0.0.1:38546","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-02-06T04:35:38.576454Z","caller":"traceutil/trace.go:171","msg":"trace[1224066860] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"523.019563ms","start":"2026-02-06T04:35:38.053428Z","end":"2026-02-06T04:35:38.576448Z","steps":["trace[1224066860] 'process raft request' (duration: 522.674276ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:35:38.576483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:35:38.053423Z","time spent":"523.044744ms","remote":"127.0.0.1:38546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T04:35:48.317889Z","caller":"traceutil/trace.go:171","msg":"trace[1685186642] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"122.881374ms","start":"2026-02-06T04:35:48.19499Z","end":"2026-02-06T04:35:48.317871Z","steps":["trace[1685186642] 'process raft request' (duration: 122.784252ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:35:48.805672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.75338ms","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-02-06T04:35:48.80572Z","caller":"traceutil/trace.go:171","msg":"trace[1595570420] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:856; }","duration":"200.827471ms","start":"2026-02-06T04:35:48.604879Z","end":"2026-02-06T04:35:48.805707Z","steps":["trace[1595570420] 'range keys from in-memory index tree' (duration: 200.656166ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:35:48.805849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.6237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-06T04:35:48.805862Z","caller":"traceutil/trace.go:171","msg":"trace[1723925295] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:856; }","duration":"194.646191ms","start":"2026-02-06T04:35:48.611211Z","end":"2026-02-06T04:35:48.805858Z","steps":["trace[1723925295] 'range keys from in-memory index tree' (duration: 194.571279ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:35:48.805948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.303049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-06T04:35:48.805959Z","caller":"traceutil/trace.go:171","msg":"trace[1684716327] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:856; }","duration":"190.314989ms","start":"2026-02-06T04:35:48.615641Z","end":"2026-02-06T04:35:48.805956Z","steps":["trace[1684716327] 'range keys from in-memory index tree' (duration: 190.226207ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:35:48.80606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.86892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-06T04:35:48.806073Z","caller":"traceutil/trace.go:171","msg":"trace[531565418] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:856; }","duration":"189.89148ms","start":"2026-02-06T04:35:48.616177Z","end":"2026-02-06T04:35:48.806069Z","steps":["trace[531565418] 'range keys from in-memory index tree' (duration: 189.792068ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:35:48.80615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.543015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-06T04:35:48.806161Z","caller":"traceutil/trace.go:171","msg":"trace[575496] range","detail":"{range_begin:/registry/masterleases/162.253.55.227; range_end:; response_count:1; response_revision:856; }","duration":"182.563585ms","start":"2026-02-06T04:35:48.623594Z","end":"2026-02-06T04:35:48.806157Z","steps":["trace[575496] 'range keys from in-memory index tree' (duration: 182.497483ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T04:35:53.387663Z","caller":"traceutil/trace.go:171","msg":"trace[1978495675] linearizableReadLoop","detail":"{readStateIndex:905; appliedIndex:904; }","duration":"480.382443ms","start":"2026-02-06T04:35:52.907198Z","end":"2026-02-06T04:35:53.387581Z","steps":["trace[1978495675] 'read index received' (duration: 480.177539ms)","trace[1978495675] 'applied index is now lower than readState.Index' (duration: 204.014µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T04:35:53.387895Z","caller":"traceutil/trace.go:171","msg":"trace[648889254] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"490.223421ms","start":"2026-02-06T04:35:52.897661Z","end":"2026-02-06T04:35:53.387884Z","steps":["trace[648889254] 'process raft request' (duration: 489.766622ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:35:53.387981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:35:52.897652Z","time spent":"490.270042ms","remote":"127.0.0.1:38546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T04:35:53.388213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.006686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-06T04:35:53.388245Z","caller":"traceutil/trace.go:171","msg":"trace[1321625838] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:870; }","duration":"481.049437ms","start":"2026-02-06T04:35:52.907185Z","end":"2026-02-06T04:35:53.388235Z","steps":["trace[1321625838] 'agreement among raft nodes before linearized reading' (duration: 480.932784ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:35:53.388271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:35:52.90718Z","time spent":"481.085217ms","remote":"127.0.0.1:38546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-06T04:35:53.38843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.080384ms","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-02-06T04:35:53.388455Z","caller":"traceutil/trace.go:171","msg":"trace[557818410] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:870; }","duration":"433.122615ms","start":"2026-02-06T04:35:52.955325Z","end":"2026-02-06T04:35:53.388448Z","steps":["trace[557818410] 'agreement among raft nodes before linearized reading' (duration: 433.054684ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:35:53.388478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:35:52.955314Z","time spent":"433.158515ms","remote":"127.0.0.1:38546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-06T04:35:53.388694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.092255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-06T04:35:53.388724Z","caller":"traceutil/trace.go:171","msg":"trace[1205608011] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:870; }","duration":"432.130955ms","start":"2026-02-06T04:35:52.956585Z","end":"2026-02-06T04:35:53.388716Z","steps":["trace[1205608011] 'agreement among raft nodes before linearized reading' (duration: 432.055343ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:35:53.388749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:35:52.956581Z","time spent":"432.163016ms","remote":"127.0.0.1:38546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-06T04:35:54.476267Z","caller":"traceutil/trace.go:171","msg":"trace[1574477046] linearizableReadLoop","detail":"{readStateIndex:909; appliedIndex:908; }","duration":"137.695167ms","start":"2026-02-06T04:35:54.33855Z","end":"2026-02-06T04:35:54.476245Z","steps":["trace[1574477046] 'read index received' (duration: 71.03431ms)","trace[1574477046] 'applied index is now lower than readState.Index' (duration: 66.659347ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T04:35:54.476662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.087965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-06T04:35:54.476692Z","caller":"traceutil/trace.go:171","msg":"trace[830318918] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:873; }","duration":"138.156616ms","start":"2026-02-06T04:35:54.338527Z","end":"2026-02-06T04:35:54.476683Z","steps":["trace[830318918] 'agreement among raft nodes before linearized reading' (duration: 137.986753ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T04:35:55.641074Z","caller":"traceutil/trace.go:171","msg":"trace[1565122366] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"154.949802ms","start":"2026-02-06T04:35:55.486104Z","end":"2026-02-06T04:35:55.641054Z","steps":["trace[1565122366] 'process raft request' (duration: 154.915802ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T04:35:55.642058Z","caller":"traceutil/trace.go:171","msg":"trace[1900726166] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"167.445406ms","start":"2026-02-06T04:35:55.474596Z","end":"2026-02-06T04:35:55.642042Z","steps":["trace[1900726166] 'process raft request' (duration: 146.620555ms)","trace[1900726166] 'compare' (duration: 19.653495ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T04:35:55.642197Z","caller":"traceutil/trace.go:171","msg":"trace[1359626513] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"166.996907ms","start":"2026-02-06T04:35:55.47519Z","end":"2026-02-06T04:35:55.642187Z","steps":["trace[1359626513] 'process raft request' (duration: 165.792042ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:35:58.110358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.322611ms","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-02-06T04:35:58.110441Z","caller":"traceutil/trace.go:171","msg":"trace[561904205] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:889; }","duration":"182.422143ms","start":"2026-02-06T04:35:57.928007Z","end":"2026-02-06T04:35:58.110429Z","steps":["trace[561904205] 'range keys from in-memory index tree' (duration: 182.297779ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:35:58.110677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.135473ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T04:35:58.11074Z","caller":"traceutil/trace.go:171","msg":"trace[9616321] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"451.394291ms","start":"2026-02-06T04:35:57.659334Z","end":"2026-02-06T04:35:58.110728Z","steps":["trace[9616321] 'process raft request' (duration: 236.127765ms)","trace[9616321] 'compare' (duration: 215.066682ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T04:35:58.110772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:35:57.659327Z","time spent":"451.430632ms","remote":"127.0.0.1:38546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T04:35:58.111091Z","caller":"traceutil/trace.go:171","msg":"trace[415126653] linearizableReadLoop","detail":"{readStateIndex:927; appliedIndex:926; }","duration":"207.867829ms","start":"2026-02-06T04:35:57.903216Z","end":"2026-02-06T04:35:58.111084Z","steps":["trace[415126653] 'read index received' (duration: 207.808978ms)","trace[415126653] 'applied index is now lower than readState.Index' (duration: 58.381µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T04:35:58.11113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.91271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T04:35:58.111146Z","caller":"traceutil/trace.go:171","msg":"trace[2010198555] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:891; }","duration":"207.94957ms","start":"2026-02-06T04:35:57.903189Z","end":"2026-02-06T04:35:58.111138Z","steps":["trace[2010198555] 'agreement among raft nodes before linearized reading' (duration: 207.9223ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T04:35:58.111187Z","caller":"traceutil/trace.go:171","msg":"trace[370660660] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"372.452515ms","start":"2026-02-06T04:35:57.738725Z","end":"2026-02-06T04:35:58.111177Z","steps":["trace[370660660] 'process raft request' (duration: 372.323223ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:35:58.111247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:35:57.738707Z","time spent":"372.500816ms","remote":"127.0.0.1:38546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T04:35:58.627387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.566916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T04:35:58.627457Z","caller":"traceutil/trace.go:171","msg":"trace[615832880] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:891; }","duration":"182.683018ms","start":"2026-02-06T04:35:58.444758Z","end":"2026-02-06T04:35:58.627441Z","steps":["trace[615832880] 'count revisions from in-memory index tree' (duration: 182.474664ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:35:58.628027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.620575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-06T04:35:58.62807Z","caller":"traceutil/trace.go:171","msg":"trace[1523098050] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:891; }","duration":"135.694526ms","start":"2026-02-06T04:35:58.492365Z","end":"2026-02-06T04:35:58.62806Z","steps":["trace[1523098050] 'range keys from in-memory index tree' (duration: 135.449791ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:35:59.069183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:35:58.629786Z","time spent":"439.388748ms","remote":"127.0.0.1:38312","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-06T04:35:59.29372Z","caller":"traceutil/trace.go:171","msg":"trace[1201014411] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"661.1316ms","start":"2026-02-06T04:35:58.632572Z","end":"2026-02-06T04:35:59.293703Z","steps":["trace[1201014411] 'process raft request' (duration: 654.712995ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:35:59.293819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:35:58.632551Z","time spent":"661.216541ms","remote":"127.0.0.1:38546","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-02-06T04:35:59.970385Z","caller":"traceutil/trace.go:171","msg":"trace[534493674] linearizableReadLoop","detail":"{readStateIndex:930; appliedIndex:929; }","duration":"582.296895ms","start":"2026-02-06T04:35:59.388071Z","end":"2026-02-06T04:35:59.970368Z","steps":["trace[534493674] 'read index received' (duration: 486.342689ms)","trace[534493674] 'applied index is now lower than readState.Index' (duration: 95.953456ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T04:35:59.970462Z","caller":"traceutil/trace.go:171","msg":"trace[1757307549] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"900.218348ms","start":"2026-02-06T04:35:59.070236Z","end":"2026-02-06T04:35:59.970454Z","steps":["trace[1757307549] 'process raft request' (duration: 804.16992ms)","trace[1757307549] 'compare' (duration: 95.404684ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T04:35:59.970533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:35:59.070219Z","time spent":"900.260728ms","remote":"127.0.0.1:38312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T04:35:59.970706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.638663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T04:35:59.970731Z","caller":"traceutil/trace.go:171","msg":"trace[43402227] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:893; }","duration":"582.705514ms","start":"2026-02-06T04:35:59.388017Z","end":"2026-02-06T04:35:59.970723Z","steps":["trace[43402227] 'agreement among raft nodes before linearized reading' (duration: 582.635743ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:35:59.970759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:35:59.387999Z","time spent":"582.753455ms","remote":"127.0.0.1:55800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":3,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"info","ts":"2026-02-06T04:35:59.97265Z","caller":"traceutil/trace.go:171","msg":"trace[1076257733] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"264.972135ms","start":"2026-02-06T04:35:59.707669Z","end":"2026-02-06T04:35:59.972641Z","steps":["trace[1076257733] 'process raft request' (duration: 264.814422ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:35:59.972763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.916936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T04:35:59.972784Z","caller":"traceutil/trace.go:171","msg":"trace[1993714290] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:894; }","duration":"122.965126ms","start":"2026-02-06T04:35:59.849813Z","end":"2026-02-06T04:35:59.972778Z","steps":["trace[1993714290] 'agreement among raft nodes before linearized reading' (duration: 122.931685ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:35:59.97288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.548288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T04:35:59.972899Z","caller":"traceutil/trace.go:171","msg":"trace[1786651089] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:894; }","duration":"212.589998ms","start":"2026-02-06T04:35:59.760303Z","end":"2026-02-06T04:35:59.972893Z","steps":["trace[1786651089] 'agreement among raft nodes before linearized reading' (duration: 212.557608ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:35:59.973053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.699027ms","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-02-06T04:35:59.973074Z","caller":"traceutil/trace.go:171","msg":"trace[1067240201] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:894; }","duration":"235.762878ms","start":"2026-02-06T04:35:59.737305Z","end":"2026-02-06T04:35:59.973068Z","steps":["trace[1067240201] 'agreement among raft nodes before linearized reading' (duration: 235.676776ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:35:59.973174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.504229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T04:35:59.973194Z","caller":"traceutil/trace.go:171","msg":"trace[1172920842] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:894; }","duration":"367.560171ms","start":"2026-02-06T04:35:59.605628Z","end":"2026-02-06T04:35:59.973188Z","steps":["trace[1172920842] 'agreement among raft nodes before linearized reading' (duration: 367.52496ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:35:59.973212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:35:59.605609Z","time spent":"367.596911ms","remote":"127.0.0.1:38520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-02-06T04:35:59.973374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.954554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-06T04:35:59.973396Z","caller":"traceutil/trace.go:171","msg":"trace[1378845888] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:894; }","duration":"435.018376ms","start":"2026-02-06T04:35:59.538372Z","end":"2026-02-06T04:35:59.97339Z","steps":["trace[1378845888] 'agreement among raft nodes before linearized reading' (duration: 434.959514ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:35:59.973413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:35:59.53835Z","time spent":"435.058296ms","remote":"127.0.0.1:38546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-06T04:36:00.103321Z","caller":"traceutil/trace.go:171","msg":"trace[1953457084] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"124.539129ms","start":"2026-02-06T04:35:59.978766Z","end":"2026-02-06T04:36:00.103305Z","steps":["trace[1953457084] 'process raft request' (duration: 124.508269ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T04:36:00.103715Z","caller":"traceutil/trace.go:171","msg":"trace[1830338954] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"125.062421ms","start":"2026-02-06T04:35:59.978646Z","end":"2026-02-06T04:36:00.103708Z","steps":["trace[1830338954] 'process raft request' (duration: 118.91001ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T04:36:02.218855Z","caller":"traceutil/trace.go:171","msg":"trace[1709368749] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"107.99146ms","start":"2026-02-06T04:36:02.110841Z","end":"2026-02-06T04:36:02.218832Z","steps":["trace[1709368749] 'process raft request' (duration: 107.813357ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T04:36:02.229032Z","caller":"traceutil/trace.go:171","msg":"trace[524709507] linearizableReadLoop","detail":"{readStateIndex:950; appliedIndex:949; }","duration":"107.282264ms","start":"2026-02-06T04:36:02.121721Z","end":"2026-02-06T04:36:02.229003Z","steps":["trace[524709507] 'read index received' (duration: 100.479621ms)","trace[524709507] 'applied index is now lower than readState.Index' (duration: 6.801593ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T04:36:02.229178Z","caller":"traceutil/trace.go:171","msg":"trace[1026457149] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"116.364677ms","start":"2026-02-06T04:36:02.112802Z","end":"2026-02-06T04:36:02.229167Z","steps":["trace[1026457149] 'process raft request' (duration: 115.870517ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:36:02.230465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.729365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-06T04:36:02.230496Z","caller":"traceutil/trace.go:171","msg":"trace[1886323736] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:913; }","duration":"108.789087ms","start":"2026-02-06T04:36:02.121696Z","end":"2026-02-06T04:36:02.230485Z","steps":["trace[1886323736] 'agreement among raft nodes before linearized reading' (duration: 107.949409ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:36:02.231059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.671204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-06T04:36:02.231082Z","caller":"traceutil/trace.go:171","msg":"trace[1503798728] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:913; }","duration":"108.700575ms","start":"2026-02-06T04:36:02.122372Z","end":"2026-02-06T04:36:02.231072Z","steps":["trace[1503798728] 'agreement among raft nodes before linearized reading' (duration: 108.655554ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:36:12.979401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.132962173s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T04:36:12.979461Z","caller":"traceutil/trace.go:171","msg":"trace[1035197268] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:976; }","duration":"1.133095566s","start":"2026-02-06T04:36:11.846352Z","end":"2026-02-06T04:36:12.979448Z","steps":["trace[1035197268] 'range keys from in-memory index tree' (duration: 1.132894261s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:36:12.979493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:36:11.846333Z","time spent":"1.133154777s","remote":"127.0.0.1:38274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T04:36:12.979752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.481867059s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T04:36:12.979778Z","caller":"traceutil/trace.go:171","msg":"trace[1563459844] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:976; }","duration":"1.48189562s","start":"2026-02-06T04:36:11.497876Z","end":"2026-02-06T04:36:12.979772Z","steps":["trace[1563459844] 'count revisions from in-memory index tree' (duration: 1.481793438s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:36:12.979797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:36:11.497821Z","time spent":"1.481970202s","remote":"127.0.0.1:38546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":12,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-02-06T04:36:12.980391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.16719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-06T04:36:12.98042Z","caller":"traceutil/trace.go:171","msg":"trace[1820674777] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:976; }","duration":"669.19336ms","start":"2026-02-06T04:36:12.311217Z","end":"2026-02-06T04:36:12.98041Z","steps":["trace[1820674777] 'range keys from in-memory index tree' (duration: 669.063058ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:36:12.980438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:36:12.311199Z","time spent":"669.234162ms","remote":"127.0.0.1:38546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-06T04:36:12.980497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.047174951s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb-4442v\" ","response":"range_response_count:1 size:12337"} {"level":"info","ts":"2026-02-06T04:36:12.98143Z","caller":"traceutil/trace.go:171","msg":"trace[172299927] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb-4442v; range_end:; response_count:1; response_revision:976; }","duration":"1.04757091s","start":"2026-02-06T04:36:11.933204Z","end":"2026-02-06T04:36:12.980775Z","steps":["trace[172299927] 'range keys from in-memory index tree' (duration: 1.046960346s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:36:12.981721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:36:11.933191Z","time spent":"1.0485112s","remote":"127.0.0.1:38466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":12360,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb-4442v\" "} {"level":"warn","ts":"2026-02-06T04:36:12.980546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.075652ms","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-02-06T04:36:12.982624Z","caller":"traceutil/trace.go:171","msg":"trace[428734989] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:976; }","duration":"666.090814ms","start":"2026-02-06T04:36:12.316441Z","end":"2026-02-06T04:36:12.982532Z","steps":["trace[428734989] 'range keys from in-memory index tree' (duration: 664.00672ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:36:12.98277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:36:12.316431Z","time spent":"666.32276ms","remote":"127.0.0.1:38546","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-02-06T04:36:12.980589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.951654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-06T04:36:12.987072Z","caller":"traceutil/trace.go:171","msg":"trace[1627438367] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:976; }","duration":"666.436262ms","start":"2026-02-06T04:36:12.320623Z","end":"2026-02-06T04:36:12.987059Z","steps":["trace[1627438367] 'range keys from in-memory index tree' (duration: 659.903593ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:36:12.987211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:36:12.320617Z","time spent":"666.564705ms","remote":"127.0.0.1:38546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-06T04:36:12.987409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.485821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-06T04:36:12.987436Z","caller":"traceutil/trace.go:171","msg":"trace[2064110278] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:976; }","duration":"664.526042ms","start":"2026-02-06T04:36:12.322903Z","end":"2026-02-06T04:36:12.987429Z","steps":["trace[2064110278] 'range keys from in-memory index tree' (duration: 664.41883ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:36:12.987476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:36:12.322897Z","time spent":"664.552962ms","remote":"127.0.0.1:38546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-06T04:36:12.987637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.323193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T04:36:12.987662Z","caller":"traceutil/trace.go:171","msg":"trace[1946754446] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:976; }","duration":"579.372093ms","start":"2026-02-06T04:36:12.408284Z","end":"2026-02-06T04:36:12.987656Z","steps":["trace[1946754446] 'count revisions from in-memory index tree' (duration: 579.277962ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:36:12.987704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:36:12.408275Z","time spent":"579.421894ms","remote":"127.0.0.1:38352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-02-06T04:36:13.160266Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810167427719515,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-06T04:36:13.28453Z","caller":"traceutil/trace.go:171","msg":"trace[17107704] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"843.336287ms","start":"2026-02-06T04:36:12.441168Z","end":"2026-02-06T04:36:13.284505Z","steps":["trace[17107704] 'process raft request' (duration: 843.196435ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:36:13.284707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:36:12.441153Z","time spent":"843.45853ms","remote":"127.0.0.1:38458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5770,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T04:36:13.440863Z","caller":"traceutil/trace.go:171","msg":"trace[1068458337] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"834.361287ms","start":"2026-02-06T04:36:12.606431Z","end":"2026-02-06T04:36:13.440793Z","steps":["trace[1068458337] 'process raft request' (duration: 833.229153ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:36:13.440979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:36:12.606408Z","time spent":"834.513331ms","remote":"127.0.0.1:38546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T04:36:13.441547Z","caller":"traceutil/trace.go:171","msg":"trace[1702405145] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"453.101097ms","start":"2026-02-06T04:36:12.988432Z","end":"2026-02-06T04:36:13.441533Z","steps":["trace[1702405145] 'process raft request' (duration: 452.097615ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:36:13.441617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:36:12.988421Z","time spent":"453.162539ms","remote":"127.0.0.1:38546","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-02-06T04:36:13.44236Z","caller":"traceutil/trace.go:171","msg":"trace[923810954] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"447.197423ms","start":"2026-02-06T04:36:12.995149Z","end":"2026-02-06T04:36:13.442346Z","steps":["trace[923810954] 'process raft request' (duration: 445.601569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:36:13.442432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:36:12.995139Z","time spent":"447.257504ms","remote":"127.0.0.1:38546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T04:36:13.44388Z","caller":"traceutil/trace.go:171","msg":"trace[1338562665] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"448.327747ms","start":"2026-02-06T04:36:12.995209Z","end":"2026-02-06T04:36:13.443536Z","steps":["trace[1338562665] 'process raft request' (duration: 446.880687ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:36:13.443966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:36:12.995204Z","time spent":"448.704315ms","remote":"127.0.0.1:38546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T04:36:13.444341Z","caller":"traceutil/trace.go:171","msg":"trace[901951247] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"449.094203ms","start":"2026-02-06T04:36:12.995239Z","end":"2026-02-06T04:36:13.444333Z","steps":["trace[901951247] 'process raft request' (duration: 447.479189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:36:13.444406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:36:12.995235Z","time spent":"449.129574ms","remote":"127.0.0.1:38546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T04:36:13.445084Z","caller":"traceutil/trace.go:171","msg":"trace[65603649] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"449.606032ms","start":"2026-02-06T04:36:12.995469Z","end":"2026-02-06T04:36:13.445075Z","steps":["trace[65603649] 'process raft request' (duration: 448.819476ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:36:13.445155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:36:12.995463Z","time spent":"449.645934ms","remote":"127.0.0.1:38466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13254,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T04:36:13.444625Z","caller":"traceutil/trace.go:171","msg":"trace[1272579737] linearizableReadLoop","detail":"{readStateIndex:1022; appliedIndex:1015; }","duration":"785.25341ms","start":"2026-02-06T04:36:12.659362Z","end":"2026-02-06T04:36:13.444616Z","steps":["trace[1272579737] 'read index received' (duration: 625.081468ms)","trace[1272579737] 'applied index is now lower than readState.Index' (duration: 160.171312ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T04:36:13.445409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.046817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T04:36:13.445453Z","caller":"traceutil/trace.go:171","msg":"trace[2037155664] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:983; }","duration":"786.104538ms","start":"2026-02-06T04:36:12.659323Z","end":"2026-02-06T04:36:13.445428Z","steps":["trace[2037155664] 'agreement among raft nodes before linearized reading' (duration: 786.059167ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:36:13.445495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:36:12.659309Z","time spent":"786.16429ms","remote":"127.0.0.1:38566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-06T04:36:13.446099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.835151ms","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:531"} {"level":"info","ts":"2026-02-06T04:36:13.446127Z","caller":"traceutil/trace.go:171","msg":"trace[172125711] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:983; }","duration":"228.890672ms","start":"2026-02-06T04:36:13.21723Z","end":"2026-02-06T04:36:13.44612Z","steps":["trace[172125711] 'agreement among raft nodes before linearized reading' (duration: 228.826991ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:36:13.446338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.423769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T04:36:13.446365Z","caller":"traceutil/trace.go:171","msg":"trace[1140580562] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:983; }","duration":"458.46546ms","start":"2026-02-06T04:36:12.987892Z","end":"2026-02-06T04:36:13.446357Z","steps":["trace[1140580562] 'agreement among raft nodes before linearized reading' (duration: 458.427239ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:36:13.44641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:36:12.98788Z","time spent":"458.523781ms","remote":"127.0.0.1:38280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T04:36:13.446632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.959588ms","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-02-06T04:36:13.446658Z","caller":"traceutil/trace.go:171","msg":"trace[1143235106] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:983; }","duration":"510.025399ms","start":"2026-02-06T04:36:12.936625Z","end":"2026-02-06T04:36:13.446651Z","steps":["trace[1143235106] 'agreement among raft nodes before linearized reading' (duration: 509.088249ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:36:13.446714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T04:36:12.936607Z","time spent":"510.098701ms","remote":"127.0.0.1:38546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-06T04:39:18.973109Z","caller":"traceutil/trace.go:171","msg":"trace[1703249522] transaction","detail":"{read_only:false; response_revision:1771; number_of_response:1; }","duration":"134.645079ms","start":"2026-02-06T04:39:18.83844Z","end":"2026-02-06T04:39:18.973085Z","steps":["trace[1703249522] 'process raft request' (duration: 134.512416ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T04:39:19.079356Z","caller":"traceutil/trace.go:171","msg":"trace[1323874312] linearizableReadLoop","detail":"{readStateIndex:1850; appliedIndex:1849; }","duration":"236.356622ms","start":"2026-02-06T04:39:18.842976Z","end":"2026-02-06T04:39:19.079333Z","steps":["trace[1323874312] 'read index received' (duration: 130.713176ms)","trace[1323874312] 'applied index is now lower than readState.Index' (duration: 105.642446ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T04:39:19.079468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.467874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T04:39:19.079497Z","caller":"traceutil/trace.go:171","msg":"trace[2112544846] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1772; }","duration":"236.531745ms","start":"2026-02-06T04:39:18.842957Z","end":"2026-02-06T04:39:19.079489Z","steps":["trace[2112544846] 'agreement among raft nodes before linearized reading' (duration: 236.459394ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T04:39:19.079782Z","caller":"traceutil/trace.go:171","msg":"trace[1738347013] transaction","detail":"{read_only:false; response_revision:1772; number_of_response:1; }","duration":"241.144793ms","start":"2026-02-06T04:39:18.838605Z","end":"2026-02-06T04:39:19.07975Z","steps":["trace[1738347013] 'process raft request' (duration: 205.208132ms)","trace[1738347013] 'compare' (duration: 35.41335ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T04:39:20.550805Z","caller":"traceutil/trace.go:171","msg":"trace[1921389353] transaction","detail":"{read_only:false; response_revision:1773; number_of_response:1; }","duration":"184.448833ms","start":"2026-02-06T04:39:20.366333Z","end":"2026-02-06T04:39:20.550782Z","steps":["trace[1921389353] 'process raft request' (duration: 184.273629ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T04:39:20.551097Z","caller":"traceutil/trace.go:171","msg":"trace[545868302] linearizableReadLoop","detail":"{readStateIndex:1851; appliedIndex:1851; }","duration":"184.354101ms","start":"2026-02-06T04:39:20.366732Z","end":"2026-02-06T04:39:20.551086Z","steps":["trace[545868302] 'read index received' (duration: 184.349791ms)","trace[545868302] 'applied index is now lower than readState.Index' (duration: 3.39µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T04:39:20.551295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.308357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-06T04:39:20.551325Z","caller":"traceutil/trace.go:171","msg":"trace[12226822] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1773; }","duration":"182.360958ms","start":"2026-02-06T04:39:20.368956Z","end":"2026-02-06T04:39:20.551316Z","steps":["trace[12226822] 'agreement among raft nodes before linearized reading' (duration: 182.191254ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:39:20.551688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.949223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-06T04:39:20.551722Z","caller":"traceutil/trace.go:171","msg":"trace[1415003680] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1773; }","duration":"184.991124ms","start":"2026-02-06T04:39:20.366719Z","end":"2026-02-06T04:39:20.55171Z","steps":["trace[1415003680] 'agreement among raft nodes before linearized reading' (duration: 184.909392ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:39:20.552241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.504317ms","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-02-06T04:39:20.552271Z","caller":"traceutil/trace.go:171","msg":"trace[679389840] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1773; }","duration":"101.559319ms","start":"2026-02-06T04:39:20.450703Z","end":"2026-02-06T04:39:20.552263Z","steps":["trace[679389840] 'agreement among raft nodes before linearized reading' (duration: 101.485077ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T04:39:20.703048Z","caller":"traceutil/trace.go:171","msg":"trace[920317352] transaction","detail":"{read_only:false; response_revision:1774; number_of_response:1; }","duration":"147.308866ms","start":"2026-02-06T04:39:20.555721Z","end":"2026-02-06T04:39:20.70303Z","steps":["trace[920317352] 'process raft request' (duration: 147.190224ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T04:39:20.834277Z","caller":"traceutil/trace.go:171","msg":"trace[1547398890] transaction","detail":"{read_only:false; response_revision:1778; number_of_response:1; }","duration":"273.345044ms","start":"2026-02-06T04:39:20.560915Z","end":"2026-02-06T04:39:20.83426Z","steps":["trace[1547398890] 'process raft request' (duration: 273.317073ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T04:39:20.834592Z","caller":"traceutil/trace.go:171","msg":"trace[425839951] transaction","detail":"{read_only:false; response_revision:1775; number_of_response:1; }","duration":"274.837776ms","start":"2026-02-06T04:39:20.559746Z","end":"2026-02-06T04:39:20.834584Z","steps":["trace[425839951] 'process raft request' (duration: 201.170697ms)","trace[425839951] 'compare' (duration: 73.170608ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T04:39:20.834683Z","caller":"traceutil/trace.go:171","msg":"trace[2078860834] transaction","detail":"{read_only:false; response_revision:1776; number_of_response:1; }","duration":"274.745543ms","start":"2026-02-06T04:39:20.559931Z","end":"2026-02-06T04:39:20.834677Z","steps":["trace[2078860834] 'process raft request' (duration: 274.251413ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T04:39:20.834753Z","caller":"traceutil/trace.go:171","msg":"trace[1908103339] transaction","detail":"{read_only:false; response_revision:1777; number_of_response:1; }","duration":"274.256653ms","start":"2026-02-06T04:39:20.56049Z","end":"2026-02-06T04:39:20.834747Z","steps":["trace[1908103339] 'process raft request' (duration: 273.718982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:39:21.148906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.445107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-02-06T04:39:21.148951Z","caller":"traceutil/trace.go:171","msg":"trace[2023663830] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1778; }","duration":"188.534489ms","start":"2026-02-06T04:39:20.960407Z","end":"2026-02-06T04:39:21.148941Z","steps":["trace[2023663830] 'count revisions from in-memory index tree' (duration: 188.375766ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T04:39:24.998569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.259845ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T04:39:24.998741Z","caller":"traceutil/trace.go:171","msg":"trace[1368539628] transaction","detail":"{read_only:false; response_revision:1801; number_of_response:1; }","duration":"131.573734ms","start":"2026-02-06T04:39:24.867156Z","end":"2026-02-06T04:39:24.99873Z","steps":["trace[1368539628] 'process raft request' (duration: 131.548363ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T04:39:24.998965Z","caller":"traceutil/trace.go:171","msg":"trace[302981240] transaction","detail":"{read_only:false; response_revision:1798; number_of_response:1; }","duration":"136.924298ms","start":"2026-02-06T04:39:24.862034Z","end":"2026-02-06T04:39:24.998959Z","steps":["trace[302981240] 'process raft request' (duration: 33.224273ms)","trace[302981240] 'compare' (duration: 103.183074ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T04:39:24.999024Z","caller":"traceutil/trace.go:171","msg":"trace[1436705290] transaction","detail":"{read_only:false; response_revision:1799; number_of_response:1; }","duration":"135.59634ms","start":"2026-02-06T04:39:24.863423Z","end":"2026-02-06T04:39:24.99902Z","steps":["trace[1436705290] 'process raft request' (duration: 135.232142ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T04:39:24.999074Z","caller":"traceutil/trace.go:171","msg":"trace[1383135520] transaction","detail":"{read_only:false; response_revision:1800; number_of_response:1; }","duration":"132.740199ms","start":"2026-02-06T04:39:24.86633Z","end":"2026-02-06T04:39:24.999071Z","steps":["trace[1383135520] 'process raft request' (duration: 132.355231ms)"],"step_count":1}