{"level":"warn","ts":"2026-02-25T01:37:47.151967Z","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-25T01:37:47.152095Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.49: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.49:2380","--initial-cluster=instance=https://162.253.55.49:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.49:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.49: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-25T01:37:47.152168Z","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-25T01:37:47.152183Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.49:2380"]} {"level":"info","ts":"2026-02-25T01:37:47.152206Z","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-25T01:37:47.153488Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.49:2379"]} {"level":"info","ts":"2026-02-25T01:37:47.153601Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.49:2380"],"listen-peer-urls":["https://162.253.55.49:2380"],"advertise-client-urls":["https://162.253.55.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.49: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-25T01:37:47.159815Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.101706ms"} {"level":"info","ts":"2026-02-25T01:37:47.17255Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6a9ba61e55fef5be","cluster-id":"9612db6e19ac6ce7"} {"level":"info","ts":"2026-02-25T01:37:47.172616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T01:37:47.172649Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became follower at term 0"} {"level":"info","ts":"2026-02-25T01:37:47.172664Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6a9ba61e55fef5be [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T01:37:47.172679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became follower at term 1"} {"level":"info","ts":"2026-02-25T01:37:47.172738Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=(7681916238632515006)"} {"level":"warn","ts":"2026-02-25T01:37:47.181881Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T01:37:47.184266Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T01:37:47.185429Z","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-25T01:37:47.187548Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6a9ba61e55fef5be","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T01:37:47.191011Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6a9ba61e55fef5be","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T01:37:47.191268Z","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-25T01:37:47.191423Z","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-25T01:37:47.191478Z","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-25T01:37:47.195039Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=(7681916238632515006)"} {"level":"info","ts":"2026-02-25T01:37:47.195243Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9612db6e19ac6ce7","local-member-id":"6a9ba61e55fef5be","added-peer-id":"6a9ba61e55fef5be","added-peer-peer-urls":["https://162.253.55.49:2380"]} {"level":"info","ts":"2026-02-25T01:37:47.195986Z","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-25T01:37:47.196212Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6a9ba61e55fef5be","initial-advertise-peer-urls":["https://162.253.55.49:2380"],"listen-peer-urls":["https://162.253.55.49:2380"],"advertise-client-urls":["https://162.253.55.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T01:37:47.196248Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T01:37:47.196372Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.49:2380"} {"level":"info","ts":"2026-02-25T01:37:47.196384Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.49:2380"} {"level":"info","ts":"2026-02-25T01:37:47.974504Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T01:37:47.974584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T01:37:47.974624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be received MsgPreVoteResp from 6a9ba61e55fef5be at term 1"} {"level":"info","ts":"2026-02-25T01:37:47.974649Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became candidate at term 2"} {"level":"info","ts":"2026-02-25T01:37:47.974661Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be received MsgVoteResp from 6a9ba61e55fef5be at term 2"} {"level":"info","ts":"2026-02-25T01:37:47.974673Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became leader at term 2"} {"level":"info","ts":"2026-02-25T01:37:47.974683Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6a9ba61e55fef5be elected leader 6a9ba61e55fef5be at term 2"} {"level":"info","ts":"2026-02-25T01:37:47.975691Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T01:37:47.976133Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6a9ba61e55fef5be","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.49:2379]}","request-path":"/0/members/6a9ba61e55fef5be/attributes","cluster-id":"9612db6e19ac6ce7","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T01:37:47.97632Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T01:37:47.976401Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T01:37:47.976609Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T01:37:47.976671Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T01:37:47.976959Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9612db6e19ac6ce7","local-member-id":"6a9ba61e55fef5be","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T01:37:47.977093Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T01:37:47.977134Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T01:37:47.979293Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.49:2379"} {"level":"info","ts":"2026-02-25T01:37:47.980738Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-25T01:38:15.288739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.425814ms","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-25T01:38:15.288861Z","caller":"traceutil/trace.go:171","msg":"trace[1572710174] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:470; }","duration":"245.67359ms","start":"2026-02-25T01:38:15.043168Z","end":"2026-02-25T01:38:15.288842Z","steps":["trace[1572710174] 'range keys from in-memory index tree' (duration: 245.292271ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:38:19.239938Z","caller":"traceutil/trace.go:171","msg":"trace[1831877388] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"296.17988ms","start":"2026-02-25T01:38:18.943736Z","end":"2026-02-25T01:38:19.239916Z","steps":["trace[1831877388] 'process raft request' (duration: 296.038847ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:19.488175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.077454ms","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-25T01:38:19.488293Z","caller":"traceutil/trace.go:171","msg":"trace[71242796] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:514; }","duration":"142.288459ms","start":"2026-02-25T01:38:19.345976Z","end":"2026-02-25T01:38:19.488264Z","steps":["trace[71242796] 'range keys from in-memory index tree' (duration: 141.92241ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:38:20.540715Z","caller":"traceutil/trace.go:171","msg":"trace[1521962787] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"204.153137ms","start":"2026-02-25T01:38:20.336531Z","end":"2026-02-25T01:38:20.540684Z","steps":["trace[1521962787] 'process raft request' (duration: 203.989843ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:38:25.704394Z","caller":"traceutil/trace.go:171","msg":"trace[901332465] linearizableReadLoop","detail":"{readStateIndex:550; appliedIndex:549; }","duration":"178.087562ms","start":"2026-02-25T01:38:25.526282Z","end":"2026-02-25T01:38:25.70437Z","steps":["trace[901332465] 'read index received' (duration: 177.851807ms)","trace[901332465] 'applied index is now lower than readState.Index' (duration: 234.445µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:38:25.704469Z","caller":"traceutil/trace.go:171","msg":"trace[594912086] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"437.840254ms","start":"2026-02-25T01:38:25.266583Z","end":"2026-02-25T01:38:25.704423Z","steps":["trace[594912086] 'process raft request' (duration: 437.612478ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:25.704815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.514532ms","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-25T01:38:25.704956Z","caller":"traceutil/trace.go:171","msg":"trace[1968635346] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:532; }","duration":"178.707037ms","start":"2026-02-25T01:38:25.526225Z","end":"2026-02-25T01:38:25.704932Z","steps":["trace[1968635346] 'agreement among raft nodes before linearized reading' (duration: 178.282747ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:25.705049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:25.266566Z","time spent":"437.968996ms","remote":"127.0.0.1:44280","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-25T01:38:26.038002Z","caller":"traceutil/trace.go:171","msg":"trace[1561496272] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"329.461553ms","start":"2026-02-25T01:38:25.708478Z","end":"2026-02-25T01:38:26.03794Z","steps":["trace[1561496272] 'process raft request' (duration: 287.216923ms)","trace[1561496272] 'compare' (duration: 41.991794ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:38:26.038144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:25.708447Z","time spent":"329.623627ms","remote":"127.0.0.1:44280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:38:26.651763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.934872ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:38:26.652102Z","caller":"traceutil/trace.go:171","msg":"trace[236314571] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"943.547703ms","start":"2026-02-25T01:38:25.708499Z","end":"2026-02-25T01:38:26.652047Z","steps":["trace[236314571] 'process raft request' (duration: 451.067908ms)","trace[236314571] 'compare' (duration: 491.759497ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:38:26.652361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:25.708477Z","time spent":"943.80912ms","remote":"127.0.0.1:44280","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-25T01:38:26.652416Z","caller":"traceutil/trace.go:171","msg":"trace[1110637839] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:550; }","duration":"702.742772ms","start":"2026-02-25T01:38:25.949363Z","end":"2026-02-25T01:38:26.652106Z","steps":["trace[1110637839] 'read index received' (duration: 46.34294ms)","trace[1110637839] 'applied index is now lower than readState.Index' (duration: 656.393072ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:38:26.83043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.555562ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-02-25T01:38:26.830419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.035967ms","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-25T01:38:26.830562Z","caller":"traceutil/trace.go:171","msg":"trace[1228105236] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:534; }","duration":"881.218102ms","start":"2026-02-25T01:38:25.949312Z","end":"2026-02-25T01:38:26.83053Z","steps":["trace[1228105236] 'agreement among raft nodes before linearized reading' (duration: 703.150992ms)","trace[1228105236] 'range keys from in-memory index tree' (duration: 177.741342ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:38:26.830621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:25.949292Z","time spent":"881.311875ms","remote":"127.0.0.1:44280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T01:38:26.830654Z","caller":"traceutil/trace.go:171","msg":"trace[274051006] linearizableReadLoop","detail":"{readStateIndex:553; appliedIndex:552; }","duration":"178.038199ms","start":"2026-02-25T01:38:26.652511Z","end":"2026-02-25T01:38:26.830549Z","steps":["trace[274051006] 'read index received' (duration: 65.733µs)","trace[274051006] 'applied index is now lower than readState.Index' (duration: 177.970126ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:38:26.831031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.525632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-7jm4t\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-25T01:38:26.831202Z","caller":"traceutil/trace.go:171","msg":"trace[431273377] range","detail":"{range_begin:/registry/pods/kube-system/cilium-7jm4t; range_end:; response_count:1; response_revision:534; }","duration":"708.624374ms","start":"2026-02-25T01:38:26.122459Z","end":"2026-02-25T01:38:26.831083Z","steps":["trace[431273377] 'agreement among raft nodes before linearized reading' (duration: 708.502161ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:26.831334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:26.122441Z","time spent":"708.87328ms","remote":"127.0.0.1:44180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16675,"request content":"key:\"/registry/pods/kube-system/cilium-7jm4t\" "} {"level":"warn","ts":"2026-02-25T01:38:28.100894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.589881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-25T01:38:28.100925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.76295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:38:28.100983Z","caller":"traceutil/trace.go:171","msg":"trace[18316558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:536; }","duration":"392.727335ms","start":"2026-02-25T01:38:27.708229Z","end":"2026-02-25T01:38:28.100956Z","steps":["trace[18316558] 'range keys from in-memory index tree' (duration: 392.365916ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:28.101044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:27.708208Z","time spent":"392.827057ms","remote":"127.0.0.1:44280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T01:38:28.10104Z","caller":"traceutil/trace.go:171","msg":"trace[195349989] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:536; }","duration":"359.959397ms","start":"2026-02-25T01:38:27.741053Z","end":"2026-02-25T01:38:28.101012Z","steps":["trace[195349989] 'range keys from in-memory index tree' (duration: 359.636557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:28.101163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:27.741014Z","time spent":"360.081849ms","remote":"127.0.0.1:44002","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-25T01:38:28.382095Z","caller":"traceutil/trace.go:171","msg":"trace[1375735921] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"276.649316ms","start":"2026-02-25T01:38:28.105415Z","end":"2026-02-25T01:38:28.382065Z","steps":["trace[1375735921] 'process raft request' (duration: 276.43297ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:38:28.4354Z","caller":"traceutil/trace.go:171","msg":"trace[33735462] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"328.849837ms","start":"2026-02-25T01:38:28.106524Z","end":"2026-02-25T01:38:28.435374Z","steps":["trace[33735462] 'process raft request' (duration: 328.336535ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:28.435583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:28.106486Z","time spent":"329.010261ms","remote":"127.0.0.1:44280","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-25T01:38:30.577377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.061748ms","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-25T01:38:30.577458Z","caller":"traceutil/trace.go:171","msg":"trace[505461374] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:567; }","duration":"191.192231ms","start":"2026-02-25T01:38:30.386248Z","end":"2026-02-25T01:38:30.577441Z","steps":["trace[505461374] 'range keys from in-memory index tree' (duration: 190.948926ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:38:30.584171Z","caller":"traceutil/trace.go:171","msg":"trace[2112129076] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:586; }","duration":"145.728332ms","start":"2026-02-25T01:38:30.43842Z","end":"2026-02-25T01:38:30.584149Z","steps":["trace[2112129076] 'read index received' (duration: 145.688361ms)","trace[2112129076] 'applied index is now lower than readState.Index' (duration: 38.941µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:38:30.584318Z","caller":"traceutil/trace.go:171","msg":"trace[757888992] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"191.865677ms","start":"2026-02-25T01:38:30.392406Z","end":"2026-02-25T01:38:30.584272Z","steps":["trace[757888992] 'process raft request' (duration: 191.642661ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:30.584346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.909036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T01:38:30.584395Z","caller":"traceutil/trace.go:171","msg":"trace[1454591491] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:568; }","duration":"146.007218ms","start":"2026-02-25T01:38:30.438377Z","end":"2026-02-25T01:38:30.584384Z","steps":["trace[1454591491] 'agreement among raft nodes before linearized reading' (duration: 145.856705ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:38:40.740448Z","caller":"traceutil/trace.go:171","msg":"trace[1692605964] linearizableReadLoop","detail":"{readStateIndex:723; appliedIndex:722; }","duration":"108.783201ms","start":"2026-02-25T01:38:40.631642Z","end":"2026-02-25T01:38:40.740425Z","steps":["trace[1692605964] 'read index received' (duration: 108.550085ms)","trace[1692605964] 'applied index is now lower than readState.Index' (duration: 232.326µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:38:40.740554Z","caller":"traceutil/trace.go:171","msg":"trace[517802885] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"113.65562ms","start":"2026-02-25T01:38:40.626891Z","end":"2026-02-25T01:38:40.740547Z","steps":["trace[517802885] 'process raft request' (duration: 113.402964ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:40.740922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.264304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T01:38:40.740957Z","caller":"traceutil/trace.go:171","msg":"trace[1648201855] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:700; }","duration":"109.361946ms","start":"2026-02-25T01:38:40.631584Z","end":"2026-02-25T01:38:40.740946Z","steps":["trace[1648201855] 'agreement among raft nodes before linearized reading' (duration: 109.283794ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:39:19.227858Z","caller":"traceutil/trace.go:171","msg":"trace[1357724823] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"117.517309ms","start":"2026-02-25T01:39:19.110281Z","end":"2026-02-25T01:39:19.227798Z","steps":["trace[1357724823] 'process raft request' (duration: 117.033468ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:20.862639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.524785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T01:39:20.862738Z","caller":"traceutil/trace.go:171","msg":"trace[1647061162] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:819; }","duration":"324.698609ms","start":"2026-02-25T01:39:20.53802Z","end":"2026-02-25T01:39:20.862719Z","steps":["trace[1647061162] 'count revisions from in-memory index tree' (duration: 324.408303ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:20.862795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:20.537973Z","time spent":"324.808312ms","remote":"127.0.0.1:44208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":40,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-02-25T01:39:21.160683Z","caller":"traceutil/trace.go:171","msg":"trace[561563215] linearizableReadLoop","detail":"{readStateIndex:851; appliedIndex:850; }","duration":"147.751005ms","start":"2026-02-25T01:39:21.01291Z","end":"2026-02-25T01:39:21.160661Z","steps":["trace[561563215] 'read index received' (duration: 147.54658ms)","trace[561563215] 'applied index is now lower than readState.Index' (duration: 203.185µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:39:21.160793Z","caller":"traceutil/trace.go:171","msg":"trace[348740757] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"158.365585ms","start":"2026-02-25T01:39:21.002389Z","end":"2026-02-25T01:39:21.160755Z","steps":["trace[348740757] 'process raft request' (duration: 158.12963ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:21.16092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.99815ms","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-25T01:39:21.160969Z","caller":"traceutil/trace.go:171","msg":"trace[587273255] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:820; }","duration":"148.084413ms","start":"2026-02-25T01:39:21.012871Z","end":"2026-02-25T01:39:21.160956Z","steps":["trace[587273255] 'agreement among raft nodes before linearized reading' (duration: 147.912229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:21.16116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.043232ms","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-25T01:39:21.16127Z","caller":"traceutil/trace.go:171","msg":"trace[1398178364] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:820; }","duration":"118.195566ms","start":"2026-02-25T01:39:21.043052Z","end":"2026-02-25T01:39:21.161247Z","steps":["trace[1398178364] 'agreement among raft nodes before linearized reading' (duration: 117.802276ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:39:32.711125Z","caller":"traceutil/trace.go:171","msg":"trace[1195337082] linearizableReadLoop","detail":"{readStateIndex:941; appliedIndex:940; }","duration":"337.465671ms","start":"2026-02-25T01:39:32.373634Z","end":"2026-02-25T01:39:32.7111Z","steps":["trace[1195337082] 'read index received' (duration: 337.269867ms)","trace[1195337082] 'applied index is now lower than readState.Index' (duration: 194.224µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:39:32.711414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.760758ms","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-25T01:39:32.711448Z","caller":"traceutil/trace.go:171","msg":"trace[1147799693] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:905; }","duration":"337.85867ms","start":"2026-02-25T01:39:32.373579Z","end":"2026-02-25T01:39:32.711438Z","steps":["trace[1147799693] 'agreement among raft nodes before linearized reading' (duration: 337.632495ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:32.711503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:32.37356Z","time spent":"337.934132ms","remote":"127.0.0.1:44280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-25T01:39:32.711794Z","caller":"traceutil/trace.go:171","msg":"trace[1826907070] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"439.77294ms","start":"2026-02-25T01:39:32.272011Z","end":"2026-02-25T01:39:32.711784Z","steps":["trace[1826907070] 'process raft request' (duration: 438.955311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:32.711902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:32.271978Z","time spent":"439.843034ms","remote":"127.0.0.1:44280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:39:39.477849Z","caller":"traceutil/trace.go:171","msg":"trace[1565340371] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"192.747939ms","start":"2026-02-25T01:39:39.285062Z","end":"2026-02-25T01:39:39.47781Z","steps":["trace[1565340371] 'process raft request' (duration: 192.291428ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:39:39.478514Z","caller":"traceutil/trace.go:171","msg":"trace[1960774423] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"190.206479ms","start":"2026-02-25T01:39:39.288283Z","end":"2026-02-25T01:39:39.478489Z","steps":["trace[1960774423] 'process raft request' (duration: 190.047365ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:39:43.263579Z","caller":"traceutil/trace.go:171","msg":"trace[1672891508] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"483.67787ms","start":"2026-02-25T01:39:42.779868Z","end":"2026-02-25T01:39:43.263545Z","steps":["trace[1672891508] 'process raft request' (duration: 483.495846ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:43.263801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:42.779846Z","time spent":"483.833274ms","remote":"127.0.0.1:44280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:39:43.413337Z","caller":"traceutil/trace.go:171","msg":"trace[1819667669] linearizableReadLoop","detail":"{readStateIndex:972; appliedIndex:971; }","duration":"121.698899ms","start":"2026-02-25T01:39:43.291615Z","end":"2026-02-25T01:39:43.413313Z","steps":["trace[1819667669] 'read index received' (duration: 95.721914ms)","trace[1819667669] 'applied index is now lower than readState.Index' (duration: 25.975625ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:39:43.413522Z","caller":"traceutil/trace.go:171","msg":"trace[607234195] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"346.173028ms","start":"2026-02-25T01:39:43.067303Z","end":"2026-02-25T01:39:43.413476Z","steps":["trace[607234195] 'process raft request' (duration: 320.152353ms)","trace[607234195] 'compare' (duration: 25.757109ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:39:43.413544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.901223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:39:43.413638Z","caller":"traceutil/trace.go:171","msg":"trace[1679609139] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:934; }","duration":"122.076448ms","start":"2026-02-25T01:39:43.29154Z","end":"2026-02-25T01:39:43.413616Z","steps":["trace[1679609139] 'agreement among raft nodes before linearized reading' (duration: 121.902414ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:43.413716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:43.067265Z","time spent":"346.354682ms","remote":"127.0.0.1:44280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:39:43.818819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.733128ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:39:43.819074Z","caller":"traceutil/trace.go:171","msg":"trace[1268337496] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"318.337978ms","start":"2026-02-25T01:39:43.500707Z","end":"2026-02-25T01:39:43.819045Z","steps":["trace[1268337496] 'process raft request' (duration: 185.244931ms)","trace[1268337496] 'compare' (duration: 132.36713ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:39:43.819156Z","caller":"traceutil/trace.go:171","msg":"trace[264954597] linearizableReadLoop","detail":"{readStateIndex:975; appliedIndex:972; }","duration":"315.162923ms","start":"2026-02-25T01:39:43.503971Z","end":"2026-02-25T01:39:43.819134Z","steps":["trace[264954597] 'read index received' (duration: 182.192069ms)","trace[264954597] 'applied index is now lower than readState.Index' (duration: 132.969324ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:39:43.819201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:43.500687Z","time spent":"318.448411ms","remote":"127.0.0.1:44280","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-25T01:39:43.819223Z","caller":"traceutil/trace.go:171","msg":"trace[206194092] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"317.841877ms","start":"2026-02-25T01:39:43.501334Z","end":"2026-02-25T01:39:43.819176Z","steps":["trace[206194092] 'process raft request' (duration: 317.627122ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:39:43.819287Z","caller":"traceutil/trace.go:171","msg":"trace[1324497022] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"317.838687ms","start":"2026-02-25T01:39:43.501409Z","end":"2026-02-25T01:39:43.819248Z","steps":["trace[1324497022] 'process raft request' (duration: 317.683033ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:43.819446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:43.501377Z","time spent":"317.980681ms","remote":"127.0.0.1:44280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:39:43.819513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.519433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-25T01:39:43.819554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:43.501305Z","time spent":"318.077223ms","remote":"127.0.0.1:44280","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-25T01:39:43.819662Z","caller":"traceutil/trace.go:171","msg":"trace[962562854] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:937; }","duration":"315.659186ms","start":"2026-02-25T01:39:43.503932Z","end":"2026-02-25T01:39:43.819591Z","steps":["trace[962562854] 'agreement among raft nodes before linearized reading' (duration: 315.304077ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:43.819716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:43.503921Z","time spent":"315.784959ms","remote":"127.0.0.1:44280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T01:39:58.912905Z","caller":"traceutil/trace.go:171","msg":"trace[196743681] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"307.705157ms","start":"2026-02-25T01:39:58.605163Z","end":"2026-02-25T01:39:58.912868Z","steps":["trace[196743681] 'process raft request' (duration: 227.937151ms)","trace[196743681] 'compare' (duration: 79.49306ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:39:58.913162Z","caller":"traceutil/trace.go:171","msg":"trace[1144282227] linearizableReadLoop","detail":"{readStateIndex:1038; appliedIndex:1035; }","duration":"302.631039ms","start":"2026-02-25T01:39:58.610505Z","end":"2026-02-25T01:39:58.913136Z","steps":["trace[1144282227] 'read index received' (duration: 29.476498ms)","trace[1144282227] 'applied index is now lower than readState.Index' (duration: 273.153041ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:39:58.91322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:58.605113Z","time spent":"307.906142ms","remote":"127.0.0.1:44288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1507,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:39:58.913288Z","caller":"traceutil/trace.go:171","msg":"trace[997330206] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"304.558864ms","start":"2026-02-25T01:39:58.608719Z","end":"2026-02-25T01:39:58.913278Z","steps":["trace[997330206] 'process raft request' (duration: 304.072011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:58.913429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:58.608711Z","time spent":"304.614005ms","remote":"127.0.0.1:44466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:39:58.913473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.584599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:39:58.913575Z","caller":"traceutil/trace.go:171","msg":"trace[497384866] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:997; }","duration":"171.744443ms","start":"2026-02-25T01:39:58.741808Z","end":"2026-02-25T01:39:58.913553Z","steps":["trace[497384866] 'agreement among raft nodes before linearized reading' (duration: 171.589119ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:58.913664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.10567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T01:39:58.913767Z","caller":"traceutil/trace.go:171","msg":"trace[500759304] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:997; }","duration":"303.264444ms","start":"2026-02-25T01:39:58.61047Z","end":"2026-02-25T01:39:58.913734Z","steps":["trace[500759304] 'agreement among raft nodes before linearized reading' (duration: 303.043809ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:58.913848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:58.610459Z","time spent":"303.354776ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-25T01:40:01.548138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.992548ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:40:01.5483Z","caller":"traceutil/trace.go:171","msg":"trace[1913022641] linearizableReadLoop","detail":"{readStateIndex:1052; appliedIndex:1051; }","duration":"199.614941ms","start":"2026-02-25T01:40:01.348663Z","end":"2026-02-25T01:40:01.548278Z","steps":["trace[1913022641] 'read index received' (duration: 58.891µs)","trace[1913022641] 'applied index is now lower than readState.Index' (duration: 199.55329ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:40:01.548424Z","caller":"traceutil/trace.go:171","msg":"trace[201415677] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"359.84016ms","start":"2026-02-25T01:40:01.188537Z","end":"2026-02-25T01:40:01.548377Z","steps":["trace[201415677] 'process raft request' (duration: 53.480845ms)","trace[201415677] 'compare' (duration: 305.668179ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:40:01.548634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:01.188508Z","time spent":"360.010835ms","remote":"127.0.0.1:44040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:40:01.54863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.972619ms","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-25T01:40:01.548701Z","caller":"traceutil/trace.go:171","msg":"trace[1009665821] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1010; }","duration":"200.080932ms","start":"2026-02-25T01:40:01.348608Z","end":"2026-02-25T01:40:01.548689Z","steps":["trace[1009665821] 'agreement among raft nodes before linearized reading' (duration: 199.724343ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:02.59141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.08267ms","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-25T01:40:02.591548Z","caller":"traceutil/trace.go:171","msg":"trace[1547062263] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1010; }","duration":"799.256865ms","start":"2026-02-25T01:40:01.792268Z","end":"2026-02-25T01:40:02.591525Z","steps":["trace[1547062263] 'range keys from in-memory index tree' (duration: 799.05813ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:02.592233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.520989ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:40:02.592342Z","caller":"traceutil/trace.go:171","msg":"trace[635065748] linearizableReadLoop","detail":"{readStateIndex:1053; appliedIndex:1052; }","duration":"1.03794729s","start":"2026-02-25T01:40:01.554377Z","end":"2026-02-25T01:40:02.592324Z","steps":["trace[635065748] 'read index received' (duration: 411.185775ms)","trace[635065748] 'applied index is now lower than readState.Index' (duration: 626.759835ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:40:02.592557Z","caller":"traceutil/trace.go:171","msg":"trace[516529614] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"1.039080906s","start":"2026-02-25T01:40:01.553466Z","end":"2026-02-25T01:40:02.592547Z","steps":["trace[516529614] 'process raft request' (duration: 412.083426ms)","trace[516529614] 'compare' (duration: 625.923215ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:40:02.592602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:01.553446Z","time spent":"1.039133327s","remote":"127.0.0.1:44280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:40:02.592858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.038482152s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-25T01:40:02.592888Z","caller":"traceutil/trace.go:171","msg":"trace[1184720455] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:1011; }","duration":"1.038526503s","start":"2026-02-25T01:40:01.554351Z","end":"2026-02-25T01:40:02.592878Z","steps":["trace[1184720455] 'agreement among raft nodes before linearized reading' (duration: 1.038416231s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:02.592913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:01.554335Z","time spent":"1.038572795s","remote":"127.0.0.1:44040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-02-25T01:40:02.592968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.310325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:40:02.593069Z","caller":"traceutil/trace.go:171","msg":"trace[22876077] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1011; }","duration":"851.483909ms","start":"2026-02-25T01:40:01.741562Z","end":"2026-02-25T01:40:02.593046Z","steps":["trace[22876077] 'agreement among raft nodes before linearized reading' (duration: 851.304855ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:02.59312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:01.741534Z","time spent":"851.577042ms","remote":"127.0.0.1:44002","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-25T01:40:02.593146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.10236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-25T01:40:02.593204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.642214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T01:40:02.593233Z","caller":"traceutil/trace.go:171","msg":"trace[1455696506] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1011; }","duration":"654.696535ms","start":"2026-02-25T01:40:01.93853Z","end":"2026-02-25T01:40:02.593226Z","steps":["trace[1455696506] 'agreement among raft nodes before linearized reading' (duration: 654.618653ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:40:02.593243Z","caller":"traceutil/trace.go:171","msg":"trace[1943684515] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1011; }","duration":"654.230293ms","start":"2026-02-25T01:40:01.938991Z","end":"2026-02-25T01:40:02.593222Z","steps":["trace[1943684515] 'agreement among raft nodes before linearized reading' (duration: 653.897805ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:02.59326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:01.938517Z","time spent":"654.738306ms","remote":"127.0.0.1:44280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T01:40:02.593291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:01.938985Z","time spent":"654.297605ms","remote":"127.0.0.1:44280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T01:40:02.593328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.173792ms","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-25T01:40:02.593414Z","caller":"traceutil/trace.go:171","msg":"trace[1886484932] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1011; }","duration":"654.324045ms","start":"2026-02-25T01:40:01.939077Z","end":"2026-02-25T01:40:02.593401Z","steps":["trace[1886484932] 'agreement among raft nodes before linearized reading' (duration: 653.906036ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:02.593461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.678912ms","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-25T01:40:02.593505Z","caller":"traceutil/trace.go:171","msg":"trace[1005254130] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1011; }","duration":"675.768173ms","start":"2026-02-25T01:40:01.917724Z","end":"2026-02-25T01:40:02.593492Z","steps":["trace[1005254130] 'agreement among raft nodes before linearized reading' (duration: 675.61465ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:02.593538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:01.917691Z","time spent":"675.840545ms","remote":"127.0.0.1:44280","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-25T01:40:02.593514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:01.93905Z","time spent":"654.433228ms","remote":"127.0.0.1:44280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T01:40:03.219816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.101771ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:40:03.220022Z","caller":"traceutil/trace.go:171","msg":"trace[163603370] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"621.241323ms","start":"2026-02-25T01:40:02.598746Z","end":"2026-02-25T01:40:03.219987Z","steps":["trace[163603370] 'process raft request' (duration: 519.841385ms)","trace[163603370] 'compare' (duration: 100.798854ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:40:03.220131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:02.598731Z","time spent":"621.353477ms","remote":"127.0.0.1:44280","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-25T01:40:03.271794Z","caller":"traceutil/trace.go:171","msg":"trace[86899989] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"672.086086ms","start":"2026-02-25T01:40:02.599683Z","end":"2026-02-25T01:40:03.271769Z","steps":["trace[86899989] 'process raft request' (duration: 672.037254ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:40:03.272077Z","caller":"traceutil/trace.go:171","msg":"trace[1068155745] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"673.250245ms","start":"2026-02-25T01:40:02.598819Z","end":"2026-02-25T01:40:03.27207Z","steps":["trace[1068155745] 'process raft request' (duration: 672.776213ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:03.272169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:02.598793Z","time spent":"673.303545ms","remote":"127.0.0.1:44280","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-25T01:40:03.272169Z","caller":"traceutil/trace.go:171","msg":"trace[2134557357] linearizableReadLoop","detail":"{readStateIndex:1057; appliedIndex:1053; }","duration":"582.161409ms","start":"2026-02-25T01:40:02.689973Z","end":"2026-02-25T01:40:03.272135Z","steps":["trace[2134557357] 'read index received' (duration: 428.631078ms)","trace[2134557357] 'applied index is now lower than readState.Index' (duration: 153.529041ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:40:03.272316Z","caller":"traceutil/trace.go:171","msg":"trace[1456911625] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"670.919299ms","start":"2026-02-25T01:40:02.601386Z","end":"2026-02-25T01:40:03.272305Z","steps":["trace[1456911625] 'process raft request' (duration: 670.362355ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:03.272309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:02.59965Z","time spent":"672.493976ms","remote":"127.0.0.1:44280","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-25T01:40:03.272463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.498107ms","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":"warn","ts":"2026-02-25T01:40:03.272467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:02.601359Z","time spent":"671.000161ms","remote":"127.0.0.1:44280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:40:03.272495Z","caller":"traceutil/trace.go:171","msg":"trace[503076163] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1015; }","duration":"582.577998ms","start":"2026-02-25T01:40:02.68991Z","end":"2026-02-25T01:40:03.272488Z","steps":["trace[503076163] 'agreement among raft nodes before linearized reading' (duration: 582.523497ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:03.272518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:02.689884Z","time spent":"582.6303ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-02-25T01:40:03.272572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.225772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:40:03.27266Z","caller":"traceutil/trace.go:171","msg":"trace[1098397807] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1015; }","duration":"259.360555ms","start":"2026-02-25T01:40:03.013282Z","end":"2026-02-25T01:40:03.272642Z","steps":["trace[1098397807] 'agreement among raft nodes before linearized reading' (duration: 259.15302ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:40:04.813107Z","caller":"traceutil/trace.go:171","msg":"trace[1583277660] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"113.852203ms","start":"2026-02-25T01:40:04.699215Z","end":"2026-02-25T01:40:04.813068Z","steps":["trace[1583277660] 'process raft request' (duration: 113.662299ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:40:05.451967Z","caller":"traceutil/trace.go:171","msg":"trace[1113698522] linearizableReadLoop","detail":"{readStateIndex:1062; appliedIndex:1061; }","duration":"175.00001ms","start":"2026-02-25T01:40:05.27675Z","end":"2026-02-25T01:40:05.45175Z","steps":["trace[1113698522] 'read index received' (duration: 174.729672ms)","trace[1113698522] 'applied index is now lower than readState.Index' (duration: 268.947µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:40:05.452142Z","caller":"traceutil/trace.go:171","msg":"trace[119296533] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"209.446544ms","start":"2026-02-25T01:40:05.242621Z","end":"2026-02-25T01:40:05.452068Z","steps":["trace[119296533] 'process raft request' (duration: 208.933632ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:05.452557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.727936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-25T01:40:05.452588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.362448ms","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-25T01:40:05.452642Z","caller":"traceutil/trace.go:171","msg":"trace[549466640] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1020; }","duration":"175.87399ms","start":"2026-02-25T01:40:05.276745Z","end":"2026-02-25T01:40:05.452619Z","steps":["trace[549466640] 'agreement among raft nodes before linearized reading' (duration: 175.601213ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:40:05.452661Z","caller":"traceutil/trace.go:171","msg":"trace[1849549005] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1020; }","duration":"175.48183ms","start":"2026-02-25T01:40:05.277162Z","end":"2026-02-25T01:40:05.452644Z","steps":["trace[1849549005] 'agreement among raft nodes before linearized reading' (duration: 175.216684ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:05.452906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.160786ms","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-25T01:40:05.452954Z","caller":"traceutil/trace.go:171","msg":"trace[1528620517] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1020; }","duration":"176.234868ms","start":"2026-02-25T01:40:05.276705Z","end":"2026-02-25T01:40:05.45294Z","steps":["trace[1528620517] 'agreement among raft nodes before linearized reading' (duration: 175.43386ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:40:05.848697Z","caller":"traceutil/trace.go:171","msg":"trace[756743107] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"391.633116ms","start":"2026-02-25T01:40:05.457011Z","end":"2026-02-25T01:40:05.848644Z","steps":["trace[756743107] 'process raft request' (duration: 349.608554ms)","trace[756743107] 'compare' (duration: 41.592632ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:40:05.849172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:05.456984Z","time spent":"392.071205ms","remote":"127.0.0.1:44280","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-25T01:40:05.89953Z","caller":"traceutil/trace.go:171","msg":"trace[1647485072] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"441.664186ms","start":"2026-02-25T01:40:05.457837Z","end":"2026-02-25T01:40:05.899501Z","steps":["trace[1647485072] 'process raft request' (duration: 441.616505ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:05.899732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:05.457814Z","time spent":"441.802589ms","remote":"127.0.0.1:44280","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-25T01:40:05.899836Z","caller":"traceutil/trace.go:171","msg":"trace[2067439131] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"441.965543ms","start":"2026-02-25T01:40:05.457844Z","end":"2026-02-25T01:40:05.899809Z","steps":["trace[2067439131] 'process raft request' (duration: 441.480421ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:05.899937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:05.457817Z","time spent":"442.067546ms","remote":"127.0.0.1:44280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:40:06.15854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.873933ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:40:06.158778Z","caller":"traceutil/trace.go:171","msg":"trace[1883730011] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"211.106992ms","start":"2026-02-25T01:40:05.947644Z","end":"2026-02-25T01:40:06.158751Z","steps":["trace[1883730011] 'process raft request' (duration: 42.912912ms)","trace[1883730011] 'compare' (duration: 167.676038ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:40:06.63857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.440978ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T01:40:06.707816Z","caller":"traceutil/trace.go:171","msg":"trace[494302260] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"116.617093ms","start":"2026-02-25T01:40:06.591166Z","end":"2026-02-25T01:40:06.707783Z","steps":["trace[494302260] 'process raft request' (duration: 116.407168ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:40:07.173817Z","caller":"traceutil/trace.go:171","msg":"trace[1256148381] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"308.532073ms","start":"2026-02-25T01:40:06.865252Z","end":"2026-02-25T01:40:07.173784Z","steps":["trace[1256148381] 'process raft request' (duration: 308.469511ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:40:07.173882Z","caller":"traceutil/trace.go:171","msg":"trace[545885459] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"361.36535ms","start":"2026-02-25T01:40:06.81244Z","end":"2026-02-25T01:40:07.173806Z","steps":["trace[545885459] 'process raft request' (duration: 281.059835ms)","trace[545885459] 'compare' (duration: 80.015228ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:40:07.174022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:06.865218Z","time spent":"308.697357ms","remote":"127.0.0.1:44072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":782,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T01:40:07.174175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:06.812413Z","time spent":"361.610486ms","remote":"127.0.0.1:44280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":495,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:40:08.097608Z","caller":"traceutil/trace.go:171","msg":"trace[761772514] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"188.53905ms","start":"2026-02-25T01:40:07.909041Z","end":"2026-02-25T01:40:08.09758Z","steps":["trace[761772514] 'process raft request' (duration: 188.288555ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:40:08.111176Z","caller":"traceutil/trace.go:171","msg":"trace[1435608719] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"201.822404ms","start":"2026-02-25T01:40:07.909339Z","end":"2026-02-25T01:40:08.111161Z","steps":["trace[1435608719] 'process raft request' (duration: 201.736892ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:09.798414Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.052582297s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T01:40:09.798853Z","caller":"traceutil/trace.go:171","msg":"trace[582765371] linearizableReadLoop","detail":"{readStateIndex:1079; appliedIndex:1078; }","duration":"243.503548ms","start":"2026-02-25T01:40:09.55532Z","end":"2026-02-25T01:40:09.798823Z","steps":["trace[582765371] 'read index received' (duration: 243.334444ms)","trace[582765371] 'applied index is now lower than readState.Index' (duration: 167.924µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:40:09.798916Z","caller":"traceutil/trace.go:171","msg":"trace[754308205] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"1.053180341s","start":"2026-02-25T01:40:08.745699Z","end":"2026-02-25T01:40:09.798879Z","steps":["trace[754308205] 'process raft request' (duration: 1.052967196s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:09.799086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:08.745677Z","time spent":"1.053301253s","remote":"127.0.0.1:44280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:40:09.799264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.911958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T01:40:09.799327Z","caller":"traceutil/trace.go:171","msg":"trace[311958087] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1036; }","duration":"244.058262ms","start":"2026-02-25T01:40:09.555254Z","end":"2026-02-25T01:40:09.799312Z","steps":["trace[311958087] 'agreement among raft nodes before linearized reading' (duration: 243.756594ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:40:10.096241Z","caller":"traceutil/trace.go:171","msg":"trace[651289746] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"291.682816ms","start":"2026-02-25T01:40:09.804512Z","end":"2026-02-25T01:40:10.096195Z","steps":["trace[651289746] 'process raft request' (duration: 204.391905ms)","trace[651289746] 'compare' (duration: 87.119996ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:40:10.09822Z","caller":"traceutil/trace.go:171","msg":"trace[1489161715] linearizableReadLoop","detail":"{readStateIndex:1081; appliedIndex:1079; }","duration":"195.597358ms","start":"2026-02-25T01:40:09.90259Z","end":"2026-02-25T01:40:10.098187Z","steps":["trace[1489161715] 'read index received' (duration: 106.30496ms)","trace[1489161715] 'applied index is now lower than readState.Index' (duration: 89.291208ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:40:10.098522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.877744ms","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-25T01:40:10.098609Z","caller":"traceutil/trace.go:171","msg":"trace[717748350] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1038; }","duration":"196.089019ms","start":"2026-02-25T01:40:09.902503Z","end":"2026-02-25T01:40:10.098592Z","steps":["trace[717748350] 'agreement among raft nodes before linearized reading' (duration: 195.799802ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:40:10.098698Z","caller":"traceutil/trace.go:171","msg":"trace[1248570828] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"293.555949ms","start":"2026-02-25T01:40:09.805104Z","end":"2026-02-25T01:40:10.09866Z","steps":["trace[1248570828] 'process raft request' (duration: 292.966695ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:40:14.817077Z","caller":"traceutil/trace.go:171","msg":"trace[46289964] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"122.062982ms","start":"2026-02-25T01:40:14.694974Z","end":"2026-02-25T01:40:14.817037Z","steps":["trace[46289964] 'process raft request' (duration: 118.20552ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:53.786886Z","caller":"traceutil/trace.go:171","msg":"trace[1494068890] transaction","detail":"{read_only:false; response_revision:1730; number_of_response:1; }","duration":"455.888851ms","start":"2026-02-25T01:42:53.330949Z","end":"2026-02-25T01:42:53.786838Z","steps":["trace[1494068890] 'process raft request' (duration: 455.503903ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:53.787109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:53.330924Z","time spent":"456.070056ms","remote":"127.0.0.1:44280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:43:18.81715Z","caller":"traceutil/trace.go:171","msg":"trace[812094034] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"521.620517ms","start":"2026-02-25T01:43:18.295479Z","end":"2026-02-25T01:43:18.8171Z","steps":["trace[812094034] 'process raft request' (duration: 521.412482ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:18.817382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:43:18.295453Z","time spent":"521.79441ms","remote":"127.0.0.1:44280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:43:29.28085Z","caller":"traceutil/trace.go:171","msg":"trace[162148410] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"111.297169ms","start":"2026-02-25T01:43:29.169524Z","end":"2026-02-25T01:43:29.280821Z","steps":["trace[162148410] 'process raft request' (duration: 111.045144ms)"],"step_count":1}